builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0076 starttime: 1464372923.12 results: success (0) buildid: 20160527093441 builduid: 2f62a19f8076409f813b3fc4e7ccef8c revision: 9d87d3a279a08ac507dda94c36d5a12185fea4a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:23.126617) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:23.127163) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:23.127440) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:23.151059) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:23.151387) ========= 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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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-05-27 11:15:23-- 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% 36.0M=0s 2016-05-27 11:15:23 (36.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.449808 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:23.621434) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:23.621800) ========= 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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.028409 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:23.670286) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 11:15:23.670644) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9d87d3a279a08ac507dda94c36d5a12185fea4a6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9d87d3a279a08ac507dda94c36d5a12185fea4a6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7jGoE4fmHR/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.1xoUdsYmV7/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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-05-27 11:15:23,747 truncating revision to first 12 chars 2016-05-27 11:15:23,747 Setting DEBUG logging. 2016-05-27 11:15:23,747 attempt 1/10 2016-05-27 11:15:23,748 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9d87d3a279a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 11:15:24,554 unpacking tar archive at: mozilla-aurora-9d87d3a279a0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.054819 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 11:15:24.744859) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:24.745161) ========= script_repo_revision: 9d87d3a279a08ac507dda94c36d5a12185fea4a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:24.745544) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:24.745809) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 11:15:24.762415) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-05-27 11:15:24.762747) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7jGoE4fmHR/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.1xoUdsYmV7/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 11:15:24 INFO - MultiFileLogger online at 20160527 11:15:24 in /builds/slave/test 11:15:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 11:15:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:15:24 INFO - {'append_to_log': False, 11:15:24 INFO - 'base_work_dir': '/builds/slave/test', 11:15:24 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:15:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:15:24 INFO - 'branch': 'Mozilla-Aurora', 11:15:24 INFO - 'buildbot_json_path': 'buildprops.json', 11:15:24 INFO - 'config_files': ('talos/mac_config.py',), 11:15:24 INFO - 'default_actions': ('clobber', 11:15:24 INFO - 'read-buildbot-config', 11:15:24 INFO - 'download-and-extract', 11:15:24 INFO - 'populate-webroot', 11:15:24 INFO - 'create-virtualenv', 11:15:24 INFO - 'install', 11:15:24 INFO - 'run-tests'), 11:15:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:15:24 INFO - 'download_minidump_stackwalk': True, 11:15:24 INFO - 'download_symbols': 'ondemand', 11:15:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:15:24 INFO - 'tooltool.py': '/tools/tooltool.py', 11:15:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:15:24 INFO - '/tools/misc-python/virtualenv.py')}, 11:15:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:15:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:15:24 INFO - 'installer_path': 'installer.exe', 11:15:24 INFO - 'log_level': 'info', 11:15:24 INFO - 'log_name': 'talos', 11:15:24 INFO - 'log_to_console': True, 11:15:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:15:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:15:24 INFO - 'opt_config_files': (), 11:15:24 INFO - 'pip_index': False, 11:15:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:15:24 INFO - 'cmd': ('bash', 11:15:24 INFO - '-c', 11:15:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:15:24 INFO - 'enabled': True, 11:15:24 INFO - 'halt_on_failure': False, 11:15:24 INFO - 'name': 'check_screen_resolution'},), 11:15:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:15:24 INFO - 'cmd': ('bash', 11:15:24 INFO - '-c', 11:15:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:15:24 INFO - 'enabled': True, 11:15:24 INFO - 'halt_on_failure': False, 11:15:24 INFO - 'name': 'check_screen_resolution'},), 11:15:24 INFO - 'run_cmd_checks_enabled': True, 11:15:24 INFO - 'sps_profile': False, 11:15:24 INFO - 'sps_profile_interval': 0, 11:15:24 INFO - 'suite': 'dromaeojs-e10s', 11:15:24 INFO - 'system_bits': '32', 11:15:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:15:24 INFO - 'title': 't-yosemite-r7-0076', 11:15:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:15:24 INFO - 'use_talos_json': True, 11:15:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:15:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:15:24 INFO - 'work_dir': 'build'} 11:15:24 INFO - ##### 11:15:24 INFO - ##### Running clobber step. 11:15:24 INFO - ##### 11:15:24 INFO - Running pre-action listener: _resource_record_pre_action 11:15:24 INFO - Running main action method: clobber 11:15:24 INFO - rmtree: /builds/slave/test/build 11:15:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:15:26 INFO - Running post-action listener: _resource_record_post_action 11:15:26 INFO - ##### 11:15:26 INFO - ##### Running read-buildbot-config step. 11:15:26 INFO - ##### 11:15:26 INFO - Running pre-action listener: _resource_record_pre_action 11:15:26 INFO - Running main action method: read_buildbot_config 11:15:26 INFO - Using buildbot properties: 11:15:26 INFO - { 11:15:26 INFO - "project": "", 11:15:26 INFO - "product": "firefox", 11:15:26 INFO - "script_repo_revision": "production", 11:15:26 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 11:15:26 INFO - "repository": "", 11:15:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 11:15:26 INFO - "buildid": "20160527093441", 11:15:26 INFO - "pgo_build": "False", 11:15:26 INFO - "basedir": "/builds/slave/test", 11:15:26 INFO - "buildnumber": 18, 11:15:26 INFO - "slavename": "t-yosemite-r7-0076", 11:15:26 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 11:15:26 INFO - "platform": "yosemite_r7", 11:15:26 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:15:26 INFO - "branch": "mozilla-aurora", 11:15:26 INFO - "repo_path": "releases/mozilla-aurora", 11:15:26 INFO - "revision": "9d87d3a279a08ac507dda94c36d5a12185fea4a6", 11:15:26 INFO - "stage_platform": "macosx64", 11:15:26 INFO - "builduid": "2f62a19f8076409f813b3fc4e7ccef8c", 11:15:26 INFO - "slavebuilddir": "test" 11:15:26 INFO - } 11:15:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 11:15:26 INFO - Running post-action listener: _resource_record_post_action 11:15:26 INFO - ##### 11:15:26 INFO - ##### Running download-and-extract step. 11:15:26 INFO - ##### 11:15:26 INFO - Running pre-action listener: _resource_record_pre_action 11:15:26 INFO - Running main action method: download_and_extract 11:15:26 INFO - mkdir: /builds/slave/test/build/tests 11:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:26 INFO - https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:15:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 11:15:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 11:15:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 11:15:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 11:15:39 INFO - Downloaded 1199 bytes. 11:15:39 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 11:15:39 INFO - Using the following test package requirements: 11:15:39 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 11:15:39 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:15:39 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 11:15:39 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:15:39 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 11:15:39 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:15:39 INFO - u'jsshell-mac.zip'], 11:15:39 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:15:39 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 11:15:39 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 11:15:39 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:15:39 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 11:15:39 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:15:39 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 11:15:39 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:15:39 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 11:15:39 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 11:15:39 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 11:15:39 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:15:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:39 INFO - https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:15:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:15:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:15:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:15:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:15:41 INFO - Downloaded 18425581 bytes. 11:15:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:15:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:15:44 INFO - Return code: 0 11:15:44 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 11:15: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')]} 11:15:44 INFO - https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:15:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:15:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:15:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 11:15:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:15:53 INFO - Downloaded 18425581 bytes. 11:15:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:15:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:15:56 INFO - Return code: 0 11:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:56 INFO - https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:15:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 11:15:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 11:15:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 11:15:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:15:57 INFO - Downloaded 10840145 bytes. 11:15:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:15:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:15:57 INFO - Return code: 0 11:15: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')]} 11:15:57 INFO - https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 11:15:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:15:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:15:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 11:15:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 11:16:10 INFO - Downloaded 114143863 bytes. 11:16:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:16:10 INFO - mkdir: /builds/slave/test/properties 11:16:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:16:10 INFO - Writing to file /builds/slave/test/properties/build_url 11:16:10 INFO - Contents: 11:16:10 INFO - build_url:https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 11:16:11 INFO - Running post-action listener: _resource_record_post_action 11:16:11 INFO - Running post-action listener: set_extra_try_arguments 11:16:11 INFO - ##### 11:16:11 INFO - ##### Running populate-webroot step. 11:16:11 INFO - ##### 11:16:11 INFO - Running pre-action listener: _resource_record_pre_action 11:16:11 INFO - Running main action method: populate_webroot 11:16:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:16:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:16:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:16:11 INFO - '--tppagecycles', 11:16:11 INFO - '7'], 11:16:11 INFO - 'tests': ['tsvgm']}}, 11:16:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:16:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:16:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:16:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:16:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:16:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:16:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:11 INFO - 'talos_options': ['--disable-e10s'], 11:16:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:16:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:16:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:11 INFO - 'talos_options': ['--disable-e10s'], 11:16:11 INFO - 'tests': ['damp', 'tps']}, 11:16:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:11 INFO - 'tests': ['damp', 'tps']}, 11:16:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:16:11 INFO - 'tests': ['dromaeo_dom']}, 11:16:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:16:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:16:11 INFO - 'tests': ['a11yr', 11:16:11 INFO - 'ts_paint', 11:16:11 INFO - 'tpaint', 11:16:11 INFO - 'sessionrestore', 11:16:11 INFO - 'sessionrestore_no_auto_restore', 11:16:11 INFO - 'tabpaint']}, 11:16:11 INFO - 'other-e10s': {'tests': ['a11yr', 11:16:11 INFO - 'ts_paint', 11:16:11 INFO - 'tpaint', 11:16:11 INFO - 'sessionrestore', 11:16:11 INFO - 'sessionrestore_no_auto_restore', 11:16:11 INFO - 'tabpaint']}, 11:16:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:16:11 INFO - 'ts_paint', 11:16:11 INFO - 'tpaint', 11:16:11 INFO - 'sessionrestore', 11:16:11 INFO - 'sessionrestore_no_auto_restore', 11:16:11 INFO - 'tabpaint']}, 11:16:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:16:11 INFO - 'ts_paint', 11:16:11 INFO - 'tpaint', 11:16:11 INFO - 'sessionrestore', 11:16:11 INFO - 'sessionrestore_no_auto_restore', 11:16:11 INFO - 'tabpaint']}, 11:16:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:16:11 INFO - 'tests': ['a11yr', 11:16:11 INFO - 'ts_paint', 11:16:11 INFO - 'tpaint', 11:16:11 INFO - 'sessionrestore', 11:16:11 INFO - 'sessionrestore_no_auto_restore', 11:16:11 INFO - 'tabpaint']}, 11:16:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:16:11 INFO - 'tests': ['a11yr', 11:16:11 INFO - 'ts_paint', 11:16:11 INFO - 'tpaint', 11:16:11 INFO - 'sessionrestore', 11:16:11 INFO - 'sessionrestore_no_auto_restore', 11:16:11 INFO - 'tabpaint']}, 11:16:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:16:11 INFO - 'tests': ['tsvgx', 11:16:11 INFO - 'tsvgr_opacity', 11:16:11 INFO - 'tart', 11:16:11 INFO - 'tscrollx', 11:16:11 INFO - 'cart']}, 11:16:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:16:11 INFO - 'tsvgr_opacity', 11:16:11 INFO - 'tart', 11:16:11 INFO - 'tscrollx', 11:16:11 INFO - 'cart']}, 11:16:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:11 INFO - 'talos_options': ['--disable-e10s'], 11:16:11 INFO - 'tests': ['tp5o']}, 11:16:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:11 INFO - 'tests': ['tp5o']}, 11:16:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:11 INFO - 'talos_options': ['--disable-e10s', 11:16:11 INFO - '--xperf_path', 11:16:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:16:11 INFO - 'tests': ['tp5n']}, 11:16:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:16:11 INFO - 'talos_options': ['--xperf_path', 11:16:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:16:11 INFO - 'tests': ['tp5n']}}, 11:16:11 INFO - 'talos.zip': {'path': '', 11:16:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:16:11 INFO - Running post-action listener: _resource_record_post_action 11:16:11 INFO - ##### 11:16:11 INFO - ##### Running create-virtualenv step. 11:16:11 INFO - ##### 11:16:11 INFO - Running pre-action listener: _resource_record_pre_action 11:16:11 INFO - Running main action method: create_virtualenv 11:16:11 INFO - Creating virtualenv /builds/slave/test/build/venv 11:16:11 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 11:16:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:16:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:16:11 INFO - Using real prefix '/tools/python27' 11:16:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:16:12 INFO - Installing distribute.............................................................................................................................................................................................done. 11:16:15 INFO - Installing pip.................done. 11:16:15 INFO - Return code: 0 11:16:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:15 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 0x106e33a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e43420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5a1464dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2e00>, '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 0x106b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ccdb20>, '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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 11:16:15 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 11:16:15 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 11:16:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jGoE4fmHR/Render', 11:16:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:16:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:16:15 INFO - 'HOME': '/Users/cltbld', 11:16:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:16:15 INFO - 'LOGNAME': 'cltbld', 11:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:15 INFO - 'MOZ_NO_REMOTE': '1', 11:16:15 INFO - 'NO_EM_RESTART': '1', 11:16:15 INFO - 'PAGER': '/bin/cat', 11:16:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:15 INFO - 'PWD': '/builds/slave/test', 11:16:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:16:15 INFO - 'SHELL': '/bin/bash', 11:16:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1xoUdsYmV7/Listeners', 11:16:15 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 11:16:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:16:15 INFO - 'USER': 'cltbld', 11:16:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:16:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:15 INFO - 'XPC_FLAGS': '0x0', 11:16:15 INFO - 'XPC_SERVICE_NAME': '0', 11:16:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:16:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:15 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 11:16:15 INFO - Cleaning up... 11:16:15 INFO - Return code: 0 11:16:15 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:15 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e33a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e43420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5a1464dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2e00>, '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 0x106b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ccdb20>, '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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 11:16:15 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>=0.7.1'] in /builds/slave/test/build 11:16:15 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>=0.7.1 11:16:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jGoE4fmHR/Render', 11:16:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:16:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:16:15 INFO - 'HOME': '/Users/cltbld', 11:16:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:16:15 INFO - 'LOGNAME': 'cltbld', 11:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:15 INFO - 'MOZ_NO_REMOTE': '1', 11:16:15 INFO - 'NO_EM_RESTART': '1', 11:16:15 INFO - 'PAGER': '/bin/cat', 11:16:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:15 INFO - 'PWD': '/builds/slave/test', 11:16:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:16:15 INFO - 'SHELL': '/bin/bash', 11:16:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1xoUdsYmV7/Listeners', 11:16:15 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 11:16:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:16:15 INFO - 'USER': 'cltbld', 11:16:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:16:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:15 INFO - 'XPC_FLAGS': '0x0', 11:16:15 INFO - 'XPC_SERVICE_NAME': '0', 11:16:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:16:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:15 INFO - Downloading/unpacking psutil>=0.7.1 11:16: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 11:16: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 11:16: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 11:16: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 11:16: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 11:16: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 11:16:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:16:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:16:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:16:18 INFO - Installing collected packages: psutil 11:16:18 INFO - Running setup.py install for psutil 11:16:18 INFO - building 'psutil._psutil_osx' extension 11:16:18 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 11:16:19 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 11:16:19 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 11:16:19 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 11:16:19 INFO - building 'psutil._psutil_posix' extension 11:16:19 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 11:16:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:16:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:16:19 INFO - ^ 11:16:19 INFO - 1 warning generated. 11:16:19 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 11:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:16:19 INFO - Successfully installed psutil 11:16:19 INFO - Cleaning up... 11:16:19 INFO - Return code: 0 11:16:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:16: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')]} 11:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16: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')]} 11:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16: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', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e33a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e43420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5a1464dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2e00>, '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 0x106b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ccdb20>, '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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 11:16: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 11:16: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 mozsystemmonitor==0.0.0 11:16:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jGoE4fmHR/Render', 11:16:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:16:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:16:19 INFO - 'HOME': '/Users/cltbld', 11:16:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:16:19 INFO - 'LOGNAME': 'cltbld', 11:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:19 INFO - 'MOZ_NO_REMOTE': '1', 11:16:19 INFO - 'NO_EM_RESTART': '1', 11:16:19 INFO - 'PAGER': '/bin/cat', 11:16:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:19 INFO - 'PWD': '/builds/slave/test', 11:16:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:16:19 INFO - 'SHELL': '/bin/bash', 11:16:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1xoUdsYmV7/Listeners', 11:16:19 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 11:16:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:16:19 INFO - 'USER': 'cltbld', 11:16:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:16:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:19 INFO - 'XPC_FLAGS': '0x0', 11:16:19 INFO - 'XPC_SERVICE_NAME': '0', 11:16:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:19 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:16: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 11:16: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 11:16: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 11:16: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 11:16: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 11:16: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 11:16:22 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:16:22 INFO - Installing collected packages: mozsystemmonitor 11:16:22 INFO - Running setup.py install for mozsystemmonitor 11:16:22 INFO - Successfully installed mozsystemmonitor 11:16:22 INFO - Cleaning up... 11:16:22 INFO - Return code: 0 11:16:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:16:22 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')]} 11:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:22 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')]} 11:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:22 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 0x106e33a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e43420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5a1464dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2e00>, '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 0x106b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ccdb20>, '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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 11:16:22 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 11:16:22 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 11:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jGoE4fmHR/Render', 11:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:16:22 INFO - 'HOME': '/Users/cltbld', 11:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:16:22 INFO - 'LOGNAME': 'cltbld', 11:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:22 INFO - 'MOZ_NO_REMOTE': '1', 11:16:22 INFO - 'NO_EM_RESTART': '1', 11:16:22 INFO - 'PAGER': '/bin/cat', 11:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:22 INFO - 'PWD': '/builds/slave/test', 11:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:16:22 INFO - 'SHELL': '/bin/bash', 11:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1xoUdsYmV7/Listeners', 11:16:22 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 11:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:16:22 INFO - 'USER': 'cltbld', 11:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:22 INFO - 'XPC_FLAGS': '0x0', 11:16:22 INFO - 'XPC_SERVICE_NAME': '0', 11:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:22 INFO - Downloading/unpacking blobuploader==1.2.4 11:16: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 11:16: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 11:16: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 11:16: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 11:16: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 11:16: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 11:16:25 INFO - Downloading blobuploader-1.2.4.tar.gz 11:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:16:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:16:25 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 11:16:25 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 11:16:25 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 11:16:25 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 11:16:25 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 11:16:25 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 11:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:16:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:16:26 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 11:16:26 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 11:16:26 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 11:16:26 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 11:16:26 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 11:16:26 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 11:16:26 INFO - Downloading docopt-0.6.1.tar.gz 11:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:16:26 INFO - Installing collected packages: blobuploader, requests, docopt 11:16:26 INFO - Running setup.py install for blobuploader 11:16:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:16:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:16:26 INFO - Running setup.py install for requests 11:16:27 INFO - Running setup.py install for docopt 11:16:27 INFO - Successfully installed blobuploader requests docopt 11:16:27 INFO - Cleaning up... 11:16:27 INFO - Return code: 0 11:16:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:16:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:27 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 0x106e33a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e43420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5a1464dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2e00>, '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 0x106b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ccdb20>, '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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 11:16:27 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 11:16:27 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 11:16:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jGoE4fmHR/Render', 11:16:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:16:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:16:27 INFO - 'HOME': '/Users/cltbld', 11:16:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:16:27 INFO - 'LOGNAME': 'cltbld', 11:16:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:27 INFO - 'MOZ_NO_REMOTE': '1', 11:16:27 INFO - 'NO_EM_RESTART': '1', 11:16:27 INFO - 'PAGER': '/bin/cat', 11:16:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:16:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:27 INFO - 'PWD': '/builds/slave/test', 11:16:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:16:27 INFO - 'SHELL': '/bin/bash', 11:16:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1xoUdsYmV7/Listeners', 11:16:27 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 11:16:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:16:27 INFO - 'USER': 'cltbld', 11:16:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:16:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:16:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:27 INFO - 'XPC_FLAGS': '0x0', 11:16:27 INFO - 'XPC_SERVICE_NAME': '0', 11:16:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:16:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:16:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-8mo5Ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:16:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-08HK1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:16:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:16:27 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-qEOoK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:16:28 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-LEvphQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:16:28 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-uCsQ_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:16:28 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-vhFLJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:16:28 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-4Yvsla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:16:28 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-oxQk7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:16:28 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-C99t_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:16:28 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-zhEAeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:16:28 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-mSYo0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:16:28 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-5Casup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:16:29 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-kF1pN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:16:29 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-2aEdXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:29 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-MH79Lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:16:29 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-XVyJnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:16:29 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-qCxM6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:16:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:16:29 INFO - Running setup.py install for manifestparser 11:16:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:16:29 INFO - Running setup.py install for mozcrash 11:16:29 INFO - Running setup.py install for mozdebug 11:16:29 INFO - Running setup.py install for mozdevice 11:16:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Running setup.py install for mozfile 11:16:30 INFO - Running setup.py install for mozhttpd 11:16:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Running setup.py install for mozinfo 11:16:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Running setup.py install for mozInstall 11:16:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:16:30 INFO - Running setup.py install for mozleak 11:16:30 INFO - Running setup.py install for mozlog 11:16:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:16:31 INFO - Running setup.py install for moznetwork 11:16:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:16:31 INFO - Running setup.py install for mozprocess 11:16:31 INFO - Running setup.py install for mozprofile 11:16:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:16:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:16:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:16:31 INFO - Running setup.py install for mozrunner 11:16:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:16:31 INFO - Running setup.py install for mozscreenshot 11:16:31 INFO - Running setup.py install for moztest 11:16:31 INFO - Running setup.py install for mozversion 11:16:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:16:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:16:32 INFO - Cleaning up... 11:16:32 INFO - Return code: 0 11:16:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16:32 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')]} 11:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:32 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')]} 11:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:32 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 0x106e33a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e43420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5a1464dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2e00>, '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 0x106b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ccdb20>, '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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 11:16:32 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 11:16:32 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 11:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jGoE4fmHR/Render', 11:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:16:32 INFO - 'HOME': '/Users/cltbld', 11:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:16:32 INFO - 'LOGNAME': 'cltbld', 11:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:32 INFO - 'MOZ_NO_REMOTE': '1', 11:16:32 INFO - 'NO_EM_RESTART': '1', 11:16:32 INFO - 'PAGER': '/bin/cat', 11:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:32 INFO - 'PWD': '/builds/slave/test', 11:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:16:32 INFO - 'SHELL': '/bin/bash', 11:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1xoUdsYmV7/Listeners', 11:16:32 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 11:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:16:32 INFO - 'USER': 'cltbld', 11:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:32 INFO - 'XPC_FLAGS': '0x0', 11:16:32 INFO - 'XPC_SERVICE_NAME': '0', 11:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:16:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-yfy7WK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:16:32 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)) 11:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:16:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-gATvNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:16:32 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)) 11:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:16:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-D69YW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:16:32 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)) 11:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:16:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-iYJZSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:16:32 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)) 11:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:16:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-Njrvbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:16:32 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)) 11:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:16:32 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-QILAa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:16:33 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)) 11:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:16:33 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-6E9mXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:16:33 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)) 11:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:16:33 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-qYI7sx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:16:33 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)) 11:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:16:33 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-K2qclf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:16:33 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)) 11:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:16:33 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-tCGjYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 11:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:16:33 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-zMLrG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:16:33 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)) 11:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:16:33 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-7_Ywxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:16:33 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)) 11:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:16:33 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-Hq72B4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:16:33 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)) 11:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:16:33 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-1VPoRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:16:33 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)) 11:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:33 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-i8qLgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:16:34 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)) 11:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:16:34 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-xVkynC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:16:34 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)) 11:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:16:34 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-IOqPea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:16:34 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)) 11:16:34 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)) 11:16:34 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)) 11:16:34 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)) 11:16:34 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)) 11:16:34 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)) 11:16:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:36 INFO - Downloading blessings-1.6.tar.gz 11:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:16:36 INFO - Installing collected packages: blessings 11:16:36 INFO - Running setup.py install for blessings 11:16:36 INFO - Successfully installed blessings 11:16:36 INFO - Cleaning up... 11:16:37 INFO - Return code: 0 11:16:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:16:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:16:37 INFO - Reading from file tmpfile_stdout 11:16:37 INFO - Current package versions: 11:16:37 INFO - blessings == 1.6 11:16:37 INFO - blobuploader == 1.2.4 11:16:37 INFO - docopt == 0.6.1 11:16:37 INFO - manifestparser == 1.1 11:16:37 INFO - mozInstall == 1.12 11:16:37 INFO - mozcrash == 0.17 11:16:37 INFO - mozdebug == 0.1 11:16:37 INFO - mozdevice == 0.48 11:16:37 INFO - mozfile == 1.2 11:16:37 INFO - mozhttpd == 0.7 11:16:37 INFO - mozinfo == 0.9 11:16:37 INFO - mozleak == 0.1 11:16:37 INFO - mozlog == 3.1 11:16:37 INFO - moznetwork == 0.27 11:16:37 INFO - mozprocess == 0.22 11:16:37 INFO - mozprofile == 0.28 11:16:37 INFO - mozrunner == 6.11 11:16:37 INFO - mozscreenshot == 0.1 11:16:37 INFO - mozsystemmonitor == 0.0 11:16:37 INFO - moztest == 0.7 11:16:37 INFO - mozversion == 1.4 11:16:37 INFO - psutil == 3.1.1 11:16:37 INFO - requests == 1.2.3 11:16:37 INFO - wsgiref == 0.1.2 11:16:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:16:37 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')]} 11:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:37 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')]} 11:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:37 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 0x106e33a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e43420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5a1464dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2e00>, '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 0x106b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ccdb20>, '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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 11:16:37 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 11:16:37 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 11:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jGoE4fmHR/Render', 11:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:16:37 INFO - 'HOME': '/Users/cltbld', 11:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:16:37 INFO - 'LOGNAME': 'cltbld', 11:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:37 INFO - 'MOZ_NO_REMOTE': '1', 11:16:37 INFO - 'NO_EM_RESTART': '1', 11:16:37 INFO - 'PAGER': '/bin/cat', 11:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:37 INFO - 'PWD': '/builds/slave/test', 11:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:16:37 INFO - 'SHELL': '/bin/bash', 11:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1xoUdsYmV7/Listeners', 11:16:37 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 11:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:16:37 INFO - 'USER': 'cltbld', 11:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:37 INFO - 'XPC_FLAGS': '0x0', 11:16:37 INFO - 'XPC_SERVICE_NAME': '0', 11:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:37 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)) 11:16:37 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)) 11:16:37 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)) 11:16:37 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)) 11:16:37 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)) 11:16:37 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)) 11:16:37 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)) 11:16:37 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)) 11:16:37 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)) 11:16:37 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)) 11:16:37 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)) 11:16:37 INFO - Cleaning up... 11:16:37 INFO - Return code: 0 11:16:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:16:37 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')]} 11:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:37 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')]} 11:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:37 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 0x106e33a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e43420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5a1464dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2e00>, '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 0x106b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ccdb20>, '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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 11:16:37 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 11:16:37 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 11:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jGoE4fmHR/Render', 11:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:16:37 INFO - 'HOME': '/Users/cltbld', 11:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:16:37 INFO - 'LOGNAME': 'cltbld', 11:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:37 INFO - 'MOZ_NO_REMOTE': '1', 11:16:37 INFO - 'NO_EM_RESTART': '1', 11:16:37 INFO - 'PAGER': '/bin/cat', 11:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:37 INFO - 'PWD': '/builds/slave/test', 11:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:16:37 INFO - 'SHELL': '/bin/bash', 11:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1xoUdsYmV7/Listeners', 11:16:37 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 11:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:16:37 INFO - 'USER': 'cltbld', 11:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:37 INFO - 'XPC_FLAGS': '0x0', 11:16:37 INFO - 'XPC_SERVICE_NAME': '0', 11:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:37 INFO - Downloading/unpacking jsonschema 11:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:16:40 INFO - Downloading/unpacking functools32 (from jsonschema) 11:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:40 INFO - Downloading functools32-3.2.3-2.tar.gz 11:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:16:40 INFO - warning: no files found matching '*.txt' 11:16:40 INFO - no previously-included directories found matching 'build' 11:16:40 INFO - no previously-included directories found matching 'dist' 11:16:40 INFO - no previously-included directories found matching '.git*' 11:16:40 INFO - Installing collected packages: jsonschema, functools32 11:16:40 INFO - Running setup.py install for functools32 11:16:40 INFO - warning: no files found matching '*.txt' 11:16:40 INFO - no previously-included directories found matching 'build' 11:16:40 INFO - no previously-included directories found matching 'dist' 11:16:40 INFO - no previously-included directories found matching '.git*' 11:16:40 INFO - Successfully installed jsonschema functools32 11:16:40 INFO - Cleaning up... 11:16:41 INFO - Return code: 0 11:16:41 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:16:41 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')]} 11:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:41 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')]} 11:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:16:41 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 0x106e33a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e43420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5a1464dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2e00>, '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 0x106b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ccdb20>, '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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 11:16:41 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 11:16:41 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 11:16:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jGoE4fmHR/Render', 11:16:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:16:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:16:41 INFO - 'HOME': '/Users/cltbld', 11:16:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:16:41 INFO - 'LOGNAME': 'cltbld', 11:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:41 INFO - 'MOZ_NO_REMOTE': '1', 11:16:41 INFO - 'NO_EM_RESTART': '1', 11:16:41 INFO - 'PAGER': '/bin/cat', 11:16:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:41 INFO - 'PWD': '/builds/slave/test', 11:16:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:16:41 INFO - 'SHELL': '/bin/bash', 11:16:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1xoUdsYmV7/Listeners', 11:16:41 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 11:16:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:16:41 INFO - 'USER': 'cltbld', 11:16:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:16:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:41 INFO - 'XPC_FLAGS': '0x0', 11:16:41 INFO - 'XPC_SERVICE_NAME': '0', 11:16:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:16:41 INFO - Downloading/unpacking flake8 11:16:41 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 11:16:41 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 11:16:41 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 11:16:41 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 11:16:41 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 11:16:41 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 11:16:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:16:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:16:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:16:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:16:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:16:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:16:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:16:45 INFO - no previously-included directories found matching 'docs/_build' 11:16:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:16:45 INFO - Running setup.py install for pep8 11:16:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:16:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:16:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:16:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:16:45 INFO - no previously-included directories found matching 'docs/_build' 11:16:45 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:16:45 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:16:45 INFO - Cleaning up... 11:16:45 INFO - Return code: 0 11:16:45 INFO - Running post-action listener: _resource_record_post_action 11:16:45 INFO - Running post-action listener: _start_resource_monitoring 11:16:45 INFO - Starting resource monitoring. 11:16:45 INFO - ##### 11:16:45 INFO - ##### Running install step. 11:16:45 INFO - ##### 11:16:45 INFO - Running pre-action listener: _resource_record_pre_action 11:16:45 INFO - Running main action method: install 11:16:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:16:45 INFO - Reading from file tmpfile_stdout 11:16:45 INFO - Detecting whether we're running mozinstall >=1.0... 11:16:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:16:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:16:46 INFO - Reading from file tmpfile_stdout 11:16:46 INFO - Output received: 11:16:46 INFO - Usage: mozinstall [options] installer 11:16:46 INFO - Options: 11:16:46 INFO - -h, --help show this help message and exit 11:16:46 INFO - -d DEST, --destination=DEST 11:16:46 INFO - Directory to install application into. [default: 11:16:46 INFO - "/builds/slave/test"] 11:16:46 INFO - --app=APP Application being installed. [default: firefox] 11:16:46 INFO - mkdir: /builds/slave/test/build/application 11:16:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 11:17:17 INFO - Reading from file tmpfile_stdout 11:17:17 INFO - Output received: 11:17:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:17:17 INFO - Running post-action listener: _resource_record_post_action 11:17:17 INFO - ##### 11:17:17 INFO - ##### Running run-tests step. 11:17:17 INFO - ##### 11:17:17 INFO - Running pre-action listener: _resource_record_pre_action 11:17:17 INFO - Running main action method: run_tests 11:17:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:17:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:17:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:17:17 INFO - 2016-05-27 11:17:17.503 screenresolution[1652:8173] starting screenresolution argv=screenresolution get 11:17:17 INFO - 2016-05-27 11:17:17.506 screenresolution[1652:8173] Display 0: 1600x1200x32@60 11:17:17 INFO - 2016-05-27 11:17:17.512 screenresolution[1653:8175] starting screenresolution argv=screenresolution list 11:17:17 INFO - Available Modes on Display 0 11:17:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:17:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:17:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:17:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:17:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:17:17 INFO - Intel Iris: 11:17:17 INFO - Chipset Model: Intel Iris 11:17:17 INFO - Type: GPU 11:17:17 INFO - Bus: Built-In 11:17:17 INFO - VRAM (Dynamic, Max): 1536 MB 11:17:17 INFO - Vendor: Intel (0x8086) 11:17:17 INFO - Device ID: 0x0a2e 11:17:17 INFO - Revision ID: 0x0009 11:17:17 INFO - Displays: 11:17:17 INFO - Display: 11:17:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:17:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:17:17 INFO - Display Serial Number: 1600x1200 60 11:17:17 INFO - Main Display: Yes 11:17:17 INFO - Mirror: Off 11:17:17 INFO - Online: Yes 11:17:17 INFO - Rotation: Supported 11:17:17 INFO - Return code: 0 11:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:17:17 INFO - Python 2.7.3 11:17:17 INFO - Return code: 0 11:17:17 INFO - grabbing minidump binary from tooltool 11:17:17 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')]} 11:17:17 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2e00>, '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 0x106b3bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ccdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:17:17 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 11:17:17 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 11:17:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:17:17 INFO - Return code: 0 11:17:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:17:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:17:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:17:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:17:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:17:18 INFO - Return code: 0 11:17:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:17:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0076 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:17:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7jGoE4fmHR/Render', 11:17:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:18 INFO - 'HOME': '/Users/cltbld', 11:17:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:18 INFO - 'LOGNAME': 'cltbld', 11:17:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:17:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:18 INFO - 'MOZ_NO_REMOTE': '1', 11:17:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:17:18 INFO - 'NO_EM_RESTART': '1', 11:17:18 INFO - 'PAGER': '/bin/cat', 11:17:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:18 INFO - 'PWD': '/builds/slave/test', 11:17:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:17:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:18 INFO - 'SHELL': '/bin/bash', 11:17:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1xoUdsYmV7/Listeners', 11:17:18 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 11:17:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:18 INFO - 'USER': 'cltbld', 11:17:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:18 INFO - 'XPC_FLAGS': '0x0', 11:17:18 INFO - 'XPC_SERVICE_NAME': '0', 11:17:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:17:19 INFO - mozversion application_buildid: 20160527093441 11:17:19 INFO - mozversion application_changeset: 9d87d3a279a08ac507dda94c36d5a12185fea4a6 11:17:19 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:17:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:17:19 INFO - mozversion application_name: Firefox 11:17:19 INFO - mozversion application_remotingname: firefox-dev 11:17:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:17:19 INFO - mozversion application_vendor: Mozilla 11:17:19 INFO - mozversion application_version: 48.0a2 11:17:19 INFO - mozversion platform_buildid: 20160527093441 11:17:19 INFO - mozversion platform_changeset: 9d87d3a279a08ac507dda94c36d5a12185fea4a6 11:17:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:17:19 INFO - mozversion platform_version: 48.0a2 11:17:19 INFO - using testdate: 1464373039 11:17:19 INFO - actual date: 1464373039 11:17:19 INFO - starting webserver on 'localhost:49277' 11:17:19 INFO - SUITE-START | Running 2 tests 11:17:19 INFO - TEST-START | dromaeo_css 11:17:19 INFO - operating with platform_type : mac_ 11:17:19 INFO - Initialising browser for dromaeo_css test... 11:17:19 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpU1uF56/profile http://localhost:49277/getInfo.html) 11:17:22 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 11:17:22 INFO - PROCESS | 1671 | colorDepth:24 11:17:22 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 11:17:22 INFO - PROCESS | 1671 | __metrics 11:17:22 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 11:17:22 INFO - TEST-INFO | 1671: exit 0 11:17:22 INFO - Browser initialized. 11:17:22 INFO - Running cycle 1/1 for dromaeo_css test... 11:17:22 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpU1uF56/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:17:34 INFO - PROCESS | 1675 | RSS: Main: 220487680 11:17:34 INFO - PROCESS | 1675 | 11:18:24 INFO - PROCESS | 1675 | 1464373104395 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] 11:20:34 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 11:20:36 INFO - PROCESS | 1675 | RSS: Main: 231112704 11:20:36 INFO - PROCESS | 1675 | 11:23:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 11:23:39 INFO - PROCESS | 1675 | RSS: Main: 242827264 11:23:39 INFO - PROCESS | 1675 | 11:26:40 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 11:26:41 INFO - PROCESS | 1675 | RSS: Main: 244793344 11:26:41 INFO - PROCESS | 1675 | 11:29:43 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 11:29:43 INFO - PROCESS | 1675 | RSS: Main: 247250944 11:29:43 INFO - PROCESS | 1675 | 11:32:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 11:32:47 INFO - PROCESS | 1675 | RSS: Main: 249577472 11:32:47 INFO - PROCESS | 1675 | 11:35:49 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 11:35:49 INFO - PROCESS | 1675 | RSS: Main: 251080704 11:35:49 INFO - PROCESS | 1675 | 11:35:49 INFO - PROCESS | 1675 | __start_tp_report 11:35:49 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 11:35:49 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 11:35:49 INFO - PROCESS | 1675 | |i|pagename|runs| 11:35:49 INFO - PROCESS | 1675 | |0;dojo.html;2977.761779057675;3270.0680306112763;3390;3396.439587468068;3443.741719371046;5545.805896793613;5571.804986825462;5579.804706835262;5588.916166257499;5679.687617181054;4516.125807789772;5392.865178370543;5607.831765047055;5620.100783874571;5655.1517272409155;4968.677035992649;5124.359455068116;5187.974060129713;5196.714180720059;5236.869078273055;4878.975605121969;5052.469490703478;5201.973990130044;5282.2076688496645;5337.2794672719265;5172.146595811687;5190.636655434121;5194.68831870089;5210.16637338026;5212.244224587433;4741.577526741999;4965.553100220961;5147.871303217412;5183.403908550489;5189.6107791915565;5025.7235852028125;5070.518300761421;5113.846584602468;5136.922946155811;5212.218167274901;5570.749316280777;5581.358143813432;5588.0779671354585;5591.944080559183;5610.270664813589;5966.612170208966;6056.727447264883;6071.332153463116;6097.115918191882;6105.084237364431;5139.254808052849;6024.397560243984;6094.481969032638;6112.113743507211;6117.27937334216;5691.715414229272;5708.315002199721;5709.257796486471;5713.228714123582;5725.59920805548;6105.389461053859;6197.225346831646;6210.968945155245;6227.19046523949;6246.125542424064;5990.760369585255;6043.153958445821;6056.454919057306;6092.116643086728;6107.541934354852;6134;6135.723892424762;6141.140240366352;6142.232220972379;6163.753449862045;5962.642241465439;6013.789517366871;6037.366076561985;6050.818475445743;6058.242719660043;6117.042466735707;6122.154209354628;6125.785597504066;6126.336365086956;6133.457080270857;4790.688605240648;4798.5441383068555;4828.46886842447;4834.661573689878;4863.927041094386;4296.505901821268;4314.201872653569;4331.480222373335;4366.126774645084;4393.758343291086;4226.535081141072;4856.975715121473;4902.656814022984;5006.773340531593;5682.715864206773;6040.154378387029;6104.4505994460715;6125.877482450415;6145.938540614537;6151.507879369639;5467.261919640877;5586.078297080936;5648.943510564924;5656.547476201977;5694.487496125369;4540.205464043779;4708.223143181405;5267.52343453319;5290.153575427913;5290.470952904756;6202.906956395657;6206.4724498416335;6224.315325314212;6226.999999999909;6240.31356550779;6030.216071910623;6115.082737589397;6171.56799024064;6175.938240617536;6199.225096862892;6209.844753881009;6239.719212635533;6260.467860231931;6263.279722831932;6270.561060725888;4686.648501362343;5699.943000569941;5914.142449344735;6089.48239399656;6104.11490333908;6108.664023478751;6109.495762817132;6162.568620196544;6167.167432396569;6170.043643235305;4820.301056346881;6011.849703757441;6039.063945088518;6046.12331211963;6071.5446341523675;5729;5950.018246989372;6025.66858822769;6025.849353766191;6039.486643635164;5717.456841600041;5801.912971305349;5829;5839.708014599338;5849.210356601974;5921.792737254175;5994.07091900756;6017.06735456005;6049.820285044374;6069.544784141119;5676.17695434168;5719.370869204301;5796.304443466811;5865.472107510344;5892.528597712087 11:35:49 INFO - PROCESS | 1675 | |1;ext.html;514.1054565056802;547.0481362429372;555.7749111609794;591.1369400675014;594.4174708785391;29839.552406713916;39022;39093.022674433116;39106.21787564247;39229.01927451815;33139.834300828465;40456.78629641108;41041.97395065125;41095.58904410963;41163.970900727494;9421.811563768737;9631.277654175965;9662.806743865118;9669;9693.757656058584;9602.855957160647;9749.025097490265;9764.609415623367;9786.510674466284;9817.754556136082;8869.95565022174;9780.510974451285;9786.608535658565;9791.559379827924;9817.410955342666;9332.206762425201;9798.853017204749;9806.16647584949;9812.165965892907;9817.803643927153;9603.37578057431;9713.854292185622;9724.805503889882;9738.707838764849;9743.610255589769;33652;39243.41134882979;39444.40833387501;39646.40530392047;39727.8013609933;2795.2313113893842;3181.681831816823;3267.036224313833;3295.1597342677705;3308.4541050726602;27253.727462725317;27446;27626.861865690542;29088.8545557273;30482.780688772422;2740.369714965549;2783.4293969736154;2788.6653601567737;2791.916242512728;2855.52883774177;356826;357230;360704;361290.9999999974;362469;58615.70692146512;58953.70523147357;59363.70318148468;59389.70305148447;59514;64504.35495645077;69470.30529694639;70964.29035709577;71098.57802844017;71439.64280178565;16884.91557542229;17873;17986.370477033503;18027.909860450876;18030.459086227173;12107.697307567205;13235.669108272177;13625;13710.725785484234;13711.794323085162;16238.837611623969;19539.0230488475;19715;19878.403647890587;20179.899100504404;8214.63034163464;8283.25450709439;8372.665093396317;8409.495430274203;8440.957795210985;941.4539567051094;995.9004099589984;1074.908632766208;1121.3552207480732;1125.392288164382;28732.712672873422;30428.087157385315;30726.84636576803;30734.16278848924;30923.8453807734;1087.1302957633861;1111.372074777748;1113.8440618313443;1118.6084870295495;1155.399192419937;14094.788578171336;14490.840732741355;15670.608234794221;15866.761998570031;16007.67984640324;741778;745249.2737536278;752299;753921;757577;56760.43239567634;57631.423685763446;58351.5412114692;58596.414035859096;58644;1528.8700460460764;1530.051368151753;1544.9304781284868;1545;1552.301464341051;28341.71658283473;29595.40809183847;29744;30198.396032079676;30681;52333.69165770886;52850;52890.206646899554;52959.73520132375;53044.73477632587;568370.1581492067;583838;589424;589734.0513297578;590285.0485747715;27470.450590987668;27585.448291034467;27613.171604851887;27745.722542774314;27894.44211115807;39790.40314395229;45697.54302457066;45844.7707761459;47620.2856957139;48016.5198348026;30378.696213037587;35975.460368095024;36531;36788.63211367959;36957.07607309839;31944.36111277715;34704.826475867456;36021;36708.44937325996;36737.08157295982;35403.468947966314;35986;35988.74039408608;36096.91709248727;36356.09109772277;27724.445511089263;29459.55810662799;30253.697463025088;33466.33067338688;33553.882273523974;23645.64531531994;23854.642180366962;23926.282211533682;24040.87979560091;24128.396790080387 11:35:49 INFO - PROCESS | 1675 | |2;jquery.html;404.81580880699283;405.2381522737254;405.53971242639614;406.16330359459533;417.3801904172304;20070.799292007076;20787.792122078794;20812.479688007807;20825.479363015933;22295.108195672194;21213.469663258427;21469.463263418384;21650.675239871416;21680.891595542038;22011;5889.293284805819;5956.16613674086;6090.4214099660685;6200.534959878005;6209.068639704035;6071.483923866476;6148;6172.691365431733;6186.84532886677;6200.410960958701;5746.454086861741;6065.878682426349;6078.483328917047;6080.665563394034;6147.293061297941;5920.171176035337;6000.18997435345;6061.333253342129;6815.2843951385385;6817.147856517935;6053.303870054956;6061.878762424727;6065.696715164268;6076.756929722806;6084.26988761347;13591.592252232449;15099.849001509954;24371.390715232083;25791.226263212135;26570.6714664266;2223.9332820015425;2320.9651855222187;2327.93016209514;2670.319068637485;2760.213339198339;9362.90637093634;9895.752606184831;9899.307048506607;9926.801463970753;9927.652532161339;1718.3298513579714;1728.7493313469477;1806.9638607227914;1809.0412081596776;1812.809654986234;74809.6259518699;95540;115229.42385288104;117747;121318.69226363867;11797.29216247028;11930.673518663778;11973.341466219441;12032.578859739868;12149.878501215051;15107.697846043018;15372;15388.615284617974;15442.305096270695;15454.845451545565;8534.914650853536;8811.779705507286;8948.239399650973;8973.820523589491;9057.731917531552;9018.458892466473;9042.909570904207;9044.8643270351;9104.81790364189;9132.543372831466;4719.315699223594;4747;4834.709917404967;4836.250381190921;4863.197572400514;1990.3829812758092;2063.6285468615793;2067.9689804652944;2100.6218880601637;2103.642380795238;749.4903465643415;849.6431498770531;850.8808766772656;911.3028533172129;1075.1882328841675;20559.38321850347;20910.895445522674;20922.476938076365;20971.37085887426;21299.254526091514;988.584794386345;1001.1990407673977;1003.7340104872214;1013.6452241715341;1020.9183265338755;8782.736517904474;11267.15496337778;11507.309561426342;11525.827112593317;11536.538538458368;239061;433977;466473;631641;632665;122856;124617;124809;127211.36394318154;130563;1018.9796204075877;1025.958961641541;1045.0385645206277;1048.558232430393;1048.7849990751877;17996.10019499046;18030.38166719927;18173.273069077088;18180.545486362418;18440.90779546094;50859.98280034453;51224.23163652623;51813.74093129661;52044.69888252824;52321.7383913078;72060.63969680118;74234;75165.06145134053;76141.4771704574;76464.85302720575;7886.44794864354;8294;8302.70940517079;8375.37184711137;8643.17889800468;10283.90979235579;12235.510579576718;12303.93848030754;12591.118621696392;12679.93660031694;11032.676078870587;11047.060999814785;11089.772687593944;11130.053945414407;11140.832887506533;10755.731106722395;10939.781204376028;10994.010539051524;11024.283421577571;11114.499847507042;11265.493052812806;11334.886651133384;11445.599404020819;11645.475953582278;11693.649190524298;8298.751037468886;8386.28716559075;8441.198086181803;8495.150484951702;8574.72538999868;10409.42748148859;11204.551817927191;11384.089272858317;11466.541338346373;11473.311601303949 11:35:49 INFO - PROCESS | 1675 | |3;mootools.html;1581.517637120678;1596.513063515628;1845.1881172284193;1919.481739930219;1994.88030718157;7900.130985591581;8563.443376180543;8716.389852710305;8901.777455563613;8945.776355591106;8464.238218560344;8503.744887653365;8594.097619749915;8615.741527754162;8646.394752367336;2189.0915270162864;2335.848169868953;2341.5316936612744;2442.9144979925663;2449.5223431430877;2228.487447886987;2406.1698713943747;2451.742567030463;2458.2379462366725;2460.4463995601027;2424.866632335221;2443.218170185541;2451.0685939343025;2465.9136930207446;2466.235467005234;2160.8655455885705;2405.1461731085474;2428.9392765180837;2457.7665121813397;2458.9508209835794;2427.07771047001;2462.372095115734;2463;2466.1121996081397;2475.9133430329853;6833.350831670983;8434.74695759122;8536.74389768308;8757.912420875837;8783.472991620523;4536.795844186987;5226.033183860998;5336.519713225828;5451.127819548893;5487.6158668893195;3134.905952821419;3512.5960514540643;3580.785152890835;3641.998450426154;3642.7814331140216;981.5190556627273;1012.792377562598;1050.2333296693457;1071.919606029551;1076.8815430302661;10380.377177369306;11760.353180575064;11857.466414011391;11865.88134118665;11886.465109070112;4761.928451676738;4810.254410566298;4829.299751536078;4881.8535443936735;4898.975505122452;10160.898391015995;10164.4917754112;10187.949060254652;10234.48827558619;10264.973502649824;4628.259478483494;5424.321959755031;5427.538659213932;5453.590980676465;5463.754131064112;3675.834587443518;4842.903141937141;4966.925496117561;4967.552920237196;4978;4084.0606660468256;4147.138474452679;4155.355919832431;4181.770002649822;4185.330347144442;1211.7576484702918;1370.1162750026178;1375.2573610250554;1380.1650001748797;1386.5424409944897;828.3994104274353;912.4023764434173;933.9066093390607;935.864299676543;942.4204114469552;5371.677699337974;5848.64908105515;6015.187949626832;6017.518598512109;6047.7278522466595;944.5560586524323;951.5860600638745;968.2883080935507;990.5636826601457;1014.3052009373603;3785.4511095890944;3923.7645741255615;3928.194720082376;3954.980225098856;3966.206758648224;9496.76542049529;9788.510574471247;10406.47967601632;10799.83800242997;10849.186311026704;10296.07335339823;10603.363798172135;10642.733931651615;10777.78444431107;10830.51262693165;7152.749653762094;8420.663173473115;8435.915640843514;8449.57752112392;8470.110638383005;5772.2207501986695;5840.8247752567495;5850.7074646266665;5901.3213480450295;5956.851078723066;9595;9710.951445242728;9727.610895564381;9742.561584728563;9780.364276322302;9800.36297640651;10015.749606259902;10063.597456101967;10104.393736375841;10178.694639160836;6377.489800816016;6811;7035.296470352924;7074.469414793808;7190.4966652335925;8210.548419836967;8324.791880203044;8333.708320208763;8360.205780450848;8364.581770911553;6406.423421892052;6850.280720524273;7043.802553566009;7270.890936635848;7426.220246874216;6240.563160578716;7222.349988501061;7238.0952380952385;7354.889676654748;7364.226756190523;6795.966020169867;6984.371406573433;6986.790396288119;6992.545484543489;7002.7899163025195;4906.656534042726;4949.257611358325;4957.429895561912;5009.248612708123;5050.444451110301;4975.179095449211;4975.601951843916;5039.0929632666475;5054;5190 11:35:49 INFO - PROCESS | 1675 | |4;prototype.html;309.9132242971968;328.2990814610805;332.25740470049436;334.84429740170816;338.16979315779764;6804.8979265311;6888.75889343873;6950.443964482842;6978.023076769244;6997.720091196359;6803.149606299213;6817.147856517935;6824.795256142312;6837.17953845538;6854.96572517138;4838.129136755391;4885.780139893714;4986.725730084844;5014.272930425104;5027.874303142414;4953.1579631462555;4990.500949904998;4991.301217829507;4995.875103122415;4997.800087996494;4941.184704523767;4941.555260026596;4948.5298896604945;4964.702117872939;4984.252362145671;4959.404871415417;4963.875903102416;4966.130927087764;4993.251012348141;5016.673916195436;4279.293916503773;4281.207976524322;4288.44250247469;4295;4306.289462238727;5303.310569625963;6351.587146835441;6536.11762412073;6553.574017688835;6593.571417857824;5888.352281249102;5912.46787789101;5923.614965027259;5951.285845698544;5959.0763431668165;5911.852203694899;7219.675114619855;7245.746398876066;7261.963690181568;7271.163816161156;4780.784864681098;4790.592799612037;4790.760461976887;4797.0645724083815;4821.831235906761;6611.966940165316;7665.8850117248285;7694.269044440769;7713.575753333621;7738.574378409186;4530.320451932236;4684.039771846763;4704.647151463654;4717.693349932243;4718.31584420264;4777.737224452619;4833.154198015403;4836.032793441326;4839.080574690868;4847.369841920598;5685.516731077904;5819.243498345188;5835.854103647443;5841.503472204825;5847.853803654943;5311.654742441729;6256.280527739368;7353.374963128087;7364;7370;1638.0908595729484;1672.3059930128861;1677.5973766296245;1680.2942764039133;1684.5872761173591;1021.6117875207374;1058.9364638121738;1061.7080302916759;1066.7173199102244;1078.7141407527013;5431.348238211361;6597.010448432773;6799;6809.693563789641;6811.557248778813;7336.156342020629;7579.924200758032;7599.506032107895;7610.124835643861;7634.732784352521;6723.226828914738;6827.52207345491;6854.965725171442;6893.655317234118;6917.169939607179;3495.8987918805497;3917.5494818095713;4041.7170798044444;4079.8164082616354;4090.2433049885867;8031.678732850621;8048.275655191061;8053.59732013397;8061.435699500978;8063.919360806434;7550.282723141293;7857.882131768028;7874.133845277015;7879.566623835744;7900.2889739923685;7946.920530794734;7981;8008.919910800934;8032.799180020546;8059.5164290142775;7713.845723085395;7713.884291735741;7715.57564333967;7729.381649467917;7762.7671169865;7499.925000749922;7519.135299440634;7536.246375362639;7544.698212071456;7560.168381477923;7695.576743279174;8059.597020149086;8064.798380040311;8064.9596752018215;8091;4712.08114417699;4731.763411829464;4762.618990480684;4777.95222047775;4786.593139583035;6628.801135965929;7650.73222437212;7655.61721913891;7669.622417050732;7704.229577042251;5960.940390596039;6497.415232629086;6536.150300460909;7398.3341499265325;7418.666160022704;7078.806518613181;7278.745243916437;7285.271472852673;7320.304571065741;7357.19070902211;5800.332961709458;7176.246494118042;7216.278372162742;7221.097362829646;7234.457415693949;5599.776008959596;6779.711854747779;7547.622618869144;7558.357539609195;7593.734219302298;6952.339527744856;7203.315685009916;7215.67529461165;7233.421326293778;7251.709931602677 11:35:49 INFO - PROCESS | 1675 | |5;yui.html;631.1321932343028;695.2144077192772;706.1667232665453;764.8011517005584;785.4501848705905;14780.778288325684;14819.555413337617;14919.477818276362;14978.176200308979;14987.250637468123;13646.863531364683;13668.658283542916;13716.93141534291;13730.038897277194;15625.984311019776;781.6052893288881;782.4835608498378;952.4951775558965;953.8235426446095;958.6357184269967;797.6849144587884;840.5125027484075;928.3084102343763;932.4964519159646;944.4663764972803;956.8086382723483;980.2011360740973;995.1292618958412;996.3424140067557;998.9450580218121;862.0948004595182;961.1061712607273;961.3078583419926;970.4807927758686;1001.7946321004213;711.4379640084327;732.9157146928064;736.3446532586003;747.1781040855016;747.3946103656056;12939.48242070316;15180.316885740058;15280.770788438182;15287.159206243652;15338.846611533854;10673.466326683712;12388.442520086617;12430.627081187578;12439;12731.172473789175;10069.54687039085;10121.645742399054;10235.385876847338;10260.538275777608;10479.899610540935;3440.535527703753;3452.879149229765;3462.221000274943;3570.7143428525656;3711.2948539777626;85753.57123214469;85764.57117714372;86407;86500.70248946271;87223;9141.103685240123;9412;9428.62285508572;9437.292203084797;9659.227261819038;21394.465138371666;21415.892920535298;21468.033938472807;21580.920953952238;21983.450413739785;7314.597697126709;7322.1579518355;7369.042024536776;7464.850702986018;7497.1753107158165;4004.5394779600138;4015.7992100394863;4067.023914260556;4145.626893579592;4195.328747400401;3154.8264845433264;3613.5663720353728;3695.3533131701843;3695.667389934865;3702.648248416396;644.2076246217179;656.6913550631195;661.9205695316593;664.880321542127;667.6227931218846;508.84225889974226;568.0938902450584;592.736232376597;604.0848115105531;622.9937700623026;7537.886931696029;7913.248241417056;8340.29107525855;8433.072362040171;8451.830963380698;502.57281310886185;580.9157672137601;597.489146779506;619.44250174843;622.3030206169119;3342.5320455136302;3754.6057663944894;3881.650651441327;3902.23906338267;4077.2660921033907;83825.58087209647;84129;91738.08261917428;95201.57197642041;95308.52345738227;69045.65477172582;69618.3038169622;70012;70165;74075.25924740791;66343.6731265372;66852;67324;67624;67915.66042169856;8478.32173426112;8590.957045214735;8669.393142479967;8706.782330441789;8720.088648411034;47015.05969880515;49124;49378.75310623424;49745;50787;53927.730361347945;63335.6833215831;65070;66661.0000849978;67314;10898.782024359627;11049.72375690582;11217.326960382403;11257.605983790529;11341.546338146381;9772.56023478931;11778.293302401884;12068.939655301667;12111.394430278275;12281.570145045238;8428.410011299151;9275.953620232125;10169.389836609827;10615.31000484966;11412.82880756806;11169.329840209613;11288.492017859051;11625.76748465009;11692.47383867711;11706.297622142698;9554.713358599252;10838.241323107306;11185.38480383554;11220.495077721358;11520.366379848854;9542.475163865776;9763.560639771085;9785.21718262523;9787.608495660093;9811.411315321104;9544.904550954401;9874.950625246827;9890.752731181778;9897.554610042422;9984.950075249577 11:35:49 INFO - PROCESS | 1675 | __end_tp_report 11:35:49 INFO - PROCESS | 1675 | __start_cc_report 11:35:49 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,425 11:35:49 INFO - PROCESS | 1675 | __end_cc_report 11:35:49 INFO - PROCESS | 1675 | __startTimestamp1464374149402__endTimestamp 11:35:49 INFO - PROCESS | 1675 | 11:35:49 INFO - PROCESS | 1675 | ------- Summary: start ------- 11:35:49 INFO - PROCESS | 1675 | Number of tests: 6 11:35:49 INFO - PROCESS | 1675 | 11:35:49 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5586.34 Median:5729.00 stddev:664.34 (11.6%) stddev-sans-first:634.08 11:35:49 INFO - PROCESS | 1675 | Values: 2977.8 3270.1 3390.0 3396.4 3443.7 5545.8 5571.8 5579.8 5588.9 5679.7 4516.1 5392.9 5607.8 5620.1 5655.2 4968.7 5124.4 5188.0 5196.7 5236.9 4879.0 5052.5 5202.0 5282.2 5337.3 5172.1 5190.6 5194.7 5210.2 5212.2 4741.6 4965.6 5147.9 5183.4 5189.6 5025.7 5070.5 5113.8 5136.9 5212.2 5570.7 5581.4 5588.1 5591.9 5610.3 5966.6 6056.7 6071.3 6097.1 6105.1 5139.3 6024.4 6094.5 6112.1 6117.3 5691.7 5708.3 5709.3 5713.2 5725.6 6105.4 6197.2 6211.0 6227.2 6246.1 5990.8 6043.2 6056.5 6092.1 6107.5 6134.0 6135.7 6141.1 6142.2 6163.8 5962.6 6013.8 6037.4 6050.8 6058.2 6117.0 6122.2 6125.8 6126.3 6133.5 4790.7 4798.5 4828.5 4834.7 4863.9 4296.5 4314.2 4331.5 4366.1 4393.8 4226.5 4857.0 4902.7 5006.8 5682.7 6040.2 6104.5 6125.9 6145.9 6151.5 5467.3 5586.1 5648.9 5656.5 5694.5 4540.2 4708.2 5267.5 5290.2 5290.5 6202.9 6206.5 6224.3 6227.0 6240.3 6030.2 6115.1 6171.6 6175.9 6199.2 6209.8 6239.7 6260.5 6263.3 6270.6 4686.6 5699.9 5914.1 6089.5 6104.1 6108.7 6109.5 6162.6 6167.2 6170.0 4820.3 6011.8 6039.1 6046.1 6071.5 5729.0 5950.0 6025.7 6025.8 6039.5 5717.5 5801.9 5829.0 5839.7 5849.2 5921.8 5994.1 6017.1 6049.8 6069.5 5676.2 5719.4 5796.3 5865.5 5892.5 11:35:49 INFO - PROCESS | 1675 | 11:35:49 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:69549.32 Median:36443.55 stddev:157751.11 (432.9%) stddev-sans-first:158108.49 11:35:49 INFO - PROCESS | 1675 | Values: 514.1 547.0 555.8 591.1 594.4 29839.6 39022.0 39093.0 39106.2 39229.0 33139.8 40456.8 41042.0 41095.6 41164.0 9421.8 9631.3 9662.8 9669.0 9693.8 9602.9 9749.0 9764.6 9786.5 9817.8 8870.0 9780.5 9786.6 9791.6 9817.4 9332.2 9798.9 9806.2 9812.2 9817.8 9603.4 9713.9 9724.8 9738.7 9743.6 33652.0 39243.4 39444.4 39646.4 39727.8 2795.2 3181.7 3267.0 3295.2 3308.5 27253.7 27446.0 27626.9 29088.9 30482.8 2740.4 2783.4 2788.7 2791.9 2855.5 356826.0 357230.0 360704.0 361291.0 362469.0 58615.7 58953.7 59363.7 59389.7 59514.0 64504.4 69470.3 70964.3 71098.6 71439.6 16884.9 17873.0 17986.4 18027.9 18030.5 12107.7 13235.7 13625.0 13710.7 13711.8 16238.8 19539.0 19715.0 19878.4 20179.9 8214.6 8283.3 8372.7 8409.5 8441.0 941.5 995.9 1074.9 1121.4 1125.4 28732.7 30428.1 30726.8 30734.2 30923.8 1087.1 1111.4 1113.8 1118.6 1155.4 14094.8 14490.8 15670.6 15866.8 16007.7 741778.0 745249.3 752299.0 753921.0 757577.0 56760.4 57631.4 58351.5 58596.4 58644.0 1528.9 1530.1 1544.9 1545.0 1552.3 28341.7 29595.4 29744.0 30198.4 30681.0 52333.7 52850.0 52890.2 52959.7 53044.7 568370.2 583838.0 589424.0 589734.1 590285.0 27470.5 27585.4 27613.2 27745.7 27894.4 39790.4 45697.5 45844.8 47620.3 48016.5 30378.7 35975.5 36531.0 36788.6 36957.1 31944.4 34704.8 36021.0 36708.4 36737.1 35403.5 35986.0 35988.7 36096.9 36356.1 27724.4 29459.6 30253.7 33466.3 33553.9 23645.6 23854.6 23926.3 24040.9 24128.4 11:35:49 INFO - PROCESS | 1675 | 11:35:49 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:31262.82 Median:2324.45 stddev:84673.18 (3642.7%) stddev-sans-first:84879.01 11:35:49 INFO - PROCESS | 1675 | Values: 404.8 405.2 405.5 406.2 417.4 20070.8 20787.8 20812.5 20825.5 22295.1 21213.5 21469.5 21650.7 21680.9 22011.0 5889.3 5956.2 6090.4 6200.5 6209.1 6071.5 6148.0 6172.7 6186.8 6200.4 5746.5 6065.9 6078.5 6080.7 6147.3 5920.2 6000.2 6061.3 6815.3 6817.1 6053.3 6061.9 6065.7 6076.8 6084.3 13591.6 15099.8 24371.4 25791.2 26570.7 2223.9 2321.0 2327.9 2670.3 2760.2 9362.9 9895.8 9899.3 9926.8 9927.7 1718.3 1728.7 1807.0 1809.0 1812.8 74809.6 95540.0 115229.4 117747.0 121318.7 11797.3 11930.7 11973.3 12032.6 12149.9 15107.7 15372.0 15388.6 15442.3 15454.8 8534.9 8811.8 8948.2 8973.8 9057.7 9018.5 9042.9 9044.9 9104.8 9132.5 4719.3 4747.0 4834.7 4836.3 4863.2 1990.4 2063.6 2068.0 2100.6 2103.6 749.5 849.6 850.9 911.3 1075.2 20559.4 20910.9 20922.5 20971.4 21299.3 988.6 1001.2 1003.7 1013.6 1020.9 8782.7 11267.2 11507.3 11525.8 11536.5 239061.0 433977.0 466473.0 631641.0 632665.0 122856.0 124617.0 124809.0 127211.4 130563.0 1019.0 1026.0 1045.0 1048.6 1048.8 17996.1 18030.4 18173.3 18180.5 18440.9 50860.0 51224.2 51813.7 52044.7 52321.7 72060.6 74234.0 75165.1 76141.5 76464.9 7886.4 8294.0 8302.7 8375.4 8643.2 10283.9 12235.5 12303.9 12591.1 12679.9 11032.7 11047.1 11089.8 11130.1 11140.8 10755.7 10939.8 10994.0 11024.3 11114.5 11265.5 11334.9 11445.6 11645.5 11693.6 8298.8 8386.3 8441.2 8495.2 8574.7 10409.4 11204.6 11384.1 11466.5 11473.3 11:35:49 INFO - PROCESS | 1675 | 11:35:49 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:5658.15 Median:4971.37 stddev:3122.94 (62.8%) stddev-sans-first:3116.67 11:35:49 INFO - PROCESS | 1675 | Values: 1581.5 1596.5 1845.2 1919.5 1994.9 7900.1 8563.4 8716.4 8901.8 8945.8 8464.2 8503.7 8594.1 8615.7 8646.4 2189.1 2335.8 2341.5 2442.9 2449.5 2228.5 2406.2 2451.7 2458.2 2460.4 2424.9 2443.2 2451.1 2465.9 2466.2 2160.9 2405.1 2428.9 2457.8 2459.0 2427.1 2462.4 2463.0 2466.1 2475.9 6833.4 8434.7 8536.7 8757.9 8783.5 4536.8 5226.0 5336.5 5451.1 5487.6 3134.9 3512.6 3580.8 3642.0 3642.8 981.5 1012.8 1050.2 1071.9 1076.9 10380.4 11760.4 11857.5 11865.9 11886.5 4761.9 4810.3 4829.3 4881.9 4899.0 10160.9 10164.5 10187.9 10234.5 10265.0 4628.3 5424.3 5427.5 5453.6 5463.8 3675.8 4842.9 4966.9 4967.6 4978.0 4084.1 4147.1 4155.4 4181.8 4185.3 1211.8 1370.1 1375.3 1380.2 1386.5 828.4 912.4 933.9 935.9 942.4 5371.7 5848.6 6015.2 6017.5 6047.7 944.6 951.6 968.3 990.6 1014.3 3785.5 3923.8 3928.2 3955.0 3966.2 9496.8 9788.5 10406.5 10799.8 10849.2 10296.1 10603.4 10642.7 10777.8 10830.5 7152.7 8420.7 8435.9 8449.6 8470.1 5772.2 5840.8 5850.7 5901.3 5956.9 9595.0 9711.0 9727.6 9742.6 9780.4 9800.4 10015.7 10063.6 10104.4 10178.7 6377.5 6811.0 7035.3 7074.5 7190.5 8210.5 8324.8 8333.7 8360.2 8364.6 6406.4 6850.3 7043.8 7270.9 7426.2 6240.6 7222.3 7238.1 7354.9 7364.2 6796.0 6984.4 6986.8 6992.5 7002.8 4906.7 4949.3 4957.4 5009.2 5050.4 4975.2 4975.6 5039.1 5054.0 5190.0 11:35:49 INFO - PROCESS | 1675 | 11:35:49 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:5937.68 Median:6620.38 stddev:1928.99 (29.1%) stddev-sans-first:1887.58 11:35:49 INFO - PROCESS | 1675 | Values: 309.9 328.3 332.3 334.8 338.2 6804.9 6888.8 6950.4 6978.0 6997.7 6803.1 6817.1 6824.8 6837.2 6855.0 4838.1 4885.8 4986.7 5014.3 5027.9 4953.2 4990.5 4991.3 4995.9 4997.8 4941.2 4941.6 4948.5 4964.7 4984.3 4959.4 4963.9 4966.1 4993.3 5016.7 4279.3 4281.2 4288.4 4295.0 4306.3 5303.3 6351.6 6536.1 6553.6 6593.6 5888.4 5912.5 5923.6 5951.3 5959.1 5911.9 7219.7 7245.7 7262.0 7271.2 4780.8 4790.6 4790.8 4797.1 4821.8 6612.0 7665.9 7694.3 7713.6 7738.6 4530.3 4684.0 4704.6 4717.7 4718.3 4777.7 4833.2 4836.0 4839.1 4847.4 5685.5 5819.2 5835.9 5841.5 5847.9 5311.7 6256.3 7353.4 7364.0 7370.0 1638.1 1672.3 1677.6 1680.3 1684.6 1021.6 1058.9 1061.7 1066.7 1078.7 5431.3 6597.0 6799.0 6809.7 6811.6 7336.2 7579.9 7599.5 7610.1 7634.7 6723.2 6827.5 6855.0 6893.7 6917.2 3495.9 3917.5 4041.7 4079.8 4090.2 8031.7 8048.3 8053.6 8061.4 8063.9 7550.3 7857.9 7874.1 7879.6 7900.3 7946.9 7981.0 8008.9 8032.8 8059.5 7713.8 7713.9 7715.6 7729.4 7762.8 7499.9 7519.1 7536.2 7544.7 7560.2 7695.6 8059.6 8064.8 8065.0 8091.0 4712.1 4731.8 4762.6 4778.0 4786.6 6628.8 7650.7 7655.6 7669.6 7704.2 5960.9 6497.4 6536.2 7398.3 7418.7 7078.8 7278.7 7285.3 7320.3 7357.2 5800.3 7176.2 7216.3 7221.1 7234.5 5599.8 6779.7 7547.6 7558.4 7593.7 6952.3 7203.3 7215.7 7233.4 7251.7 11:35:49 INFO - PROCESS | 1675 | 11:35:49 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:17904.59 Median:32857.10 stddev:25072.76 (76.3%) stddev-sans-first:25109.55 11:35:49 INFO - PROCESS | 1675 | Values: 631.1 695.2 706.2 764.8 785.5 14780.8 14819.6 14919.5 14978.2 14987.3 13646.9 13668.7 13716.9 13730.0 15626.0 781.6 782.5 952.5 953.8 958.6 797.7 840.5 928.3 932.5 944.5 956.8 980.2 995.1 996.3 998.9 862.1 961.1 961.3 970.5 1001.8 711.4 732.9 736.3 747.2 747.4 12939.5 15180.3 15280.8 15287.2 15338.8 10673.5 12388.4 12430.6 12439.0 12731.2 10069.5 10121.6 10235.4 10260.5 10479.9 3440.5 3452.9 3462.2 3570.7 3711.3 85753.6 85764.6 86407.0 86500.7 87223.0 9141.1 9412.0 9428.6 9437.3 9659.2 21394.5 21415.9 21468.0 21580.9 21983.5 7314.6 7322.2 7369.0 7464.9 7497.2 4004.5 4015.8 4067.0 4145.6 4195.3 3154.8 3613.6 3695.4 3695.7 3702.6 644.2 656.7 661.9 664.9 667.6 508.8 568.1 592.7 604.1 623.0 7537.9 7913.2 8340.3 8433.1 8451.8 502.6 580.9 597.5 619.4 622.3 3342.5 3754.6 3881.7 3902.2 4077.3 83825.6 84129.0 91738.1 95201.6 95308.5 69045.7 69618.3 70012.0 70165.0 74075.3 66343.7 66852.0 67324.0 67624.0 67915.7 8478.3 8591.0 8669.4 8706.8 8720.1 47015.1 49124.0 49378.8 49745.0 50787.0 53927.7 63335.7 65070.0 66661.0 67314.0 10898.8 11049.7 11217.3 11257.6 11341.5 9772.6 11778.3 12068.9 12111.4 12281.6 8428.4 9276.0 10169.4 10615.3 11412.8 11169.3 11288.5 11625.8 11692.5 11706.3 9554.7 10838.2 11185.4 11220.5 11520.4 9542.5 9763.6 9785.2 9787.6 9811.4 9544.9 9875.0 9890.8 9897.6 9985.0 11:35:49 INFO - PROCESS | 1675 | -------- Summary: end -------- 11:35:49 INFO - PROCESS | 1675 | 11:35:49 INFO - TEST-INFO | 1675: exit 0 11:35:49 INFO - TEST-OK | dromaeo_css | took 1110794ms 11:35:49 INFO - TEST-START | kraken 11:35:49 INFO - operating with platform_type : mac_ 11:35:49 INFO - Initialising browser for kraken test... 11:35:49 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpOfRQmJ/profile http://localhost:49277/getInfo.html) 11:35:52 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 11:35:52 INFO - PROCESS | 1683 | colorDepth:24 11:35:52 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 11:35:52 INFO - PROCESS | 1683 | __metrics 11:35:52 ERROR - PROCESS | 1683 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 11:35:53 INFO - TEST-INFO | 1683: exit 0 11:35:53 INFO - Browser initialized. 11:35:53 INFO - Running cycle 1/1 for kraken test... 11:35:53 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpOfRQmJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:36:05 INFO - PROCESS | 1686 | RSS: Main: 221179904 11:36:05 INFO - PROCESS | 1686 | 11:36:08 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 11:36:09 INFO - PROCESS | 1686 | RSS: Main: 232927232 11:36:09 INFO - PROCESS | 1686 | 11:36:11 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 11:36:12 INFO - PROCESS | 1686 | RSS: Main: 241905664 11:36:12 INFO - PROCESS | 1686 | 11:36:15 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 11:36:16 INFO - PROCESS | 1686 | RSS: Main: 245817344 11:36:16 INFO - PROCESS | 1686 | 11:36:18 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 11:36:19 INFO - PROCESS | 1686 | RSS: Main: 245600256 11:36:19 INFO - PROCESS | 1686 | 11:36:21 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:36:21 INFO - PROCESS | 1686 | RSS: Main: 246456320 11:36:21 INFO - PROCESS | 1686 | 11:36:24 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 11:36:25 INFO - PROCESS | 1686 | RSS: Main: 248049664 11:36:25 INFO - PROCESS | 1686 | 11:36:27 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 11:36:28 INFO - PROCESS | 1686 | RSS: Main: 247681024 11:36:28 INFO - PROCESS | 1686 | 11:36:31 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 11:36:31 INFO - PROCESS | 1686 | RSS: Main: 247820288 11:36:31 INFO - PROCESS | 1686 | 11:36:33 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:36:34 INFO - PROCESS | 1686 | RSS: Main: 247279616 11:36:34 INFO - PROCESS | 1686 | 11:36:36 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:36:36 INFO - PROCESS | 1686 | RSS: Main: 247316480 11:36:36 INFO - PROCESS | 1686 | 11:36:39 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:36:39 INFO - PROCESS | 1686 | RSS: Main: 247615488 11:36:39 INFO - PROCESS | 1686 | 11:36:42 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:36:43 INFO - PROCESS | 1686 | RSS: Main: 247701504 11:36:43 INFO - PROCESS | 1686 | 11:36:46 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:36:47 INFO - PROCESS | 1686 | RSS: Main: 247656448 11:36:47 INFO - PROCESS | 1686 | 11:36:48 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 11:36:49 INFO - PROCESS | 1686 | RSS: Main: 249430016 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | __start_tp_report 11:36:49 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 11:36:49 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 11:36:49 INFO - PROCESS | 1686 | |i|pagename|runs| 11:36:49 INFO - PROCESS | 1686 | |0;ai-astar;113;117;141;116;120;118;108;118;114;115 11:36:49 INFO - PROCESS | 1686 | |1;audio-beat-detection;148;131;160;155;122;152;122;157;160;128 11:36:49 INFO - PROCESS | 1686 | |2;audio-dft;199;200;202;215;198;195;199;199;196;197 11:36:49 INFO - PROCESS | 1686 | |3;audio-fft;68;85;109;158;56;56;56;61;142;80 11:36:49 INFO - PROCESS | 1686 | |4;audio-oscillator;79;84;124;77;79;92;118;80;79;121 11:36:49 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;90;88;89;85;90;86;88;86;88;86 11:36:49 INFO - PROCESS | 1686 | |6;imaging-darkroom;108;95;94;94;118;148;96;93;95;95 11:36:49 INFO - PROCESS | 1686 | |7;imaging-desaturate;92;94;105;90;93;94;91;91;92;91 11:36:49 INFO - PROCESS | 1686 | |8;json-parse-financial;61;64;66;65;65;67;64;81;78;76 11:36:49 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;64;66;67;78;90;91;103;100;98;98 11:36:49 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;92;108;75;97;72;99;97;104;107;107 11:36:49 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;152;150;152;147;157;134;128;130;150;153 11:36:49 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;219;164;218;226;160;211;212;215;169;167 11:36:49 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;70;69;78;95;63;56;91;97;91;54 11:36:49 INFO - PROCESS | 1686 | __end_tp_report 11:36:49 INFO - PROCESS | 1686 | __start_cc_report 11:36:49 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,1089 11:36:49 INFO - PROCESS | 1686 | __end_cc_report 11:36:49 INFO - PROCESS | 1686 | __startTimestamp1464374209179__endTimestamp 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | ------- Summary: start ------- 11:36:49 INFO - PROCESS | 1686 | Number of tests: 14 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:118.00 Median:117.50 stddev:8.74 (7.4%) stddev-sans-first:9.08 11:36:49 INFO - PROCESS | 1686 | Values: 113.0 117.0 141.0 116.0 120.0 118.0 108.0 118.0 114.0 115.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:153.50 stddev:15.89 (10.4%) stddev-sans-first:16.77 11:36:49 INFO - PROCESS | 1686 | Values: 148.0 131.0 160.0 155.0 122.0 152.0 122.0 157.0 160.0 128.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:200.00 Median:199.00 stddev:5.64 (2.8%) stddev-sans-first:5.97 11:36:49 INFO - PROCESS | 1686 | Values: 199.0 200.0 202.0 215.0 198.0 195.0 199.0 199.0 196.0 197.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:87.10 Median:58.50 stddev:37.30 (63.8%) stddev-sans-first:38.92 11:36:49 INFO - PROCESS | 1686 | Values: 68.0 85.0 109.0 158.0 56.0 56.0 56.0 61.0 142.0 80.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:79.00 stddev:19.62 (24.8%) stddev-sans-first:20.11 11:36:49 INFO - PROCESS | 1686 | Values: 79.0 84.0 124.0 77.0 79.0 92.0 118.0 80.0 79.0 121.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.00 stddev:1.78 (2.0%) stddev-sans-first:1.66 11:36:49 INFO - PROCESS | 1686 | Values: 90.0 88.0 89.0 85.0 90.0 86.0 88.0 86.0 88.0 86.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:94.50 stddev:17.56 (18.6%) stddev-sans-first:18.55 11:36:49 INFO - PROCESS | 1686 | Values: 108.0 95.0 94.0 94.0 118.0 148.0 96.0 93.0 95.0 95.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:92.00 stddev:4.32 (4.7%) stddev-sans-first:4.56 11:36:49 INFO - PROCESS | 1686 | Values: 92.0 94.0 105.0 90.0 93.0 94.0 91.0 91.0 92.0 91.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:68.70 Median:66.50 stddev:6.93 (10.4%) stddev-sans-first:6.77 11:36:49 INFO - PROCESS | 1686 | Values: 61.0 64.0 66.0 65.0 65.0 67.0 64.0 81.0 78.0 76.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:85.50 Median:84.00 stddev:15.35 (18.3%) stddev-sans-first:14.17 11:36:49 INFO - PROCESS | 1686 | Values: 64.0 66.0 67.0 78.0 90.0 91.0 103.0 100.0 98.0 98.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:95.80 Median:83.50 stddev:12.88 (15.4%) stddev-sans-first:13.59 11:36:49 INFO - PROCESS | 1686 | Values: 92.0 108.0 75.0 97.0 72.0 99.0 97.0 104.0 107.0 107.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:151.00 stddev:10.51 (7.0%) stddev-sans-first:10.86 11:36:49 INFO - PROCESS | 1686 | Values: 152.0 150.0 152.0 147.0 157.0 134.0 128.0 130.0 150.0 153.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.10 Median:213.50 stddev:27.17 (12.7%) stddev-sans-first:27.53 11:36:49 INFO - PROCESS | 1686 | Values: 219.0 164.0 218.0 226.0 160.0 211.0 212.0 215.0 169.0 167.0 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.40 Median:84.50 stddev:16.30 (19.3%) stddev-sans-first:17.13 11:36:49 INFO - PROCESS | 1686 | Values: 70.0 69.0 78.0 95.0 63.0 56.0 91.0 97.0 91.0 54.0 11:36:49 INFO - PROCESS | 1686 | -------- Summary: end -------- 11:36:49 INFO - PROCESS | 1686 | 11:36:49 INFO - TEST-INFO | 1686: exit 0 11:36:49 INFO - TEST-OK | kraken | took 59950ms 11:36:49 INFO - SUITE-END | took 1170s 11:36:50 INFO - Completed test suite (00:19:31) 11:36:50 INFO - javascript benchmark 11:36:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2977.761779057675, 3270.0680306112763, 3390.0, 3396.439587468068, 3443.741719371046, 5545.805896793613, 5571.804986825462, 5579.804706835262, 5588.916166257499, 5679.687617181054, 4516.125807789772, 5392.865178370543, 5607.831765047055, 5620.100783874571, 5655.1517272409155, 4968.677035992649, 5124.359455068116, 5187.974060129713, 5196.714180720059, 5236.869078273055, 4878.975605121969, 5052.469490703478, 5201.973990130044, 5282.2076688496645, 5337.2794672719265, 5172.146595811687, 5190.636655434121, 5194.68831870089, 5210.16637338026, 5212.244224587433, 4741.577526741999, 4965.553100220961, 5147.871303217412, 5183.403908550489, 5189.6107791915565, 5025.7235852028125, 5070.518300761421, 5113.846584602468, 5136.922946155811, 5212.218167274901, 5570.749316280777, 5581.358143813432, 5588.0779671354585, 5591.944080559183, 5610.270664813589, 5966.612170208966, 6056.727447264883, 6071.332153463116, 6097.115918191882, 6105.084237364431, 5139.254808052849, 6024.397560243984, 6094.481969032638, 6112.113743507211, 6117.27937334216, 5691.715414229272, 5708.315002199721, 5709.257796486471, 5713.228714123582, 5725.59920805548, 6105.389461053859, 6197.225346831646, 6210.968945155245, 6227.19046523949, 6246.125542424064, 5990.760369585255, 6043.153958445821, 6056.454919057306, 6092.116643086728, 6107.541934354852, 6134.0, 6135.723892424762, 6141.140240366352, 6142.232220972379, 6163.753449862045, 5962.642241465439, 6013.789517366871, 6037.366076561985, 6050.818475445743, 6058.242719660043, 6117.042466735707, 6122.154209354628, 6125.785597504066, 6126.336365086956, 6133.457080270857, 4790.688605240648, 4798.5441383068555, 4828.46886842447, 4834.661573689878, 4863.927041094386, 4296.505901821268, 4314.201872653569, 4331.480222373335, 4366.126774645084, 4393.758343291086, 4226.535081141072, 4856.975715121473, 4902.656814022984, 5006.773340531593, 5682.715864206773, 6040.154378387029, 6104.4505994460715, 6125.877482450415, 6145.938540614537, 6151.507879369639, 5467.261919640877, 5586.078297080936, 5648.943510564924, 5656.547476201977, 5694.487496125369, 4540.205464043779, 4708.223143181405, 5267.52343453319, 5290.153575427913, 5290.470952904756, 6202.906956395657, 6206.4724498416335, 6224.315325314212, 6226.999999999909, 6240.31356550779, 6030.216071910623, 6115.082737589397, 6171.56799024064, 6175.938240617536, 6199.225096862892, 6209.844753881009, 6239.719212635533, 6260.467860231931, 6263.279722831932, 6270.561060725888, 4686.648501362343, 5699.943000569941, 5914.142449344735, 6089.48239399656, 6104.11490333908, 6108.664023478751, 6109.495762817132, 6162.568620196544, 6167.167432396569, 6170.043643235305, 4820.301056346881, 6011.849703757441, 6039.063945088518, 6046.12331211963, 6071.5446341523675, 5729.0, 5950.018246989372, 6025.66858822769, 6025.849353766191, 6039.486643635164, 5717.456841600041, 5801.912971305349, 5829.0, 5839.708014599338, 5849.210356601974, 5921.792737254175, 5994.07091900756, 6017.06735456005, 6049.820285044374, 6069.544784141119, 5676.17695434168, 5719.370869204301, 5796.304443466811, 5865.472107510344, 5892.528597712087], "value": 5544.508736993804, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.1054565056802, 547.0481362429372, 555.7749111609794, 591.1369400675014, 594.4174708785391, 29839.552406713916, 39022.0, 39093.022674433116, 39106.21787564247, 39229.01927451815, 33139.834300828465, 40456.78629641108, 41041.97395065125, 41095.58904410963, 41163.970900727494, 9421.811563768737, 9631.277654175965, 9662.806743865118, 9669.0, 9693.757656058584, 9602.855957160647, 9749.025097490265, 9764.609415623367, 9786.510674466284, 9817.754556136082, 8869.95565022174, 9780.510974451285, 9786.608535658565, 9791.559379827924, 9817.410955342666, 9332.206762425201, 9798.853017204749, 9806.16647584949, 9812.165965892907, 9817.803643927153, 9603.37578057431, 9713.854292185622, 9724.805503889882, 9738.707838764849, 9743.610255589769, 33652.0, 39243.41134882979, 39444.40833387501, 39646.40530392047, 39727.8013609933, 2795.2313113893842, 3181.681831816823, 3267.036224313833, 3295.1597342677705, 3308.4541050726602, 27253.727462725317, 27446.0, 27626.861865690542, 29088.8545557273, 30482.780688772422, 2740.369714965549, 2783.4293969736154, 2788.6653601567737, 2791.916242512728, 2855.52883774177, 356826.0, 357230.0, 360704.0, 361290.9999999974, 362469.0, 58615.70692146512, 58953.70523147357, 59363.70318148468, 59389.70305148447, 59514.0, 64504.35495645077, 69470.30529694639, 70964.29035709577, 71098.57802844017, 71439.64280178565, 16884.91557542229, 17873.0, 17986.370477033503, 18027.909860450876, 18030.459086227173, 12107.697307567205, 13235.669108272177, 13625.0, 13710.725785484234, 13711.794323085162, 16238.837611623969, 19539.0230488475, 19715.0, 19878.403647890587, 20179.899100504404, 8214.63034163464, 8283.25450709439, 8372.665093396317, 8409.495430274203, 8440.957795210985, 941.4539567051094, 995.9004099589984, 1074.908632766208, 1121.3552207480732, 1125.392288164382, 28732.712672873422, 30428.087157385315, 30726.84636576803, 30734.16278848924, 30923.8453807734, 1087.1302957633861, 1111.372074777748, 1113.8440618313443, 1118.6084870295495, 1155.399192419937, 14094.788578171336, 14490.840732741355, 15670.608234794221, 15866.761998570031, 16007.67984640324, 741778.0, 745249.2737536278, 752299.0, 753921.0, 757577.0, 56760.43239567634, 57631.423685763446, 58351.5412114692, 58596.414035859096, 58644.0, 1528.8700460460764, 1530.051368151753, 1544.9304781284868, 1545.0, 1552.301464341051, 28341.71658283473, 29595.40809183847, 29744.0, 30198.396032079676, 30681.0, 52333.69165770886, 52850.0, 52890.206646899554, 52959.73520132375, 53044.73477632587, 568370.1581492067, 583838.0, 589424.0, 589734.0513297578, 590285.0485747715, 27470.450590987668, 27585.448291034467, 27613.171604851887, 27745.722542774314, 27894.44211115807, 39790.40314395229, 45697.54302457066, 45844.7707761459, 47620.2856957139, 48016.5198348026, 30378.696213037587, 35975.460368095024, 36531.0, 36788.63211367959, 36957.07607309839, 31944.36111277715, 34704.826475867456, 36021.0, 36708.44937325996, 36737.08157295982, 35403.468947966314, 35986.0, 35988.74039408608, 36096.91709248727, 36356.09109772277, 27724.445511089263, 29459.55810662799, 30253.697463025088, 33466.33067338688, 33553.882273523974, 23645.64531531994, 23854.642180366962, 23926.282211533682, 24040.87979560091, 24128.396790080387], "value": 19845.276560948674, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.81580880699283, 405.2381522737254, 405.53971242639614, 406.16330359459533, 417.3801904172304, 20070.799292007076, 20787.792122078794, 20812.479688007807, 20825.479363015933, 22295.108195672194, 21213.469663258427, 21469.463263418384, 21650.675239871416, 21680.891595542038, 22011.0, 5889.293284805819, 5956.16613674086, 6090.4214099660685, 6200.534959878005, 6209.068639704035, 6071.483923866476, 6148.0, 6172.691365431733, 6186.84532886677, 6200.410960958701, 5746.454086861741, 6065.878682426349, 6078.483328917047, 6080.665563394034, 6147.293061297941, 5920.171176035337, 6000.18997435345, 6061.333253342129, 6815.2843951385385, 6817.147856517935, 6053.303870054956, 6061.878762424727, 6065.696715164268, 6076.756929722806, 6084.26988761347, 13591.592252232449, 15099.849001509954, 24371.390715232083, 25791.226263212135, 26570.6714664266, 2223.9332820015425, 2320.9651855222187, 2327.93016209514, 2670.319068637485, 2760.213339198339, 9362.90637093634, 9895.752606184831, 9899.307048506607, 9926.801463970753, 9927.652532161339, 1718.3298513579714, 1728.7493313469477, 1806.9638607227914, 1809.0412081596776, 1812.809654986234, 74809.6259518699, 95540.0, 115229.42385288104, 117747.0, 121318.69226363867, 11797.29216247028, 11930.673518663778, 11973.341466219441, 12032.578859739868, 12149.878501215051, 15107.697846043018, 15372.0, 15388.615284617974, 15442.305096270695, 15454.845451545565, 8534.914650853536, 8811.779705507286, 8948.239399650973, 8973.820523589491, 9057.731917531552, 9018.458892466473, 9042.909570904207, 9044.8643270351, 9104.81790364189, 9132.543372831466, 4719.315699223594, 4747.0, 4834.709917404967, 4836.250381190921, 4863.197572400514, 1990.3829812758092, 2063.6285468615793, 2067.9689804652944, 2100.6218880601637, 2103.642380795238, 749.4903465643415, 849.6431498770531, 850.8808766772656, 911.3028533172129, 1075.1882328841675, 20559.38321850347, 20910.895445522674, 20922.476938076365, 20971.37085887426, 21299.254526091514, 988.584794386345, 1001.1990407673977, 1003.7340104872214, 1013.6452241715341, 1020.9183265338755, 8782.736517904474, 11267.15496337778, 11507.309561426342, 11525.827112593317, 11536.538538458368, 239061.0, 433977.0, 466473.0, 631641.0, 632665.0, 122856.0, 124617.0, 124809.0, 127211.36394318154, 130563.0, 1018.9796204075877, 1025.958961641541, 1045.0385645206277, 1048.558232430393, 1048.7849990751877, 17996.10019499046, 18030.38166719927, 18173.273069077088, 18180.545486362418, 18440.90779546094, 50859.98280034453, 51224.23163652623, 51813.74093129661, 52044.69888252824, 52321.7383913078, 72060.63969680118, 74234.0, 75165.06145134053, 76141.4771704574, 76464.85302720575, 7886.44794864354, 8294.0, 8302.70940517079, 8375.37184711137, 8643.17889800468, 10283.90979235579, 12235.510579576718, 12303.93848030754, 12591.118621696392, 12679.93660031694, 11032.676078870587, 11047.060999814785, 11089.772687593944, 11130.053945414407, 11140.832887506533, 10755.731106722395, 10939.781204376028, 10994.010539051524, 11024.283421577571, 11114.499847507042, 11265.493052812806, 11334.886651133384, 11445.599404020819, 11645.475953582278, 11693.649190524298, 8298.751037468886, 8386.28716559075, 8441.198086181803, 8495.150484951702, 8574.72538999868, 10409.42748148859, 11204.551817927191, 11384.089272858317, 11466.541338346373, 11473.311601303949], "value": 9636.260561939898, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1581.517637120678, 1596.513063515628, 1845.1881172284193, 1919.481739930219, 1994.88030718157, 7900.130985591581, 8563.443376180543, 8716.389852710305, 8901.777455563613, 8945.776355591106, 8464.238218560344, 8503.744887653365, 8594.097619749915, 8615.741527754162, 8646.394752367336, 2189.0915270162864, 2335.848169868953, 2341.5316936612744, 2442.9144979925663, 2449.5223431430877, 2228.487447886987, 2406.1698713943747, 2451.742567030463, 2458.2379462366725, 2460.4463995601027, 2424.866632335221, 2443.218170185541, 2451.0685939343025, 2465.9136930207446, 2466.235467005234, 2160.8655455885705, 2405.1461731085474, 2428.9392765180837, 2457.7665121813397, 2458.9508209835794, 2427.07771047001, 2462.372095115734, 2463.0, 2466.1121996081397, 2475.9133430329853, 6833.350831670983, 8434.74695759122, 8536.74389768308, 8757.912420875837, 8783.472991620523, 4536.795844186987, 5226.033183860998, 5336.519713225828, 5451.127819548893, 5487.6158668893195, 3134.905952821419, 3512.5960514540643, 3580.785152890835, 3641.998450426154, 3642.7814331140216, 981.5190556627273, 1012.792377562598, 1050.2333296693457, 1071.919606029551, 1076.8815430302661, 10380.377177369306, 11760.353180575064, 11857.466414011391, 11865.88134118665, 11886.465109070112, 4761.928451676738, 4810.254410566298, 4829.299751536078, 4881.8535443936735, 4898.975505122452, 10160.898391015995, 10164.4917754112, 10187.949060254652, 10234.48827558619, 10264.973502649824, 4628.259478483494, 5424.321959755031, 5427.538659213932, 5453.590980676465, 5463.754131064112, 3675.834587443518, 4842.903141937141, 4966.925496117561, 4967.552920237196, 4978.0, 4084.0606660468256, 4147.138474452679, 4155.355919832431, 4181.770002649822, 4185.330347144442, 1211.7576484702918, 1370.1162750026178, 1375.2573610250554, 1380.1650001748797, 1386.5424409944897, 828.3994104274353, 912.4023764434173, 933.9066093390607, 935.864299676543, 942.4204114469552, 5371.677699337974, 5848.64908105515, 6015.187949626832, 6017.518598512109, 6047.7278522466595, 944.5560586524323, 951.5860600638745, 968.2883080935507, 990.5636826601457, 1014.3052009373603, 3785.4511095890944, 3923.7645741255615, 3928.194720082376, 3954.980225098856, 3966.206758648224, 9496.76542049529, 9788.510574471247, 10406.47967601632, 10799.83800242997, 10849.186311026704, 10296.07335339823, 10603.363798172135, 10642.733931651615, 10777.78444431107, 10830.51262693165, 7152.749653762094, 8420.663173473115, 8435.915640843514, 8449.57752112392, 8470.110638383005, 5772.2207501986695, 5840.8247752567495, 5850.7074646266665, 5901.3213480450295, 5956.851078723066, 9595.0, 9710.951445242728, 9727.610895564381, 9742.561584728563, 9780.364276322302, 9800.36297640651, 10015.749606259902, 10063.597456101967, 10104.393736375841, 10178.694639160836, 6377.489800816016, 6811.0, 7035.296470352924, 7074.469414793808, 7190.4966652335925, 8210.548419836967, 8324.791880203044, 8333.708320208763, 8360.205780450848, 8364.581770911553, 6406.423421892052, 6850.280720524273, 7043.802553566009, 7270.890936635848, 7426.220246874216, 6240.563160578716, 7222.349988501061, 7238.0952380952385, 7354.889676654748, 7364.226756190523, 6795.966020169867, 6984.371406573433, 6986.790396288119, 6992.545484543489, 7002.7899163025195, 4906.656534042726, 4949.257611358325, 4957.429895561912, 5009.248612708123, 5050.444451110301, 4975.179095449211, 4975.601951843916, 5039.0929632666475, 5054.0, 5190.0], "value": 4592.209653610268, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.9132242971968, 328.2990814610805, 332.25740470049436, 334.84429740170816, 338.16979315779764, 6804.8979265311, 6888.75889343873, 6950.443964482842, 6978.023076769244, 6997.720091196359, 6803.149606299213, 6817.147856517935, 6824.795256142312, 6837.17953845538, 6854.96572517138, 4838.129136755391, 4885.780139893714, 4986.725730084844, 5014.272930425104, 5027.874303142414, 4953.1579631462555, 4990.500949904998, 4991.301217829507, 4995.875103122415, 4997.800087996494, 4941.184704523767, 4941.555260026596, 4948.5298896604945, 4964.702117872939, 4984.252362145671, 4959.404871415417, 4963.875903102416, 4966.130927087764, 4993.251012348141, 5016.673916195436, 4279.293916503773, 4281.207976524322, 4288.44250247469, 4295.0, 4306.289462238727, 5303.310569625963, 6351.587146835441, 6536.11762412073, 6553.574017688835, 6593.571417857824, 5888.352281249102, 5912.46787789101, 5923.614965027259, 5951.285845698544, 5959.0763431668165, 5911.852203694899, 7219.675114619855, 7245.746398876066, 7261.963690181568, 7271.163816161156, 4780.784864681098, 4790.592799612037, 4790.760461976887, 4797.0645724083815, 4821.831235906761, 6611.966940165316, 7665.8850117248285, 7694.269044440769, 7713.575753333621, 7738.574378409186, 4530.320451932236, 4684.039771846763, 4704.647151463654, 4717.693349932243, 4718.31584420264, 4777.737224452619, 4833.154198015403, 4836.032793441326, 4839.080574690868, 4847.369841920598, 5685.516731077904, 5819.243498345188, 5835.854103647443, 5841.503472204825, 5847.853803654943, 5311.654742441729, 6256.280527739368, 7353.374963128087, 7364.0, 7370.0, 1638.0908595729484, 1672.3059930128861, 1677.5973766296245, 1680.2942764039133, 1684.5872761173591, 1021.6117875207374, 1058.9364638121738, 1061.7080302916759, 1066.7173199102244, 1078.7141407527013, 5431.348238211361, 6597.010448432773, 6799.0, 6809.693563789641, 6811.557248778813, 7336.156342020629, 7579.924200758032, 7599.506032107895, 7610.124835643861, 7634.732784352521, 6723.226828914738, 6827.52207345491, 6854.965725171442, 6893.655317234118, 6917.169939607179, 3495.8987918805497, 3917.5494818095713, 4041.7170798044444, 4079.8164082616354, 4090.2433049885867, 8031.678732850621, 8048.275655191061, 8053.59732013397, 8061.435699500978, 8063.919360806434, 7550.282723141293, 7857.882131768028, 7874.133845277015, 7879.566623835744, 7900.2889739923685, 7946.920530794734, 7981.0, 8008.919910800934, 8032.799180020546, 8059.5164290142775, 7713.845723085395, 7713.884291735741, 7715.57564333967, 7729.381649467917, 7762.7671169865, 7499.925000749922, 7519.135299440634, 7536.246375362639, 7544.698212071456, 7560.168381477923, 7695.576743279174, 8059.597020149086, 8064.798380040311, 8064.9596752018215, 8091.0, 4712.08114417699, 4731.763411829464, 4762.618990480684, 4777.95222047775, 4786.593139583035, 6628.801135965929, 7650.73222437212, 7655.61721913891, 7669.622417050732, 7704.229577042251, 5960.940390596039, 6497.415232629086, 6536.150300460909, 7398.3341499265325, 7418.666160022704, 7078.806518613181, 7278.745243916437, 7285.271472852673, 7320.304571065741, 7357.19070902211, 5800.332961709458, 7176.246494118042, 7216.278372162742, 7221.097362829646, 7234.457415693949, 5599.776008959596, 6779.711854747779, 7547.622618869144, 7558.357539609195, 7593.734219302298, 6952.339527744856, 7203.315685009916, 7215.67529461165, 7233.421326293778, 7251.709931602677], "value": 5289.861779574258, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.1321932343028, 695.2144077192772, 706.1667232665453, 764.8011517005584, 785.4501848705905, 14780.778288325684, 14819.555413337617, 14919.477818276362, 14978.176200308979, 14987.250637468123, 13646.863531364683, 13668.658283542916, 13716.93141534291, 13730.038897277194, 15625.984311019776, 781.6052893288881, 782.4835608498378, 952.4951775558965, 953.8235426446095, 958.6357184269967, 797.6849144587884, 840.5125027484075, 928.3084102343763, 932.4964519159646, 944.4663764972803, 956.8086382723483, 980.2011360740973, 995.1292618958412, 996.3424140067557, 998.9450580218121, 862.0948004595182, 961.1061712607273, 961.3078583419926, 970.4807927758686, 1001.7946321004213, 711.4379640084327, 732.9157146928064, 736.3446532586003, 747.1781040855016, 747.3946103656056, 12939.48242070316, 15180.316885740058, 15280.770788438182, 15287.159206243652, 15338.846611533854, 10673.466326683712, 12388.442520086617, 12430.627081187578, 12439.0, 12731.172473789175, 10069.54687039085, 10121.645742399054, 10235.385876847338, 10260.538275777608, 10479.899610540935, 3440.535527703753, 3452.879149229765, 3462.221000274943, 3570.7143428525656, 3711.2948539777626, 85753.57123214469, 85764.57117714372, 86407.0, 86500.70248946271, 87223.0, 9141.103685240123, 9412.0, 9428.62285508572, 9437.292203084797, 9659.227261819038, 21394.465138371666, 21415.892920535298, 21468.033938472807, 21580.920953952238, 21983.450413739785, 7314.597697126709, 7322.1579518355, 7369.042024536776, 7464.850702986018, 7497.1753107158165, 4004.5394779600138, 4015.7992100394863, 4067.023914260556, 4145.626893579592, 4195.328747400401, 3154.8264845433264, 3613.5663720353728, 3695.3533131701843, 3695.667389934865, 3702.648248416396, 644.2076246217179, 656.6913550631195, 661.9205695316593, 664.880321542127, 667.6227931218846, 508.84225889974226, 568.0938902450584, 592.736232376597, 604.0848115105531, 622.9937700623026, 7537.886931696029, 7913.248241417056, 8340.29107525855, 8433.072362040171, 8451.830963380698, 502.57281310886185, 580.9157672137601, 597.489146779506, 619.44250174843, 622.3030206169119, 3342.5320455136302, 3754.6057663944894, 3881.650651441327, 3902.23906338267, 4077.2660921033907, 83825.58087209647, 84129.0, 91738.08261917428, 95201.57197642041, 95308.52345738227, 69045.65477172582, 69618.3038169622, 70012.0, 70165.0, 74075.25924740791, 66343.6731265372, 66852.0, 67324.0, 67624.0, 67915.66042169856, 8478.32173426112, 8590.957045214735, 8669.393142479967, 8706.782330441789, 8720.088648411034, 47015.05969880515, 49124.0, 49378.75310623424, 49745.0, 50787.0, 53927.730361347945, 63335.6833215831, 65070.0, 66661.0000849978, 67314.0, 10898.782024359627, 11049.72375690582, 11217.326960382403, 11257.605983790529, 11341.546338146381, 9772.56023478931, 11778.293302401884, 12068.939655301667, 12111.394430278275, 12281.570145045238, 8428.410011299151, 9275.953620232125, 10169.389836609827, 10615.31000484966, 11412.82880756806, 11169.329840209613, 11288.492017859051, 11625.76748465009, 11692.47383867711, 11706.297622142698, 9554.713358599252, 10838.241323107306, 11185.38480383554, 11220.495077721358, 11520.366379848854, 9542.475163865776, 9763.560639771085, 9785.21718262523, 9787.608495660093, 9811.411315321104, 9544.904550954401, 9874.950625246827, 9890.752731181778, 9897.554610042422, 9984.950075249577], "value": 6852.717584098236, "unit": "score"}], "name": "dromaeo_css", "value": 7489.724432755554, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 141.0, 116.0, 120.0, 118.0, 108.0, 118.0, 114.0, 115.0], "value": 118.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 131.0, 160.0, 155.0, 122.0, 152.0, 122.0, 157.0, 160.0, 128.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 200.0, 202.0, 215.0, 198.0, 195.0, 199.0, 199.0, 196.0, 197.0], "value": 200.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 85.0, 109.0, 158.0, 56.0, 56.0, 56.0, 61.0, 142.0, 80.0], "value": 87.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 124.0, 77.0, 79.0, 92.0, 118.0, 80.0, 79.0, 121.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 85.0, 90.0, 86.0, 88.0, 86.0, 88.0, 86.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 95.0, 94.0, 94.0, 118.0, 148.0, 96.0, 93.0, 95.0, 95.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 105.0, 90.0, 93.0, 94.0, 91.0, 91.0, 92.0, 91.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 66.0, 65.0, 65.0, 67.0, 64.0, 81.0, 78.0, 76.0], "value": 68.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 67.0, 78.0, 90.0, 91.0, 103.0, 100.0, 98.0, 98.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 108.0, 75.0, 97.0, 72.0, 99.0, 97.0, 104.0, 107.0, 107.0], "value": 95.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 150.0, 152.0, 147.0, 157.0, 134.0, 128.0, 130.0, 150.0, 153.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 164.0, 218.0, 226.0, 160.0, 211.0, 212.0, 215.0, 169.0, 167.0], "value": 196.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 78.0, 95.0, 63.0, 56.0, 91.0, 97.0, 91.0, 54.0], "value": 76.4, "unit": "score"}], "name": "kraken", "value": 1594.1999999999998, "alertThreshold": 2.0}]} 11:36:50 INFO - Return code: 0 11:36:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:36:50 ERROR - # TBPL SUCCESS # 11:36:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:36:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:36:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:36:50 INFO - 2016-05-27 11:36:50.497 screenresolution[1691:11013] starting screenresolution argv=screenresolution get 11:36:50 INFO - 2016-05-27 11:36:50.500 screenresolution[1691:11013] Display 0: 1600x1200x32@60 11:36:50 INFO - 2016-05-27 11:36:50.506 screenresolution[1692:11017] starting screenresolution argv=screenresolution list 11:36:50 INFO - Available Modes on Display 0 11:36:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:36:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:36:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:36:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:36:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:36:50 INFO - Intel Iris: 11:36:50 INFO - Chipset Model: Intel Iris 11:36:50 INFO - Type: GPU 11:36:50 INFO - Bus: Built-In 11:36:50 INFO - VRAM (Dynamic, Max): 1536 MB 11:36:50 INFO - Vendor: Intel (0x8086) 11:36:50 INFO - Device ID: 0x0a2e 11:36:50 INFO - Revision ID: 0x0009 11:36:50 INFO - Displays: 11:36:50 INFO - Display: 11:36:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:36:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:36:50 INFO - Display Serial Number: 1600x1200 60 11:36:50 INFO - Main Display: Yes 11:36:50 INFO - Mirror: Off 11:36:50 INFO - Online: Yes 11:36:50 INFO - Rotation: Supported 11:36:50 INFO - Return code: 0 11:36:50 INFO - Running post-action listener: _resource_record_post_action 11:36:50 INFO - Running post-run listener: _resource_record_post_run 11:36:51 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 32718336; Write bytes: 656684544; Read time: 313; Write time: 1955 11:36:51 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345801216; Write bytes: 346498560; Read time: 26522; Write time: 662 11:36:51 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 31796736; Write bytes: 300228608; Read time: 300; Write time: 1259 11:36:51 INFO - Running post-run listener: _upload_blobber_files 11:36:51 INFO - Blob upload gear active. 11:36:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:36:51 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 11:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:36:52 INFO - (blobuploader) - INFO - Open directory for files ... 11:36:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:36:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:36:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:36:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:36:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:36:53 INFO - (blobuploader) - INFO - Done attempting. 11:36:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:36:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:36:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:36:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:36:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:36:54 INFO - (blobuploader) - INFO - Done attempting. 11:36:54 INFO - (blobuploader) - INFO - Iteration through files over. 11:36:54 INFO - Return code: 0 11:36:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:36:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:36:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b8b5b392dd3ebe37914d1fac4fecf1b4c121fd90af3c539b81d61de4c720e6dff8ee64e492b79ade867045641f207b6da3e38466ad4f26636d774a3bad56347", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/384cfe94578a638791b1509ebeaf059d1329362d2238b14cb88d295d06554d1f1b11c5e121e43d682c61fae6c610f4ff8649d6c1edce2232e711e973ee405851"} 11:36:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:36:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:36:54 INFO - Contents: 11:36:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b8b5b392dd3ebe37914d1fac4fecf1b4c121fd90af3c539b81d61de4c720e6dff8ee64e492b79ade867045641f207b6da3e38466ad4f26636d774a3bad56347", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/384cfe94578a638791b1509ebeaf059d1329362d2238b14cb88d295d06554d1f1b11c5e121e43d682c61fae6c610f4ff8649d6c1edce2232e711e973ee405851"} 11:36:54 INFO - Running post-run listener: copy_logs_to_upload_dir 11:36:54 INFO - Copying logs to upload dir... 11:36:54 INFO - mkdir: /builds/slave/test/build/upload/logs 11:36:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.716426 ========= master_lag: 0.54 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-05-27 11:36:55.015619) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 11:36:55.020958) ========= 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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b8b5b392dd3ebe37914d1fac4fecf1b4c121fd90af3c539b81d61de4c720e6dff8ee64e492b79ade867045641f207b6da3e38466ad4f26636d774a3bad56347", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/384cfe94578a638791b1509ebeaf059d1329362d2238b14cb88d295d06554d1f1b11c5e121e43d682c61fae6c610f4ff8649d6c1edce2232e711e973ee405851"} build_url:https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/Dg4OwvgpSiqmHTc0jIrS5g/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8b8b5b392dd3ebe37914d1fac4fecf1b4c121fd90af3c539b81d61de4c720e6dff8ee64e492b79ade867045641f207b6da3e38466ad4f26636d774a3bad56347", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/384cfe94578a638791b1509ebeaf059d1329362d2238b14cb88d295d06554d1f1b11c5e121e43d682c61fae6c610f4ff8649d6c1edce2232e711e973ee405851"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 11:36:55.051658) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:36:55.052058) ========= 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.7jGoE4fmHR/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.1xoUdsYmV7/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.005182 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:36:55.100454) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 11:36:55.100781) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 11:36:55.101121) ========= ========= Total master_lag: 0.66 =========