builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0222 starttime: 1464069972.06 results: success (0) buildid: 20160523220203 builduid: ef737131239b46eea1c42de17647cc5f revision: 9b9a73a98de81f22189dd3972c922459bce28393 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:12.056048) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:12.056468) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:12.056757) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:12.113493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:12.113866) ========= 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.nnRy2jcToC/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.YmPRKQngD7/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-23 23:06:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 72.4M=0s 2016-05-23 23:06:12 (72.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165943 ========= master_lag: 0.35 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:12.626009) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:12.626350) ========= 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.nnRy2jcToC/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.YmPRKQngD7/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.026176 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:12.704958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 23:06:12.705301) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9b9a73a98de81f22189dd3972c922459bce28393 --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 9b9a73a98de81f22189dd3972c922459bce28393 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nnRy2jcToC/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.YmPRKQngD7/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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-23 23:06:12,783 truncating revision to first 12 chars 2016-05-23 23:06:12,784 Setting DEBUG logging. 2016-05-23 23:06:12,784 attempt 1/10 2016-05-23 23:06:12,784 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9b9a73a98de8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 23:06:13,759 unpacking tar archive at: mozilla-aurora-9b9a73a98de8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.232654 ========= master_lag: 0.39 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 23:06:14.331125) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:14.331473) ========= script_repo_revision: 9b9a73a98de81f22189dd3972c922459bce28393 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:14.331842) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:14.332101) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 23:06:14.962744) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-23 23:06:14.963008) ========= /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.nnRy2jcToC/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.YmPRKQngD7/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 23:06:15 INFO - MultiFileLogger online at 20160523 23:06:15 in /builds/slave/test 23:06:15 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 23:06:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:06:15 INFO - {'append_to_log': False, 23:06:15 INFO - 'base_work_dir': '/builds/slave/test', 23:06:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:06:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:06:15 INFO - 'branch': 'Mozilla-Aurora', 23:06:15 INFO - 'buildbot_json_path': 'buildprops.json', 23:06:15 INFO - 'config_files': ('talos/mac_config.py',), 23:06:15 INFO - 'default_actions': ('clobber', 23:06:15 INFO - 'read-buildbot-config', 23:06:15 INFO - 'download-and-extract', 23:06:15 INFO - 'populate-webroot', 23:06:15 INFO - 'create-virtualenv', 23:06:15 INFO - 'install', 23:06:15 INFO - 'run-tests'), 23:06:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:06:15 INFO - 'download_minidump_stackwalk': True, 23:06:15 INFO - 'download_symbols': 'ondemand', 23:06:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:06:15 INFO - 'tooltool.py': '/tools/tooltool.py', 23:06:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:06:15 INFO - '/tools/misc-python/virtualenv.py')}, 23:06:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:06:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:06:15 INFO - 'installer_path': 'installer.exe', 23:06:15 INFO - 'log_level': 'info', 23:06:15 INFO - 'log_name': 'talos', 23:06:15 INFO - 'log_to_console': True, 23:06:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:06:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:06:15 INFO - 'opt_config_files': (), 23:06:15 INFO - 'pip_index': False, 23:06:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:06:15 INFO - 'cmd': ('bash', 23:06:15 INFO - '-c', 23:06:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:06:15 INFO - 'enabled': True, 23:06:15 INFO - 'halt_on_failure': False, 23:06:15 INFO - 'name': 'check_screen_resolution'},), 23:06:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:06:15 INFO - 'cmd': ('bash', 23:06:15 INFO - '-c', 23:06:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:06:15 INFO - 'enabled': True, 23:06:15 INFO - 'halt_on_failure': False, 23:06:15 INFO - 'name': 'check_screen_resolution'},), 23:06:15 INFO - 'run_cmd_checks_enabled': True, 23:06:15 INFO - 'sps_profile': False, 23:06:15 INFO - 'sps_profile_interval': 0, 23:06:15 INFO - 'suite': 'dromaeojs', 23:06:15 INFO - 'system_bits': '32', 23:06:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:06:15 INFO - 'title': 't-yosemite-r7-0222', 23:06:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:06:15 INFO - 'use_talos_json': True, 23:06:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:06:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:06:15 INFO - 'work_dir': 'build'} 23:06:15 INFO - ##### 23:06:15 INFO - ##### Running clobber step. 23:06:15 INFO - ##### 23:06:15 INFO - Running pre-action listener: _resource_record_pre_action 23:06:15 INFO - Running main action method: clobber 23:06:15 INFO - rmtree: /builds/slave/test/build 23:06:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:06:17 INFO - Running post-action listener: _resource_record_post_action 23:06:17 INFO - ##### 23:06:17 INFO - ##### Running read-buildbot-config step. 23:06:17 INFO - ##### 23:06:17 INFO - Running pre-action listener: _resource_record_pre_action 23:06:17 INFO - Running main action method: read_buildbot_config 23:06:17 INFO - Using buildbot properties: 23:06:17 INFO - { 23:06:17 INFO - "project": "", 23:06:17 INFO - "product": "firefox", 23:06:17 INFO - "script_repo_revision": "production", 23:06:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 23:06:17 INFO - "repository": "", 23:06:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 23:06:17 INFO - "buildid": "20160523220203", 23:06:17 INFO - "pgo_build": "False", 23:06:17 INFO - "basedir": "/builds/slave/test", 23:06:17 INFO - "buildnumber": 13, 23:06:17 INFO - "slavename": "t-yosemite-r7-0222", 23:06:17 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 23:06:17 INFO - "platform": "yosemite_r7", 23:06:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 23:06:17 INFO - "branch": "mozilla-aurora", 23:06:17 INFO - "repo_path": "releases/mozilla-aurora", 23:06:17 INFO - "revision": "9b9a73a98de81f22189dd3972c922459bce28393", 23:06:17 INFO - "stage_platform": "macosx64", 23:06:17 INFO - "builduid": "ef737131239b46eea1c42de17647cc5f", 23:06:17 INFO - "slavebuilddir": "test" 23:06:17 INFO - } 23:06:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 23:06:17 INFO - Running post-action listener: _resource_record_post_action 23:06:17 INFO - ##### 23:06:17 INFO - ##### Running download-and-extract step. 23:06:17 INFO - ##### 23:06:17 INFO - Running pre-action listener: _resource_record_pre_action 23:06:17 INFO - Running main action method: download_and_extract 23:06:17 INFO - mkdir: /builds/slave/test/build/tests 23:06:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:17 INFO - https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:06:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 23:06:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 23:06:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:29 INFO - Downloaded 1199 bytes. 23:06:29 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 23:06:29 INFO - Using the following test package requirements: 23:06:29 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 23:06:29 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 23:06:29 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 23:06:29 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'jsshell-mac.zip'], 23:06:29 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 23:06:29 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 23:06:29 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 23:06:29 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 23:06:29 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 23:06:29 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 23:06:29 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 23:06:29 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 23:06:29 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')]} 23:06:29 INFO - https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:06:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:06:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:06:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:31 INFO - Downloaded 18102373 bytes. 23:06:31 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'] 23:06:31 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 23:06:34 INFO - Return code: 0 23:06:34 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 23:06:34 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')]} 23:06:34 INFO - https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:06:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:06:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 23:06:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:35 INFO - Downloaded 18102373 bytes. 23:06:35 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'] 23:06:35 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 23:06:39 INFO - Return code: 0 23:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:39 INFO - https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:06:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 23:06:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 23:06:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:40 INFO - Downloaded 10840145 bytes. 23:06:40 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'] 23:06:40 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 23:06:40 INFO - Return code: 0 23:06:40 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')]} 23:06:40 INFO - https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 23:06:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:06:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:06:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 23:06:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EvgWke2bS7i6rGX5abNlvg/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 23:06:43 INFO - Downloaded 114133571 bytes. 23:06:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:06:43 INFO - mkdir: /builds/slave/test/properties 23:06:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:06:43 INFO - Writing to file /builds/slave/test/properties/build_url 23:06:43 INFO - Contents: 23:06:43 INFO - build_url:https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 23:06:44 INFO - Running post-action listener: _resource_record_post_action 23:06:44 INFO - Running post-action listener: set_extra_try_arguments 23:06:44 INFO - ##### 23:06:44 INFO - ##### Running populate-webroot step. 23:06:44 INFO - ##### 23:06:44 INFO - Running pre-action listener: _resource_record_pre_action 23:06:44 INFO - Running main action method: populate_webroot 23:06:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:06:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:06:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:06:44 INFO - '--tppagecycles', 23:06:44 INFO - '7'], 23:06:44 INFO - 'tests': ['tsvgm']}}, 23:06:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:06:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:06:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:06:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:06:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:06:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:06:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:44 INFO - 'talos_options': ['--disable-e10s'], 23:06:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:06:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:06:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:44 INFO - 'talos_options': ['--disable-e10s'], 23:06:44 INFO - 'tests': ['damp', 'tps']}, 23:06:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:44 INFO - 'tests': ['damp', 'tps']}, 23:06:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:06:44 INFO - 'tests': ['dromaeo_dom']}, 23:06:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:06:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:06:44 INFO - 'tests': ['a11yr', 23:06:44 INFO - 'ts_paint', 23:06:44 INFO - 'tpaint', 23:06:44 INFO - 'sessionrestore', 23:06:44 INFO - 'sessionrestore_no_auto_restore', 23:06:44 INFO - 'tabpaint']}, 23:06:44 INFO - 'other-e10s': {'tests': ['a11yr', 23:06:44 INFO - 'ts_paint', 23:06:44 INFO - 'tpaint', 23:06:44 INFO - 'sessionrestore', 23:06:44 INFO - 'sessionrestore_no_auto_restore', 23:06:44 INFO - 'tabpaint']}, 23:06:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:06:44 INFO - 'ts_paint', 23:06:44 INFO - 'tpaint', 23:06:44 INFO - 'sessionrestore', 23:06:44 INFO - 'sessionrestore_no_auto_restore', 23:06:44 INFO - 'tabpaint']}, 23:06:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:06:44 INFO - 'ts_paint', 23:06:44 INFO - 'tpaint', 23:06:44 INFO - 'sessionrestore', 23:06:44 INFO - 'sessionrestore_no_auto_restore', 23:06:44 INFO - 'tabpaint']}, 23:06:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:06:44 INFO - 'tests': ['a11yr', 23:06:44 INFO - 'ts_paint', 23:06:44 INFO - 'tpaint', 23:06:44 INFO - 'sessionrestore', 23:06:44 INFO - 'sessionrestore_no_auto_restore', 23:06:44 INFO - 'tabpaint']}, 23:06:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:06:44 INFO - 'tests': ['a11yr', 23:06:44 INFO - 'ts_paint', 23:06:44 INFO - 'tpaint', 23:06:44 INFO - 'sessionrestore', 23:06:44 INFO - 'sessionrestore_no_auto_restore', 23:06:44 INFO - 'tabpaint']}, 23:06:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:06:44 INFO - 'tests': ['tsvgx', 23:06:44 INFO - 'tsvgr_opacity', 23:06:44 INFO - 'tart', 23:06:44 INFO - 'tscrollx', 23:06:44 INFO - 'cart']}, 23:06:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:06:44 INFO - 'tsvgr_opacity', 23:06:44 INFO - 'tart', 23:06:44 INFO - 'tscrollx', 23:06:44 INFO - 'cart']}, 23:06:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:44 INFO - 'talos_options': ['--disable-e10s'], 23:06:44 INFO - 'tests': ['tp5o']}, 23:06:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:44 INFO - 'tests': ['tp5o']}, 23:06:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:44 INFO - 'talos_options': ['--disable-e10s', 23:06:44 INFO - '--xperf_path', 23:06:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:06:44 INFO - 'tests': ['tp5n']}, 23:06:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:06:44 INFO - 'talos_options': ['--xperf_path', 23:06:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:06:44 INFO - 'tests': ['tp5n']}}, 23:06:44 INFO - 'talos.zip': {'path': '', 23:06:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:06:44 INFO - Running post-action listener: _resource_record_post_action 23:06:44 INFO - ##### 23:06:44 INFO - ##### Running create-virtualenv step. 23:06:44 INFO - ##### 23:06:44 INFO - Running pre-action listener: _resource_record_pre_action 23:06:44 INFO - Running main action method: create_virtualenv 23:06:44 INFO - Creating virtualenv /builds/slave/test/build/venv 23:06:44 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 23:06:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:06:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:06:44 INFO - Using real prefix '/tools/python27' 23:06:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:06:45 INFO - Installing distribute.............................................................................................................................................................................................done. 23:06:48 INFO - Installing pip.................done. 23:06:48 INFO - Return code: 0 23:06:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107b5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd525e8870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079fde00>, '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 0x107866db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079f8b20>, '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.nnRy2jcToC/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.YmPRKQngD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 23:06:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:06:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnRy2jcToC/Render', 23:06:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:48 INFO - 'HOME': '/Users/cltbld', 23:06:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:48 INFO - 'LOGNAME': 'cltbld', 23:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:48 INFO - 'MOZ_NO_REMOTE': '1', 23:06:48 INFO - 'NO_EM_RESTART': '1', 23:06:48 INFO - 'PAGER': '/bin/cat', 23:06:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:48 INFO - 'PWD': '/builds/slave/test', 23:06:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:48 INFO - 'SHELL': '/bin/bash', 23:06:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPRKQngD7/Listeners', 23:06:48 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 23:06:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:48 INFO - 'USER': 'cltbld', 23:06:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:48 INFO - 'XPC_FLAGS': '0x0', 23:06:48 INFO - 'XPC_SERVICE_NAME': '0', 23:06:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:48 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 23:06:48 INFO - Cleaning up... 23:06:48 INFO - Return code: 0 23:06:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107b5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd525e8870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079fde00>, '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 0x107866db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079f8b20>, '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.nnRy2jcToC/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.YmPRKQngD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 23:06:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 23:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 23:06:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnRy2jcToC/Render', 23:06:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:48 INFO - 'HOME': '/Users/cltbld', 23:06:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:48 INFO - 'LOGNAME': 'cltbld', 23:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:48 INFO - 'MOZ_NO_REMOTE': '1', 23:06:48 INFO - 'NO_EM_RESTART': '1', 23:06:48 INFO - 'PAGER': '/bin/cat', 23:06:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:48 INFO - 'PWD': '/builds/slave/test', 23:06:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:48 INFO - 'SHELL': '/bin/bash', 23:06:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPRKQngD7/Listeners', 23:06:48 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 23:06:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:48 INFO - 'USER': 'cltbld', 23:06:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:48 INFO - 'XPC_FLAGS': '0x0', 23:06:48 INFO - 'XPC_SERVICE_NAME': '0', 23:06:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:48 INFO - Downloading/unpacking psutil>=0.7.1 23:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:06:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:06:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:06:53 INFO - Installing collected packages: psutil 23:06:53 INFO - Running setup.py install for psutil 23:06:53 INFO - building 'psutil._psutil_osx' extension 23:06:53 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 23:06:53 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 23:06:54 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 23:06:54 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 23:06:54 INFO - building 'psutil._psutil_posix' extension 23:06:54 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 23:06:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:06:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:06:54 INFO - ^ 23:06:54 INFO - 1 warning generated. 23:06:54 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 23:06:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:06:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:06:54 INFO - Successfully installed psutil 23:06:54 INFO - Cleaning up... 23:06:54 INFO - Return code: 0 23:06:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:06:54 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')]} 23:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:54 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')]} 23:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:54 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 0x107b5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd525e8870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079fde00>, '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 0x107866db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079f8b20>, '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.nnRy2jcToC/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.YmPRKQngD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 23:06:54 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 23:06:54 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 23:06:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnRy2jcToC/Render', 23:06:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:54 INFO - 'HOME': '/Users/cltbld', 23:06:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:54 INFO - 'LOGNAME': 'cltbld', 23:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:54 INFO - 'MOZ_NO_REMOTE': '1', 23:06:54 INFO - 'NO_EM_RESTART': '1', 23:06:54 INFO - 'PAGER': '/bin/cat', 23:06:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:54 INFO - 'PWD': '/builds/slave/test', 23:06:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:54 INFO - 'SHELL': '/bin/bash', 23:06:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPRKQngD7/Listeners', 23:06:54 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 23:06:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:54 INFO - 'USER': 'cltbld', 23:06:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:54 INFO - 'XPC_FLAGS': '0x0', 23:06:54 INFO - 'XPC_SERVICE_NAME': '0', 23:06:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:06: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 23:06: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 23:06: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 23:06: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 23:06: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 23:06: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 23:06:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:06:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:06:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:06:57 INFO - Installing collected packages: mozsystemmonitor 23:06:57 INFO - Running setup.py install for mozsystemmonitor 23:06:57 INFO - Successfully installed mozsystemmonitor 23:06:57 INFO - Cleaning up... 23:06:57 INFO - Return code: 0 23:06:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:06:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107b5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd525e8870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079fde00>, '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 0x107866db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079f8b20>, '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.nnRy2jcToC/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.YmPRKQngD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 23:06:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnRy2jcToC/Render', 23:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:57 INFO - 'HOME': '/Users/cltbld', 23:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:57 INFO - 'LOGNAME': 'cltbld', 23:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:57 INFO - 'MOZ_NO_REMOTE': '1', 23:06:57 INFO - 'NO_EM_RESTART': '1', 23:06:57 INFO - 'PAGER': '/bin/cat', 23:06:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:57 INFO - 'PWD': '/builds/slave/test', 23:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:57 INFO - 'SHELL': '/bin/bash', 23:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPRKQngD7/Listeners', 23:06:57 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 23:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:57 INFO - 'USER': 'cltbld', 23:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:57 INFO - 'XPC_FLAGS': '0x0', 23:06:57 INFO - 'XPC_SERVICE_NAME': '0', 23:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:57 INFO - Downloading/unpacking blobuploader==1.2.4 23:06: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 23:06: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 23:06: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 23:06: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 23:06: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 23:06: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 23:07:00 INFO - Downloading blobuploader-1.2.4.tar.gz 23:07:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:07:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:07:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:01 INFO - Downloading docopt-0.6.1.tar.gz 23:07:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:07:01 INFO - Installing collected packages: blobuploader, requests, docopt 23:07:01 INFO - Running setup.py install for blobuploader 23:07:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:07:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:07:01 INFO - Running setup.py install for requests 23:07:02 INFO - Running setup.py install for docopt 23:07:02 INFO - Successfully installed blobuploader requests docopt 23:07:02 INFO - Cleaning up... 23:07:02 INFO - Return code: 0 23:07:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07:02 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')]} 23:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:02 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')]} 23:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:02 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 0x107b5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd525e8870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079fde00>, '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 0x107866db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079f8b20>, '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.nnRy2jcToC/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.YmPRKQngD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 23:07:02 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 23:07:02 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 23:07:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnRy2jcToC/Render', 23:07:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:02 INFO - 'HOME': '/Users/cltbld', 23:07:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:02 INFO - 'LOGNAME': 'cltbld', 23:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:02 INFO - 'MOZ_NO_REMOTE': '1', 23:07:02 INFO - 'NO_EM_RESTART': '1', 23:07:02 INFO - 'PAGER': '/bin/cat', 23:07:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:02 INFO - 'PWD': '/builds/slave/test', 23:07:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:02 INFO - 'SHELL': '/bin/bash', 23:07:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPRKQngD7/Listeners', 23:07:02 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 23:07:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:02 INFO - 'USER': 'cltbld', 23:07:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:02 INFO - 'XPC_FLAGS': '0x0', 23:07:02 INFO - 'XPC_SERVICE_NAME': '0', 23:07:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:07:02 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ISi1PL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:07:02 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-kOc8VI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:07:02 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-cL_gSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:07:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:07:02 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-NXiau7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:07:03 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-32Rqom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:07:03 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-Wburgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:07:03 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-kK6ya3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:07:03 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-F39rZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:07:03 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-pcaVZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:07:03 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-pOo3k2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:07:03 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-7e65Fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:07:03 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-4N6Yoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:07:03 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-vLxO0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:07:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:07:03 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-sPeB3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:04 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-gfoBEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:07:04 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-IFWoMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:07:04 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-iEzlqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:07:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:07:04 INFO - Running setup.py install for manifestparser 23:07:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:07:04 INFO - Running setup.py install for mozcrash 23:07:04 INFO - Running setup.py install for mozdebug 23:07:04 INFO - Running setup.py install for mozdevice 23:07:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:07:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:07:04 INFO - Running setup.py install for mozfile 23:07:05 INFO - Running setup.py install for mozhttpd 23:07:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:07:05 INFO - Running setup.py install for mozinfo 23:07:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:07:05 INFO - Running setup.py install for mozInstall 23:07:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:07:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:07:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:07:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:07:05 INFO - Running setup.py install for mozleak 23:07:05 INFO - Running setup.py install for mozlog 23:07:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:07:05 INFO - Running setup.py install for moznetwork 23:07:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:07:05 INFO - Running setup.py install for mozprocess 23:07:06 INFO - Running setup.py install for mozprofile 23:07:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:07:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:07:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:07:06 INFO - Running setup.py install for mozrunner 23:07:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:07:06 INFO - Running setup.py install for mozscreenshot 23:07:06 INFO - Running setup.py install for moztest 23:07:06 INFO - Running setup.py install for mozversion 23:07:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:07:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:07:06 INFO - Cleaning up... 23:07:06 INFO - Return code: 0 23:07:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:06 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 0x107b5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd525e8870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079fde00>, '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 0x107866db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079f8b20>, '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.nnRy2jcToC/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.YmPRKQngD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 23:07:06 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 23:07:06 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 23:07:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnRy2jcToC/Render', 23:07:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:06 INFO - 'HOME': '/Users/cltbld', 23:07:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:06 INFO - 'LOGNAME': 'cltbld', 23:07:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:06 INFO - 'MOZ_NO_REMOTE': '1', 23:07:06 INFO - 'NO_EM_RESTART': '1', 23:07:06 INFO - 'PAGER': '/bin/cat', 23:07:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:06 INFO - 'PWD': '/builds/slave/test', 23:07:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:06 INFO - 'SHELL': '/bin/bash', 23:07:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPRKQngD7/Listeners', 23:07:06 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 23:07:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:06 INFO - 'USER': 'cltbld', 23:07:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:06 INFO - 'XPC_FLAGS': '0x0', 23:07:06 INFO - 'XPC_SERVICE_NAME': '0', 23:07:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:07:07 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-lAO1h8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:07:07 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)) 23:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:07:07 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-k7P9zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:07:07 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)) 23:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:07:07 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-06adHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:07:07 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)) 23:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:07:07 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-l9Fygz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:07:07 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)) 23:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:07:07 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-nhQsNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:07:07 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)) 23:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:07:07 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-mtoIMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:07:07 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)) 23:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:07:07 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-0Dr6f_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:07:07 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)) 23:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:07:07 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-bGCFPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:07:08 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)) 23:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:07:08 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-3_Pexz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:07:08 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)) 23:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:07:08 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-ImtcHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:07:08 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)) 23:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:07:08 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-EcDyA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:07:08 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)) 23:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:07:08 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-q1HB9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:07:08 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)) 23:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:07:08 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-atI27c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:07:08 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)) 23:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:07:08 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-CCgsCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:07:08 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)) 23:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:08 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-pDSYAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:08 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)) 23:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:07:08 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-bqiOrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:07:08 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)) 23:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:07:08 INFO - Running setup.py (path:/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/pip-JZWo96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:07:08 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)) 23:07:08 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)) 23:07:08 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)) 23:07:08 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)) 23:07:08 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)) 23:07:08 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)) 23:07:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:07:08 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 23:07:08 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 23:07:08 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 23:07:08 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 23:07:08 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 23:07:08 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 23:07:11 INFO - Downloading blessings-1.6.tar.gz 23:07:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:07:11 INFO - Installing collected packages: blessings 23:07:11 INFO - Running setup.py install for blessings 23:07:11 INFO - Successfully installed blessings 23:07:11 INFO - Cleaning up... 23:07:11 INFO - Return code: 0 23:07:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:07:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:07:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:07:12 INFO - Reading from file tmpfile_stdout 23:07:12 INFO - Current package versions: 23:07:12 INFO - blessings == 1.6 23:07:12 INFO - blobuploader == 1.2.4 23:07:12 INFO - docopt == 0.6.1 23:07:12 INFO - manifestparser == 1.1 23:07:12 INFO - mozInstall == 1.12 23:07:12 INFO - mozcrash == 0.17 23:07:12 INFO - mozdebug == 0.1 23:07:12 INFO - mozdevice == 0.48 23:07:12 INFO - mozfile == 1.2 23:07:12 INFO - mozhttpd == 0.7 23:07:12 INFO - mozinfo == 0.9 23:07:12 INFO - mozleak == 0.1 23:07:12 INFO - mozlog == 3.1 23:07:12 INFO - moznetwork == 0.27 23:07:12 INFO - mozprocess == 0.22 23:07:12 INFO - mozprofile == 0.28 23:07:12 INFO - mozrunner == 6.11 23:07:12 INFO - mozscreenshot == 0.1 23:07:12 INFO - mozsystemmonitor == 0.0 23:07:12 INFO - moztest == 0.7 23:07:12 INFO - mozversion == 1.4 23:07:12 INFO - psutil == 3.1.1 23:07:12 INFO - requests == 1.2.3 23:07:12 INFO - wsgiref == 0.1.2 23:07:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07: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')]} 23:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07: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')]} 23:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:12 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 0x107b5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd525e8870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079fde00>, '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 0x107866db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079f8b20>, '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.nnRy2jcToC/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.YmPRKQngD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 23:07:12 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 23:07:12 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 23:07:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnRy2jcToC/Render', 23:07:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:12 INFO - 'HOME': '/Users/cltbld', 23:07:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:12 INFO - 'LOGNAME': 'cltbld', 23:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:12 INFO - 'MOZ_NO_REMOTE': '1', 23:07:12 INFO - 'NO_EM_RESTART': '1', 23:07:12 INFO - 'PAGER': '/bin/cat', 23:07:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:12 INFO - 'PWD': '/builds/slave/test', 23:07:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:12 INFO - 'SHELL': '/bin/bash', 23:07:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPRKQngD7/Listeners', 23:07:12 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 23:07:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:12 INFO - 'USER': 'cltbld', 23:07:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:12 INFO - 'XPC_FLAGS': '0x0', 23:07:12 INFO - 'XPC_SERVICE_NAME': '0', 23:07:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:12 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)) 23:07:12 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)) 23:07:12 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)) 23:07:12 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)) 23:07:12 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)) 23:07:12 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)) 23:07:12 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)) 23:07:12 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)) 23:07:12 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)) 23:07:12 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)) 23:07:12 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)) 23:07:12 INFO - Cleaning up... 23:07:12 INFO - Return code: 0 23:07:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:07: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')]} 23:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07: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')]} 23:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107b5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd525e8870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079fde00>, '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 0x107866db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079f8b20>, '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.nnRy2jcToC/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.YmPRKQngD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 23:07: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', 'jsonschema'] in /builds/slave/test/build 23:07: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 jsonschema 23:07:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnRy2jcToC/Render', 23:07:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:12 INFO - 'HOME': '/Users/cltbld', 23:07:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:12 INFO - 'LOGNAME': 'cltbld', 23:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:12 INFO - 'MOZ_NO_REMOTE': '1', 23:07:12 INFO - 'NO_EM_RESTART': '1', 23:07:12 INFO - 'PAGER': '/bin/cat', 23:07:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:12 INFO - 'PWD': '/builds/slave/test', 23:07:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:12 INFO - 'SHELL': '/bin/bash', 23:07:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPRKQngD7/Listeners', 23:07:12 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 23:07:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:12 INFO - 'USER': 'cltbld', 23:07:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:12 INFO - 'XPC_FLAGS': '0x0', 23:07:12 INFO - 'XPC_SERVICE_NAME': '0', 23:07:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:12 INFO - Downloading/unpacking jsonschema 23:07: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 23:07: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 23:07: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 23:07: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 23:07: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 23:07: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 23:07:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:07:15 INFO - Downloading/unpacking functools32 (from jsonschema) 23:07: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 23:07: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 23:07: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 23:07: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 23:07: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 23:07: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 23:07:15 INFO - Downloading functools32-3.2.3-2.tar.gz 23:07:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:07:15 INFO - warning: no files found matching '*.txt' 23:07:15 INFO - no previously-included directories found matching 'build' 23:07:15 INFO - no previously-included directories found matching 'dist' 23:07:15 INFO - no previously-included directories found matching '.git*' 23:07:15 INFO - Installing collected packages: jsonschema, functools32 23:07:15 INFO - Running setup.py install for functools32 23:07:15 INFO - warning: no files found matching '*.txt' 23:07:15 INFO - no previously-included directories found matching 'build' 23:07:15 INFO - no previously-included directories found matching 'dist' 23:07:15 INFO - no previously-included directories found matching '.git*' 23:07:15 INFO - Successfully installed jsonschema functools32 23:07:15 INFO - Cleaning up... 23:07:15 INFO - Return code: 0 23:07:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107b5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b6e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd525e8870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079fde00>, '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 0x107866db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079f8b20>, '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.nnRy2jcToC/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.YmPRKQngD7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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 23:07:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:07:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnRy2jcToC/Render', 23:07:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:15 INFO - 'HOME': '/Users/cltbld', 23:07:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:15 INFO - 'LOGNAME': 'cltbld', 23:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:15 INFO - 'MOZ_NO_REMOTE': '1', 23:07:15 INFO - 'NO_EM_RESTART': '1', 23:07:15 INFO - 'PAGER': '/bin/cat', 23:07:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:15 INFO - 'PWD': '/builds/slave/test', 23:07:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:15 INFO - 'SHELL': '/bin/bash', 23:07:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPRKQngD7/Listeners', 23:07:15 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 23:07:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:15 INFO - 'USER': 'cltbld', 23:07:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:15 INFO - 'XPC_FLAGS': '0x0', 23:07:15 INFO - 'XPC_SERVICE_NAME': '0', 23:07:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:16 INFO - Downloading/unpacking flake8 23:07:16 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 23:07:16 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 23:07:16 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 23:07:16 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 23:07:16 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 23:07:16 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 23:07:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:07:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:07:18 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 23:07:18 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 23:07:18 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 23:07:18 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 23:07:18 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 23:07:18 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 23:07:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:07:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:07:18 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 23:07:18 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 23:07:18 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 23:07:18 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 23:07:18 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 23:07:18 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 23:07:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:07:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:07:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:07:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:07:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:07:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:07:19 INFO - no previously-included directories found matching 'docs/_build' 23:07:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:07:19 INFO - Running setup.py install for pep8 23:07:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:07:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:07:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:07:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:07:20 INFO - no previously-included directories found matching 'docs/_build' 23:07:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:07:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:07:20 INFO - Cleaning up... 23:07:20 INFO - Return code: 0 23:07:20 INFO - Running post-action listener: _resource_record_post_action 23:07:20 INFO - Running post-action listener: _start_resource_monitoring 23:07:20 INFO - Starting resource monitoring. 23:07:20 INFO - ##### 23:07:20 INFO - ##### Running install step. 23:07:20 INFO - ##### 23:07:20 INFO - Running pre-action listener: _resource_record_pre_action 23:07:20 INFO - Running main action method: install 23:07:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:07:20 INFO - Reading from file tmpfile_stdout 23:07:20 INFO - Detecting whether we're running mozinstall >=1.0... 23:07:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:07:20 INFO - Reading from file tmpfile_stdout 23:07:20 INFO - Output received: 23:07:20 INFO - Usage: mozinstall [options] installer 23:07:20 INFO - Options: 23:07:20 INFO - -h, --help show this help message and exit 23:07:20 INFO - -d DEST, --destination=DEST 23:07:20 INFO - Directory to install application into. [default: 23:07:20 INFO - "/builds/slave/test"] 23:07:20 INFO - --app=APP Application being installed. [default: firefox] 23:07:20 INFO - mkdir: /builds/slave/test/build/application 23:07:20 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'] 23:07:20 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 23:07:52 INFO - Reading from file tmpfile_stdout 23:07:52 INFO - Output received: 23:07:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 23:07:52 INFO - Running post-action listener: _resource_record_post_action 23:07:52 INFO - ##### 23:07:52 INFO - ##### Running run-tests step. 23:07:52 INFO - ##### 23:07:52 INFO - Running pre-action listener: _resource_record_pre_action 23:07:52 INFO - Running main action method: run_tests 23:07:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:07:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:07:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:07:52 INFO - 2016-05-23 23:07:52.996 screenresolution[1646:8458] starting screenresolution argv=screenresolution get 23:07:52 INFO - 2016-05-23 23:07:52.999 screenresolution[1646:8458] Display 0: 1600x1200x32@60 23:07:53 INFO - 2016-05-23 23:07:53.004 screenresolution[1647:8461] starting screenresolution argv=screenresolution list 23:07:53 INFO - Available Modes on Display 0 23:07:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:07:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:07:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:07:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:07:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:07:53 INFO - Intel Iris: 23:07:53 INFO - Chipset Model: Intel Iris 23:07:53 INFO - Type: GPU 23:07:53 INFO - Bus: Built-In 23:07:53 INFO - VRAM (Dynamic, Max): 1536 MB 23:07:53 INFO - Vendor: Intel (0x8086) 23:07:53 INFO - Device ID: 0x0a2e 23:07:53 INFO - Revision ID: 0x0009 23:07:53 INFO - Displays: 23:07:53 INFO - Display: 23:07:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:07:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:07:53 INFO - Display Serial Number: 1600x1200 60 23:07:53 INFO - Main Display: Yes 23:07:53 INFO - Mirror: Off 23:07:53 INFO - Online: Yes 23:07:53 INFO - Rotation: Supported 23:07:53 INFO - Return code: 0 23:07:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:07:53 INFO - Python 2.7.3 23:07:53 INFO - Return code: 0 23:07:53 INFO - grabbing minidump binary from tooltool 23:07: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')]} 23:07:53 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 0x1079fde00>, '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 0x107866db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1079f8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:07:53 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 23:07:53 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 23:07:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:07:53 INFO - Return code: 0 23:07:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:07:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:07:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:07:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:07:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:07:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:07:54 INFO - Return code: 0 23:07:54 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/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--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 23:07:54 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/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0222 --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 23:07:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nnRy2jcToC/Render', 23:07:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:07:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:07:54 INFO - 'HOME': '/Users/cltbld', 23:07:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:07:54 INFO - 'LOGNAME': 'cltbld', 23:07:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:07:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:54 INFO - 'MOZ_NO_REMOTE': '1', 23:07:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:07:54 INFO - 'NO_EM_RESTART': '1', 23:07:54 INFO - 'PAGER': '/bin/cat', 23:07:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:54 INFO - 'PWD': '/builds/slave/test', 23:07:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:07:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:07:54 INFO - 'SHELL': '/bin/bash', 23:07:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPRKQngD7/Listeners', 23:07:54 INFO - 'TMPDIR': '/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/', 23:07:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:07:54 INFO - 'USER': 'cltbld', 23:07:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:07:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:54 INFO - 'XPC_FLAGS': '0x0', 23:07:54 INFO - 'XPC_SERVICE_NAME': '0', 23:07:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:07:54 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/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0222', '--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 23:07:54 INFO - mozversion application_buildid: 20160523220203 23:07:54 INFO - mozversion application_changeset: 9b9a73a98de81f22189dd3972c922459bce28393 23:07:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:07:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:07:54 INFO - mozversion application_name: Firefox 23:07:54 INFO - mozversion application_remotingname: firefox-dev 23:07:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:07:54 INFO - mozversion application_vendor: Mozilla 23:07:54 INFO - mozversion application_version: 48.0a2 23:07:54 INFO - mozversion platform_buildid: 20160523220203 23:07:54 INFO - mozversion platform_changeset: 9b9a73a98de81f22189dd3972c922459bce28393 23:07:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:07:54 INFO - mozversion platform_version: 48.0a2 23:07:54 INFO - using testdate: 1464070074 23:07:54 INFO - actual date: 1464070074 23:07:54 INFO - starting webserver on 'localhost:49271' 23:07:54 INFO - SUITE-START | Running 2 tests 23:07:54 INFO - TEST-START | dromaeo_css 23:07:54 INFO - operating with platform_type : mac_ 23:07:54 INFO - Initialising browser for dromaeo_css test... 23:07:54 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpOJcuT0/profile http://localhost:49271/getInfo.html) 23:07:56 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 23:07:56 INFO - PROCESS | 1665 | colorDepth:24 23:07:56 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 23:07:56 INFO - PROCESS | 1665 | __metrics 23:07:56 INFO - PROCESS | 1665 | 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" 23:07:56 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:07:57 INFO - TEST-INFO | 1665: exit 0 23:07:57 INFO - Browser initialized. 23:07:57 INFO - Running cycle 1/1 for dromaeo_css test... 23:07:57 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmpOJcuT0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:08:09 INFO - PROCESS | 1668 | RSS: Main: 210948096 23:08:09 INFO - PROCESS | 1668 | 23:09:01 INFO - PROCESS | 1668 | 1464070141241 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] 23:11:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 23:11:13 INFO - PROCESS | 1668 | RSS: Main: 338948096 23:11:13 INFO - PROCESS | 1668 | 23:14:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 23:14:16 INFO - PROCESS | 1668 | RSS: Main: 347049984 23:14:16 INFO - PROCESS | 1668 | 23:17:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 23:17:19 INFO - PROCESS | 1668 | RSS: Main: 361013248 23:17:19 INFO - PROCESS | 1668 | 23:20:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 23:20:22 INFO - PROCESS | 1668 | RSS: Main: 360312832 23:20:22 INFO - PROCESS | 1668 | 23:23:25 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 23:23:26 INFO - PROCESS | 1668 | RSS: Main: 329367552 23:23:26 INFO - PROCESS | 1668 | 23:26:28 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 23:26:29 INFO - PROCESS | 1668 | RSS: Main: 371355648 23:26:29 INFO - PROCESS | 1668 | 23:26:29 INFO - PROCESS | 1668 | __start_tp_report 23:26:29 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 23:26:29 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 23:26:29 INFO - PROCESS | 1668 | |i|pagename|runs| 23:26:29 INFO - PROCESS | 1668 | |0;dojo.html;3399.2521645238053;3432.4679674650424;3453.568303962005;3456.5679290088738;3513.9648603513956;5651.491365777079;5651.576131790117;5689.2319536862515;5689.943100568993;5711.628744131639;5703.429657034305;5740.024195886699;5745.741441635126;5762.3949485304065;5769.336526299467;5117.385913690344;5133.050385678643;5133.050385678652;5267.973660131694;5276.92084618731;5201.297824793661;5201.323827902367;5209.166533354664;5209.869753256161;5233.084210263208;5176.5341119299255;5182.170852663575;5204.713740744258;5221.060209162369;5229.111051121299;5238.240455133984;5240.947590524103;5249.136556522418;5259.237410575445;5297.231901374279;4130.938035929464;5092.185250359962;5105.438401775802;5153.330067091291;5192.273081768555;5554.833354999357;5569.554435645138;5588.720563971826;5589.832305030855;5592.5805564582415;6142.385761423867;6151.876962460726;6158.753649854045;6160.322364539897;6180.783672571483;6127.250613123855;6161.599496032788;6210.31686514483;6239.313675495692;6252.6561039142825;5744.368119506851;5774.971125144348;5780.855478612984;5788.016037273632;5810.912836307459;6258.436740693269;6261.906071408933;6265.4361107500545;6269.843253918689;6271.62370257786;5993.160957465958;6045.486133678599;6082.60463069899;6101.572889897665;6142.692865356715;6238.719257633417;6240.188775459252;6243.282022567372;6247.656378899113;6255.687215639291;6116.908246376308;6173.969130154321;6184.907226391608;6190.50475961931;6192.566520343623;6119.015143218474;6135.570510064342;6142.253225532421;6191.19514463117;6227.688615569204;4827.493113223061;4878.07316609843;4898.853034408973;4902.558769710743;4907.337509436252;4398.824047038082;4412.84555040572;4416.712913660602;4430.446194225722;4466.709663871838;5578.163275508706;5613.550915926798;5632.04255276604;5650.971745141248;5652.321721393377;6132.98805697055;6192.535559833031;6195.380461953769;6200.255969283715;6208.2239720034995;4771.928421073687;5619.185218143428;5668.348139963874;5719.799807006735;5719.999000174953;5316.867078322995;5352.277442545284;5358.3837858645975;5360.678359298455;5364.597655175878;6175.258968923668;6212.968935155295;6272.937270627418;6286.339934306923;6291.150694656254;6192.349803270592;6209.130721698875;6250.531210159165;6256.593321434092;6259.311475737756;6215.316315205414;6258.843528911813;6266.749330026931;6296.653684047421;6297.622142671455;6029.4874935629205;6088.756449742138;6136.171616831671;6142.170806941184;6161.291451482957;6152.323244443018;6209.037599172136;6218.409251121137;6223.502119830493;6226.221722284715;6044.425779550936;6052.546059045677;6061.878762424815;6063.605865618721;6116.113163591343;5002.2496625506465;5919.91120133207;6021.608595441282;6073.635581865102;6102.237220347457;5903.822885313448;5915.497182739516;5946.137810017437;5950.821475355746;5958.7616495341435;6044.516438684806;6105.48103411215;6128.4790792783115;6136.29432615255;6140.754369825157;5769.423057694197;5841.795537156179;5916.67458289781;5962.956482615438;6051.667158306159 23:26:29 INFO - PROCESS | 1668 | |1;ext.html;516.5093161496578;530.7531997621107;574.5518495573449;596.9821454420213;603.1887111834584;26721.732782672167;30677.38645227097;36620;38604.22791544164;39714.404283935764;33806.32387352252;42148;42559.9360015999;42701.57298427015;42726.572734272646;9466.148046675815;9694.418334899894;9702.757431064209;9703.514824258795;9715.805683886354;8693.217610415062;8892.377533572653;11742.236754610965;11924;11933.880661193407;11619.012383947333;11665;11672.474738636782;11677.124215683814;11801.29192248468;8637.39538232324;8830.337724670675;11555.19113662044;11620.535178592847;11644.59243926467;8566.528840913747;11605.30368177912;11798;11800.055995520337;11804.763904721858;39543.209135817124;39955.4006689897;40000.799995999834;40069.797906062864;40123.7993810032;3117.184133760573;3210.5505229267687;3220.3237320162793;3391.9830400847836;3497.6327485613897;28539.57190642142;29065.564016539767;29083.41833163325;29323;29577.422577422578;2677.799165062628;2693.501702185102;2705.0397109026408;2723.346396864758;2798.6081948527017;478239;480667;481844;482850;483403;36115.09712257162;65232.673836630514;66423;66485;66594.66702666553;36353.909382718564;36477.45283820745;36487.08782280464;36490.087747806516;36513.63486365102;15544.76682849758;15732.370705171665;15774.974626649231;15803.446879359168;15804;12151.392430378446;12378.504859805687;12404.19372740769;13214.075014748876;13344.065915386023;7756.340711039512;7855.842883142305;8292.834143317099;8389.286910612665;8489.702860399857;3057.312104776452;3325.9357005757925;3380.5267262583257;3427.674370934807;3430.6740859618294;902.7201567514091;924.1867156901883;980.4215512847454;988.6554286170802;1100.1034157161887;24068.759312407;29210.26974325616;29581.260468488028;30108;30776.846115769727;987.8468837330082;1031.8091153136543;1166.3060479014973;1166.9299842009336;1214.690253985228;12800.295983720798;13989.3704783285;14736.631584210481;15248.923755381153;15471.5358539244;588957;610774;622627;623181;626006;62006.37993620006;62575.68712156501;62670.0599491008;62795.6860215696;62800;1437.381925771928;1468.6181592785952;1507.600485871267;1510.4637853561828;1528.2282447364232;25379.238622841345;25573.87213063923;26036.479270414864;26791.464170716867;26792.59811102874;49169.50830491649;49506.75246623744;49647.50352496429;49652.75173624109;49737;424463;428264;428343.71656284283;428513;428705;24358.39104022343;27546.586801197598;28034.85982570156;28200.85899570489;28324.575131373458;36016.63983360133;47812;48415.27377089417;49137.508624913295;50147;35478.46782298319;36926.815365923;36965.815170923976;36982;37665.2466950665;36124.09689757777;36212.818935905154;36420.27159456743;36738.63261367352;36843.894683159546;35509.64490355063;36148.09629759277;36728;37172.07069823276;37301.880943571734;32175;33346.16634584155;33568.66431335655;33645.66354336523;33655;24729.505409892063;24888.751112488644;24928.759261295916;25529;25594.232173034838 23:26:29 INFO - PROCESS | 1668 | |2;jquery.html;398.13008576260864;399.9180168065547;402.121364817873;404.45398711739136;413.3242149086243;15451.304691288891;20379.796202037975;20453.48866278342;21165.365039048833;21192.788072119292;20165.395038148843;20344.898275508604;20821.68767468489;21330.360089197347;21341.93290335485;5737.942620573783;5788.884222315541;5916;5918.556108291874;5922.703864806764;5832.358440571534;5839.153322768196;5881.088431293157;5881.382454842223;5884.794032208873;4099.61053699898;6137.6624285664275;6424.38968298011;6441.227052753676;6452.290248072708;6185.134081228632;6407.006913928349;6411.262704788963;6415.101885735978;6422.293547709748;5609.074502707049;5624.746886390122;5629.971850140764;5633.267675202238;5634.3802181759975;25573.744262557324;25764.74235257661;26032.609510857354;26143.477130457475;26175.73824261752;2025.8885761283123;2137.0062920741775;2183.369124908439;2635.512430200419;2896.681365049843;9756.853647195298;9926.553305101215;9962.551685174185;9979.600815967353;10094.091531762104;1727.8790484666079;1764.3207365164503;1803.7564928734585;1841.3739328628196;1843.2995461724593;107621;111910;113087;113868;114573.854261458;11628.476718547685;11637.127215458706;11648.398882441388;11685.766284674259;11794.76410471803;12724;14148.504802332074;14531.709365812836;15062.472813451477;15064.397424103132;8733.694320698745;8737.650493980169;8810.339224558184;8902.109789021175;8906.86639700405;8762.123787621187;8799.91200087991;8806.603702833389;8903.821923561492;8918.241949434241;4443.200223959654;4601.895545069159;4649.349091127245;4651.325557794101;4668.39310889589;1947.805219478041;1996.4709352021725;2013.90937407817;2018.9394318170184;2057.68105943579;897.9326550508738;958.0563145301873;980.6616717232544;1000.3497726477848;1051.1380667852288;19945.800541994686;19987.800121998593;20293.042856642813;20315.796842031392;20762.688559671617;832.820943497151;955.9044095590385;987.6148302162162;993.4105431309846;1033.7105610429091;10388;10751.354918704903;10785.13718902486;10825.079868211133;10950.233483656068;526081;569246;612217;629064;634876;121151;125868;126385;130525.34737326253;132856;999.160705007797;1024.8513965475113;1039.838824982114;1044.9895501044891;1045.1690905729963;17646.94118352903;17652.20565074549;18062.458126256235;18119.547011324823;18707.906460468155;38556.807215964865;50275.99448011092;50558.49441505538;53519;53547.464525355805;57646;59380;60588;72891;72997;7229.819254518679;7378.667959941923;7983.081945576333;8022.558759268296;8169.2647661710735;10170.542325595217;11028.558857645605;12184.147109702235;12434.689132771391;12513.43689533955;9452.716418507456;10334.689959301233;10766.300190487593;11108.833367499656;11195.664130076111;10582.947085264525;10736.464510713846;10919.508622112184;11087.390193539433;11203.663890083311;10352.482375881327;11210.27133236337;11488.712782180512;11493.885061149282;11531.711707207387;8101.959490202511;8112.797180070545;8189.836203276021;8205.671773129008;8311.33509319265;8213.712520061768;9980.850287245552;11009.2843965142;11230.494627741935;11258.605948791754 23:26:29 INFO - PROCESS | 1668 | |3;mootools.html;1438.762604170312;1589.0783345659515;1619.732744097224;1877.962440751185;1990.0945069993154;7823.256790604891;8579.442336248147;8682.479051256912;8687.348448866329;8708.172723591264;8305.377096717755;8429.45208561443;8443.197896199852;8443.282321002707;8444;2129.233475948665;2349.5066036132434;2379.393254720044;2415.9516809663796;2419.395151212197;2436.5979613363775;2446.7920226780743;2453.2885463215734;2456.312232574873;2457.5453541094953;2370.6562548430466;2375.2636682628354;2421.3462365161486;2426.648136020276;2443.2914454808083;1990.0547240061;2129.936101916945;2391.342380845264;2424.7817696407233;2433.5984562547246;2394.904203831845;2431.2098567965304;2432.428379330856;2434.926952191437;2437.2200895713377;8507.787305317355;8560.828783424358;8568.742937711879;8569.057403685589;8607.053224145338;5113.437521872591;5199.402068762065;5260.28986086881;5314.681119132864;5322.946770532322;2978.3596526746855;3459.6540345965454;3513.1568423578415;3579.425052976708;3592.3354179476883;1025.4103890263054;1031.5306535526317;1060.363781730963;1072.544168728295;1075.295681328731;11955.64133076009;11960.102992275613;11965.700857478545;11981.28112313264;11994.10044246685;4654.441467023911;4700.647451441156;4732.266498692708;4797.18447863864;4826.420829500483;9996.050375214343;10131.594736210616;10158.39049657023;10161.18710503158;10165.644202453026;5379.246905433242;5419.701916394636;5422.91865622016;5423.023855705932;5427.348718153847;4275.657947364203;4431.423914891108;4687.789049492781;4865.537773911544;4872.780724867319;3932.8230229639735;3959.703022273341;4000.9199816003515;4049.6152865477734;4072.511298644182;1326.7810811216234;1327.9070465067352;1335.5392389625563;1342.9989315919406;1345.0651797001185;828.8052307707684;840.9747707568782;912.1562554636935;921.2860033474111;923.2567782934668;5530.55755539564;5568.549613752306;5646.971765141148;5653.717314134276;5800.0429929062075;843.304273973974;929.330881765128;972.4457059476172;980.0248752491175;984.7100298608846;3293.176705823544;3641.7632853864943;3693.8918324502542;3704.7406681532575;3708.8887333380044;8618.396712230084;10633.946830265799;10675.679729608124;10689.037986581245;10824.971627695357;10560.683179504627;10676.89323106759;10684.091852192496;10686.25196236256;10707.464626768631;7956.323712484504;8221.260086592236;8245.422820402635;8338.374621903382;8351.08138104808;5461.754221060141;5531.7234138293725;5533.529649979797;5639.971800140973;5699.9693017637355;9316.767080823034;9370.437773733598;9617.903820961701;9629.56666950003;9631.807363852824;9334.906650933404;9774.951125244328;9782.461964592016;10037.648682296085;10041.447720375158;6301.590396624205;6454.41910228063;6843.3840954314355;6984.895226571504;7087.50387472872;8197.590120494071;8222.794430139294;8294.29498492635;8297.751067467985;8308.25225729663;7239.601821899846;7337.633118344168;7417.221191774786;7470.028896243453;7495.17553069151;7265.920578334319;7274.163471200668;7302.087239095114;7357.301056399633;7372.889406658797;5890.705464726832;6879.071325371011;6889.104416425833;6898.413634840894;6941.5140940135725;4562.08758248345;4702.811887524461;4720.905581888412;4738.691985020962;4843.39457567804;4775.570198682135;4816.20532612115;4852.708837469763;4864.781084851119;4905.288733133747 23:26:29 INFO - PROCESS | 1668 | |4;prototype.html;281.6732590195374;291.536457033317;311.22504962643023;320.89132048771495;330.19926677806313;6510.218773747157;6626.8674626507445;6646.800595982122;6669.833254168642;6704.5306828522025;6403.871922561546;6636.96681516593;6642.734290628381;6656.733730650768;6665.066890635315;4891.16850135476;4903.65674402792;4908.8281910133155;4913.7788799504015;4920.917398172396;4665.113628410608;4742.834000809972;4917.754112294371;4920.03198720511;4928.901421971576;4820.156472617296;4825.324454576362;4836.564709176174;4839.903201935959;4862.4408193057725;4800.447948485917;4807.687500312469;4812.590929770974;4828.517148285178;4845.079434907374;4119.567445418218;4119.814608342632;4120.649744771668;4120.91758164838;4135.255653982282;6347.1114044033875;6421.068945002995;6421.454176395012;6426.260979987314;6435.292117867031;5580.135079062752;5794.478496935254;5813.505852002585;5821.970890145564;5826.82519524415;7035.261297563785;7042;7051.294870512959;7051.330123638298;7083.4416428385675;4658.743769092698;4702.153612349775;4711.4346278446465;4716.198246298138;4727.881802954919;7424.294692004251;7432.554046757212;7443.181250062543;7444.9627751861435;7452.627368631547;4763.356946812205;4768.237082066853;4769.546893045155;4774.904501909942;4777.737224452619;4669.310921699951;4773.164696178224;4831.347768051339;4835.081334546425;4835.564799168092;5789.102689083199;5819.679917604488;5831.708414579339;5837.0368889132815;5849.970750146222;7101.289871012961;7104.325089116525;7119.857602847914;7127.643617819088;7129.4652901031595;1603.5830684022005;1632.0289427790447;1655.0648883380852;1665.8833881628411;1696.9321227150776;1085.1861104171871;1085.2240647936605;1097.5774326884207;1101.5263436722125;1103.61925135828;6532.510061745388;6536.346365363521;6540.345965403517;6540.444062254762;6604.735810567524;7048.083749112582;7302.160251571031;7308.5614863108385;7309.159446663702;7310.52481588695;5591.916121258185;6528.804135875931;6534.150560427114;6553.525456772188;6564.916788729806;3844.1350696093714;3895.590962948906;3896.922061558753;3900.395438707005;3902.5902280260166;7239.148487575966;7680.772575953023;7687.500312479671;7694.384449244047;7711.113221979432;6202.627842329384;6794.3545363190415;7530.171681115073;7548.282913123245;7567.243275672388;7634.770956871303;7643.312101910855;7649.235076492307;7661.080670319486;7664.4634875559295;6991.9300806993315;7277;7280.708771649075;7303.963480182778;7312.451566132455;7159.534630249017;7176.102987126609;7186.784396468114;7197.640117993975;7227.1327440705345;7556.395488360808;7629.2370762923265;7645.694172233048;7657.349125324187;7662.0422447194105;4798.184308667465;4803.327534145153;4818.301346304696;4822.927656085091;4824.879378015437;5749.511291540266;7196.10048743907;7218.566885986857;7247.9637601811655;7332;5607.299087614048;7140.571565706075;7191;7201.099862517185;7239.095113110861;7068.081149450539;7125.287471252833;7175.995360649617;7190.388816950618;7202.96398518004;6998.965005174941;7005.719771209095;7010.843210870149;7024.578525288499;7029.61337126463;5486.369067557282;5775.826725198251;6855.862882742417;7165.068541089625;7192.784216473514;5683.71581420919;6971;7009.843375843133;7017.403520700797;7045.577265364094 23:26:29 INFO - PROCESS | 1668 | |5;yui.html;668.742534124362;726.4079774983385;729.4674887332247;737.9852402951941;749.8087987563172;13585.456581736718;13613.863861361384;14586.562403127897;14910.5526834195;14933.104013759168;14303.499377521788;14365.856341436582;14374.281285935687;14379.137251764876;14386.568402947929;724.5616402076747;858.3047731337621;891.0287786312917;891.603236559728;896.8296023755498;882.4176043811086;886.2334081019903;888.6312180445108;888.8133491966695;904.7150147703485;935.0182308576002;935.2004036548755;936.2229349639816;936.2369668720005;954.9713508594754;924.3575714878195;926.5969303353063;926.8239034583441;927.4713413354391;947.4315410753561;707.7664370757637;707.9504434689574;708.479267738212;708.922018577956;709.7338498063226;14542;14630.975831691785;14632.853671463363;14716.48492302775;14834.480793172188;12352.25886446807;12382.195157314745;12432.689182770504;12625.368731563476;12700.682482937907;9757.90242097577;9850.950745246228;10088.293819432643;10140.847887281696;10362.481875906175;3428.245785927092;3492.388831954398;3524.8061356625376;3574.696150827183;3588.856445742167;68285.65857170732;70166;82615.17384826134;82880.58559707223;84583.57708211482;8567.785805354792;8693.521856297964;8737.694180703646;8746.56267186638;8835.514046727492;18572.978486183118;18583;18596.977166255714;18599.97700126479;18623.534411639546;7231.710731570678;7294.270572942663;7303.817404564928;7317.597532135784;7473.140588832245;3992.321305378093;4053.8986525337104;4126.42230087788;4132.752034877917;4303.569643035671;2934.6478422589425;3435.896923092311;3483.2336885885065;3489.3195826814035;3490.9127271818406;593.9762409503571;619.8822223777468;627.7771391156165;634.4416913116428;650.8437974886087;582.7581553655185;584.5528170949227;586.4253032028636;589.2663633775976;620;7738.845223095506;8168.959155204305;8192.30365418946;8258.380621453414;8266.62293843752;544.5697898660014;548.8188897663762;570.3811364669298;578.9305283366023;594.7323704333068;3063;3627.7460577759302;3828.674562662149;3839.2705385976574;3868.5434748934067;89795.65306520407;91465;92709.60935585972;92725;92821.07178928124;55171.72414137904;74420;79134.60432697799;79145.60427197827;80501.59749201217;43994.34008489875;57020.714896426085;58673.413265867646;59855.40144598585;60022.39977600255;8350.206730360605;8458.915410845813;8547.95726021366;8579.571021448777;8592.109735858101;38095.23809523849;44762.3285650709;46864.53135468738;47905.76047119742;48005.27992080191;60545.69727151336;62961.37038629555;66164;66538.66922661423;68940.6552967232;10795.40625265618;10927.344359338465;11145.108391328837;11186.888131118585;11237.550497980315;11825.053995680153;11869.129119567044;12064.517419303129;12078.33569153705;12403.25580465175;10957.958993895567;10967;11199.992000719974;11355.148363872577;12011.519539218334;10937.781244375226;11320.26418282809;11367.317960922372;11451.448653181898;11754;11121.94439027832;11272.436378181223;11274.529315766285;11434.542618295174;11563.132765042486;9337.439753614804;9374.625014999323;9547.952260238655;9650.71047868565;9671.564779584794;9693.369930954465;9703.194637839362;9718.854217186607;9786.902130978599;9806.990088680059 23:26:29 INFO - PROCESS | 1668 | __end_tp_report 23:26:29 INFO - PROCESS | 1668 | __start_cc_report 23:26:29 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1179 23:26:29 INFO - PROCESS | 1668 | __end_cc_report 23:26:29 INFO - PROCESS | 1668 | __startTimestamp1464071189194__endTimestamp 23:26:29 INFO - PROCESS | 1668 | 23:26:29 INFO - PROCESS | 1668 | ------- Summary: start ------- 23:26:29 INFO - PROCESS | 1668 | Number of tests: 6 23:26:29 INFO - PROCESS | 1668 | 23:26:29 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5702.47 Median:5946.14 stddev:634.85 (10.7%) stddev-sans-first:610.55 23:26:29 INFO - PROCESS | 1668 | Values: 3399.3 3432.5 3453.6 3456.6 3514.0 5651.5 5651.6 5689.2 5689.9 5711.6 5703.4 5740.0 5745.7 5762.4 5769.3 5117.4 5133.1 5133.1 5268.0 5276.9 5201.3 5201.3 5209.2 5209.9 5233.1 5176.5 5182.2 5204.7 5221.1 5229.1 5238.2 5240.9 5249.1 5259.2 5297.2 4130.9 5092.2 5105.4 5153.3 5192.3 5554.8 5569.6 5588.7 5589.8 5592.6 6142.4 6151.9 6158.8 6160.3 6180.8 6127.3 6161.6 6210.3 6239.3 6252.7 5744.4 5775.0 5780.9 5788.0 5810.9 6258.4 6261.9 6265.4 6269.8 6271.6 5993.2 6045.5 6082.6 6101.6 6142.7 6238.7 6240.2 6243.3 6247.7 6255.7 6116.9 6174.0 6184.9 6190.5 6192.6 6119.0 6135.6 6142.3 6191.2 6227.7 4827.5 4878.1 4898.9 4902.6 4907.3 4398.8 4412.8 4416.7 4430.4 4466.7 5578.2 5613.6 5632.0 5651.0 5652.3 6133.0 6192.5 6195.4 6200.3 6208.2 4771.9 5619.2 5668.3 5719.8 5720.0 5316.9 5352.3 5358.4 5360.7 5364.6 6175.3 6213.0 6272.9 6286.3 6291.2 6192.3 6209.1 6250.5 6256.6 6259.3 6215.3 6258.8 6266.7 6296.7 6297.6 6029.5 6088.8 6136.2 6142.2 6161.3 6152.3 6209.0 6218.4 6223.5 6226.2 6044.4 6052.5 6061.9 6063.6 6116.1 5002.2 5919.9 6021.6 6073.6 6102.2 5903.8 5915.5 5946.1 5950.8 5958.8 6044.5 6105.5 6128.5 6136.3 6140.8 5769.4 5841.8 5916.7 5963.0 6051.7 23:26:29 INFO - PROCESS | 1668 | 23:26:29 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:63644.82 Median:36065.87 stddev:137279.67 (380.6%) stddev-sans-first:137582.95 23:26:29 INFO - PROCESS | 1668 | Values: 516.5 530.8 574.6 597.0 603.2 26721.7 30677.4 36620.0 38604.2 39714.4 33806.3 42148.0 42559.9 42701.6 42726.6 9466.1 9694.4 9702.8 9703.5 9715.8 8693.2 8892.4 11742.2 11924.0 11933.9 11619.0 11665.0 11672.5 11677.1 11801.3 8637.4 8830.3 11555.2 11620.5 11644.6 8566.5 11605.3 11798.0 11800.1 11804.8 39543.2 39955.4 40000.8 40069.8 40123.8 3117.2 3210.6 3220.3 3392.0 3497.6 28539.6 29065.6 29083.4 29323.0 29577.4 2677.8 2693.5 2705.0 2723.3 2798.6 478239.0 480667.0 481844.0 482850.0 483403.0 36115.1 65232.7 66423.0 66485.0 66594.7 36353.9 36477.5 36487.1 36490.1 36513.6 15544.8 15732.4 15775.0 15803.4 15804.0 12151.4 12378.5 12404.2 13214.1 13344.1 7756.3 7855.8 8292.8 8389.3 8489.7 3057.3 3325.9 3380.5 3427.7 3430.7 902.7 924.2 980.4 988.7 1100.1 24068.8 29210.3 29581.3 30108.0 30776.8 987.8 1031.8 1166.3 1166.9 1214.7 12800.3 13989.4 14736.6 15248.9 15471.5 588957.0 610774.0 622627.0 623181.0 626006.0 62006.4 62575.7 62670.1 62795.7 62800.0 1437.4 1468.6 1507.6 1510.5 1528.2 25379.2 25573.9 26036.5 26791.5 26792.6 49169.5 49506.8 49647.5 49652.8 49737.0 424463.0 428264.0 428343.7 428513.0 428705.0 24358.4 27546.6 28034.9 28200.9 28324.6 36016.6 47812.0 48415.3 49137.5 50147.0 35478.5 36926.8 36965.8 36982.0 37665.2 36124.1 36212.8 36420.3 36738.6 36843.9 35509.6 36148.1 36728.0 37172.1 37301.9 32175.0 33346.2 33568.7 33645.7 33655.0 24729.5 24888.8 24928.8 25529.0 25594.2 23:26:29 INFO - PROCESS | 1668 | 23:26:29 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34204.69 Median:26088.04 stddev:99183.75 (380.2%) stddev-sans-first:99429.50 23:26:29 INFO - PROCESS | 1668 | Values: 398.1 399.9 402.1 404.5 413.3 15451.3 20379.8 20453.5 21165.4 21192.8 20165.4 20344.9 20821.7 21330.4 21341.9 5737.9 5788.9 5916.0 5918.6 5922.7 5832.4 5839.2 5881.1 5881.4 5884.8 4099.6 6137.7 6424.4 6441.2 6452.3 6185.1 6407.0 6411.3 6415.1 6422.3 5609.1 5624.7 5630.0 5633.3 5634.4 25573.7 25764.7 26032.6 26143.5 26175.7 2025.9 2137.0 2183.4 2635.5 2896.7 9756.9 9926.6 9962.6 9979.6 10094.1 1727.9 1764.3 1803.8 1841.4 1843.3 107621.0 111910.0 113087.0 113868.0 114573.9 11628.5 11637.1 11648.4 11685.8 11794.8 12724.0 14148.5 14531.7 15062.5 15064.4 8733.7 8737.7 8810.3 8902.1 8906.9 8762.1 8799.9 8806.6 8903.8 8918.2 4443.2 4601.9 4649.3 4651.3 4668.4 1947.8 1996.5 2013.9 2018.9 2057.7 897.9 958.1 980.7 1000.3 1051.1 19945.8 19987.8 20293.0 20315.8 20762.7 832.8 955.9 987.6 993.4 1033.7 10388.0 10751.4 10785.1 10825.1 10950.2 526081.0 569246.0 612217.0 629064.0 634876.0 121151.0 125868.0 126385.0 130525.3 132856.0 999.2 1024.9 1039.8 1045.0 1045.2 17646.9 17652.2 18062.5 18119.5 18707.9 38556.8 50276.0 50558.5 53519.0 53547.5 57646.0 59380.0 60588.0 72891.0 72997.0 7229.8 7378.7 7983.1 8022.6 8169.3 10170.5 11028.6 12184.1 12434.7 12513.4 9452.7 10334.7 10766.3 11108.8 11195.7 10582.9 10736.5 10919.5 11087.4 11203.7 10352.5 11210.3 11488.7 11493.9 11531.7 8102.0 8112.8 8189.8 8205.7 8311.3 8213.7 9980.9 11009.3 11230.5 11258.6 23:26:29 INFO - PROCESS | 1668 | 23:26:29 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5613.86 Median:4858.74 stddev:3149.19 (64.8%) stddev-sans-first:3142.40 23:26:29 INFO - PROCESS | 1668 | Values: 1438.8 1589.1 1619.7 1878.0 1990.1 7823.3 8579.4 8682.5 8687.3 8708.2 8305.4 8429.5 8443.2 8443.3 8444.0 2129.2 2349.5 2379.4 2416.0 2419.4 2436.6 2446.8 2453.3 2456.3 2457.5 2370.7 2375.3 2421.3 2426.6 2443.3 1990.1 2129.9 2391.3 2424.8 2433.6 2394.9 2431.2 2432.4 2434.9 2437.2 8507.8 8560.8 8568.7 8569.1 8607.1 5113.4 5199.4 5260.3 5314.7 5322.9 2978.4 3459.7 3513.2 3579.4 3592.3 1025.4 1031.5 1060.4 1072.5 1075.3 11955.6 11960.1 11965.7 11981.3 11994.1 4654.4 4700.6 4732.3 4797.2 4826.4 9996.1 10131.6 10158.4 10161.2 10165.6 5379.2 5419.7 5422.9 5423.0 5427.3 4275.7 4431.4 4687.8 4865.5 4872.8 3932.8 3959.7 4000.9 4049.6 4072.5 1326.8 1327.9 1335.5 1343.0 1345.1 828.8 841.0 912.2 921.3 923.3 5530.6 5568.5 5647.0 5653.7 5800.0 843.3 929.3 972.4 980.0 984.7 3293.2 3641.8 3693.9 3704.7 3708.9 8618.4 10633.9 10675.7 10689.0 10825.0 10560.7 10676.9 10684.1 10686.3 10707.5 7956.3 8221.3 8245.4 8338.4 8351.1 5461.8 5531.7 5533.5 5640.0 5700.0 9316.8 9370.4 9617.9 9629.6 9631.8 9334.9 9775.0 9782.5 10037.6 10041.4 6301.6 6454.4 6843.4 6984.9 7087.5 8197.6 8222.8 8294.3 8297.8 8308.3 7239.6 7337.6 7417.2 7470.0 7495.2 7265.9 7274.2 7302.1 7357.3 7372.9 5890.7 6879.1 6889.1 6898.4 6941.5 4562.1 4702.8 4720.9 4738.7 4843.4 4775.6 4816.2 4852.7 4864.8 4905.3 23:26:29 INFO - PROCESS | 1668 | 23:26:29 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5783.62 Median:6533.33 stddev:1827.80 (28.0%) stddev-sans-first:1785.67 23:26:29 INFO - PROCESS | 1668 | Values: 281.7 291.5 311.2 320.9 330.2 6510.2 6626.9 6646.8 6669.8 6704.5 6403.9 6637.0 6642.7 6656.7 6665.1 4891.2 4903.7 4908.8 4913.8 4920.9 4665.1 4742.8 4917.8 4920.0 4928.9 4820.2 4825.3 4836.6 4839.9 4862.4 4800.4 4807.7 4812.6 4828.5 4845.1 4119.6 4119.8 4120.6 4120.9 4135.3 6347.1 6421.1 6421.5 6426.3 6435.3 5580.1 5794.5 5813.5 5822.0 5826.8 7035.3 7042.0 7051.3 7051.3 7083.4 4658.7 4702.2 4711.4 4716.2 4727.9 7424.3 7432.6 7443.2 7445.0 7452.6 4763.4 4768.2 4769.5 4774.9 4777.7 4669.3 4773.2 4831.3 4835.1 4835.6 5789.1 5819.7 5831.7 5837.0 5850.0 7101.3 7104.3 7119.9 7127.6 7129.5 1603.6 1632.0 1655.1 1665.9 1696.9 1085.2 1085.2 1097.6 1101.5 1103.6 6532.5 6536.3 6540.3 6540.4 6604.7 7048.1 7302.2 7308.6 7309.2 7310.5 5591.9 6528.8 6534.2 6553.5 6564.9 3844.1 3895.6 3896.9 3900.4 3902.6 7239.1 7680.8 7687.5 7694.4 7711.1 6202.6 6794.4 7530.2 7548.3 7567.2 7634.8 7643.3 7649.2 7661.1 7664.5 6991.9 7277.0 7280.7 7304.0 7312.5 7159.5 7176.1 7186.8 7197.6 7227.1 7556.4 7629.2 7645.7 7657.3 7662.0 4798.2 4803.3 4818.3 4822.9 4824.9 5749.5 7196.1 7218.6 7248.0 7332.0 5607.3 7140.6 7191.0 7201.1 7239.1 7068.1 7125.3 7176.0 7190.4 7203.0 6999.0 7005.7 7010.8 7024.6 7029.6 5486.4 5775.8 6855.9 7165.1 7192.8 5683.7 6971.0 7009.8 7017.4 7045.6 23:26:29 INFO - PROCESS | 1668 | 23:26:29 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:17343.50 Median:594.35 stddev:24260.25 (4081.8%) stddev-sans-first:24296.00 23:26:29 INFO - PROCESS | 1668 | Values: 668.7 726.4 729.5 738.0 749.8 13585.5 13613.9 14586.6 14910.6 14933.1 14303.5 14365.9 14374.3 14379.1 14386.6 724.6 858.3 891.0 891.6 896.8 882.4 886.2 888.6 888.8 904.7 935.0 935.2 936.2 936.2 955.0 924.4 926.6 926.8 927.5 947.4 707.8 708.0 708.5 708.9 709.7 14542.0 14631.0 14632.9 14716.5 14834.5 12352.3 12382.2 12432.7 12625.4 12700.7 9757.9 9851.0 10088.3 10140.8 10362.5 3428.2 3492.4 3524.8 3574.7 3588.9 68285.7 70166.0 82615.2 82880.6 84583.6 8567.8 8693.5 8737.7 8746.6 8835.5 18573.0 18583.0 18597.0 18600.0 18623.5 7231.7 7294.3 7303.8 7317.6 7473.1 3992.3 4053.9 4126.4 4132.8 4303.6 2934.6 3435.9 3483.2 3489.3 3490.9 594.0 619.9 627.8 634.4 650.8 582.8 584.6 586.4 589.3 620.0 7738.8 8169.0 8192.3 8258.4 8266.6 544.6 548.8 570.4 578.9 594.7 3063.0 3627.7 3828.7 3839.3 3868.5 89795.7 91465.0 92709.6 92725.0 92821.1 55171.7 74420.0 79134.6 79145.6 80501.6 43994.3 57020.7 58673.4 59855.4 60022.4 8350.2 8458.9 8548.0 8579.6 8592.1 38095.2 44762.3 46864.5 47905.8 48005.3 60545.7 62961.4 66164.0 66538.7 68940.7 10795.4 10927.3 11145.1 11186.9 11237.6 11825.1 11869.1 12064.5 12078.3 12403.3 10958.0 10967.0 11200.0 11355.1 12011.5 10937.8 11320.3 11367.3 11451.4 11754.0 11121.9 11272.4 11274.5 11434.5 11563.1 9337.4 9374.6 9548.0 9650.7 9671.6 9693.4 9703.2 9718.9 9786.9 9807.0 23:26:29 INFO - PROCESS | 1668 | -------- Summary: end -------- 23:26:29 INFO - PROCESS | 1668 | 23:26:29 INFO - TEST-INFO | 1668: exit 0 23:26:29 INFO - TEST-OK | dromaeo_css | took 1115125ms 23:26:29 INFO - TEST-START | kraken 23:26:29 INFO - operating with platform_type : mac_ 23:26:29 INFO - Initialising browser for kraken test... 23:26:29 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmp1ym31v/profile http://localhost:49271/getInfo.html) 23:26:31 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 23:26:31 INFO - PROCESS | 1674 | colorDepth:24 23:26:31 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 23:26:31 INFO - PROCESS | 1674 | __metrics 23:26:31 INFO - PROCESS | 1674 | 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" 23:26:31 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:26:32 INFO - TEST-INFO | 1674: exit 0 23:26:32 INFO - Browser initialized. 23:26:32 INFO - Running cycle 1/1 for kraken test... 23:26:32 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/T/tmp1ym31v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:26:44 INFO - PROCESS | 1676 | RSS: Main: 210882560 23:26:44 INFO - PROCESS | 1676 | 23:26:47 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 23:26:48 INFO - PROCESS | 1676 | RSS: Main: 271429632 23:26:48 INFO - PROCESS | 1676 | 23:26:51 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 23:26:51 INFO - PROCESS | 1676 | RSS: Main: 285675520 23:26:51 INFO - PROCESS | 1676 | 23:26:54 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 23:26:55 INFO - PROCESS | 1676 | RSS: Main: 371204096 23:26:55 INFO - PROCESS | 1676 | 23:26:57 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 23:26:58 INFO - PROCESS | 1676 | RSS: Main: 279384064 23:26:58 INFO - PROCESS | 1676 | 23:27:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:27:01 INFO - PROCESS | 1676 | RSS: Main: 280469504 23:27:01 INFO - PROCESS | 1676 | 23:27:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 23:27:04 INFO - PROCESS | 1676 | RSS: Main: 317288448 23:27:04 INFO - PROCESS | 1676 | 23:27:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 23:27:07 INFO - PROCESS | 1676 | RSS: Main: 317427712 23:27:07 INFO - PROCESS | 1676 | 23:27:10 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 23:27:11 INFO - PROCESS | 1676 | RSS: Main: 318005248 23:27:11 INFO - PROCESS | 1676 | 23:27:13 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:27:13 INFO - PROCESS | 1676 | RSS: Main: 309116928 23:27:13 INFO - PROCESS | 1676 | 23:27:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:27:16 INFO - PROCESS | 1676 | RSS: Main: 316432384 23:27:16 INFO - PROCESS | 1676 | 23:27:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:27:19 INFO - PROCESS | 1676 | RSS: Main: 318451712 23:27:19 INFO - PROCESS | 1676 | 23:27:21 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:27:22 INFO - PROCESS | 1676 | RSS: Main: 323723264 23:27:22 INFO - PROCESS | 1676 | 23:27:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:27:25 INFO - PROCESS | 1676 | RSS: Main: 321810432 23:27:25 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 23:27:27 INFO - PROCESS | 1676 | RSS: Main: 386007040 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | __start_tp_report 23:27:27 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 23:27:27 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 23:27:27 INFO - PROCESS | 1676 | |i|pagename|runs| 23:27:27 INFO - PROCESS | 1676 | |0;ai-astar;114;114;128;165;154;111;113;121;165;183 23:27:27 INFO - PROCESS | 1676 | |1;audio-beat-detection;153;121;154;162;148;170;167;118;168;121 23:27:27 INFO - PROCESS | 1676 | |2;audio-dft;183;186;184;186;183;183;181;186;185;182 23:27:27 INFO - PROCESS | 1676 | |3;audio-fft;71;78;74;148;56;55;57;66;134;56 23:27:27 INFO - PROCESS | 1676 | |4;audio-oscillator;95;94;139;77;80;91;127;117;113;162 23:27:27 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;90;89;91;89;91;87;91;88;92;87 23:27:27 INFO - PROCESS | 1676 | |6;imaging-darkroom;96;102;97;96;95;96;98;96;95;96 23:27:27 INFO - PROCESS | 1676 | |7;imaging-desaturate;85;84;85;83;87;86;96;133;133;87 23:27:27 INFO - PROCESS | 1676 | |8;json-parse-financial;60;64;64;64;63;63;66;82;61;59 23:27:27 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;63;67;66;70;81;78;73;68;69;69 23:27:27 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;83;81;79;83;78;80;81;78;82;84 23:27:27 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;130;137;135;114;151;152;148;146;149;132 23:27:27 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;163;165;167;165;165;167;165;165;168;163 23:27:27 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;67;67;69;66;66;67;67;69;67;66 23:27:27 INFO - PROCESS | 1676 | __end_tp_report 23:27:27 INFO - PROCESS | 1676 | __start_cc_report 23:27:27 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1988 23:27:27 INFO - PROCESS | 1676 | __end_cc_report 23:27:27 INFO - PROCESS | 1676 | __startTimestamp1464071247770__endTimestamp 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | ------- Summary: start ------- 23:27:27 INFO - PROCESS | 1676 | Number of tests: 14 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:136.80 Median:141.00 stddev:27.12 (19.2%) stddev-sans-first:27.48 23:27:27 INFO - PROCESS | 1676 | Values: 114.0 114.0 128.0 165.0 154.0 111.0 113.0 121.0 165.0 183.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:158.00 stddev:20.71 (13.1%) stddev-sans-first:21.89 23:27:27 INFO - PROCESS | 1676 | Values: 153.0 121.0 154.0 162.0 148.0 170.0 167.0 118.0 168.0 121.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.79 (1.0%) stddev-sans-first:1.87 23:27:27 INFO - PROCESS | 1676 | Values: 183.0 186.0 184.0 186.0 183.0 183.0 181.0 186.0 185.0 182.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:79.50 Median:61.50 stddev:33.60 (54.6%) stddev-sans-first:35.50 23:27:27 INFO - PROCESS | 1676 | Values: 71.0 78.0 74.0 148.0 56.0 55.0 57.0 66.0 134.0 56.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:109.50 Median:78.50 stddev:27.33 (34.8%) stddev-sans-first:28.48 23:27:27 INFO - PROCESS | 1676 | Values: 95.0 94.0 139.0 77.0 80.0 91.0 127.0 117.0 113.0 162.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:90.50 stddev:1.78 (2.0%) stddev-sans-first:1.88 23:27:27 INFO - PROCESS | 1676 | Values: 90.0 89.0 91.0 89.0 91.0 87.0 91.0 88.0 92.0 87.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:96.00 stddev:2.06 (2.1%) stddev-sans-first:2.17 23:27:27 INFO - PROCESS | 1676 | Values: 96.0 102.0 97.0 96.0 95.0 96.0 98.0 96.0 95.0 96.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:85.50 stddev:19.87 (23.2%) stddev-sans-first:20.68 23:27:27 INFO - PROCESS | 1676 | Values: 85.0 84.0 85.0 83.0 87.0 86.0 96.0 133.0 133.0 87.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.00 stddev:6.47 (10.1%) stddev-sans-first:6.64 23:27:27 INFO - PROCESS | 1676 | Values: 60.0 64.0 64.0 64.0 63.0 63.0 66.0 82.0 61.0 59.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:69.50 stddev:5.50 (7.9%) stddev-sans-first:5.14 23:27:27 INFO - PROCESS | 1676 | Values: 63.0 67.0 66.0 70.0 81.0 78.0 73.0 68.0 69.0 69.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.50 stddev:2.13 (2.6%) stddev-sans-first:2.12 23:27:27 INFO - PROCESS | 1676 | Values: 83.0 81.0 79.0 83.0 78.0 80.0 81.0 78.0 82.0 84.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:147.00 stddev:12.09 (8.2%) stddev-sans-first:12.34 23:27:27 INFO - PROCESS | 1676 | Values: 130.0 137.0 135.0 114.0 151.0 152.0 148.0 146.0 149.0 132.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.30 Median:165.00 stddev:1.64 (1.0%) stddev-sans-first:1.51 23:27:27 INFO - PROCESS | 1676 | Values: 163.0 165.0 167.0 165.0 165.0 167.0 165.0 165.0 168.0 163.0 23:27:27 INFO - PROCESS | 1676 | 23:27:27 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 23:27:27 INFO - PROCESS | 1676 | Values: 67.0 67.0 69.0 66.0 66.0 67.0 67.0 69.0 67.0 66.0 23:27:27 INFO - PROCESS | 1676 | -------- Summary: end -------- 23:27:27 INFO - PROCESS | 1676 | 23:27:28 INFO - TEST-INFO | 1676: exit 0 23:27:28 INFO - TEST-OK | kraken | took 58743ms 23:27:28 INFO - SUITE-END | took 1173s 23:27:28 INFO - Completed test suite (00:19:34) 23:27:28 INFO - javascript benchmark 23:27:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3399.2521645238053, 3432.4679674650424, 3453.568303962005, 3456.5679290088738, 3513.9648603513956, 5651.491365777079, 5651.576131790117, 5689.2319536862515, 5689.943100568993, 5711.628744131639, 5703.429657034305, 5740.024195886699, 5745.741441635126, 5762.3949485304065, 5769.336526299467, 5117.385913690344, 5133.050385678643, 5133.050385678652, 5267.973660131694, 5276.92084618731, 5201.297824793661, 5201.323827902367, 5209.166533354664, 5209.869753256161, 5233.084210263208, 5176.5341119299255, 5182.170852663575, 5204.713740744258, 5221.060209162369, 5229.111051121299, 5238.240455133984, 5240.947590524103, 5249.136556522418, 5259.237410575445, 5297.231901374279, 4130.938035929464, 5092.185250359962, 5105.438401775802, 5153.330067091291, 5192.273081768555, 5554.833354999357, 5569.554435645138, 5588.720563971826, 5589.832305030855, 5592.5805564582415, 6142.385761423867, 6151.876962460726, 6158.753649854045, 6160.322364539897, 6180.783672571483, 6127.250613123855, 6161.599496032788, 6210.31686514483, 6239.313675495692, 6252.6561039142825, 5744.368119506851, 5774.971125144348, 5780.855478612984, 5788.016037273632, 5810.912836307459, 6258.436740693269, 6261.906071408933, 6265.4361107500545, 6269.843253918689, 6271.62370257786, 5993.160957465958, 6045.486133678599, 6082.60463069899, 6101.572889897665, 6142.692865356715, 6238.719257633417, 6240.188775459252, 6243.282022567372, 6247.656378899113, 6255.687215639291, 6116.908246376308, 6173.969130154321, 6184.907226391608, 6190.50475961931, 6192.566520343623, 6119.015143218474, 6135.570510064342, 6142.253225532421, 6191.19514463117, 6227.688615569204, 4827.493113223061, 4878.07316609843, 4898.853034408973, 4902.558769710743, 4907.337509436252, 4398.824047038082, 4412.84555040572, 4416.712913660602, 4430.446194225722, 4466.709663871838, 5578.163275508706, 5613.550915926798, 5632.04255276604, 5650.971745141248, 5652.321721393377, 6132.98805697055, 6192.535559833031, 6195.380461953769, 6200.255969283715, 6208.2239720034995, 4771.928421073687, 5619.185218143428, 5668.348139963874, 5719.799807006735, 5719.999000174953, 5316.867078322995, 5352.277442545284, 5358.3837858645975, 5360.678359298455, 5364.597655175878, 6175.258968923668, 6212.968935155295, 6272.937270627418, 6286.339934306923, 6291.150694656254, 6192.349803270592, 6209.130721698875, 6250.531210159165, 6256.593321434092, 6259.311475737756, 6215.316315205414, 6258.843528911813, 6266.749330026931, 6296.653684047421, 6297.622142671455, 6029.4874935629205, 6088.756449742138, 6136.171616831671, 6142.170806941184, 6161.291451482957, 6152.323244443018, 6209.037599172136, 6218.409251121137, 6223.502119830493, 6226.221722284715, 6044.425779550936, 6052.546059045677, 6061.878762424815, 6063.605865618721, 6116.113163591343, 5002.2496625506465, 5919.91120133207, 6021.608595441282, 6073.635581865102, 6102.237220347457, 5903.822885313448, 5915.497182739516, 5946.137810017437, 5950.821475355746, 5958.7616495341435, 6044.516438684806, 6105.48103411215, 6128.4790792783115, 6136.29432615255, 6140.754369825157, 5769.423057694197, 5841.795537156179, 5916.67458289781, 5962.956482615438, 6051.667158306159], "value": 5662.9590037231965, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.5093161496578, 530.7531997621107, 574.5518495573449, 596.9821454420213, 603.1887111834584, 26721.732782672167, 30677.38645227097, 36620.0, 38604.22791544164, 39714.404283935764, 33806.32387352252, 42148.0, 42559.9360015999, 42701.57298427015, 42726.572734272646, 9466.148046675815, 9694.418334899894, 9702.757431064209, 9703.514824258795, 9715.805683886354, 8693.217610415062, 8892.377533572653, 11742.236754610965, 11924.0, 11933.880661193407, 11619.012383947333, 11665.0, 11672.474738636782, 11677.124215683814, 11801.29192248468, 8637.39538232324, 8830.337724670675, 11555.19113662044, 11620.535178592847, 11644.59243926467, 8566.528840913747, 11605.30368177912, 11798.0, 11800.055995520337, 11804.763904721858, 39543.209135817124, 39955.4006689897, 40000.799995999834, 40069.797906062864, 40123.7993810032, 3117.184133760573, 3210.5505229267687, 3220.3237320162793, 3391.9830400847836, 3497.6327485613897, 28539.57190642142, 29065.564016539767, 29083.41833163325, 29323.0, 29577.422577422578, 2677.799165062628, 2693.501702185102, 2705.0397109026408, 2723.346396864758, 2798.6081948527017, 478239.0, 480667.0, 481844.0, 482850.0, 483403.0, 36115.09712257162, 65232.673836630514, 66423.0, 66485.0, 66594.66702666553, 36353.909382718564, 36477.45283820745, 36487.08782280464, 36490.087747806516, 36513.63486365102, 15544.76682849758, 15732.370705171665, 15774.974626649231, 15803.446879359168, 15804.0, 12151.392430378446, 12378.504859805687, 12404.19372740769, 13214.075014748876, 13344.065915386023, 7756.340711039512, 7855.842883142305, 8292.834143317099, 8389.286910612665, 8489.702860399857, 3057.312104776452, 3325.9357005757925, 3380.5267262583257, 3427.674370934807, 3430.6740859618294, 902.7201567514091, 924.1867156901883, 980.4215512847454, 988.6554286170802, 1100.1034157161887, 24068.759312407, 29210.26974325616, 29581.260468488028, 30108.0, 30776.846115769727, 987.8468837330082, 1031.8091153136543, 1166.3060479014973, 1166.9299842009336, 1214.690253985228, 12800.295983720798, 13989.3704783285, 14736.631584210481, 15248.923755381153, 15471.5358539244, 588957.0, 610774.0, 622627.0, 623181.0, 626006.0, 62006.37993620006, 62575.68712156501, 62670.0599491008, 62795.6860215696, 62800.0, 1437.381925771928, 1468.6181592785952, 1507.600485871267, 1510.4637853561828, 1528.2282447364232, 25379.238622841345, 25573.87213063923, 26036.479270414864, 26791.464170716867, 26792.59811102874, 49169.50830491649, 49506.75246623744, 49647.50352496429, 49652.75173624109, 49737.0, 424463.0, 428264.0, 428343.71656284283, 428513.0, 428705.0, 24358.39104022343, 27546.586801197598, 28034.85982570156, 28200.85899570489, 28324.575131373458, 36016.63983360133, 47812.0, 48415.27377089417, 49137.508624913295, 50147.0, 35478.46782298319, 36926.815365923, 36965.815170923976, 36982.0, 37665.2466950665, 36124.09689757777, 36212.818935905154, 36420.27159456743, 36738.63261367352, 36843.894683159546, 35509.64490355063, 36148.09629759277, 36728.0, 37172.07069823276, 37301.880943571734, 32175.0, 33346.16634584155, 33568.66431335655, 33645.66354336523, 33655.0, 24729.505409892063, 24888.751112488644, 24928.759261295916, 25529.0, 25594.232173034838], "value": 18588.56461378811, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.13008576260864, 399.9180168065547, 402.121364817873, 404.45398711739136, 413.3242149086243, 15451.304691288891, 20379.796202037975, 20453.48866278342, 21165.365039048833, 21192.788072119292, 20165.395038148843, 20344.898275508604, 20821.68767468489, 21330.360089197347, 21341.93290335485, 5737.942620573783, 5788.884222315541, 5916.0, 5918.556108291874, 5922.703864806764, 5832.358440571534, 5839.153322768196, 5881.088431293157, 5881.382454842223, 5884.794032208873, 4099.61053699898, 6137.6624285664275, 6424.38968298011, 6441.227052753676, 6452.290248072708, 6185.134081228632, 6407.006913928349, 6411.262704788963, 6415.101885735978, 6422.293547709748, 5609.074502707049, 5624.746886390122, 5629.971850140764, 5633.267675202238, 5634.3802181759975, 25573.744262557324, 25764.74235257661, 26032.609510857354, 26143.477130457475, 26175.73824261752, 2025.8885761283123, 2137.0062920741775, 2183.369124908439, 2635.512430200419, 2896.681365049843, 9756.853647195298, 9926.553305101215, 9962.551685174185, 9979.600815967353, 10094.091531762104, 1727.8790484666079, 1764.3207365164503, 1803.7564928734585, 1841.3739328628196, 1843.2995461724593, 107621.0, 111910.0, 113087.0, 113868.0, 114573.854261458, 11628.476718547685, 11637.127215458706, 11648.398882441388, 11685.766284674259, 11794.76410471803, 12724.0, 14148.504802332074, 14531.709365812836, 15062.472813451477, 15064.397424103132, 8733.694320698745, 8737.650493980169, 8810.339224558184, 8902.109789021175, 8906.86639700405, 8762.123787621187, 8799.91200087991, 8806.603702833389, 8903.821923561492, 8918.241949434241, 4443.200223959654, 4601.895545069159, 4649.349091127245, 4651.325557794101, 4668.39310889589, 1947.805219478041, 1996.4709352021725, 2013.90937407817, 2018.9394318170184, 2057.68105943579, 897.9326550508738, 958.0563145301873, 980.6616717232544, 1000.3497726477848, 1051.1380667852288, 19945.800541994686, 19987.800121998593, 20293.042856642813, 20315.796842031392, 20762.688559671617, 832.820943497151, 955.9044095590385, 987.6148302162162, 993.4105431309846, 1033.7105610429091, 10388.0, 10751.354918704903, 10785.13718902486, 10825.079868211133, 10950.233483656068, 526081.0, 569246.0, 612217.0, 629064.0, 634876.0, 121151.0, 125868.0, 126385.0, 130525.34737326253, 132856.0, 999.160705007797, 1024.8513965475113, 1039.838824982114, 1044.9895501044891, 1045.1690905729963, 17646.94118352903, 17652.20565074549, 18062.458126256235, 18119.547011324823, 18707.906460468155, 38556.807215964865, 50275.99448011092, 50558.49441505538, 53519.0, 53547.464525355805, 57646.0, 59380.0, 60588.0, 72891.0, 72997.0, 7229.819254518679, 7378.667959941923, 7983.081945576333, 8022.558759268296, 8169.2647661710735, 10170.542325595217, 11028.558857645605, 12184.147109702235, 12434.689132771391, 12513.43689533955, 9452.716418507456, 10334.689959301233, 10766.300190487593, 11108.833367499656, 11195.664130076111, 10582.947085264525, 10736.464510713846, 10919.508622112184, 11087.390193539433, 11203.663890083311, 10352.482375881327, 11210.27133236337, 11488.712782180512, 11493.885061149282, 11531.711707207387, 8101.959490202511, 8112.797180070545, 8189.836203276021, 8205.671773129008, 8311.33509319265, 8213.712520061768, 9980.850287245552, 11009.2843965142, 11230.494627741935, 11258.605948791754], "value": 9509.357191636422, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1438.762604170312, 1589.0783345659515, 1619.732744097224, 1877.962440751185, 1990.0945069993154, 7823.256790604891, 8579.442336248147, 8682.479051256912, 8687.348448866329, 8708.172723591264, 8305.377096717755, 8429.45208561443, 8443.197896199852, 8443.282321002707, 8444.0, 2129.233475948665, 2349.5066036132434, 2379.393254720044, 2415.9516809663796, 2419.395151212197, 2436.5979613363775, 2446.7920226780743, 2453.2885463215734, 2456.312232574873, 2457.5453541094953, 2370.6562548430466, 2375.2636682628354, 2421.3462365161486, 2426.648136020276, 2443.2914454808083, 1990.0547240061, 2129.936101916945, 2391.342380845264, 2424.7817696407233, 2433.5984562547246, 2394.904203831845, 2431.2098567965304, 2432.428379330856, 2434.926952191437, 2437.2200895713377, 8507.787305317355, 8560.828783424358, 8568.742937711879, 8569.057403685589, 8607.053224145338, 5113.437521872591, 5199.402068762065, 5260.28986086881, 5314.681119132864, 5322.946770532322, 2978.3596526746855, 3459.6540345965454, 3513.1568423578415, 3579.425052976708, 3592.3354179476883, 1025.4103890263054, 1031.5306535526317, 1060.363781730963, 1072.544168728295, 1075.295681328731, 11955.64133076009, 11960.102992275613, 11965.700857478545, 11981.28112313264, 11994.10044246685, 4654.441467023911, 4700.647451441156, 4732.266498692708, 4797.18447863864, 4826.420829500483, 9996.050375214343, 10131.594736210616, 10158.39049657023, 10161.18710503158, 10165.644202453026, 5379.246905433242, 5419.701916394636, 5422.91865622016, 5423.023855705932, 5427.348718153847, 4275.657947364203, 4431.423914891108, 4687.789049492781, 4865.537773911544, 4872.780724867319, 3932.8230229639735, 3959.703022273341, 4000.9199816003515, 4049.6152865477734, 4072.511298644182, 1326.7810811216234, 1327.9070465067352, 1335.5392389625563, 1342.9989315919406, 1345.0651797001185, 828.8052307707684, 840.9747707568782, 912.1562554636935, 921.2860033474111, 923.2567782934668, 5530.55755539564, 5568.549613752306, 5646.971765141148, 5653.717314134276, 5800.0429929062075, 843.304273973974, 929.330881765128, 972.4457059476172, 980.0248752491175, 984.7100298608846, 3293.176705823544, 3641.7632853864943, 3693.8918324502542, 3704.7406681532575, 3708.8887333380044, 8618.396712230084, 10633.946830265799, 10675.679729608124, 10689.037986581245, 10824.971627695357, 10560.683179504627, 10676.89323106759, 10684.091852192496, 10686.25196236256, 10707.464626768631, 7956.323712484504, 8221.260086592236, 8245.422820402635, 8338.374621903382, 8351.08138104808, 5461.754221060141, 5531.7234138293725, 5533.529649979797, 5639.971800140973, 5699.9693017637355, 9316.767080823034, 9370.437773733598, 9617.903820961701, 9629.56666950003, 9631.807363852824, 9334.906650933404, 9774.951125244328, 9782.461964592016, 10037.648682296085, 10041.447720375158, 6301.590396624205, 6454.41910228063, 6843.3840954314355, 6984.895226571504, 7087.50387472872, 8197.590120494071, 8222.794430139294, 8294.29498492635, 8297.751067467985, 8308.25225729663, 7239.601821899846, 7337.633118344168, 7417.221191774786, 7470.028896243453, 7495.17553069151, 7265.920578334319, 7274.163471200668, 7302.087239095114, 7357.301056399633, 7372.889406658797, 5890.705464726832, 6879.071325371011, 6889.104416425833, 6898.413634840894, 6941.5140940135725, 4562.08758248345, 4702.811887524461, 4720.905581888412, 4738.691985020962, 4843.39457567804, 4775.570198682135, 4816.20532612115, 4852.708837469763, 4864.781084851119, 4905.288733133747], "value": 4532.1247410446595, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.6732590195374, 291.536457033317, 311.22504962643023, 320.89132048771495, 330.19926677806313, 6510.218773747157, 6626.8674626507445, 6646.800595982122, 6669.833254168642, 6704.5306828522025, 6403.871922561546, 6636.96681516593, 6642.734290628381, 6656.733730650768, 6665.066890635315, 4891.16850135476, 4903.65674402792, 4908.8281910133155, 4913.7788799504015, 4920.917398172396, 4665.113628410608, 4742.834000809972, 4917.754112294371, 4920.03198720511, 4928.901421971576, 4820.156472617296, 4825.324454576362, 4836.564709176174, 4839.903201935959, 4862.4408193057725, 4800.447948485917, 4807.687500312469, 4812.590929770974, 4828.517148285178, 4845.079434907374, 4119.567445418218, 4119.814608342632, 4120.649744771668, 4120.91758164838, 4135.255653982282, 6347.1114044033875, 6421.068945002995, 6421.454176395012, 6426.260979987314, 6435.292117867031, 5580.135079062752, 5794.478496935254, 5813.505852002585, 5821.970890145564, 5826.82519524415, 7035.261297563785, 7042.0, 7051.294870512959, 7051.330123638298, 7083.4416428385675, 4658.743769092698, 4702.153612349775, 4711.4346278446465, 4716.198246298138, 4727.881802954919, 7424.294692004251, 7432.554046757212, 7443.181250062543, 7444.9627751861435, 7452.627368631547, 4763.356946812205, 4768.237082066853, 4769.546893045155, 4774.904501909942, 4777.737224452619, 4669.310921699951, 4773.164696178224, 4831.347768051339, 4835.081334546425, 4835.564799168092, 5789.102689083199, 5819.679917604488, 5831.708414579339, 5837.0368889132815, 5849.970750146222, 7101.289871012961, 7104.325089116525, 7119.857602847914, 7127.643617819088, 7129.4652901031595, 1603.5830684022005, 1632.0289427790447, 1655.0648883380852, 1665.8833881628411, 1696.9321227150776, 1085.1861104171871, 1085.2240647936605, 1097.5774326884207, 1101.5263436722125, 1103.61925135828, 6532.510061745388, 6536.346365363521, 6540.345965403517, 6540.444062254762, 6604.735810567524, 7048.083749112582, 7302.160251571031, 7308.5614863108385, 7309.159446663702, 7310.52481588695, 5591.916121258185, 6528.804135875931, 6534.150560427114, 6553.525456772188, 6564.916788729806, 3844.1350696093714, 3895.590962948906, 3896.922061558753, 3900.395438707005, 3902.5902280260166, 7239.148487575966, 7680.772575953023, 7687.500312479671, 7694.384449244047, 7711.113221979432, 6202.627842329384, 6794.3545363190415, 7530.171681115073, 7548.282913123245, 7567.243275672388, 7634.770956871303, 7643.312101910855, 7649.235076492307, 7661.080670319486, 7664.4634875559295, 6991.9300806993315, 7277.0, 7280.708771649075, 7303.963480182778, 7312.451566132455, 7159.534630249017, 7176.102987126609, 7186.784396468114, 7197.640117993975, 7227.1327440705345, 7556.395488360808, 7629.2370762923265, 7645.694172233048, 7657.349125324187, 7662.0422447194105, 4798.184308667465, 4803.327534145153, 4818.301346304696, 4822.927656085091, 4824.879378015437, 5749.511291540266, 7196.10048743907, 7218.566885986857, 7247.9637601811655, 7332.0, 5607.299087614048, 7140.571565706075, 7191.0, 7201.099862517185, 7239.095113110861, 7068.081149450539, 7125.287471252833, 7175.995360649617, 7190.388816950618, 7202.96398518004, 6998.965005174941, 7005.719771209095, 7010.843210870149, 7024.578525288499, 7029.61337126463, 5486.369067557282, 5775.826725198251, 6855.862882742417, 7165.068541089625, 7192.784216473514, 5683.71581420919, 6971.0, 7009.843375843133, 7017.403520700797, 7045.577265364094], "value": 5165.226636733915, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.742534124362, 726.4079774983385, 729.4674887332247, 737.9852402951941, 749.8087987563172, 13585.456581736718, 13613.863861361384, 14586.562403127897, 14910.5526834195, 14933.104013759168, 14303.499377521788, 14365.856341436582, 14374.281285935687, 14379.137251764876, 14386.568402947929, 724.5616402076747, 858.3047731337621, 891.0287786312917, 891.603236559728, 896.8296023755498, 882.4176043811086, 886.2334081019903, 888.6312180445108, 888.8133491966695, 904.7150147703485, 935.0182308576002, 935.2004036548755, 936.2229349639816, 936.2369668720005, 954.9713508594754, 924.3575714878195, 926.5969303353063, 926.8239034583441, 927.4713413354391, 947.4315410753561, 707.7664370757637, 707.9504434689574, 708.479267738212, 708.922018577956, 709.7338498063226, 14542.0, 14630.975831691785, 14632.853671463363, 14716.48492302775, 14834.480793172188, 12352.25886446807, 12382.195157314745, 12432.689182770504, 12625.368731563476, 12700.682482937907, 9757.90242097577, 9850.950745246228, 10088.293819432643, 10140.847887281696, 10362.481875906175, 3428.245785927092, 3492.388831954398, 3524.8061356625376, 3574.696150827183, 3588.856445742167, 68285.65857170732, 70166.0, 82615.17384826134, 82880.58559707223, 84583.57708211482, 8567.785805354792, 8693.521856297964, 8737.694180703646, 8746.56267186638, 8835.514046727492, 18572.978486183118, 18583.0, 18596.977166255714, 18599.97700126479, 18623.534411639546, 7231.710731570678, 7294.270572942663, 7303.817404564928, 7317.597532135784, 7473.140588832245, 3992.321305378093, 4053.8986525337104, 4126.42230087788, 4132.752034877917, 4303.569643035671, 2934.6478422589425, 3435.896923092311, 3483.2336885885065, 3489.3195826814035, 3490.9127271818406, 593.9762409503571, 619.8822223777468, 627.7771391156165, 634.4416913116428, 650.8437974886087, 582.7581553655185, 584.5528170949227, 586.4253032028636, 589.2663633775976, 620.0, 7738.845223095506, 8168.959155204305, 8192.30365418946, 8258.380621453414, 8266.62293843752, 544.5697898660014, 548.8188897663762, 570.3811364669298, 578.9305283366023, 594.7323704333068, 3063.0, 3627.7460577759302, 3828.674562662149, 3839.2705385976574, 3868.5434748934067, 89795.65306520407, 91465.0, 92709.60935585972, 92725.0, 92821.07178928124, 55171.72414137904, 74420.0, 79134.60432697799, 79145.60427197827, 80501.59749201217, 43994.34008489875, 57020.714896426085, 58673.413265867646, 59855.40144598585, 60022.39977600255, 8350.206730360605, 8458.915410845813, 8547.95726021366, 8579.571021448777, 8592.109735858101, 38095.23809523849, 44762.3285650709, 46864.53135468738, 47905.76047119742, 48005.27992080191, 60545.69727151336, 62961.37038629555, 66164.0, 66538.66922661423, 68940.6552967232, 10795.40625265618, 10927.344359338465, 11145.108391328837, 11186.888131118585, 11237.550497980315, 11825.053995680153, 11869.129119567044, 12064.517419303129, 12078.33569153705, 12403.25580465175, 10957.958993895567, 10967.0, 11199.992000719974, 11355.148363872577, 12011.519539218334, 10937.781244375226, 11320.26418282809, 11367.317960922372, 11451.448653181898, 11754.0, 11121.94439027832, 11272.436378181223, 11274.529315766285, 11434.542618295174, 11563.132765042486, 9337.439753614804, 9374.625014999323, 9547.952260238655, 9650.71047868565, 9671.564779584794, 9693.369930954465, 9703.194637839362, 9718.854217186607, 9786.902130978599, 9806.990088680059], "value": 6722.968220433913, "unit": "score"}], "name": "dromaeo_css", "value": 7349.166110919684, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 128.0, 165.0, 154.0, 111.0, 113.0, 121.0, 165.0, 183.0], "value": 136.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 121.0, 154.0, 162.0, 148.0, 170.0, 167.0, 118.0, 168.0, 121.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 184.0, 186.0, 183.0, 183.0, 181.0, 186.0, 185.0, 182.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 74.0, 148.0, 56.0, 55.0, 57.0, 66.0, 134.0, 56.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 139.0, 77.0, 80.0, 91.0, 127.0, 117.0, 113.0, 162.0], "value": 109.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 91.0, 89.0, 91.0, 87.0, 91.0, 88.0, 92.0, 87.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 102.0, 97.0, 96.0, 95.0, 96.0, 98.0, 96.0, 95.0, 96.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 83.0, 87.0, 86.0, 96.0, 133.0, 133.0, 87.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 64.0, 64.0, 63.0, 63.0, 66.0, 82.0, 61.0, 59.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 66.0, 70.0, 81.0, 78.0, 73.0, 68.0, 69.0, 69.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 79.0, 83.0, 78.0, 80.0, 81.0, 78.0, 82.0, 84.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 137.0, 135.0, 114.0, 151.0, 152.0, 148.0, 146.0, 149.0, 132.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 167.0, 165.0, 165.0, 167.0, 165.0, 165.0, 168.0, 163.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 66.0, 66.0, 67.0, 67.0, 69.0, 67.0, 66.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1527.7, "alertThreshold": 2.0}]} 23:27:29 INFO - Return code: 0 23:27:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:27:29 ERROR - # TBPL SUCCESS # 23:27:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:27:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:27:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:27:29 INFO - 2016-05-23 23:27:29.109 screenresolution[1680:10514] starting screenresolution argv=screenresolution get 23:27:29 INFO - 2016-05-23 23:27:29.112 screenresolution[1680:10514] Display 0: 1600x1200x32@60 23:27:29 INFO - 2016-05-23 23:27:29.117 screenresolution[1681:10517] starting screenresolution argv=screenresolution list 23:27:29 INFO - Available Modes on Display 0 23:27:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:27:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:27:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:27:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:27:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:27:29 INFO - Intel Iris: 23:27:29 INFO - Chipset Model: Intel Iris 23:27:29 INFO - Type: GPU 23:27:29 INFO - Bus: Built-In 23:27:29 INFO - VRAM (Dynamic, Max): 1536 MB 23:27:29 INFO - Vendor: Intel (0x8086) 23:27:29 INFO - Device ID: 0x0a2e 23:27:29 INFO - Revision ID: 0x0009 23:27:29 INFO - Displays: 23:27:29 INFO - Display: 23:27:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:27:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:27:29 INFO - Display Serial Number: 1600x1200 60 23:27:29 INFO - Main Display: Yes 23:27:29 INFO - Mirror: Off 23:27:29 INFO - Online: Yes 23:27:29 INFO - Rotation: Supported 23:27:29 INFO - Return code: 0 23:27:29 INFO - Running post-action listener: _resource_record_post_action 23:27:29 INFO - Running post-run listener: _resource_record_post_run 23:27:30 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 31550976; Write bytes: 653368320; Read time: 197; Write time: 1821 23:27:30 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 345817600; Write bytes: 346326528; Read time: 27339; Write time: 725 23:27:30 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30629376; Write bytes: 297084416; Read time: 185; Write time: 1061 23:27:30 INFO - Running post-run listener: _upload_blobber_files 23:27:30 INFO - Blob upload gear active. 23:27:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:27:30 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 23:27:30 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'] 23:27:30 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 23:27:30 INFO - (blobuploader) - INFO - Open directory for files ... 23:27:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:27:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:27:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:27:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:27:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:27:31 INFO - (blobuploader) - INFO - Done attempting. 23:27:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:27:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:27:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:27:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:27:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:27:32 INFO - (blobuploader) - INFO - Done attempting. 23:27:32 INFO - (blobuploader) - INFO - Iteration through files over. 23:27:32 INFO - Return code: 0 23:27:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:27:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:27:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d4912e1063433f159c2cebf455419911a957725d6cda64c8add8b20fe94437a3e8723f26164d72d65eed502993a70253ce8f9706c30b684faa4ec7827e3de4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b950669f3ec88a4114bfbe1c548ad7c84f477de8b8d3e50adfa0be640c93ff3275b86cbd0c057b4ed8d5f9f150accb383b10ac1a2c3e203378cb404887168b9"} 23:27:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:27:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:27:32 INFO - Contents: 23:27:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d4912e1063433f159c2cebf455419911a957725d6cda64c8add8b20fe94437a3e8723f26164d72d65eed502993a70253ce8f9706c30b684faa4ec7827e3de4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b950669f3ec88a4114bfbe1c548ad7c84f477de8b8d3e50adfa0be640c93ff3275b86cbd0c057b4ed8d5f9f150accb383b10ac1a2c3e203378cb404887168b9"} 23:27:32 INFO - Running post-run listener: copy_logs_to_upload_dir 23:27:32 INFO - Copying logs to upload dir... 23:27:32 INFO - mkdir: /builds/slave/test/build/upload/logs 23:27:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.995188 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-23 23:27:33.076305) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 23:27:33.079934) ========= 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.nnRy2jcToC/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.YmPRKQngD7/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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/6d4912e1063433f159c2cebf455419911a957725d6cda64c8add8b20fe94437a3e8723f26164d72d65eed502993a70253ce8f9706c30b684faa4ec7827e3de4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b950669f3ec88a4114bfbe1c548ad7c84f477de8b8d3e50adfa0be640c93ff3275b86cbd0c057b4ed8d5f9f150accb383b10ac1a2c3e203378cb404887168b9"} build_url:https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011373 build_url: 'https://queue.taskcluster.net/v1/task/EvgWke2bS7i6rGX5abNlvg/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/6d4912e1063433f159c2cebf455419911a957725d6cda64c8add8b20fe94437a3e8723f26164d72d65eed502993a70253ce8f9706c30b684faa4ec7827e3de4b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b950669f3ec88a4114bfbe1c548ad7c84f477de8b8d3e50adfa0be640c93ff3275b86cbd0c057b4ed8d5f9f150accb383b10ac1a2c3e203378cb404887168b9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 23:27:33.115556) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:27:33.116249) ========= 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.nnRy2jcToC/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.YmPRKQngD7/Listeners TMPDIR=/var/folders/85/j97sz5wj77sdq3g1dc7sg48000000w/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.005090 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:27:33.169906) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 23:27:33.170189) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 23:27:33.170476) ========= ========= Total master_lag: 0.98 =========