builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0013 starttime: 1464394701.36 results: success (0) buildid: 20160527151144 builduid: 5f3e6f6a3dc94ab6a9ca5b77e66fbee2 revision: f8e493cd1e87a482c0246f4a8fe4229142bc1ccc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.362190) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.362799) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.363263) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.404606) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.405361) ========= 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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:18:21-- 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% 148M=0s 2016-05-27 17:18:21 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.538113 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.964782) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:21.965090) ========= 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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.020556 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:22.013667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 17:18:22.014038) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f8e493cd1e87a482c0246f4a8fe4229142bc1ccc --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 f8e493cd1e87a482c0246f4a8fe4229142bc1ccc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.STyvZnxLVJ/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.uUpp0ANf9F/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:18:22,091 truncating revision to first 12 chars 2016-05-27 17:18:22,091 Setting DEBUG logging. 2016-05-27 17:18:22,091 attempt 1/10 2016-05-27 17:18:22,091 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f8e493cd1e87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 17:18:22,843 unpacking tar archive at: mozilla-aurora-f8e493cd1e87/testing/mozharness/ program finished with exit code 0 elapsedTime=1.029656 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 17:18:23.067734) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:23.068256) ========= script_repo_revision: f8e493cd1e87a482c0246f4a8fe4229142bc1ccc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:23.068972) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:23.069489) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 17:18:23.088310) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 5 secs) (at 2016-05-27 17:18:23.088705) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.STyvZnxLVJ/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.uUpp0ANf9F/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:18:23 INFO - MultiFileLogger online at 20160527 17:18:23 in /builds/slave/test 17:18:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:18:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:18:23 INFO - {'append_to_log': False, 17:18:23 INFO - 'base_work_dir': '/builds/slave/test', 17:18:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:18:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:18:23 INFO - 'branch': 'Mozilla-Aurora', 17:18:23 INFO - 'buildbot_json_path': 'buildprops.json', 17:18:23 INFO - 'config_files': ('talos/mac_config.py',), 17:18:23 INFO - 'default_actions': ('clobber', 17:18:23 INFO - 'read-buildbot-config', 17:18:23 INFO - 'download-and-extract', 17:18:23 INFO - 'populate-webroot', 17:18:23 INFO - 'create-virtualenv', 17:18:23 INFO - 'install', 17:18:23 INFO - 'run-tests'), 17:18:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:18:23 INFO - 'download_minidump_stackwalk': True, 17:18:23 INFO - 'download_symbols': 'ondemand', 17:18:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:18:23 INFO - 'tooltool.py': '/tools/tooltool.py', 17:18:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:18:23 INFO - '/tools/misc-python/virtualenv.py')}, 17:18:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:18:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:18:23 INFO - 'installer_path': 'installer.exe', 17:18:23 INFO - 'log_level': 'info', 17:18:23 INFO - 'log_name': 'talos', 17:18:23 INFO - 'log_to_console': True, 17:18:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:18:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:18:23 INFO - 'opt_config_files': (), 17:18:23 INFO - 'pip_index': False, 17:18:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:18:23 INFO - 'cmd': ('bash', 17:18:23 INFO - '-c', 17:18:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:18:23 INFO - 'enabled': True, 17:18:23 INFO - 'halt_on_failure': False, 17:18:23 INFO - 'name': 'check_screen_resolution'},), 17:18:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:18:23 INFO - 'cmd': ('bash', 17:18:23 INFO - '-c', 17:18:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:18:23 INFO - 'enabled': True, 17:18:23 INFO - 'halt_on_failure': False, 17:18:23 INFO - 'name': 'check_screen_resolution'},), 17:18:23 INFO - 'run_cmd_checks_enabled': True, 17:18:23 INFO - 'sps_profile': False, 17:18:23 INFO - 'sps_profile_interval': 0, 17:18:23 INFO - 'suite': 'dromaeojs', 17:18:23 INFO - 'system_bits': '32', 17:18:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:18:23 INFO - 'title': 't-yosemite-r7-0013', 17:18:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:18:23 INFO - 'use_talos_json': True, 17:18:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:18:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:18:23 INFO - 'work_dir': 'build'} 17:18:23 INFO - ##### 17:18:23 INFO - ##### Running clobber step. 17:18:23 INFO - ##### 17:18:23 INFO - Running pre-action listener: _resource_record_pre_action 17:18:23 INFO - Running main action method: clobber 17:18:23 INFO - rmtree: /builds/slave/test/build 17:18:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:18:23 INFO - Running post-action listener: _resource_record_post_action 17:18:23 INFO - ##### 17:18:23 INFO - ##### Running read-buildbot-config step. 17:18:23 INFO - ##### 17:18:23 INFO - Running pre-action listener: _resource_record_pre_action 17:18:23 INFO - Running main action method: read_buildbot_config 17:18:23 INFO - Using buildbot properties: 17:18:23 INFO - { 17:18:23 INFO - "project": "", 17:18:23 INFO - "product": "firefox", 17:18:23 INFO - "script_repo_revision": "production", 17:18:23 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 17:18:23 INFO - "repository": "", 17:18:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 17:18:23 INFO - "buildid": "20160527151144", 17:18:23 INFO - "pgo_build": "False", 17:18:23 INFO - "basedir": "/builds/slave/test", 17:18:23 INFO - "buildnumber": 11, 17:18:23 INFO - "slavename": "t-yosemite-r7-0013", 17:18:23 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:18:23 INFO - "platform": "yosemite_r7", 17:18:23 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:18:23 INFO - "branch": "mozilla-aurora", 17:18:23 INFO - "repo_path": "releases/mozilla-aurora", 17:18:23 INFO - "revision": "f8e493cd1e87a482c0246f4a8fe4229142bc1ccc", 17:18:23 INFO - "stage_platform": "macosx64", 17:18:23 INFO - "builduid": "5f3e6f6a3dc94ab6a9ca5b77e66fbee2", 17:18:23 INFO - "slavebuilddir": "test" 17:18:23 INFO - } 17:18:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 17:18:23 INFO - Running post-action listener: _resource_record_post_action 17:18:23 INFO - ##### 17:18:23 INFO - ##### Running download-and-extract step. 17:18:23 INFO - ##### 17:18:23 INFO - Running pre-action listener: _resource_record_pre_action 17:18:23 INFO - Running main action method: download_and_extract 17:18:23 INFO - mkdir: /builds/slave/test/build/tests 17:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:18:23 INFO - https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:18:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:18:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:18:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/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 17:18:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/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 17:18:53 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 17:18:53 INFO - retry: attempt #1 caught exception: timed out 17:18:53 INFO - retry: Failed, sleeping 30 seconds before retrying 17:19:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/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 #2 17:19:25 INFO - Downloaded 1199 bytes. 17:19:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 17:19:25 INFO - Using the following test package requirements: 17:19:25 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:19:25 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:19:25 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 17:19:25 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:19:25 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 17:19:25 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:19:25 INFO - u'jsshell-mac.zip'], 17:19:25 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:19:25 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 17:19:25 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:19:25 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:19:25 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 17:19:25 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:19:25 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 17:19:25 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:19:25 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 17:19:25 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:19:25 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 17:19:25 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:25 INFO - https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:19:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:19:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:19:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/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 17:19:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/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 17:19:27 INFO - Downloaded 18425618 bytes. 17:19:27 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'] 17:19:27 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 17:19:30 INFO - Return code: 0 17:19:30 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 17:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:30 INFO - https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:19:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:19:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:19:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/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 17:19:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/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 17:19:32 INFO - Downloaded 18425618 bytes. 17:19:32 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'] 17:19:32 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 17:19:36 INFO - Return code: 0 17:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:36 INFO - https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:19:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:19:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:19:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/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 17:19:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/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 17:19:37 INFO - Downloaded 10840145 bytes. 17:19:37 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'] 17:19:37 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 17:19:37 INFO - Return code: 0 17:19: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')]} 17:19:37 INFO - https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 17:19:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:19:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:19:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 17:19:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LtwiupzRRJOcSQHBnqSX9w/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 17:19:41 INFO - Downloaded 114147676 bytes. 17:19:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:19:41 INFO - mkdir: /builds/slave/test/properties 17:19:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:19:41 INFO - Writing to file /builds/slave/test/properties/build_url 17:19:41 INFO - Contents: 17:19:41 INFO - build_url:https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:19:42 INFO - Running post-action listener: _resource_record_post_action 17:19:42 INFO - Running post-action listener: set_extra_try_arguments 17:19:42 INFO - ##### 17:19:42 INFO - ##### Running populate-webroot step. 17:19:42 INFO - ##### 17:19:42 INFO - Running pre-action listener: _resource_record_pre_action 17:19:42 INFO - Running main action method: populate_webroot 17:19:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:19:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:19:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:19:42 INFO - '--tppagecycles', 17:19:42 INFO - '7'], 17:19:42 INFO - 'tests': ['tsvgm']}}, 17:19:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:19:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:19:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:19:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:19:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:19:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:19:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:42 INFO - 'talos_options': ['--disable-e10s'], 17:19:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:19:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:19:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:42 INFO - 'talos_options': ['--disable-e10s'], 17:19:42 INFO - 'tests': ['damp', 'tps']}, 17:19:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:42 INFO - 'tests': ['damp', 'tps']}, 17:19:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:19:42 INFO - 'tests': ['dromaeo_dom']}, 17:19:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:19:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:19:42 INFO - 'tests': ['a11yr', 17:19:42 INFO - 'ts_paint', 17:19:42 INFO - 'tpaint', 17:19:42 INFO - 'sessionrestore', 17:19:42 INFO - 'sessionrestore_no_auto_restore', 17:19:42 INFO - 'tabpaint']}, 17:19:42 INFO - 'other-e10s': {'tests': ['a11yr', 17:19:42 INFO - 'ts_paint', 17:19:42 INFO - 'tpaint', 17:19:42 INFO - 'sessionrestore', 17:19:42 INFO - 'sessionrestore_no_auto_restore', 17:19:42 INFO - 'tabpaint']}, 17:19:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:19:42 INFO - 'ts_paint', 17:19:42 INFO - 'tpaint', 17:19:42 INFO - 'sessionrestore', 17:19:42 INFO - 'sessionrestore_no_auto_restore', 17:19:42 INFO - 'tabpaint']}, 17:19:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:19:42 INFO - 'ts_paint', 17:19:42 INFO - 'tpaint', 17:19:42 INFO - 'sessionrestore', 17:19:42 INFO - 'sessionrestore_no_auto_restore', 17:19:42 INFO - 'tabpaint']}, 17:19:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:19:42 INFO - 'tests': ['a11yr', 17:19:42 INFO - 'ts_paint', 17:19:42 INFO - 'tpaint', 17:19:42 INFO - 'sessionrestore', 17:19:42 INFO - 'sessionrestore_no_auto_restore', 17:19:42 INFO - 'tabpaint']}, 17:19:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:19:42 INFO - 'tests': ['a11yr', 17:19:42 INFO - 'ts_paint', 17:19:42 INFO - 'tpaint', 17:19:42 INFO - 'sessionrestore', 17:19:42 INFO - 'sessionrestore_no_auto_restore', 17:19:42 INFO - 'tabpaint']}, 17:19:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:19:42 INFO - 'tests': ['tsvgx', 17:19:42 INFO - 'tsvgr_opacity', 17:19:42 INFO - 'tart', 17:19:42 INFO - 'tscrollx', 17:19:42 INFO - 'cart']}, 17:19:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:19:42 INFO - 'tsvgr_opacity', 17:19:42 INFO - 'tart', 17:19:42 INFO - 'tscrollx', 17:19:42 INFO - 'cart']}, 17:19:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:42 INFO - 'talos_options': ['--disable-e10s'], 17:19:42 INFO - 'tests': ['tp5o']}, 17:19:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:42 INFO - 'tests': ['tp5o']}, 17:19:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:42 INFO - 'talos_options': ['--disable-e10s', 17:19:42 INFO - '--xperf_path', 17:19:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:19:42 INFO - 'tests': ['tp5n']}, 17:19:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:19:42 INFO - 'talos_options': ['--xperf_path', 17:19:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:19:42 INFO - 'tests': ['tp5n']}}, 17:19:42 INFO - 'talos.zip': {'path': '', 17:19:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:19:42 INFO - Running post-action listener: _resource_record_post_action 17:19:42 INFO - ##### 17:19:42 INFO - ##### Running create-virtualenv step. 17:19:42 INFO - ##### 17:19:42 INFO - Running pre-action listener: _resource_record_pre_action 17:19:42 INFO - Running main action method: create_virtualenv 17:19:42 INFO - Creating virtualenv /builds/slave/test/build/venv 17:19:42 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 17:19:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:19:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:19:42 INFO - Using real prefix '/tools/python27' 17:19:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:19:43 INFO - Installing distribute.............................................................................................................................................................................................done. 17:19:46 INFO - Installing pip.................done. 17:19:46 INFO - Return code: 0 17:19:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:19:46 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')]} 17:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:46 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')]} 17:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:46 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 0x10a062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b8adb11d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f01e00>, '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 0x109d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109efcb20>, '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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:19:46 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 17:19:46 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 17:19:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.STyvZnxLVJ/Render', 17:19:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:46 INFO - 'HOME': '/Users/cltbld', 17:19:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:46 INFO - 'LOGNAME': 'cltbld', 17:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:46 INFO - 'MOZ_NO_REMOTE': '1', 17:19:46 INFO - 'NO_EM_RESTART': '1', 17:19:46 INFO - 'PAGER': '/bin/cat', 17:19:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:46 INFO - 'PWD': '/builds/slave/test', 17:19:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:46 INFO - 'SHELL': '/bin/bash', 17:19:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUpp0ANf9F/Listeners', 17:19:46 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 17:19:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:46 INFO - 'USER': 'cltbld', 17:19:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:46 INFO - 'XPC_FLAGS': '0x0', 17:19:46 INFO - 'XPC_SERVICE_NAME': '0', 17:19:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:46 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 17:19:46 INFO - Cleaning up... 17:19:46 INFO - Return code: 0 17:19:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:19:46 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')]} 17:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:46 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')]} 17:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:46 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 0x10a062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b8adb11d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f01e00>, '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 0x109d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109efcb20>, '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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:19:46 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 17:19:46 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 17:19:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.STyvZnxLVJ/Render', 17:19:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:46 INFO - 'HOME': '/Users/cltbld', 17:19:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:46 INFO - 'LOGNAME': 'cltbld', 17:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:46 INFO - 'MOZ_NO_REMOTE': '1', 17:19:46 INFO - 'NO_EM_RESTART': '1', 17:19:46 INFO - 'PAGER': '/bin/cat', 17:19:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:46 INFO - 'PWD': '/builds/slave/test', 17:19:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:46 INFO - 'SHELL': '/bin/bash', 17:19:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUpp0ANf9F/Listeners', 17:19:46 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 17:19:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:46 INFO - 'USER': 'cltbld', 17:19:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:46 INFO - 'XPC_FLAGS': '0x0', 17:19:46 INFO - 'XPC_SERVICE_NAME': '0', 17:19:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:46 INFO - Downloading/unpacking psutil>=0.7.1 17:19:46 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 17:19:46 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 17:19:46 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 17:19:46 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 17:19:46 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 17:19:46 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 17:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:19:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:19:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:19:50 INFO - Installing collected packages: psutil 17:19:50 INFO - Running setup.py install for psutil 17:19:50 INFO - building 'psutil._psutil_osx' extension 17:19:50 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 17:19:50 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 17:19:50 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 17:19:50 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 17:19:50 INFO - building 'psutil._psutil_posix' extension 17:19:50 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 17:19:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:19:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:19:50 INFO - ^ 17:19:50 INFO - 1 warning generated. 17:19:50 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 17:19:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:19:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:19:50 INFO - Successfully installed psutil 17:19:50 INFO - Cleaning up... 17:19:50 INFO - Return code: 0 17:19:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:19:50 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')]} 17:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:50 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')]} 17:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:50 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 0x10a062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b8adb11d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f01e00>, '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 0x109d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109efcb20>, '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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:19:50 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 17:19:50 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 17:19:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.STyvZnxLVJ/Render', 17:19:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:50 INFO - 'HOME': '/Users/cltbld', 17:19:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:50 INFO - 'LOGNAME': 'cltbld', 17:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:50 INFO - 'MOZ_NO_REMOTE': '1', 17:19:50 INFO - 'NO_EM_RESTART': '1', 17:19:50 INFO - 'PAGER': '/bin/cat', 17:19:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:50 INFO - 'PWD': '/builds/slave/test', 17:19:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:50 INFO - 'SHELL': '/bin/bash', 17:19:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUpp0ANf9F/Listeners', 17:19:50 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 17:19:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:50 INFO - 'USER': 'cltbld', 17:19:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:50 INFO - 'XPC_FLAGS': '0x0', 17:19:50 INFO - 'XPC_SERVICE_NAME': '0', 17:19:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:19:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:19:53 INFO - Installing collected packages: mozsystemmonitor 17:19:53 INFO - Running setup.py install for mozsystemmonitor 17:19:53 INFO - Successfully installed mozsystemmonitor 17:19:53 INFO - Cleaning up... 17:19:53 INFO - Return code: 0 17:19:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:19:53 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')]} 17:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:53 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')]} 17:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:53 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 0x10a062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b8adb11d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f01e00>, '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 0x109d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109efcb20>, '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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:19:53 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 17:19:53 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 17:19:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.STyvZnxLVJ/Render', 17:19:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:53 INFO - 'HOME': '/Users/cltbld', 17:19:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:53 INFO - 'LOGNAME': 'cltbld', 17:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:53 INFO - 'MOZ_NO_REMOTE': '1', 17:19:53 INFO - 'NO_EM_RESTART': '1', 17:19:53 INFO - 'PAGER': '/bin/cat', 17:19:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:53 INFO - 'PWD': '/builds/slave/test', 17:19:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:53 INFO - 'SHELL': '/bin/bash', 17:19:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUpp0ANf9F/Listeners', 17:19:53 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 17:19:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:53 INFO - 'USER': 'cltbld', 17:19:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:53 INFO - 'XPC_FLAGS': '0x0', 17:19:53 INFO - 'XPC_SERVICE_NAME': '0', 17:19:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:54 INFO - Downloading/unpacking blobuploader==1.2.4 17:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:56 INFO - Downloading blobuploader-1.2.4.tar.gz 17:19:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:19:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:19:56 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 17:19:56 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 17:19:56 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 17:19:56 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 17:19:56 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 17:19:56 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 17:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:19:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:19:57 INFO - Downloading docopt-0.6.1.tar.gz 17:19:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:19:57 INFO - Installing collected packages: blobuploader, requests, docopt 17:19:57 INFO - Running setup.py install for blobuploader 17:19:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:19:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:19:58 INFO - Running setup.py install for requests 17:19:58 INFO - Running setup.py install for docopt 17:19:58 INFO - Successfully installed blobuploader requests docopt 17:19:58 INFO - Cleaning up... 17:19:58 INFO - Return code: 0 17:19:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:19:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:19:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:19:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:19:58 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 0x10a062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b8adb11d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f01e00>, '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 0x109d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109efcb20>, '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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:19:58 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 17:19:58 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 17:19:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.STyvZnxLVJ/Render', 17:19:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:19:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:19:58 INFO - 'HOME': '/Users/cltbld', 17:19:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:19:58 INFO - 'LOGNAME': 'cltbld', 17:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:19:58 INFO - 'MOZ_NO_REMOTE': '1', 17:19:58 INFO - 'NO_EM_RESTART': '1', 17:19:58 INFO - 'PAGER': '/bin/cat', 17:19:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:19:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:19:58 INFO - 'PWD': '/builds/slave/test', 17:19:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:19:58 INFO - 'SHELL': '/bin/bash', 17:19:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUpp0ANf9F/Listeners', 17:19:58 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 17:19:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:19:58 INFO - 'USER': 'cltbld', 17:19:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:19:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:19:58 INFO - 'XPC_FLAGS': '0x0', 17:19:58 INFO - 'XPC_SERVICE_NAME': '0', 17:19:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:19:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:19:59 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-_TkQ47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:19:59 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-NF5eQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:19:59 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-qOA0LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:19:59 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-xcw6uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:19:59 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-asJPKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:19:59 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-YYWXzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:19:59 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-DtcoXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:19:59 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-pWGO3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:19:59 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-pqNJMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:19:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:19:59 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-670CCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:00 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-dsAMrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:00 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-HKF_96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:00 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-1Zjxvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:00 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-lQJfT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:00 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-VT1iZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:00 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-YKDPzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:00 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-nu3oA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:20:00 INFO - Running setup.py install for manifestparser 17:20:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:20:00 INFO - Running setup.py install for mozcrash 17:20:01 INFO - Running setup.py install for mozdebug 17:20:01 INFO - Running setup.py install for mozdevice 17:20:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Running setup.py install for mozfile 17:20:01 INFO - Running setup.py install for mozhttpd 17:20:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Running setup.py install for mozinfo 17:20:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Running setup.py install for mozInstall 17:20:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:20:01 INFO - Running setup.py install for mozleak 17:20:02 INFO - Running setup.py install for mozlog 17:20:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Running setup.py install for moznetwork 17:20:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Running setup.py install for mozprocess 17:20:02 INFO - Running setup.py install for mozprofile 17:20:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Running setup.py install for mozrunner 17:20:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:20:02 INFO - Running setup.py install for mozscreenshot 17:20:03 INFO - Running setup.py install for moztest 17:20:03 INFO - Running setup.py install for mozversion 17:20:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:20:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:20:03 INFO - Cleaning up... 17:20:03 INFO - Return code: 0 17:20:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:03 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 0x10a062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b8adb11d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f01e00>, '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 0x109d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109efcb20>, '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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:20:03 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 17:20:03 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 17:20:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.STyvZnxLVJ/Render', 17:20:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:03 INFO - 'HOME': '/Users/cltbld', 17:20:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:03 INFO - 'LOGNAME': 'cltbld', 17:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:03 INFO - 'MOZ_NO_REMOTE': '1', 17:20:03 INFO - 'NO_EM_RESTART': '1', 17:20:03 INFO - 'PAGER': '/bin/cat', 17:20:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:03 INFO - 'PWD': '/builds/slave/test', 17:20:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:03 INFO - 'SHELL': '/bin/bash', 17:20:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUpp0ANf9F/Listeners', 17:20:03 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 17:20:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:03 INFO - 'USER': 'cltbld', 17:20:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:03 INFO - 'XPC_FLAGS': '0x0', 17:20:03 INFO - 'XPC_SERVICE_NAME': '0', 17:20:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:20:03 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-_lB_hB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:20:03 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-WL_P2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:20:03 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-_dLRLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:20:03 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)) 17:20:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:20:03 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-c9bq1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:20:04 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-gNqBTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:20:04 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-8FxH2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:20:04 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-hWgAuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:20:04 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-tKGPhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:20:04 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-Y9tsiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:20:04 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-S3Ys43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:20:04 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-J3x1xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:20:04 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-H7zeP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:20:04 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)) 17:20:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:20:04 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-P20h2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:20:05 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)) 17:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:20:05 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-OOmQjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:20:05 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)) 17:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:05 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-i6FhXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:20:05 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)) 17:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:20:05 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-JUt5Dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:20:05 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)) 17:20:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:20:05 INFO - Running setup.py (path:/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/pip-ufaZ_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:20:05 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)) 17:20:05 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)) 17:20:05 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)) 17:20:05 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)) 17:20:05 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)) 17:20:05 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)) 17:20:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:20:05 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 17:20:05 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 17:20:05 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 17:20:05 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 17:20:05 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 17:20:05 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 17:20:07 INFO - Downloading blessings-1.6.tar.gz 17:20:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:20:07 INFO - Installing collected packages: blessings 17:20:07 INFO - Running setup.py install for blessings 17:20:08 INFO - Successfully installed blessings 17:20:08 INFO - Cleaning up... 17:20:08 INFO - Return code: 0 17:20:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:20:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:20:08 INFO - Reading from file tmpfile_stdout 17:20:08 INFO - Current package versions: 17:20:08 INFO - blessings == 1.6 17:20:08 INFO - blobuploader == 1.2.4 17:20:08 INFO - docopt == 0.6.1 17:20:08 INFO - manifestparser == 1.1 17:20:08 INFO - mozInstall == 1.12 17:20:08 INFO - mozcrash == 0.17 17:20:08 INFO - mozdebug == 0.1 17:20:08 INFO - mozdevice == 0.48 17:20:08 INFO - mozfile == 1.2 17:20:08 INFO - mozhttpd == 0.7 17:20:08 INFO - mozinfo == 0.9 17:20:08 INFO - mozleak == 0.1 17:20:08 INFO - mozlog == 3.1 17:20:08 INFO - moznetwork == 0.27 17:20:08 INFO - mozprocess == 0.22 17:20:08 INFO - mozprofile == 0.28 17:20:08 INFO - mozrunner == 6.11 17:20:08 INFO - mozscreenshot == 0.1 17:20:08 INFO - mozsystemmonitor == 0.0 17:20:08 INFO - moztest == 0.7 17:20:08 INFO - mozversion == 1.4 17:20:08 INFO - psutil == 3.1.1 17:20:08 INFO - requests == 1.2.3 17:20:08 INFO - wsgiref == 0.1.2 17:20:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:08 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 0x10a062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b8adb11d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f01e00>, '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 0x109d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109efcb20>, '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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:20:08 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 17:20:08 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 17:20:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.STyvZnxLVJ/Render', 17:20:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:08 INFO - 'HOME': '/Users/cltbld', 17:20:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:08 INFO - 'LOGNAME': 'cltbld', 17:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:08 INFO - 'MOZ_NO_REMOTE': '1', 17:20:08 INFO - 'NO_EM_RESTART': '1', 17:20:08 INFO - 'PAGER': '/bin/cat', 17:20:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:08 INFO - 'PWD': '/builds/slave/test', 17:20:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:08 INFO - 'SHELL': '/bin/bash', 17:20:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUpp0ANf9F/Listeners', 17:20:08 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 17:20:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:08 INFO - 'USER': 'cltbld', 17:20:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:08 INFO - 'XPC_FLAGS': '0x0', 17:20:08 INFO - 'XPC_SERVICE_NAME': '0', 17:20:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:08 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)) 17:20:08 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)) 17:20:08 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)) 17:20:08 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)) 17:20:08 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)) 17:20:08 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)) 17:20:08 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)) 17:20:08 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)) 17:20:08 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)) 17:20:08 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)) 17:20:08 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)) 17:20:08 INFO - Cleaning up... 17:20:08 INFO - Return code: 0 17:20:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:08 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 0x10a062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b8adb11d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f01e00>, '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 0x109d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109efcb20>, '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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:20:08 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 17:20:08 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 17:20:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.STyvZnxLVJ/Render', 17:20:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:08 INFO - 'HOME': '/Users/cltbld', 17:20:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:08 INFO - 'LOGNAME': 'cltbld', 17:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:08 INFO - 'MOZ_NO_REMOTE': '1', 17:20:08 INFO - 'NO_EM_RESTART': '1', 17:20:08 INFO - 'PAGER': '/bin/cat', 17:20:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:08 INFO - 'PWD': '/builds/slave/test', 17:20:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:08 INFO - 'SHELL': '/bin/bash', 17:20:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUpp0ANf9F/Listeners', 17:20:08 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 17:20:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:08 INFO - 'USER': 'cltbld', 17:20:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:08 INFO - 'XPC_FLAGS': '0x0', 17:20:08 INFO - 'XPC_SERVICE_NAME': '0', 17:20:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:09 INFO - Downloading/unpacking jsonschema 17:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:20:11 INFO - Downloading/unpacking functools32 (from jsonschema) 17:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:11 INFO - Downloading functools32-3.2.3-2.tar.gz 17:20:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:20:12 INFO - warning: no files found matching '*.txt' 17:20:12 INFO - no previously-included directories found matching 'build' 17:20:12 INFO - no previously-included directories found matching 'dist' 17:20:12 INFO - no previously-included directories found matching '.git*' 17:20:12 INFO - Installing collected packages: jsonschema, functools32 17:20:12 INFO - Running setup.py install for functools32 17:20:12 INFO - warning: no files found matching '*.txt' 17:20:12 INFO - no previously-included directories found matching 'build' 17:20:12 INFO - no previously-included directories found matching 'dist' 17:20:12 INFO - no previously-included directories found matching '.git*' 17:20:12 INFO - Successfully installed jsonschema functools32 17:20:12 INFO - Cleaning up... 17:20:12 INFO - Return code: 0 17:20:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:20:12 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')]} 17:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:12 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')]} 17:20:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:12 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 0x10a062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b8adb11d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f01e00>, '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 0x109d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109efcb20>, '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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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 17:20:12 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 17:20:12 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 17:20:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.STyvZnxLVJ/Render', 17:20:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:12 INFO - 'HOME': '/Users/cltbld', 17:20:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:12 INFO - 'LOGNAME': 'cltbld', 17:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:12 INFO - 'MOZ_NO_REMOTE': '1', 17:20:12 INFO - 'NO_EM_RESTART': '1', 17:20:12 INFO - 'PAGER': '/bin/cat', 17:20:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:12 INFO - 'PWD': '/builds/slave/test', 17:20:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:12 INFO - 'SHELL': '/bin/bash', 17:20:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUpp0ANf9F/Listeners', 17:20:12 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 17:20:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:12 INFO - 'USER': 'cltbld', 17:20:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:12 INFO - 'XPC_FLAGS': '0x0', 17:20:12 INFO - 'XPC_SERVICE_NAME': '0', 17:20:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:12 INFO - Downloading/unpacking flake8 17:20:12 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 17:20:12 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 17:20:12 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 17:20:12 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 17:20:12 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 17:20:12 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 17:20:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:20:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:20:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20: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 17:20:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:20:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:20:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:20:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:20:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:20:16 INFO - no previously-included directories found matching 'docs/_build' 17:20:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:20:16 INFO - Running setup.py install for pep8 17:20:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:20:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:20:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:20:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:20:16 INFO - no previously-included directories found matching 'docs/_build' 17:20:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:20:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:20:16 INFO - Cleaning up... 17:20:16 INFO - Return code: 0 17:20:16 INFO - Running post-action listener: _resource_record_post_action 17:20:16 INFO - Running post-action listener: _start_resource_monitoring 17:20:16 INFO - Starting resource monitoring. 17:20:16 INFO - ##### 17:20:16 INFO - ##### Running install step. 17:20:16 INFO - ##### 17:20:16 INFO - Running pre-action listener: _resource_record_pre_action 17:20:16 INFO - Running main action method: install 17:20:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:20:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:20:17 INFO - Reading from file tmpfile_stdout 17:20:17 INFO - Detecting whether we're running mozinstall >=1.0... 17:20:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:20:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:20:17 INFO - Reading from file tmpfile_stdout 17:20:17 INFO - Output received: 17:20:17 INFO - Usage: mozinstall [options] installer 17:20:17 INFO - Options: 17:20:17 INFO - -h, --help show this help message and exit 17:20:17 INFO - -d DEST, --destination=DEST 17:20:17 INFO - Directory to install application into. [default: 17:20:17 INFO - "/builds/slave/test"] 17:20:17 INFO - --app=APP Application being installed. [default: firefox] 17:20:17 INFO - mkdir: /builds/slave/test/build/application 17:20:17 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'] 17:20:17 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 17:20:49 INFO - Reading from file tmpfile_stdout 17:20:49 INFO - Output received: 17:20:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:20:49 INFO - Running post-action listener: _resource_record_post_action 17:20:49 INFO - ##### 17:20:49 INFO - ##### Running run-tests step. 17:20:49 INFO - ##### 17:20:49 INFO - Running pre-action listener: _resource_record_pre_action 17:20:49 INFO - Running main action method: run_tests 17:20:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:20:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:20:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:20:49 INFO - 2016-05-27 17:20:49.531 screenresolution[2213:20463] starting screenresolution argv=screenresolution get 17:20:49 INFO - 2016-05-27 17:20:49.534 screenresolution[2213:20463] Display 0: 1600x1200x32@60 17:20:49 INFO - 2016-05-27 17:20:49.540 screenresolution[2214:20465] starting screenresolution argv=screenresolution list 17:20:49 INFO - Available Modes on Display 0 17:20:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:20:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:20:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:20:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:20:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:20:49 INFO - Intel Iris: 17:20:49 INFO - Chipset Model: Intel Iris 17:20:49 INFO - Type: GPU 17:20:49 INFO - Bus: Built-In 17:20:49 INFO - VRAM (Dynamic, Max): 1536 MB 17:20:49 INFO - Vendor: Intel (0x8086) 17:20:49 INFO - Device ID: 0x0a2e 17:20:49 INFO - Revision ID: 0x0009 17:20:49 INFO - Displays: 17:20:49 INFO - Display: 17:20:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:20:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:20:49 INFO - Display Serial Number: 1600x1200 60 17:20:49 INFO - Main Display: Yes 17:20:49 INFO - Mirror: Off 17:20:49 INFO - Online: Yes 17:20:49 INFO - Rotation: Supported 17:20:49 INFO - Return code: 0 17:20:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:20:49 INFO - Python 2.7.3 17:20:49 INFO - Return code: 0 17:20:49 INFO - grabbing minidump binary from tooltool 17:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:20:49 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f01e00>, '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 0x109d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109efcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:20:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:20:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:20:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:20:49 INFO - Return code: 0 17:20:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:20:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:20:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:20:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:20:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:20:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:20:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:20:50 INFO - Return code: 0 17:20:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:20:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:20:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.STyvZnxLVJ/Render', 17:20:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:50 INFO - 'HOME': '/Users/cltbld', 17:20:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:50 INFO - 'LOGNAME': 'cltbld', 17:20:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:20:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:20:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:50 INFO - 'MOZ_NO_REMOTE': '1', 17:20:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:20:50 INFO - 'NO_EM_RESTART': '1', 17:20:50 INFO - 'PAGER': '/bin/cat', 17:20:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:50 INFO - 'PWD': '/builds/slave/test', 17:20:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:20:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:50 INFO - 'SHELL': '/bin/bash', 17:20:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uUpp0ANf9F/Listeners', 17:20:50 INFO - 'TMPDIR': '/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/', 17:20:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:50 INFO - 'USER': 'cltbld', 17:20:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:50 INFO - 'XPC_FLAGS': '0x0', 17:20:50 INFO - 'XPC_SERVICE_NAME': '0', 17:20:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:20:51 INFO - mozversion application_buildid: 20160527151144 17:20:51 INFO - mozversion application_changeset: f8e493cd1e87a482c0246f4a8fe4229142bc1ccc 17:20:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:20:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:20:51 INFO - mozversion application_name: Firefox 17:20:51 INFO - mozversion application_remotingname: firefox-dev 17:20:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:20:51 INFO - mozversion application_vendor: Mozilla 17:20:51 INFO - mozversion application_version: 48.0a2 17:20:51 INFO - mozversion platform_buildid: 20160527151144 17:20:51 INFO - mozversion platform_changeset: f8e493cd1e87a482c0246f4a8fe4229142bc1ccc 17:20:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:20:51 INFO - mozversion platform_version: 48.0a2 17:20:51 INFO - using testdate: 1464394851 17:20:51 INFO - actual date: 1464394851 17:20:51 INFO - starting webserver on 'localhost:56270' 17:20:51 INFO - SUITE-START | Running 2 tests 17:20:51 INFO - TEST-START | dromaeo_css 17:20:51 INFO - operating with platform_type : mac_ 17:20:51 INFO - Initialising browser for dromaeo_css test... 17:20:51 INFO - TEST-INFO | started process 2232 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpZsXdnc/profile http://localhost:56270/getInfo.html) 17:20:53 INFO - PROCESS | 2232 | __metrics Screen width/height:1600/1200 17:20:53 INFO - PROCESS | 2232 | colorDepth:24 17:20:53 INFO - PROCESS | 2232 | Browser inner width/height: 1024/689 17:20:53 INFO - PROCESS | 2232 | __metrics 17:20:53 INFO - PROCESS | 2232 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:20:53 ERROR - PROCESS | 2232 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:20:54 INFO - TEST-INFO | 2232: exit 0 17:20:54 INFO - Browser initialized. 17:20:54 INFO - Running cycle 1/1 for dromaeo_css test... 17:20:54 INFO - TEST-INFO | started process 2234 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmpZsXdnc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:21:06 INFO - PROCESS | 2234 | RSS: Main: 211365888 17:21:06 INFO - PROCESS | 2234 | 17:21:57 INFO - PROCESS | 2234 | 1464394917386 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] 17:24:08 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:56270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56270/tests/dromaeo/cssquery-ext.html) 17:24:09 INFO - PROCESS | 2234 | RSS: Main: 340029440 17:24:09 INFO - PROCESS | 2234 | 17:27:12 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:56270/tests/dromaeo/cssquery-ext.html (next: http://localhost:56270/tests/dromaeo/cssquery-jquery.html) 17:27:13 INFO - PROCESS | 2234 | RSS: Main: 356995072 17:27:13 INFO - PROCESS | 2234 | 17:30:15 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:56270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56270/tests/dromaeo/cssquery-mootools.html) 17:30:15 INFO - PROCESS | 2234 | RSS: Main: 358141952 17:30:15 INFO - PROCESS | 2234 | 17:33:17 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:56270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56270/tests/dromaeo/cssquery-prototype.html) 17:33:18 INFO - PROCESS | 2234 | RSS: Main: 359473152 17:33:18 INFO - PROCESS | 2234 | 17:36:21 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:56270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56270/tests/dromaeo/cssquery-yui.html) 17:36:22 INFO - PROCESS | 2234 | RSS: Main: 329363456 17:36:22 INFO - PROCESS | 2234 | 17:39:25 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:56270/tests/dromaeo/cssquery-yui.html (next: http://localhost:56270/tests/dromaeo/cssquery-dojo.html) 17:39:25 INFO - PROCESS | 2234 | RSS: Main: 367792128 17:39:25 INFO - PROCESS | 2234 | 17:39:25 INFO - PROCESS | 2234 | __start_tp_report 17:39:25 INFO - PROCESS | 2234 | _x_x_mozilla_page_load 17:39:25 INFO - PROCESS | 2234 | _x_x_mozilla_page_load_details 17:39:25 INFO - PROCESS | 2234 | |i|pagename|runs| 17:39:25 INFO - PROCESS | 2234 | |0;dojo.html;2642.260167153197;3409.198838273006;3420.9486857697157;3432.1591210153515;3492.4761285807126;5660.801871934483;5677.43225677432;5678.687672178028;5684.090545512719;5689.516391106761;5673.773049078042;5724.685142317171;5728.541716662668;5739.110437882125;5743.396943320954;5058.873528161807;5089.796408143669;5164.406093299281;5221.738913054332;5273.6572122811895;5188.948110518903;5199.168133098705;5201.0378080055125;5213.009528189651;5215.947840521603;5149.0731668299695;5161.4322424533275;5184.248283998818;5194.194899790538;5195.818146364878;5204.34945631796;5238.423773384925;5240.79036838526;5243.4232234454175;5281.86795330116;4319.941614304484;5044.344235249431;5067.949320506785;5077.517635824591;5186.740662966874;5564.081926482126;5593.552515798727;5596.692181929992;5604.971975140139;5630.0991841305195;6214.937850621481;6220.68896555175;6247.5939063960695;6265.59273647216;6288.308286088527;6132.969335153341;6186.505079593621;6285.43431091204;6298.622082675054;6304.905426418607;5803.245578074908;5831.212786273841;5832.591718579701;5834.212381328552;5883.17635531026;6224.253089629274;6232.439080482778;6255.218097737783;6265.780697675559;6290.716917738621;6013.699315034319;6079.118527813443;6123.479504242189;6160.260768707783;6160.907586386208;6246.219222597176;6251.218597675291;6261.874762504815;6265.373462653698;6266.937330626635;6135.386461353918;6186.752529898844;6208.81373558794;6215.9378406215355;6231.376862313732;6082.878342433127;6142.989934931851;6155.72299246526;6155.907661385083;6220.440160385497;4855.198892182751;4866.46468888422;4888.315635810989;4891.290762839369;4899.706017638953;4379.693421460531;4419.580139886705;4489;4503.797329120198;4514.345419914094;5583.994880921392;5638.295213098363;5643.5203007743985;5657.745401456944;5678.5741069419955;6139.079138129317;6195.690215489298;6207.131001659771;6285;6300.9684951574945;5543.944560554342;5641.435856414408;5647.632903861236;5682.062459694104;5682.204491371212;5342.171963345688;5348.090824559757;5354.892902141935;5360.785568577291;5372.086745253238;6214.40963108504;6215.564910456405;6232.563720539519;6271.4669253114;6293.842653933688;6222.191115155182;6229.750809967551;6274.372562743689;6292.339304372975;6298.874022519615;6265.906011409741;6291.465225455704;6307.463865571294;6312.242530896322;6318.526110541636;6020.397960203944;6090.177825993612;6107.310189946561;6200.224971878515;6203.06953956892;6222.688865556795;6253.6560489173535;6257.061440783918;6259.718312675849;6262.46769024638;6070.3929607038945;6104.908426373697;6110.388961103854;6115.755369785159;6116.785912493041;5966.582339236212;6036;6038.728257228523;6093.146959425595;6117.990531562243;5278.445763194809;5927.31835838884;5936.495397891227;5944.524438045032;5953.226080609493;6080.90878636812;6094.969525152346;6095.985691573962;6109.602935473257;6110.315755985069;5851.912221316598;5908.113782932594;5944.970275148597;5957.046872500379;6006.669633170043 17:39:25 INFO - PROCESS | 2234 | |1;ext.html;494.38449130832095;521.4863359590805;533.5891363649989;561.5563704673307;607.1348328631701;29434.558481622767;29602;39019.41470877935;39083.80458097713;39253;41814.58185418145;42428.78785606068;42452.363214551806;42469;42484.36273455901;8503.702370417037;9407.670731524415;9621.711348659517;9625.422474651543;9641.325107242496;8581.056083830774;11391.943040284787;11589.768204635944;11606.071514278838;11609;11389.772204555946;11478;11478.655640330762;11486.827697584502;11564.479598418091;8654.35092368075;11369.317840929529;11444.14168937333;11459.541618335339;11678.124140689482;11274.661760147208;11369.488373023234;11386.316820990683;11396.601118960796;11402.315861048364;38563;39082.60917390818;39099.41350879739;39132.60867391318;39166.60833391658;3198.2164369729567;3206.903792886217;3475.6524347565296;3601.3157500074904;3822.923541529182;28174.859125704446;28200;28553;28763.56854647182;28884;2665.6801183857865;2706.2422521693957;2789.0656630028966;2829.0381270368175;2881.5821705852745;480769;481076;481677.5916120432;482406;482482;36647.26705465929;37377.81311093482;37444.43833342502;37596.81201594029;60260;31014.534781978287;35803.28393432117;36117.09707257339;36268;36271.63728362735;15599.844001559839;15738.449154279546;15756.369745210293;15772.92113539425;15776.211189440482;12350.0737444692;12526.686832829253;13092.083554151308;13166.670833229055;13569.592912212649;8195.631196596167;8202.958985205036;8213.835723285501;8277.461964972257;8297.087320394752;3406.199543107368;3452.7583069185416;3454.240067185215;3458.688718015391;3483.965160348415;1019.021739130443;1060.192372005744;1063.7659714862873;1101.884302148279;1117.709395557161;30203;30426.78292868305;30811.8459407706;31047.68952310493;31156;1093.6281664234198;1106.280917403694;1113.8384934184498;1243.1919252485957;1264.2414551269349;15494.5351639451;15667.529974100795;15851.841481585037;15954.361825526848;16176.514704558882;557148;559931;562535;564439;566664;54775.178372324444;60475.69762151161;61818.69090654518;62543.6872815633;62550.68724656347;1358.639960410492;1392.8746412822895;1491.843356447579;1512.9258226659194;1515.9393624255126;29768.553471698375;30564.694353056184;30962.690373095982;31481;32074;57141.42858571361;58054.419455806594;58056.54858628568;58109;58119;523482.38258809986;542505;542916;543365;544872;34861.61587922747;34927.12682182864;43869;44977.55022449734;45790.54209457996;44272.77863610661;44909;45449.545504544534;45918.622441326814;46816.53183468258;37467.06332341713;38159.85520434391;38699.2260154801;39030;39897;38886.6111338883;39044.804775975936;39402;39637.60362396455;40269.39595906122;38431.80784096062;39151.82544523669;39406.40890386589;39661.2067758649;39724;32990.83504582462;34104.97273209966;34109.488357674156;34615;34697;21616.281564491303;25530.617040744033;25640;25775.742242578086;26562.46875062449 17:39:25 INFO - PROCESS | 2234 | |2;jquery.html;395.2115529518611;396.25108544850235;397.6997366987924;398.481973434535;410.1387087117055;15144.621384465394;15318;19946.800531994675;20510.487237819063;20556.38330850075;15029.549113526584;16162.676746465093;20396.694049589267;20503.38489845307;20790.584188316225;5557.555395568365;5681.517071048946;5837.854053648672;5854.18041474194;5870.647761134324;3426.2119712466024;5730.36965933747;5828.591998560102;5846.707664616773;6222.751089956397;6467.385598368148;6471.4175724184815;6476.838079048038;6485.83785405364;6489.5781774184525;6190.195274614294;6443.548951573392;6444.935550644481;6494.93505064948;6507.8698426031215;5700.40145784695;5700.686462244575;5700.973824711592;5707.286589176353;5708.3720790713405;24558.631620525706;24777.62833557498;25659.486810263876;25663.101791437395;26079.869600651877;2249.6625506174205;2279.5099053703534;2908.970910290891;2940.3972185702096;2962.155785601093;13088.149270297401;14246.07400518963;14744.262786860614;14753.11481311114;14767;1540.8613224809708;1683.797944246686;1729.4033558422325;1737.9044152571603;1740.8607311415055;110621;113184.86815131825;113837;115237.27144092845;119125;10770.946145269223;10970;11358.545658173745;11401.20191586581;11537.538498460135;12083.818742718866;14211.644708882153;14343.211123388313;14345.569632911029;14397.712045759026;7890.48711833729;8247.175282471824;8572.91427085721;8790.164934331227;8846.601902914384;8981.326400519987;8983.640654373883;8992.145746154105;9049.502277374675;9091.545422728837;6033.78881739137;6341.524385671093;6546.312637173123;6706.798796036127;6711.6979735912;1598.2568105830844;1841.7513635659282;1860.5162657709093;1867.430433717729;1895.9620807584047;886.5655828643918;949.2880339745191;964.3297907954045;1018.9286749927583;1082.1396989393452;20177.596448070955;20414.79585204129;21197.36407907765;21374.93125343758;21534.461638459165;861.237804542984;964.6479035152119;1008.9243306752023;1013.6502906497246;1019.7144799456164;10965;11463.713407164887;11479.827802582968;11483;11504.712382190512;477341;555680;563237.1838140783;570482;572200;95681.08637827204;108175;110495;113159;113371.03201387911;914.9679761208458;1046.1787496814964;1060;1065.9520321585546;1075.6342843433426;17617.119144042492;17626.38307659277;17968;18235.270589176285;18603.720944185578;50467;51032.48967510278;51320;51584;53752;69617;71639;71942.28057719355;72090.27909720836;73938.26061739314;7831.060272767304;7965.761027169194;8185.017797864294;8277.54473503963;8422.24199822019;9106.544672766202;10547.156227501628;12628.305443200712;12830.10189286741;13068.281244531641;8670.306375490072;8792.736217913473;10125.240606954656;10774.299670521705;10841.295315804762;10868.07621352194;10877.67366978992;10901.127909767041;11159.218854680095;11291.43542822872;11332.660020199408;11494.137939654727;11550.133739969368;11644.068474522188;11787.057035437316;8198.344132469269;8348.165183481604;8388.412811103164;8482.491050536988;8748.868766968373;8211.21993410625;10216.846747298649;11250.156238281998;11528.711782205512;11651.650450486499 17:39:25 INFO - PROCESS | 2234 | |3;mootools.html;1357.823482947217;1429.6068581140191;1592.8646065084467;1776.3782676063379;1952.7502398464985;7531.510451820628;7895.88156177657;8732.8690069649;8737.12628737126;8738.563071846414;8474.279686226679;8699.217070463657;8706.477611343324;8717.476951382921;8719.04090550039;2119.660854263318;2181.4437318483765;2184.6395344768093;2392.856428614284;2421.3946513371657;2098.8845613491253;2459.938501537467;2463.408781892351;2465.5561998840203;2465.568525508038;2383.6543701163323;2387.9761202387926;2429.708434987804;2430.9027638894513;2438.670779444779;2141.582391433671;2356.5404746074523;2419.6733440985417;2434.075051480435;2443.9755602443925;2410.590199666061;2447.0823441209545;2449.155041510676;2452.080469823816;2460.9261722148362;8437.071922088566;8479.703210387606;8485.193906578865;8505.192006759347;8520.020197677284;5200.843974680765;5319.840404787863;5368.32895888014;5369;5369.355677318707;3382.0868365541414;3463.8787642432394;3579.534660494145;3587.497750314958;3588.8385022673783;1026.4200726589527;1037.9273450858368;1044.670928657478;1045.2630895218851;1057.5558265528496;11683.883161168365;11804;11806.763864722743;11862.822057669056;11877.406129693567;4762.690425122356;4842.080004799077;4856.854294371175;4861.246506791453;4927.187014142698;10177.98220177991;10278.280520363653;10278.743031424274;10282.897171028344;10313.84529232062;5411.945880541223;5420.295361602966;5439.646422982493;5470.671759694352;5480.534154596824;4362.672799540047;4478.529754375743;4778.856634300907;4886.462489126193;4964.60283177345;4039.8586049488713;4107.609777071173;4143.8964025898995;4156.355764856452;4160.50073991123;1324.9801252981213;1346.145197799385;1347.8854297384833;1354.3093022558369;1361.0880709924375;777.564563844249;902.1654969153507;921.1985572551923;941.6327632223301;944.6916021310534;5684.971575142098;5783.190353350535;5839.211706419664;5865.94134058654;5907.497862681635;917.0691747875911;931.8509038553934;955.9522023898777;961.1493827962304;1015.4922538730634;3769.6041915599017;3847.153626202231;3850.7497012694084;3914.373700207953;3944.980275098606;10460.895391046144;10510.579576816994;10646.73383165427;10795.244332896778;10806.35161890289;10468.842967355497;10631.84052239217;10636;10651.254412191227;10655.893441065646;7768.92231077682;8249.958750206211;8259.95870020646;8366.288865446384;8416.4950102994;5697.4302569742695;5742.454466825645;5774.740136693775;5840.445157710011;6020;9241.214496767581;9256.814863702823;9409.717708468757;9438.24494040489;9440.197583205505;9579.994100619335;9734;9826.459544725109;9845.655402060893;9867.210623150275;7171.103612048494;7379.151397589346;7572.810679733051;7585.772426827204;7620.123685775985;8074;8136.145704701158;8179.67281308741;8183.63173657175;8190.221928916743;7280.890786638311;7419.8145046374275;7440.330370266702;7534.698612055457;7542.132654744774;7176;7292.927070729224;7322.890156647761;7426.071741032371;7557.962210188914;6886.345797149263;6909.689063992032;6916.481263905127;6948.895766563404;6959.234484206632;4677.812887484462;4829.396325459317;4901.5588597026435;4945.950540494693;4958.528939750718;4883.95116048835;4908.460069392436;4989.700617962933;5062.316587260672;5184.714840683799 17:39:25 INFO - PROCESS | 2234 | |4;prototype.html;291.91826288639186;309.66688406410407;311.07300245269096;327.6952434236159;332.4514550990865;4944.554990050895;5491.588130890189;6423.678816059196;6431.58194717343;6513.576617519858;6032.1554982302405;6150.81547553573;6223.968880155604;6407.839804004902;6517.9348206517925;4800.663953523254;4831.08209440207;4832.661713680044;4837.782299796518;4838;4834.540718631742;4857.757112144396;4861.73260470674;4863.635227357945;4871.366722326093;4561.087782443508;4609.631229501631;4703.2004559225015;4777.737224452654;4817.156997525419;3994.1612261425134;4702.365180700596;4778.4265888093305;4790.520947905216;4795.640326975491;3620.805134305699;4143.56492568279;4149.543550209474;4151.211269858717;4151.522574903865;5938.851528711773;6209.565330426872;6231.9688401557705;6234.594751341194;6244.157038799749;5610.887782244373;5621.566500542335;5631.267935168402;5631.352394474647;5653.547716182696;5828.388019257959;6550.017497375384;6797.52417330787;6810.5913645181445;6832.863342733167;4631.0549569180685;4638.397008388922;4651.697639653412;4658.34783130362;4662.254039353687;6883.65581720912;7138.535995160298;7242.71029158833;7267.273272672691;7285.890711639329;4582.839600613963;4712.410948631421;4725.7788839245495;4732.290156476556;4751.572358487752;4754.310624959361;4832.178529649968;4833.178359678863;4834.419869615669;4837.564619184291;5622.212890195376;5624.55003599711;5624.887502250014;5627.971860140673;5630.915536266959;6949.826254343682;6955.339242771928;6978.930210697828;6980.022796808451;7000.544964577286;1536.708025475156;1602.4551652438226;1635.386729976259;1660.6678664267195;1662.036019108914;1003.2976916158718;1003.7490627343165;1023.2888142740873;1025.108155904353;1029.840374741916;6246.063090536365;6319.304876463585;6353.237611486558;6369.904451433232;6411.326810684811;7077.9646101770195;7081.114860642419;7092;7106.502544821917;7113;6394.264659564116;6402.35976402356;6404.647744374104;6407.487401007908;6411.647359395278;3689.317476266899;3725.683316918038;3730.309892669865;3735.8879233623034;3738.925221495609;7204.639768011579;7384.556926584422;7457.701691932262;7579.962100189464;7598.164201937782;7447.776566703008;7491.138519070268;7508.887366689504;7509;7535.171131175566;6108.51131909455;7552.358049565739;7562.2437756223935;7578.658960346797;7606.3914886808925;7245.094363204599;7246.963765181351;7262.636868156465;7272.818179545554;7283.344498995116;6808.9659551701925;7025.929740702528;7043.788686339418;7063.046488723957;7130.679119439534;7515.586642734702;7543.0948286206;7550.131734850563;7556.433267504849;7572.318491335806;4342.500612429485;4530.660200485043;4581.175388430114;4584;4587.793549290357;6907;6994.5803251805055;6996.160460744743;7164.498485106201;7174.5695258282585;6909.343612356818;6940.652967351713;6947.687354068978;6964.721411143498;6965.442764578923;6899.551529150589;6930.549514281555;6960.965195174194;6965.79102626922;6974.6861391238535;5437.8368648940595;6502.057201705632;6777.45780337562;6778.4577233822165;6785.898211526733;6887.793366199022;6918;6931.896021559782;6941.687624057031;6958.234594194736;6810.114685090906;6818.284080171709;6837.1111755471475;6864.450843932574;6880.621565813928 17:39:25 INFO - PROCESS | 2234 | |5;yui.html;666.2770893580465;676.3338111959719;721.4769292263106;727.9662878712228;746.9514481558701;13831.009348756163;14134.515875833044;14914.552563423087;15352.232388380593;15353.385864565418;14379;14616.4884229052;14624.268786560682;14635.975481716285;14663.633409164777;730.3609341825902;740.4224704730319;740.4742632730768;881.6164968238804;883.7127933421632;731.703660017691;737.4985010193067;874.9518776467291;905.8324210021134;907.4645958884257;968.1673760565908;971.6259240192542;972.1930797438145;976.1605019683102;976.492224043497;961.1157734883889;967.9177269932064;968.6077138758743;969.4377261188529;970.2626004236831;556.5519756595938;677.2820809941478;702.5152644675209;704.4751660013271;704.6054209642616;14947.925260373737;14961.40154393823;15025.398984040625;15038.849611503963;15057.698846023019;12306.876931230663;12446.81329780054;12536.686582835411;12645.419322584678;12992.480300787956;10773.515191816387;10896.945515272373;11124.666260012213;11142.442957284991;11330.830037549444;4047.271491131595;4068.9796551017353;4097.13960068386;4097.569755175723;4142.834286628531;76080.61959690166;76849.61575192088;76892.99999999945;77009.22990770133;81867.5906620469;8085;9806.803863922682;9876.901230987742;10052.296339256181;10171.84742228867;22505.774942250697;22579.774202258097;22722.772772272398;22755.658665120038;22780.4304892379;7499.437542184358;7609.923900761032;7634.847303053908;7777.222277772177;7956.840863182704;4073.1853629274265;4098.549159592442;4108.979455102765;4222.873313800591;4353;3630.5098811660087;3693.8153092345274;3698.2418604186078;3704.3517384457614;3706.1105334719473;635.4154178156108;644.1786721929484;653.5131327161296;661.609650306312;678.7929681447107;570.6490508337342;574.6580784433255;585.8476796033062;595.4313630482878;609.6646844235653;8096.311813495919;8184.427090103635;8198.672053117809;8219.506829590244;8274.917250827535;571.3886141828204;582.5921854701726;583.9591228614041;586.6216290492608;593.8366949088949;3679.5032670589662;3854.6338097880657;3871.8064096795047;3874.496315479026;3875.0893540018074;92039.53980230055;94713;95079;95247;95366.52316738373;74648;74833.87749183767;76551;77375.61312193403;77432.61283693658;68727.65636171887;68999.93000210002;69004;69136.61726765436;71854;8321.583920804056;8456.746297611082;8458.111898250596;8620;8634.121007530432;46037.76981115073;48554.757226213645;49562.75218623884;50980.49019509906;51508.74245628748;68790;69003.30996689969;69031.30968690249;69724.6513767428;70188.64905675643;10838.566457341933;11066.502007409525;11105;11344.716382090513;11394.25937314072;11787.351695656822;11898.22661526998;11995.940020299842;12101.939490302491;12407.565735199225;11334.546618135511;11376.601818936297;11487.25332853362;11496.425178741198;11746.70633234176;11400.48697808584;11574.074074074222;11641.53433862636;11724.588476348028;11912.642620721392;11034.117270618492;11158.665240042812;11396.601118960796;11453.656390308304;11930.76138477243;9399.084659124755;9454.716358509255;9504.809903802023;9626.759331016781;9680.5643746033;9462.951323416286;9700.417974921527;9747.317687761788;9749;9911.950440247752 17:39:25 INFO - PROCESS | 2234 | __end_tp_report 17:39:25 INFO - PROCESS | 2234 | __start_cc_report 17:39:25 INFO - PROCESS | 2234 | _x_x_mozilla_cycle_collect,1200 17:39:25 INFO - PROCESS | 2234 | __end_cc_report 17:39:25 INFO - PROCESS | 2234 | __startTimestamp1464395965485__endTimestamp 17:39:25 INFO - PROCESS | 2234 | 17:39:25 INFO - PROCESS | 2234 | ------- Summary: start ------- 17:39:25 INFO - PROCESS | 2234 | Number of tests: 6 17:39:25 INFO - PROCESS | 2234 | 17:39:25 INFO - PROCESS | 2234 | [#0] dojo.html Cycles:165 Average:5717.38 Median:5953.23 stddev:657.92 (11.1%) stddev-sans-first:614.13 17:39:25 INFO - PROCESS | 2234 | Values: 2642.3 3409.2 3420.9 3432.2 3492.5 5660.8 5677.4 5678.7 5684.1 5689.5 5673.8 5724.7 5728.5 5739.1 5743.4 5058.9 5089.8 5164.4 5221.7 5273.7 5188.9 5199.2 5201.0 5213.0 5215.9 5149.1 5161.4 5184.2 5194.2 5195.8 5204.3 5238.4 5240.8 5243.4 5281.9 4319.9 5044.3 5067.9 5077.5 5186.7 5564.1 5593.6 5596.7 5605.0 5630.1 6214.9 6220.7 6247.6 6265.6 6288.3 6133.0 6186.5 6285.4 6298.6 6304.9 5803.2 5831.2 5832.6 5834.2 5883.2 6224.3 6232.4 6255.2 6265.8 6290.7 6013.7 6079.1 6123.5 6160.3 6160.9 6246.2 6251.2 6261.9 6265.4 6266.9 6135.4 6186.8 6208.8 6215.9 6231.4 6082.9 6143.0 6155.7 6155.9 6220.4 4855.2 4866.5 4888.3 4891.3 4899.7 4379.7 4419.6 4489.0 4503.8 4514.3 5584.0 5638.3 5643.5 5657.7 5678.6 6139.1 6195.7 6207.1 6285.0 6301.0 5543.9 5641.4 5647.6 5682.1 5682.2 5342.2 5348.1 5354.9 5360.8 5372.1 6214.4 6215.6 6232.6 6271.5 6293.8 6222.2 6229.8 6274.4 6292.3 6298.9 6265.9 6291.5 6307.5 6312.2 6318.5 6020.4 6090.2 6107.3 6200.2 6203.1 6222.7 6253.7 6257.1 6259.7 6262.5 6070.4 6104.9 6110.4 6115.8 6116.8 5966.6 6036.0 6038.7 6093.1 6118.0 5278.4 5927.3 5936.5 5944.5 5953.2 6080.9 6095.0 6096.0 6109.6 6110.3 5851.9 5908.1 5945.0 5957.0 6006.7 17:39:25 INFO - PROCESS | 2234 | 17:39:25 INFO - PROCESS | 2234 | [#1] ext.html Cycles:180 Average:65804.84 Median:35365.21 stddev:141034.37 (398.8%) stddev-sans-first:141344.76 17:39:25 INFO - PROCESS | 2234 | Values: 494.4 521.5 533.6 561.6 607.1 29434.6 29602.0 39019.4 39083.8 39253.0 41814.6 42428.8 42452.4 42469.0 42484.4 8503.7 9407.7 9621.7 9625.4 9641.3 8581.1 11391.9 11589.8 11606.1 11609.0 11389.8 11478.0 11478.7 11486.8 11564.5 8654.4 11369.3 11444.1 11459.5 11678.1 11274.7 11369.5 11386.3 11396.6 11402.3 38563.0 39082.6 39099.4 39132.6 39166.6 3198.2 3206.9 3475.7 3601.3 3822.9 28174.9 28200.0 28553.0 28763.6 28884.0 2665.7 2706.2 2789.1 2829.0 2881.6 480769.0 481076.0 481677.6 482406.0 482482.0 36647.3 37377.8 37444.4 37596.8 60260.0 31014.5 35803.3 36117.1 36268.0 36271.6 15599.8 15738.4 15756.4 15772.9 15776.2 12350.1 12526.7 13092.1 13166.7 13569.6 8195.6 8203.0 8213.8 8277.5 8297.1 3406.2 3452.8 3454.2 3458.7 3484.0 1019.0 1060.2 1063.8 1101.9 1117.7 30203.0 30426.8 30811.8 31047.7 31156.0 1093.6 1106.3 1113.8 1243.2 1264.2 15494.5 15667.5 15851.8 15954.4 16176.5 557148.0 559931.0 562535.0 564439.0 566664.0 54775.2 60475.7 61818.7 62543.7 62550.7 1358.6 1392.9 1491.8 1512.9 1515.9 29768.6 30564.7 30962.7 31481.0 32074.0 57141.4 58054.4 58056.5 58109.0 58119.0 523482.4 542505.0 542916.0 543365.0 544872.0 34861.6 34927.1 43869.0 44977.6 45790.5 44272.8 44909.0 45449.5 45918.6 46816.5 37467.1 38159.9 38699.2 39030.0 39897.0 38886.6 39044.8 39402.0 39637.6 40269.4 38431.8 39151.8 39406.4 39661.2 39724.0 32990.8 34105.0 34109.5 34615.0 34697.0 21616.3 25530.6 25640.0 25775.7 26562.5 17:39:25 INFO - PROCESS | 2234 | 17:39:25 INFO - PROCESS | 2234 | [#2] jquery.html Cycles:180 Average:32785.37 Median:13419.07 stddev:91420.58 (681.3%) stddev-sans-first:91644.69 17:39:25 INFO - PROCESS | 2234 | Values: 395.2 396.3 397.7 398.5 410.1 15144.6 15318.0 19946.8 20510.5 20556.4 15029.5 16162.7 20396.7 20503.4 20790.6 5557.6 5681.5 5837.9 5854.2 5870.6 3426.2 5730.4 5828.6 5846.7 6222.8 6467.4 6471.4 6476.8 6485.8 6489.6 6190.2 6443.5 6444.9 6494.9 6507.9 5700.4 5700.7 5701.0 5707.3 5708.4 24558.6 24777.6 25659.5 25663.1 26079.9 2249.7 2279.5 2909.0 2940.4 2962.2 13088.1 14246.1 14744.3 14753.1 14767.0 1540.9 1683.8 1729.4 1737.9 1740.9 110621.0 113184.9 113837.0 115237.3 119125.0 10770.9 10970.0 11358.5 11401.2 11537.5 12083.8 14211.6 14343.2 14345.6 14397.7 7890.5 8247.2 8572.9 8790.2 8846.6 8981.3 8983.6 8992.1 9049.5 9091.5 6033.8 6341.5 6546.3 6706.8 6711.7 1598.3 1841.8 1860.5 1867.4 1896.0 886.6 949.3 964.3 1018.9 1082.1 20177.6 20414.8 21197.4 21374.9 21534.5 861.2 964.6 1008.9 1013.7 1019.7 10965.0 11463.7 11479.8 11483.0 11504.7 477341.0 555680.0 563237.2 570482.0 572200.0 95681.1 108175.0 110495.0 113159.0 113371.0 915.0 1046.2 1060.0 1066.0 1075.6 17617.1 17626.4 17968.0 18235.3 18603.7 50467.0 51032.5 51320.0 51584.0 53752.0 69617.0 71639.0 71942.3 72090.3 73938.3 7831.1 7965.8 8185.0 8277.5 8422.2 9106.5 10547.2 12628.3 12830.1 13068.3 8670.3 8792.7 10125.2 10774.3 10841.3 10868.1 10877.7 10901.1 11159.2 11291.4 11332.7 11494.1 11550.1 11644.1 11787.1 8198.3 8348.2 8388.4 8482.5 8748.9 8211.2 10216.8 11250.2 11528.7 11651.7 17:39:25 INFO - PROCESS | 2234 | 17:39:25 INFO - PROCESS | 2234 | [#3] mootools.html Cycles:180 Average:5681.61 Median:4952.24 stddev:3150.70 (63.6%) stddev-sans-first:3142.78 17:39:25 INFO - PROCESS | 2234 | Values: 1357.8 1429.6 1592.9 1776.4 1952.8 7531.5 7895.9 8732.9 8737.1 8738.6 8474.3 8699.2 8706.5 8717.5 8719.0 2119.7 2181.4 2184.6 2392.9 2421.4 2098.9 2459.9 2463.4 2465.6 2465.6 2383.7 2388.0 2429.7 2430.9 2438.7 2141.6 2356.5 2419.7 2434.1 2444.0 2410.6 2447.1 2449.2 2452.1 2460.9 8437.1 8479.7 8485.2 8505.2 8520.0 5200.8 5319.8 5368.3 5369.0 5369.4 3382.1 3463.9 3579.5 3587.5 3588.8 1026.4 1037.9 1044.7 1045.3 1057.6 11683.9 11804.0 11806.8 11862.8 11877.4 4762.7 4842.1 4856.9 4861.2 4927.2 10178.0 10278.3 10278.7 10282.9 10313.8 5411.9 5420.3 5439.6 5470.7 5480.5 4362.7 4478.5 4778.9 4886.5 4964.6 4039.9 4107.6 4143.9 4156.4 4160.5 1325.0 1346.1 1347.9 1354.3 1361.1 777.6 902.2 921.2 941.6 944.7 5685.0 5783.2 5839.2 5865.9 5907.5 917.1 931.9 956.0 961.1 1015.5 3769.6 3847.2 3850.7 3914.4 3945.0 10460.9 10510.6 10646.7 10795.2 10806.4 10468.8 10631.8 10636.0 10651.3 10655.9 7768.9 8250.0 8260.0 8366.3 8416.5 5697.4 5742.5 5774.7 5840.4 6020.0 9241.2 9256.8 9409.7 9438.2 9440.2 9580.0 9734.0 9826.5 9845.7 9867.2 7171.1 7379.2 7572.8 7585.8 7620.1 8074.0 8136.1 8179.7 8183.6 8190.2 7280.9 7419.8 7440.3 7534.7 7542.1 7176.0 7292.9 7322.9 7426.1 7558.0 6886.3 6909.7 6916.5 6948.9 6959.2 4677.8 4829.4 4901.6 4946.0 4958.5 4884.0 4908.5 4989.7 5062.3 5184.7 17:39:25 INFO - PROCESS | 2234 | 17:39:25 INFO - PROCESS | 2234 | [#4] prototype.html Cycles:180 Average:5661.43 Median:6361.57 stddev:1801.59 (28.3%) stddev-sans-first:1760.99 17:39:25 INFO - PROCESS | 2234 | Values: 291.9 309.7 311.1 327.7 332.5 4944.6 5491.6 6423.7 6431.6 6513.6 6032.2 6150.8 6224.0 6407.8 6517.9 4800.7 4831.1 4832.7 4837.8 4838.0 4834.5 4857.8 4861.7 4863.6 4871.4 4561.1 4609.6 4703.2 4777.7 4817.2 3994.2 4702.4 4778.4 4790.5 4795.6 3620.8 4143.6 4149.5 4151.2 4151.5 5938.9 6209.6 6232.0 6234.6 6244.2 5610.9 5621.6 5631.3 5631.4 5653.5 5828.4 6550.0 6797.5 6810.6 6832.9 4631.1 4638.4 4651.7 4658.3 4662.3 6883.7 7138.5 7242.7 7267.3 7285.9 4582.8 4712.4 4725.8 4732.3 4751.6 4754.3 4832.2 4833.2 4834.4 4837.6 5622.2 5624.6 5624.9 5628.0 5630.9 6949.8 6955.3 6978.9 6980.0 7000.5 1536.7 1602.5 1635.4 1660.7 1662.0 1003.3 1003.7 1023.3 1025.1 1029.8 6246.1 6319.3 6353.2 6369.9 6411.3 7078.0 7081.1 7092.0 7106.5 7113.0 6394.3 6402.4 6404.6 6407.5 6411.6 3689.3 3725.7 3730.3 3735.9 3738.9 7204.6 7384.6 7457.7 7580.0 7598.2 7447.8 7491.1 7508.9 7509.0 7535.2 6108.5 7552.4 7562.2 7578.7 7606.4 7245.1 7247.0 7262.6 7272.8 7283.3 6809.0 7025.9 7043.8 7063.0 7130.7 7515.6 7543.1 7550.1 7556.4 7572.3 4342.5 4530.7 4581.2 4584.0 4587.8 6907.0 6994.6 6996.2 7164.5 7174.6 6909.3 6940.7 6947.7 6964.7 6965.4 6899.6 6930.5 6961.0 6965.8 6974.7 5437.8 6502.1 6777.5 6778.5 6785.9 6887.8 6918.0 6931.9 6941.7 6958.2 6810.1 6818.3 6837.1 6864.5 6880.6 17:39:25 INFO - PROCESS | 2234 | 17:39:25 INFO - PROCESS | 2234 | [#5] yui.html Cycles:180 Average:18353.88 Median:594.63 stddev:25565.46 (4299.4%) stddev-sans-first:25602.68 17:39:25 INFO - PROCESS | 2234 | Values: 666.3 676.3 721.5 728.0 747.0 13831.0 14134.5 14914.6 15352.2 15353.4 14379.0 14616.5 14624.3 14636.0 14663.6 730.4 740.4 740.5 881.6 883.7 731.7 737.5 875.0 905.8 907.5 968.2 971.6 972.2 976.2 976.5 961.1 967.9 968.6 969.4 970.3 556.6 677.3 702.5 704.5 704.6 14947.9 14961.4 15025.4 15038.8 15057.7 12306.9 12446.8 12536.7 12645.4 12992.5 10773.5 10896.9 11124.7 11142.4 11330.8 4047.3 4069.0 4097.1 4097.6 4142.8 76080.6 76849.6 76893.0 77009.2 81867.6 8085.0 9806.8 9876.9 10052.3 10171.8 22505.8 22579.8 22722.8 22755.7 22780.4 7499.4 7609.9 7634.8 7777.2 7956.8 4073.2 4098.5 4109.0 4222.9 4353.0 3630.5 3693.8 3698.2 3704.4 3706.1 635.4 644.2 653.5 661.6 678.8 570.6 574.7 585.8 595.4 609.7 8096.3 8184.4 8198.7 8219.5 8274.9 571.4 582.6 584.0 586.6 593.8 3679.5 3854.6 3871.8 3874.5 3875.1 92039.5 94713.0 95079.0 95247.0 95366.5 74648.0 74833.9 76551.0 77375.6 77432.6 68727.7 68999.9 69004.0 69136.6 71854.0 8321.6 8456.7 8458.1 8620.0 8634.1 46037.8 48554.8 49562.8 50980.5 51508.7 68790.0 69003.3 69031.3 69724.7 70188.6 10838.6 11066.5 11105.0 11344.7 11394.3 11787.4 11898.2 11995.9 12101.9 12407.6 11334.5 11376.6 11487.3 11496.4 11746.7 11400.5 11574.1 11641.5 11724.6 11912.6 11034.1 11158.7 11396.6 11453.7 11930.8 9399.1 9454.7 9504.8 9626.8 9680.6 9463.0 9700.4 9747.3 9749.0 9912.0 17:39:25 INFO - PROCESS | 2234 | -------- Summary: end -------- 17:39:25 INFO - PROCESS | 2234 | 17:39:26 INFO - TEST-INFO | 2234: exit 0 17:39:26 INFO - TEST-OK | dromaeo_css | took 1115001ms 17:39:26 INFO - TEST-START | kraken 17:39:26 INFO - operating with platform_type : mac_ 17:39:26 INFO - Initialising browser for kraken test... 17:39:26 INFO - TEST-INFO | started process 2238 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmp310rXW/profile http://localhost:56270/getInfo.html) 17:39:28 INFO - PROCESS | 2238 | __metrics Screen width/height:1600/1200 17:39:28 INFO - PROCESS | 2238 | colorDepth:24 17:39:28 INFO - PROCESS | 2238 | Browser inner width/height: 1024/689 17:39:28 INFO - PROCESS | 2238 | __metrics 17:39:28 INFO - PROCESS | 2238 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:39:28 ERROR - PROCESS | 2238 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:39:29 INFO - TEST-INFO | 2238: exit 0 17:39:29 INFO - Browser initialized. 17:39:29 INFO - Running cycle 1/1 for kraken test... 17:39:29 INFO - TEST-INFO | started process 2240 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/tmp310rXW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:39:41 INFO - PROCESS | 2240 | RSS: Main: 211431424 17:39:41 INFO - PROCESS | 2240 | 17:39:43 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56270/tests/kraken/driver.html?testName=audio-beat-detection) 17:39:44 INFO - PROCESS | 2240 | RSS: Main: 271945728 17:39:44 INFO - PROCESS | 2240 | 17:39:47 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56270/tests/kraken/driver.html?testName=audio-dft) 17:39:47 INFO - PROCESS | 2240 | RSS: Main: 285982720 17:39:47 INFO - PROCESS | 2240 | 17:39:51 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56270/tests/kraken/driver.html?testName=audio-fft) 17:39:51 INFO - PROCESS | 2240 | RSS: Main: 372948992 17:39:51 INFO - PROCESS | 2240 | 17:39:53 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56270/tests/kraken/driver.html?testName=audio-oscillator) 17:39:54 INFO - PROCESS | 2240 | RSS: Main: 279760896 17:39:54 INFO - PROCESS | 2240 | 17:39:56 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:39:57 INFO - PROCESS | 2240 | RSS: Main: 279666688 17:39:57 INFO - PROCESS | 2240 | 17:40:00 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56270/tests/kraken/driver.html?testName=imaging-darkroom) 17:40:00 INFO - PROCESS | 2240 | RSS: Main: 318324736 17:40:00 INFO - PROCESS | 2240 | 17:40:03 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56270/tests/kraken/driver.html?testName=imaging-desaturate) 17:40:04 INFO - PROCESS | 2240 | RSS: Main: 318648320 17:40:04 INFO - PROCESS | 2240 | 17:40:07 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56270/tests/kraken/driver.html?testName=json-parse-financial) 17:40:07 INFO - PROCESS | 2240 | RSS: Main: 317501440 17:40:07 INFO - PROCESS | 2240 | 17:40:09 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:40:09 INFO - PROCESS | 2240 | RSS: Main: 309538816 17:40:09 INFO - PROCESS | 2240 | 17:40:11 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56270/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:40:12 INFO - PROCESS | 2240 | RSS: Main: 316542976 17:40:12 INFO - PROCESS | 2240 | 17:40:14 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:40:15 INFO - PROCESS | 2240 | RSS: Main: 318734336 17:40:15 INFO - PROCESS | 2240 | 17:40:18 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:40:18 INFO - PROCESS | 2240 | RSS: Main: 324190208 17:40:18 INFO - PROCESS | 2240 | 17:40:21 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:40:22 INFO - PROCESS | 2240 | RSS: Main: 322166784 17:40:22 INFO - PROCESS | 2240 | 17:40:23 INFO - PROCESS | 2240 | Cycle 1(1): loaded http://localhost:56270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56270/tests/kraken/driver.html?testName=ai-astar) 17:40:24 INFO - PROCESS | 2240 | RSS: Main: 386494464 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | __start_tp_report 17:40:24 INFO - PROCESS | 2240 | _x_x_mozilla_page_load 17:40:24 INFO - PROCESS | 2240 | _x_x_mozilla_page_load_details 17:40:24 INFO - PROCESS | 2240 | |i|pagename|runs| 17:40:24 INFO - PROCESS | 2240 | |0;ai-astar;114;114;118;112;111;113;114;115;155;173 17:40:24 INFO - PROCESS | 2240 | |1;audio-beat-detection;156;125;148;162;118;144;140;117;166;121 17:40:24 INFO - PROCESS | 2240 | |2;audio-dft;186;186;187;184;181;184;187;187;189;183 17:40:24 INFO - PROCESS | 2240 | |3;audio-fft;72;80;73;152;57;56;59;82;169;45 17:40:24 INFO - PROCESS | 2240 | |4;audio-oscillator;97;89;139;77;83;88;123;81;77;126 17:40:24 INFO - PROCESS | 2240 | |5;imaging-gaussian-blur;118;130;92;88;91;88;91;86;133;130 17:40:24 INFO - PROCESS | 2240 | |6;imaging-darkroom;96;95;97;94;96;92;97;96;95;100 17:40:24 INFO - PROCESS | 2240 | |7;imaging-desaturate;86;87;84;84;87;83;83;85;84;84 17:40:24 INFO - PROCESS | 2240 | |8;json-parse-financial;61;67;66;65;64;63;65;82;61;57 17:40:24 INFO - PROCESS | 2240 | |9;json-stringify-tinderbox;67;67;72;84;75;71;74;68;70;70 17:40:24 INFO - PROCESS | 2240 | |10;stanford-crypto-aes;81;80;95;103;105;101;78;81;81;85 17:40:24 INFO - PROCESS | 2240 | |11;stanford-crypto-ccm;131;126;129;121;125;131;130;129;150;131 17:40:24 INFO - PROCESS | 2240 | |12;stanford-crypto-pbkdf2;170;170;171;168;168;165;168;158;167;173 17:40:24 INFO - PROCESS | 2240 | |13;stanford-crypto-sha256-iterative;70;68;69;68;70;60;57;70;91;92 17:40:24 INFO - PROCESS | 2240 | __end_tp_report 17:40:24 INFO - PROCESS | 2240 | __start_cc_report 17:40:24 INFO - PROCESS | 2240 | _x_x_mozilla_cycle_collect,2009 17:40:24 INFO - PROCESS | 2240 | __end_cc_report 17:40:24 INFO - PROCESS | 2240 | __startTimestamp1464396024155__endTimestamp 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | ------- Summary: start ------- 17:40:24 INFO - PROCESS | 2240 | Number of tests: 14 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#0] ai-astar Cycles:10 Average:123.90 Median:114.50 stddev:21.64 (18.9%) stddev-sans-first:22.65 17:40:24 INFO - PROCESS | 2240 | Values: 114.0 114.0 118.0 112.0 111.0 113.0 114.0 115.0 155.0 173.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:146.00 stddev:18.54 (12.7%) stddev-sans-first:18.70 17:40:24 INFO - PROCESS | 2240 | Values: 156.0 125.0 148.0 162.0 118.0 144.0 140.0 117.0 166.0 121.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#2] audio-dft Cycles:10 Average:185.40 Median:186.50 stddev:2.37 (1.3%) stddev-sans-first:2.50 17:40:24 INFO - PROCESS | 2240 | Values: 186.0 186.0 187.0 184.0 181.0 184.0 187.0 187.0 189.0 183.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#3] audio-fft Cycles:10 Average:84.50 Median:65.50 stddev:41.89 (63.9%) stddev-sans-first:44.18 17:40:24 INFO - PROCESS | 2240 | Values: 72.0 80.0 73.0 152.0 57.0 56.0 59.0 82.0 169.0 45.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:82.00 stddev:22.77 (27.8%) stddev-sans-first:24.15 17:40:24 INFO - PROCESS | 2240 | Values: 97.0 89.0 139.0 77.0 83.0 88.0 123.0 81.0 77.0 126.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#5] imaging-gaussian-blur Cycles:10 Average:104.70 Median:88.00 stddev:20.28 (23.0%) stddev-sans-first:20.93 17:40:24 INFO - PROCESS | 2240 | Values: 118.0 130.0 92.0 88.0 91.0 88.0 91.0 86.0 133.0 130.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:2.10 (2.2%) stddev-sans-first:2.22 17:40:24 INFO - PROCESS | 2240 | Values: 96.0 95.0 97.0 94.0 96.0 92.0 97.0 96.0 95.0 100.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:84.50 stddev:1.49 (1.8%) stddev-sans-first:1.51 17:40:24 INFO - PROCESS | 2240 | Values: 86.0 87.0 84.0 84.0 87.0 83.0 83.0 85.0 84.0 84.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.00 stddev:6.62 (10.2%) stddev-sans-first:6.86 17:40:24 INFO - PROCESS | 2240 | Values: 61.0 67.0 66.0 65.0 64.0 63.0 65.0 82.0 61.0 57.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:71.50 stddev:5.07 (7.1%) stddev-sans-first:5.07 17:40:24 INFO - PROCESS | 2240 | Values: 67.0 67.0 72.0 84.0 75.0 71.0 74.0 68.0 70.0 70.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:81.00 stddev:10.76 (13.3%) stddev-sans-first:11.02 17:40:24 INFO - PROCESS | 2240 | Values: 81.0 80.0 95.0 103.0 105.0 101.0 78.0 81.0 81.0 85.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#11] stanford-crypto-ccm Cycles:10 Average:130.30 Median:130.50 stddev:7.65 (5.9%) stddev-sans-first:8.11 17:40:24 INFO - PROCESS | 2240 | Values: 131.0 126.0 129.0 121.0 125.0 131.0 130.0 129.0 150.0 131.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:169.00 stddev:4.10 (2.4%) stddev-sans-first:4.28 17:40:24 INFO - PROCESS | 2240 | Values: 170.0 170.0 171.0 168.0 168.0 165.0 168.0 158.0 167.0 173.0 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - PROCESS | 2240 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:70.00 stddev:11.45 (16.4%) stddev-sans-first:12.13 17:40:24 INFO - PROCESS | 2240 | Values: 70.0 68.0 69.0 68.0 70.0 60.0 57.0 70.0 91.0 92.0 17:40:24 INFO - PROCESS | 2240 | -------- Summary: end -------- 17:40:24 INFO - PROCESS | 2240 | 17:40:24 INFO - TEST-INFO | 2240: exit 0 17:40:24 INFO - TEST-OK | kraken | took 58760ms 17:40:24 INFO - SUITE-END | took 1173s 17:40:25 INFO - Completed test suite (00:19:34) 17:40:25 INFO - javascript benchmark 17:40:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2642.260167153197, 3409.198838273006, 3420.9486857697157, 3432.1591210153515, 3492.4761285807126, 5660.801871934483, 5677.43225677432, 5678.687672178028, 5684.090545512719, 5689.516391106761, 5673.773049078042, 5724.685142317171, 5728.541716662668, 5739.110437882125, 5743.396943320954, 5058.873528161807, 5089.796408143669, 5164.406093299281, 5221.738913054332, 5273.6572122811895, 5188.948110518903, 5199.168133098705, 5201.0378080055125, 5213.009528189651, 5215.947840521603, 5149.0731668299695, 5161.4322424533275, 5184.248283998818, 5194.194899790538, 5195.818146364878, 5204.34945631796, 5238.423773384925, 5240.79036838526, 5243.4232234454175, 5281.86795330116, 4319.941614304484, 5044.344235249431, 5067.949320506785, 5077.517635824591, 5186.740662966874, 5564.081926482126, 5593.552515798727, 5596.692181929992, 5604.971975140139, 5630.0991841305195, 6214.937850621481, 6220.68896555175, 6247.5939063960695, 6265.59273647216, 6288.308286088527, 6132.969335153341, 6186.505079593621, 6285.43431091204, 6298.622082675054, 6304.905426418607, 5803.245578074908, 5831.212786273841, 5832.591718579701, 5834.212381328552, 5883.17635531026, 6224.253089629274, 6232.439080482778, 6255.218097737783, 6265.780697675559, 6290.716917738621, 6013.699315034319, 6079.118527813443, 6123.479504242189, 6160.260768707783, 6160.907586386208, 6246.219222597176, 6251.218597675291, 6261.874762504815, 6265.373462653698, 6266.937330626635, 6135.386461353918, 6186.752529898844, 6208.81373558794, 6215.9378406215355, 6231.376862313732, 6082.878342433127, 6142.989934931851, 6155.72299246526, 6155.907661385083, 6220.440160385497, 4855.198892182751, 4866.46468888422, 4888.315635810989, 4891.290762839369, 4899.706017638953, 4379.693421460531, 4419.580139886705, 4489.0, 4503.797329120198, 4514.345419914094, 5583.994880921392, 5638.295213098363, 5643.5203007743985, 5657.745401456944, 5678.5741069419955, 6139.079138129317, 6195.690215489298, 6207.131001659771, 6285.0, 6300.9684951574945, 5543.944560554342, 5641.435856414408, 5647.632903861236, 5682.062459694104, 5682.204491371212, 5342.171963345688, 5348.090824559757, 5354.892902141935, 5360.785568577291, 5372.086745253238, 6214.40963108504, 6215.564910456405, 6232.563720539519, 6271.4669253114, 6293.842653933688, 6222.191115155182, 6229.750809967551, 6274.372562743689, 6292.339304372975, 6298.874022519615, 6265.906011409741, 6291.465225455704, 6307.463865571294, 6312.242530896322, 6318.526110541636, 6020.397960203944, 6090.177825993612, 6107.310189946561, 6200.224971878515, 6203.06953956892, 6222.688865556795, 6253.6560489173535, 6257.061440783918, 6259.718312675849, 6262.46769024638, 6070.3929607038945, 6104.908426373697, 6110.388961103854, 6115.755369785159, 6116.785912493041, 5966.582339236212, 6036.0, 6038.728257228523, 6093.146959425595, 6117.990531562243, 5278.445763194809, 5927.31835838884, 5936.495397891227, 5944.524438045032, 5953.226080609493, 6080.90878636812, 6094.969525152346, 6095.985691573962, 6109.602935473257, 6110.315755985069, 5851.912221316598, 5908.113782932594, 5944.970275148597, 5957.046872500379, 6006.669633170043], "value": 5673.339541562001, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.38449130832095, 521.4863359590805, 533.5891363649989, 561.5563704673307, 607.1348328631701, 29434.558481622767, 29602.0, 39019.41470877935, 39083.80458097713, 39253.0, 41814.58185418145, 42428.78785606068, 42452.363214551806, 42469.0, 42484.36273455901, 8503.702370417037, 9407.670731524415, 9621.711348659517, 9625.422474651543, 9641.325107242496, 8581.056083830774, 11391.943040284787, 11589.768204635944, 11606.071514278838, 11609.0, 11389.772204555946, 11478.0, 11478.655640330762, 11486.827697584502, 11564.479598418091, 8654.35092368075, 11369.317840929529, 11444.14168937333, 11459.541618335339, 11678.124140689482, 11274.661760147208, 11369.488373023234, 11386.316820990683, 11396.601118960796, 11402.315861048364, 38563.0, 39082.60917390818, 39099.41350879739, 39132.60867391318, 39166.60833391658, 3198.2164369729567, 3206.903792886217, 3475.6524347565296, 3601.3157500074904, 3822.923541529182, 28174.859125704446, 28200.0, 28553.0, 28763.56854647182, 28884.0, 2665.6801183857865, 2706.2422521693957, 2789.0656630028966, 2829.0381270368175, 2881.5821705852745, 480769.0, 481076.0, 481677.5916120432, 482406.0, 482482.0, 36647.26705465929, 37377.81311093482, 37444.43833342502, 37596.81201594029, 60260.0, 31014.534781978287, 35803.28393432117, 36117.09707257339, 36268.0, 36271.63728362735, 15599.844001559839, 15738.449154279546, 15756.369745210293, 15772.92113539425, 15776.211189440482, 12350.0737444692, 12526.686832829253, 13092.083554151308, 13166.670833229055, 13569.592912212649, 8195.631196596167, 8202.958985205036, 8213.835723285501, 8277.461964972257, 8297.087320394752, 3406.199543107368, 3452.7583069185416, 3454.240067185215, 3458.688718015391, 3483.965160348415, 1019.021739130443, 1060.192372005744, 1063.7659714862873, 1101.884302148279, 1117.709395557161, 30203.0, 30426.78292868305, 30811.8459407706, 31047.68952310493, 31156.0, 1093.6281664234198, 1106.280917403694, 1113.8384934184498, 1243.1919252485957, 1264.2414551269349, 15494.5351639451, 15667.529974100795, 15851.841481585037, 15954.361825526848, 16176.514704558882, 557148.0, 559931.0, 562535.0, 564439.0, 566664.0, 54775.178372324444, 60475.69762151161, 61818.69090654518, 62543.6872815633, 62550.68724656347, 1358.639960410492, 1392.8746412822895, 1491.843356447579, 1512.9258226659194, 1515.9393624255126, 29768.553471698375, 30564.694353056184, 30962.690373095982, 31481.0, 32074.0, 57141.42858571361, 58054.419455806594, 58056.54858628568, 58109.0, 58119.0, 523482.38258809986, 542505.0, 542916.0, 543365.0, 544872.0, 34861.61587922747, 34927.12682182864, 43869.0, 44977.55022449734, 45790.54209457996, 44272.77863610661, 44909.0, 45449.545504544534, 45918.622441326814, 46816.53183468258, 37467.06332341713, 38159.85520434391, 38699.2260154801, 39030.0, 39897.0, 38886.6111338883, 39044.804775975936, 39402.0, 39637.60362396455, 40269.39595906122, 38431.80784096062, 39151.82544523669, 39406.40890386589, 39661.2067758649, 39724.0, 32990.83504582462, 34104.97273209966, 34109.488357674156, 34615.0, 34697.0, 21616.281564491303, 25530.617040744033, 25640.0, 25775.742242578086, 26562.46875062449], "value": 19149.455377278442, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.2115529518611, 396.25108544850235, 397.6997366987924, 398.481973434535, 410.1387087117055, 15144.621384465394, 15318.0, 19946.800531994675, 20510.487237819063, 20556.38330850075, 15029.549113526584, 16162.676746465093, 20396.694049589267, 20503.38489845307, 20790.584188316225, 5557.555395568365, 5681.517071048946, 5837.854053648672, 5854.18041474194, 5870.647761134324, 3426.2119712466024, 5730.36965933747, 5828.591998560102, 5846.707664616773, 6222.751089956397, 6467.385598368148, 6471.4175724184815, 6476.838079048038, 6485.83785405364, 6489.5781774184525, 6190.195274614294, 6443.548951573392, 6444.935550644481, 6494.93505064948, 6507.8698426031215, 5700.40145784695, 5700.686462244575, 5700.973824711592, 5707.286589176353, 5708.3720790713405, 24558.631620525706, 24777.62833557498, 25659.486810263876, 25663.101791437395, 26079.869600651877, 2249.6625506174205, 2279.5099053703534, 2908.970910290891, 2940.3972185702096, 2962.155785601093, 13088.149270297401, 14246.07400518963, 14744.262786860614, 14753.11481311114, 14767.0, 1540.8613224809708, 1683.797944246686, 1729.4033558422325, 1737.9044152571603, 1740.8607311415055, 110621.0, 113184.86815131825, 113837.0, 115237.27144092845, 119125.0, 10770.946145269223, 10970.0, 11358.545658173745, 11401.20191586581, 11537.538498460135, 12083.818742718866, 14211.644708882153, 14343.211123388313, 14345.569632911029, 14397.712045759026, 7890.48711833729, 8247.175282471824, 8572.91427085721, 8790.164934331227, 8846.601902914384, 8981.326400519987, 8983.640654373883, 8992.145746154105, 9049.502277374675, 9091.545422728837, 6033.78881739137, 6341.524385671093, 6546.312637173123, 6706.798796036127, 6711.6979735912, 1598.2568105830844, 1841.7513635659282, 1860.5162657709093, 1867.430433717729, 1895.9620807584047, 886.5655828643918, 949.2880339745191, 964.3297907954045, 1018.9286749927583, 1082.1396989393452, 20177.596448070955, 20414.79585204129, 21197.36407907765, 21374.93125343758, 21534.461638459165, 861.237804542984, 964.6479035152119, 1008.9243306752023, 1013.6502906497246, 1019.7144799456164, 10965.0, 11463.713407164887, 11479.827802582968, 11483.0, 11504.712382190512, 477341.0, 555680.0, 563237.1838140783, 570482.0, 572200.0, 95681.08637827204, 108175.0, 110495.0, 113159.0, 113371.03201387911, 914.9679761208458, 1046.1787496814964, 1060.0, 1065.9520321585546, 1075.6342843433426, 17617.119144042492, 17626.38307659277, 17968.0, 18235.270589176285, 18603.720944185578, 50467.0, 51032.48967510278, 51320.0, 51584.0, 53752.0, 69617.0, 71639.0, 71942.28057719355, 72090.27909720836, 73938.26061739314, 7831.060272767304, 7965.761027169194, 8185.017797864294, 8277.54473503963, 8422.24199822019, 9106.544672766202, 10547.156227501628, 12628.305443200712, 12830.10189286741, 13068.281244531641, 8670.306375490072, 8792.736217913473, 10125.240606954656, 10774.299670521705, 10841.295315804762, 10868.07621352194, 10877.67366978992, 10901.127909767041, 11159.218854680095, 11291.43542822872, 11332.660020199408, 11494.137939654727, 11550.133739969368, 11644.068474522188, 11787.057035437316, 8198.344132469269, 8348.165183481604, 8388.412811103164, 8482.491050536988, 8748.868766968373, 8211.21993410625, 10216.846747298649, 11250.156238281998, 11528.711782205512, 11651.650450486499], "value": 9631.1304302656, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.823482947217, 1429.6068581140191, 1592.8646065084467, 1776.3782676063379, 1952.7502398464985, 7531.510451820628, 7895.88156177657, 8732.8690069649, 8737.12628737126, 8738.563071846414, 8474.279686226679, 8699.217070463657, 8706.477611343324, 8717.476951382921, 8719.04090550039, 2119.660854263318, 2181.4437318483765, 2184.6395344768093, 2392.856428614284, 2421.3946513371657, 2098.8845613491253, 2459.938501537467, 2463.408781892351, 2465.5561998840203, 2465.568525508038, 2383.6543701163323, 2387.9761202387926, 2429.708434987804, 2430.9027638894513, 2438.670779444779, 2141.582391433671, 2356.5404746074523, 2419.6733440985417, 2434.075051480435, 2443.9755602443925, 2410.590199666061, 2447.0823441209545, 2449.155041510676, 2452.080469823816, 2460.9261722148362, 8437.071922088566, 8479.703210387606, 8485.193906578865, 8505.192006759347, 8520.020197677284, 5200.843974680765, 5319.840404787863, 5368.32895888014, 5369.0, 5369.355677318707, 3382.0868365541414, 3463.8787642432394, 3579.534660494145, 3587.497750314958, 3588.8385022673783, 1026.4200726589527, 1037.9273450858368, 1044.670928657478, 1045.2630895218851, 1057.5558265528496, 11683.883161168365, 11804.0, 11806.763864722743, 11862.822057669056, 11877.406129693567, 4762.690425122356, 4842.080004799077, 4856.854294371175, 4861.246506791453, 4927.187014142698, 10177.98220177991, 10278.280520363653, 10278.743031424274, 10282.897171028344, 10313.84529232062, 5411.945880541223, 5420.295361602966, 5439.646422982493, 5470.671759694352, 5480.534154596824, 4362.672799540047, 4478.529754375743, 4778.856634300907, 4886.462489126193, 4964.60283177345, 4039.8586049488713, 4107.609777071173, 4143.8964025898995, 4156.355764856452, 4160.50073991123, 1324.9801252981213, 1346.145197799385, 1347.8854297384833, 1354.3093022558369, 1361.0880709924375, 777.564563844249, 902.1654969153507, 921.1985572551923, 941.6327632223301, 944.6916021310534, 5684.971575142098, 5783.190353350535, 5839.211706419664, 5865.94134058654, 5907.497862681635, 917.0691747875911, 931.8509038553934, 955.9522023898777, 961.1493827962304, 1015.4922538730634, 3769.6041915599017, 3847.153626202231, 3850.7497012694084, 3914.373700207953, 3944.980275098606, 10460.895391046144, 10510.579576816994, 10646.73383165427, 10795.244332896778, 10806.35161890289, 10468.842967355497, 10631.84052239217, 10636.0, 10651.254412191227, 10655.893441065646, 7768.92231077682, 8249.958750206211, 8259.95870020646, 8366.288865446384, 8416.4950102994, 5697.4302569742695, 5742.454466825645, 5774.740136693775, 5840.445157710011, 6020.0, 9241.214496767581, 9256.814863702823, 9409.717708468757, 9438.24494040489, 9440.197583205505, 9579.994100619335, 9734.0, 9826.459544725109, 9845.655402060893, 9867.210623150275, 7171.103612048494, 7379.151397589346, 7572.810679733051, 7585.772426827204, 7620.123685775985, 8074.0, 8136.145704701158, 8179.67281308741, 8183.63173657175, 8190.221928916743, 7280.890786638311, 7419.8145046374275, 7440.330370266702, 7534.698612055457, 7542.132654744774, 7176.0, 7292.927070729224, 7322.890156647761, 7426.071741032371, 7557.962210188914, 6886.345797149263, 6909.689063992032, 6916.481263905127, 6948.895766563404, 6959.234484206632, 4677.812887484462, 4829.396325459317, 4901.5588597026435, 4945.950540494693, 4958.528939750718, 4883.95116048835, 4908.460069392436, 4989.700617962933, 5062.316587260672, 5184.714840683799], "value": 4586.782400684289, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.91826288639186, 309.66688406410407, 311.07300245269096, 327.6952434236159, 332.4514550990865, 4944.554990050895, 5491.588130890189, 6423.678816059196, 6431.58194717343, 6513.576617519858, 6032.1554982302405, 6150.81547553573, 6223.968880155604, 6407.839804004902, 6517.9348206517925, 4800.663953523254, 4831.08209440207, 4832.661713680044, 4837.782299796518, 4838.0, 4834.540718631742, 4857.757112144396, 4861.73260470674, 4863.635227357945, 4871.366722326093, 4561.087782443508, 4609.631229501631, 4703.2004559225015, 4777.737224452654, 4817.156997525419, 3994.1612261425134, 4702.365180700596, 4778.4265888093305, 4790.520947905216, 4795.640326975491, 3620.805134305699, 4143.56492568279, 4149.543550209474, 4151.211269858717, 4151.522574903865, 5938.851528711773, 6209.565330426872, 6231.9688401557705, 6234.594751341194, 6244.157038799749, 5610.887782244373, 5621.566500542335, 5631.267935168402, 5631.352394474647, 5653.547716182696, 5828.388019257959, 6550.017497375384, 6797.52417330787, 6810.5913645181445, 6832.863342733167, 4631.0549569180685, 4638.397008388922, 4651.697639653412, 4658.34783130362, 4662.254039353687, 6883.65581720912, 7138.535995160298, 7242.71029158833, 7267.273272672691, 7285.890711639329, 4582.839600613963, 4712.410948631421, 4725.7788839245495, 4732.290156476556, 4751.572358487752, 4754.310624959361, 4832.178529649968, 4833.178359678863, 4834.419869615669, 4837.564619184291, 5622.212890195376, 5624.55003599711, 5624.887502250014, 5627.971860140673, 5630.915536266959, 6949.826254343682, 6955.339242771928, 6978.930210697828, 6980.022796808451, 7000.544964577286, 1536.708025475156, 1602.4551652438226, 1635.386729976259, 1660.6678664267195, 1662.036019108914, 1003.2976916158718, 1003.7490627343165, 1023.2888142740873, 1025.108155904353, 1029.840374741916, 6246.063090536365, 6319.304876463585, 6353.237611486558, 6369.904451433232, 6411.326810684811, 7077.9646101770195, 7081.114860642419, 7092.0, 7106.502544821917, 7113.0, 6394.264659564116, 6402.35976402356, 6404.647744374104, 6407.487401007908, 6411.647359395278, 3689.317476266899, 3725.683316918038, 3730.309892669865, 3735.8879233623034, 3738.925221495609, 7204.639768011579, 7384.556926584422, 7457.701691932262, 7579.962100189464, 7598.164201937782, 7447.776566703008, 7491.138519070268, 7508.887366689504, 7509.0, 7535.171131175566, 6108.51131909455, 7552.358049565739, 7562.2437756223935, 7578.658960346797, 7606.3914886808925, 7245.094363204599, 7246.963765181351, 7262.636868156465, 7272.818179545554, 7283.344498995116, 6808.9659551701925, 7025.929740702528, 7043.788686339418, 7063.046488723957, 7130.679119439534, 7515.586642734702, 7543.0948286206, 7550.131734850563, 7556.433267504849, 7572.318491335806, 4342.500612429485, 4530.660200485043, 4581.175388430114, 4584.0, 4587.793549290357, 6907.0, 6994.5803251805055, 6996.160460744743, 7164.498485106201, 7174.5695258282585, 6909.343612356818, 6940.652967351713, 6947.687354068978, 6964.721411143498, 6965.442764578923, 6899.551529150589, 6930.549514281555, 6960.965195174194, 6965.79102626922, 6974.6861391238535, 5437.8368648940595, 6502.057201705632, 6777.45780337562, 6778.4577233822165, 6785.898211526733, 6887.793366199022, 6918.0, 6931.896021559782, 6941.687624057031, 6958.234594194736, 6810.114685090906, 6818.284080171709, 6837.1111755471475, 6864.450843932574, 6880.621565813928], "value": 5052.763538433711, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.2770893580465, 676.3338111959719, 721.4769292263106, 727.9662878712228, 746.9514481558701, 13831.009348756163, 14134.515875833044, 14914.552563423087, 15352.232388380593, 15353.385864565418, 14379.0, 14616.4884229052, 14624.268786560682, 14635.975481716285, 14663.633409164777, 730.3609341825902, 740.4224704730319, 740.4742632730768, 881.6164968238804, 883.7127933421632, 731.703660017691, 737.4985010193067, 874.9518776467291, 905.8324210021134, 907.4645958884257, 968.1673760565908, 971.6259240192542, 972.1930797438145, 976.1605019683102, 976.492224043497, 961.1157734883889, 967.9177269932064, 968.6077138758743, 969.4377261188529, 970.2626004236831, 556.5519756595938, 677.2820809941478, 702.5152644675209, 704.4751660013271, 704.6054209642616, 14947.925260373737, 14961.40154393823, 15025.398984040625, 15038.849611503963, 15057.698846023019, 12306.876931230663, 12446.81329780054, 12536.686582835411, 12645.419322584678, 12992.480300787956, 10773.515191816387, 10896.945515272373, 11124.666260012213, 11142.442957284991, 11330.830037549444, 4047.271491131595, 4068.9796551017353, 4097.13960068386, 4097.569755175723, 4142.834286628531, 76080.61959690166, 76849.61575192088, 76892.99999999945, 77009.22990770133, 81867.5906620469, 8085.0, 9806.803863922682, 9876.901230987742, 10052.296339256181, 10171.84742228867, 22505.774942250697, 22579.774202258097, 22722.772772272398, 22755.658665120038, 22780.4304892379, 7499.437542184358, 7609.923900761032, 7634.847303053908, 7777.222277772177, 7956.840863182704, 4073.1853629274265, 4098.549159592442, 4108.979455102765, 4222.873313800591, 4353.0, 3630.5098811660087, 3693.8153092345274, 3698.2418604186078, 3704.3517384457614, 3706.1105334719473, 635.4154178156108, 644.1786721929484, 653.5131327161296, 661.609650306312, 678.7929681447107, 570.6490508337342, 574.6580784433255, 585.8476796033062, 595.4313630482878, 609.6646844235653, 8096.311813495919, 8184.427090103635, 8198.672053117809, 8219.506829590244, 8274.917250827535, 571.3886141828204, 582.5921854701726, 583.9591228614041, 586.6216290492608, 593.8366949088949, 3679.5032670589662, 3854.6338097880657, 3871.8064096795047, 3874.496315479026, 3875.0893540018074, 92039.53980230055, 94713.0, 95079.0, 95247.0, 95366.52316738373, 74648.0, 74833.87749183767, 76551.0, 77375.61312193403, 77432.61283693658, 68727.65636171887, 68999.93000210002, 69004.0, 69136.61726765436, 71854.0, 8321.583920804056, 8456.746297611082, 8458.111898250596, 8620.0, 8634.121007530432, 46037.76981115073, 48554.757226213645, 49562.75218623884, 50980.49019509906, 51508.74245628748, 68790.0, 69003.30996689969, 69031.30968690249, 69724.6513767428, 70188.64905675643, 10838.566457341933, 11066.502007409525, 11105.0, 11344.716382090513, 11394.25937314072, 11787.351695656822, 11898.22661526998, 11995.940020299842, 12101.939490302491, 12407.565735199225, 11334.546618135511, 11376.601818936297, 11487.25332853362, 11496.425178741198, 11746.70633234176, 11400.48697808584, 11574.074074074222, 11641.53433862636, 11724.588476348028, 11912.642620721392, 11034.117270618492, 11158.665240042812, 11396.601118960796, 11453.656390308304, 11930.76138477243, 9399.084659124755, 9454.716358509255, 9504.809903802023, 9626.759331016781, 9680.5643746033, 9462.951323416286, 9700.417974921527, 9747.317687761788, 9749.0, 9911.950440247752], "value": 6956.880347748626, "unit": "score"}], "name": "dromaeo_css", "value": 7433.502482234828, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 118.0, 112.0, 111.0, 113.0, 114.0, 115.0, 155.0, 173.0], "value": 123.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 125.0, 148.0, 162.0, 118.0, 144.0, 140.0, 117.0, 166.0, 121.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 187.0, 184.0, 181.0, 184.0, 187.0, 187.0, 189.0, 183.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 80.0, 73.0, 152.0, 57.0, 56.0, 59.0, 82.0, 169.0, 45.0], "value": 84.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 89.0, 139.0, 77.0, 83.0, 88.0, 123.0, 81.0, 77.0, 126.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 130.0, 92.0, 88.0, 91.0, 88.0, 91.0, 86.0, 133.0, 130.0], "value": 104.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 94.0, 96.0, 92.0, 97.0, 96.0, 95.0, 100.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 84.0, 84.0, 87.0, 83.0, 83.0, 85.0, 84.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 67.0, 66.0, 65.0, 64.0, 63.0, 65.0, 82.0, 61.0, 57.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 72.0, 84.0, 75.0, 71.0, 74.0, 68.0, 70.0, 70.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 95.0, 103.0, 105.0, 101.0, 78.0, 81.0, 81.0, 85.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 126.0, 129.0, 121.0, 125.0, 131.0, 130.0, 129.0, 150.0, 131.0], "value": 130.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 171.0, 168.0, 168.0, 165.0, 168.0, 158.0, 167.0, 173.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 68.0, 70.0, 60.0, 57.0, 70.0, 91.0, 92.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1512.2, "alertThreshold": 2.0}]} 17:40:25 INFO - Return code: 0 17:40:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:40:25 ERROR - # TBPL SUCCESS # 17:40:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:40:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:40:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:40:25 INFO - 2016-05-27 17:40:25.421 screenresolution[2244:22661] starting screenresolution argv=screenresolution get 17:40:25 INFO - 2016-05-27 17:40:25.423 screenresolution[2244:22661] Display 0: 1600x1200x32@60 17:40:25 INFO - 2016-05-27 17:40:25.429 screenresolution[2245:22664] starting screenresolution argv=screenresolution list 17:40:25 INFO - Available Modes on Display 0 17:40:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:40:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:40:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:40:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:40:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:40:25 INFO - Intel Iris: 17:40:25 INFO - Chipset Model: Intel Iris 17:40:25 INFO - Type: GPU 17:40:25 INFO - Bus: Built-In 17:40:25 INFO - VRAM (Dynamic, Max): 1536 MB 17:40:25 INFO - Vendor: Intel (0x8086) 17:40:25 INFO - Device ID: 0x0a2e 17:40:25 INFO - Revision ID: 0x0009 17:40:25 INFO - Displays: 17:40:25 INFO - Display: 17:40:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:40:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:40:25 INFO - Display Serial Number: 1600x1200 60 17:40:25 INFO - Main Display: Yes 17:40:25 INFO - Mirror: Off 17:40:25 INFO - Online: Yes 17:40:25 INFO - Rotation: Supported 17:40:25 INFO - Return code: 0 17:40:25 INFO - Running post-action listener: _resource_record_post_action 17:40:25 INFO - Running post-run listener: _resource_record_post_run 17:40:26 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 126976; Write bytes: 656270848; Read time: 2; Write time: 1984 17:40:26 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 346636800; Write bytes: 345785856; Read time: 27564; Write time: 728 17:40:26 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 126976; Write bytes: 300527616; Read time: 2; Write time: 1222 17:40:26 INFO - Running post-run listener: _upload_blobber_files 17:40:26 INFO - Blob upload gear active. 17:40:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:40:26 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 17:40:26 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'] 17:40:26 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 17:40:27 INFO - (blobuploader) - INFO - Open directory for files ... 17:40:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:40:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:40:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:27 INFO - (blobuploader) - INFO - Done attempting. 17:40:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:40:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:40:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:40:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:40:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:40:28 INFO - (blobuploader) - INFO - Done attempting. 17:40:28 INFO - (blobuploader) - INFO - Iteration through files over. 17:40:28 INFO - Return code: 0 17:40:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:40:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:40:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b6c9a0d474276d66df492d65abceb28d1efa5ce0b8f8f8b74453194fb33687f4be9f81807bb41c14491d4807a6be05045f688730e7f20586c0dad395312a10d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f440dd33c32b7d82a52f317a2563e678d0929b9e751f921f44fc508ee696bdf0205253debab534ae5bef9ccaeef7815f0d7ffeb33643588fefe56de59f9fe13f"} 17:40:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:40:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:40:28 INFO - Contents: 17:40:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b6c9a0d474276d66df492d65abceb28d1efa5ce0b8f8f8b74453194fb33687f4be9f81807bb41c14491d4807a6be05045f688730e7f20586c0dad395312a10d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f440dd33c32b7d82a52f317a2563e678d0929b9e751f921f44fc508ee696bdf0205253debab534ae5bef9ccaeef7815f0d7ffeb33643588fefe56de59f9fe13f"} 17:40:28 INFO - Running post-run listener: copy_logs_to_upload_dir 17:40:28 INFO - Copying logs to upload dir... 17:40:28 INFO - mkdir: /builds/slave/test/build/upload/logs 17:40:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1325.789801 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 5 secs) (at 2016-05-27 17:40:28.924175) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 17:40:28.927970) ========= 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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b6c9a0d474276d66df492d65abceb28d1efa5ce0b8f8f8b74453194fb33687f4be9f81807bb41c14491d4807a6be05045f688730e7f20586c0dad395312a10d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f440dd33c32b7d82a52f317a2563e678d0929b9e751f921f44fc508ee696bdf0205253debab534ae5bef9ccaeef7815f0d7ffeb33643588fefe56de59f9fe13f"} build_url:https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011413 build_url: 'https://queue.taskcluster.net/v1/task/LtwiupzRRJOcSQHBnqSX9w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b6c9a0d474276d66df492d65abceb28d1efa5ce0b8f8f8b74453194fb33687f4be9f81807bb41c14491d4807a6be05045f688730e7f20586c0dad395312a10d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f440dd33c32b7d82a52f317a2563e678d0929b9e751f921f44fc508ee696bdf0205253debab534ae5bef9ccaeef7815f0d7ffeb33643588fefe56de59f9fe13f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 17:40:28.958684) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:40:28.959019) ========= 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.STyvZnxLVJ/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.uUpp0ANf9F/Listeners TMPDIR=/var/folders/2y/lpbyjybx41n9034w0fnrskpc00000w/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.005442 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 17:40:29.007340) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 17:40:29.007675) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 17:40:29.008119) ========= ========= Total master_lag: 0.18 =========