builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0117 starttime: 1462798478.19 results: success (0) buildid: 20160509005049 builduid: 41dbd660830c4270b4520649f25740b3 revision: 307b5eca8726f6a72a1d69ba76630a6dbf402477 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:38.187336) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:38.187761) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:38.188027) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:38.205722) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:38.206074) ========= 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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-09 05:54:38-- 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% 132M=0s 2016-05-09 05:54:38 (132 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097114 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:38.321175) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:38.321796) ========= 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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.021703 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:38.371114) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-09 05:54:38.371468) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 307b5eca8726f6a72a1d69ba76630a6dbf402477 --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 307b5eca8726f6a72a1d69ba76630a6dbf402477 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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-09 05:54:38,450 truncating revision to first 12 chars 2016-05-09 05:54:38,450 Setting DEBUG logging. 2016-05-09 05:54:38,450 attempt 1/10 2016-05-09 05:54:38,450 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/307b5eca8726?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 05:54:40,525 unpacking tar archive at: mozilla-aurora-307b5eca8726/testing/mozharness/ program finished with exit code 0 elapsedTime=2.329732 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-09 05:54:40.719812) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:40.720129) ========= script_repo_revision: 307b5eca8726f6a72a1d69ba76630a6dbf402477 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:40.720543) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:40.720804) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 05:54:40.731813) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-09 05:54:40.732213) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 05:54:40 INFO - MultiFileLogger online at 20160509 05:54:40 in /builds/slave/test 05:54:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:54:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:54:40 INFO - {'append_to_log': False, 05:54:40 INFO - 'base_work_dir': '/builds/slave/test', 05:54:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:54:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:54:40 INFO - 'branch': 'Mozilla-Aurora', 05:54:40 INFO - 'buildbot_json_path': 'buildprops.json', 05:54:40 INFO - 'config_files': ('talos/mac_config.py',), 05:54:40 INFO - 'default_actions': ('clobber', 05:54:40 INFO - 'read-buildbot-config', 05:54:40 INFO - 'download-and-extract', 05:54:40 INFO - 'populate-webroot', 05:54:40 INFO - 'create-virtualenv', 05:54:40 INFO - 'install', 05:54:40 INFO - 'run-tests'), 05:54:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:54:40 INFO - 'download_minidump_stackwalk': True, 05:54:40 INFO - 'download_symbols': 'ondemand', 05:54:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:54:40 INFO - 'tooltool.py': '/tools/tooltool.py', 05:54:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:54:40 INFO - '/tools/misc-python/virtualenv.py')}, 05:54:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:54:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:54:40 INFO - 'installer_path': 'installer.exe', 05:54:40 INFO - 'log_level': 'info', 05:54:40 INFO - 'log_name': 'talos', 05:54:40 INFO - 'log_to_console': True, 05:54:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:54:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:54:40 INFO - 'opt_config_files': (), 05:54:40 INFO - 'pip_index': False, 05:54:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:54:40 INFO - 'cmd': ('bash', 05:54:40 INFO - '-c', 05:54:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:54:40 INFO - 'enabled': True, 05:54:40 INFO - 'halt_on_failure': False, 05:54:40 INFO - 'name': 'check_screen_resolution'},), 05:54:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:54:40 INFO - 'cmd': ('bash', 05:54:40 INFO - '-c', 05:54:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:54:40 INFO - 'enabled': True, 05:54:40 INFO - 'halt_on_failure': False, 05:54:40 INFO - 'name': 'check_screen_resolution'},), 05:54:40 INFO - 'run_cmd_checks_enabled': True, 05:54:40 INFO - 'sps_profile': False, 05:54:40 INFO - 'sps_profile_interval': 0, 05:54:40 INFO - 'suite': 'dromaeojs-e10s', 05:54:40 INFO - 'system_bits': '32', 05:54:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:54:40 INFO - 'title': 't-yosemite-r7-0117', 05:54:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:54:40 INFO - 'use_talos_json': True, 05:54:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:54:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:54:40 INFO - 'work_dir': 'build'} 05:54:40 INFO - ##### 05:54:40 INFO - ##### Running clobber step. 05:54:40 INFO - ##### 05:54:40 INFO - Running pre-action listener: _resource_record_pre_action 05:54:40 INFO - Running main action method: clobber 05:54:40 INFO - rmtree: /builds/slave/test/build 05:54:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:54:41 INFO - Running post-action listener: _resource_record_post_action 05:54:41 INFO - ##### 05:54:41 INFO - ##### Running read-buildbot-config step. 05:54:41 INFO - ##### 05:54:41 INFO - Running pre-action listener: _resource_record_pre_action 05:54:41 INFO - Running main action method: read_buildbot_config 05:54:41 INFO - Using buildbot properties: 05:54:41 INFO - { 05:54:41 INFO - "project": "", 05:54:41 INFO - "product": "firefox", 05:54:41 INFO - "script_repo_revision": "production", 05:54:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:54:41 INFO - "repository": "", 05:54:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:54:41 INFO - "buildid": "20160509005049", 05:54:41 INFO - "pgo_build": "False", 05:54:41 INFO - "basedir": "/builds/slave/test", 05:54:41 INFO - "buildnumber": 3, 05:54:41 INFO - "slavename": "t-yosemite-r7-0117", 05:54:41 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:54:41 INFO - "platform": "yosemite_r7", 05:54:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:54:41 INFO - "branch": "mozilla-aurora", 05:54:41 INFO - "repo_path": "releases/mozilla-aurora", 05:54:41 INFO - "revision": "307b5eca8726f6a72a1d69ba76630a6dbf402477", 05:54:41 INFO - "stage_platform": "macosx64", 05:54:41 INFO - "builduid": "41dbd660830c4270b4520649f25740b3", 05:54:41 INFO - "slavebuilddir": "test" 05:54:41 INFO - } 05:54:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 05:54:41 INFO - Running post-action listener: _resource_record_post_action 05:54:41 INFO - ##### 05:54:41 INFO - ##### Running download-and-extract step. 05:54:41 INFO - ##### 05:54:41 INFO - Running pre-action listener: _resource_record_pre_action 05:54:41 INFO - Running main action method: download_and_extract 05:54:41 INFO - mkdir: /builds/slave/test/build/tests 05:54:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:41 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:54:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/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 05:54:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/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 05:54:42 INFO - Downloaded 1199 bytes. 05:54:42 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:54:42 INFO - Using the following test package requirements: 05:54:42 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:54:42 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 05:54:42 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 05:54:42 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'jsshell-mac.zip'], 05:54:42 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 05:54:42 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:54:42 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 05:54:42 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 05:54:42 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 05:54:42 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:54:42 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 05:54:42 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:42 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:54:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/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 05:54:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/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 05:54:44 INFO - Downloaded 18088795 bytes. 05:54:44 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'] 05:54:44 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 05:54:47 INFO - Return code: 0 05:54:47 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 05:54:47 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')]} 05:54:47 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:54:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:54:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/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 05:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/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 05:54:49 INFO - Downloaded 18088795 bytes. 05:54:49 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'] 05:54:49 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 05:54:52 INFO - Return code: 0 05:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:52 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:54:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:54:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:54:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/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 05:54:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/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 05:54:54 INFO - Downloaded 10840145 bytes. 05:54:54 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'] 05:54:54 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 05:54:54 INFO - Return code: 0 05:54: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')]} 05:54:54 INFO - https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 05:54:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 05:54:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/J_hY6fdxQMm_M9plHZylsA/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 05:54:58 INFO - Downloaded 114085322 bytes. 05:54:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:58 INFO - mkdir: /builds/slave/test/properties 05:54:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:54:58 INFO - Writing to file /builds/slave/test/properties/build_url 05:54:58 INFO - Contents: 05:54:58 INFO - build_url:https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:54:59 INFO - Running post-action listener: _resource_record_post_action 05:54:59 INFO - Running post-action listener: set_extra_try_arguments 05:54:59 INFO - ##### 05:54:59 INFO - ##### Running populate-webroot step. 05:54:59 INFO - ##### 05:54:59 INFO - Running pre-action listener: _resource_record_pre_action 05:54:59 INFO - Running main action method: populate_webroot 05:54:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:54:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:54:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:54:59 INFO - '--tppagecycles', 05:54:59 INFO - '7'], 05:54:59 INFO - 'tests': ['tsvgm']}}, 05:54:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:54:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:54:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:54:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:54:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:54:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:54:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:59 INFO - 'talos_options': ['--disable-e10s'], 05:54:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:59 INFO - 'talos_options': ['--disable-e10s'], 05:54:59 INFO - 'tests': ['damp', 'tps']}, 05:54:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:59 INFO - 'tests': ['damp', 'tps']}, 05:54:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:54:59 INFO - 'tests': ['dromaeo_dom']}, 05:54:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:54:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:54:59 INFO - 'tests': ['a11yr', 05:54:59 INFO - 'ts_paint', 05:54:59 INFO - 'tpaint', 05:54:59 INFO - 'sessionrestore', 05:54:59 INFO - 'sessionrestore_no_auto_restore', 05:54:59 INFO - 'tabpaint']}, 05:54:59 INFO - 'other-e10s': {'tests': ['a11yr', 05:54:59 INFO - 'ts_paint', 05:54:59 INFO - 'tpaint', 05:54:59 INFO - 'sessionrestore', 05:54:59 INFO - 'sessionrestore_no_auto_restore', 05:54:59 INFO - 'tabpaint']}, 05:54:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:54:59 INFO - 'ts_paint', 05:54:59 INFO - 'tpaint', 05:54:59 INFO - 'sessionrestore', 05:54:59 INFO - 'sessionrestore_no_auto_restore', 05:54:59 INFO - 'tabpaint']}, 05:54:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:54:59 INFO - 'ts_paint', 05:54:59 INFO - 'tpaint', 05:54:59 INFO - 'sessionrestore', 05:54:59 INFO - 'sessionrestore_no_auto_restore', 05:54:59 INFO - 'tabpaint']}, 05:54:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:54:59 INFO - 'tests': ['a11yr', 05:54:59 INFO - 'ts_paint', 05:54:59 INFO - 'tpaint', 05:54:59 INFO - 'sessionrestore', 05:54:59 INFO - 'sessionrestore_no_auto_restore', 05:54:59 INFO - 'tabpaint']}, 05:54:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:54:59 INFO - 'tests': ['a11yr', 05:54:59 INFO - 'ts_paint', 05:54:59 INFO - 'tpaint', 05:54:59 INFO - 'sessionrestore', 05:54:59 INFO - 'sessionrestore_no_auto_restore', 05:54:59 INFO - 'tabpaint']}, 05:54:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:54:59 INFO - 'tests': ['tsvgx', 05:54:59 INFO - 'tsvgr_opacity', 05:54:59 INFO - 'tart', 05:54:59 INFO - 'tscrollx', 05:54:59 INFO - 'cart']}, 05:54:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:54:59 INFO - 'tsvgr_opacity', 05:54:59 INFO - 'tart', 05:54:59 INFO - 'tscrollx', 05:54:59 INFO - 'cart']}, 05:54:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:59 INFO - 'talos_options': ['--disable-e10s'], 05:54:59 INFO - 'tests': ['tp5o']}, 05:54:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:59 INFO - 'tests': ['tp5o']}, 05:54:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:59 INFO - 'talos_options': ['--disable-e10s', 05:54:59 INFO - '--xperf_path', 05:54:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:59 INFO - 'tests': ['tp5n']}, 05:54:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:59 INFO - 'talos_options': ['--xperf_path', 05:54:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:59 INFO - 'tests': ['tp5n']}}, 05:54:59 INFO - 'talos.zip': {'path': '', 05:54:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:54:59 INFO - Running post-action listener: _resource_record_post_action 05:54:59 INFO - ##### 05:54:59 INFO - ##### Running create-virtualenv step. 05:54:59 INFO - ##### 05:54:59 INFO - Running pre-action listener: _resource_record_pre_action 05:54:59 INFO - Running main action method: create_virtualenv 05:54:59 INFO - Creating virtualenv /builds/slave/test/build/venv 05:54:59 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 05:54:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:54:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:54:59 INFO - Using real prefix '/tools/python27' 05:54:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:55:00 INFO - Installing distribute.............................................................................................................................................................................................done. 05:55:03 INFO - Installing pip.................done. 05:55:03 INFO - Return code: 0 05:55:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be66a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be76420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb220c93390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd05e00>, '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 0x10bb6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd00b20>, '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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 05:55:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:55:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:55:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wQ6LWsMRVX/Render', 05:55:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:03 INFO - 'HOME': '/Users/cltbld', 05:55:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:03 INFO - 'LOGNAME': 'cltbld', 05:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:03 INFO - 'MOZ_NO_REMOTE': '1', 05:55:03 INFO - 'NO_EM_RESTART': '1', 05:55:03 INFO - 'PAGER': '/bin/cat', 05:55:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:03 INFO - 'PWD': '/builds/slave/test', 05:55:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:03 INFO - 'SHELL': '/bin/bash', 05:55:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wi5y7Lrncx/Listeners', 05:55:03 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:55:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:03 INFO - 'USER': 'cltbld', 05:55:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:03 INFO - 'XPC_FLAGS': '0x0', 05:55:03 INFO - 'XPC_SERVICE_NAME': '0', 05:55:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:03 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 05:55:03 INFO - Cleaning up... 05:55:03 INFO - Return code: 0 05:55:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10be66a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be76420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb220c93390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd05e00>, '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 0x10bb6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd00b20>, '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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 05:55:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:55:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:55:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wQ6LWsMRVX/Render', 05:55:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:03 INFO - 'HOME': '/Users/cltbld', 05:55:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:03 INFO - 'LOGNAME': 'cltbld', 05:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:03 INFO - 'MOZ_NO_REMOTE': '1', 05:55:03 INFO - 'NO_EM_RESTART': '1', 05:55:03 INFO - 'PAGER': '/bin/cat', 05:55:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:03 INFO - 'PWD': '/builds/slave/test', 05:55:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:03 INFO - 'SHELL': '/bin/bash', 05:55:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wi5y7Lrncx/Listeners', 05:55:03 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:55:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:03 INFO - 'USER': 'cltbld', 05:55:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:03 INFO - 'XPC_FLAGS': '0x0', 05:55:03 INFO - 'XPC_SERVICE_NAME': '0', 05:55:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:03 INFO - Downloading/unpacking psutil>=0.7.1 05:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:55:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:06 INFO - Installing collected packages: psutil 05:55:06 INFO - Running setup.py install for psutil 05:55:06 INFO - building 'psutil._psutil_osx' extension 05:55:06 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 05:55:07 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 05:55:07 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 05:55:07 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 05:55:07 INFO - building 'psutil._psutil_posix' extension 05:55:07 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 05:55:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:55:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:55:07 INFO - ^ 05:55:07 INFO - 1 warning generated. 05:55:07 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 05:55:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:07 INFO - Successfully installed psutil 05:55:07 INFO - Cleaning up... 05:55:07 INFO - Return code: 0 05:55:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:55:07 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')]} 05:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:07 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')]} 05:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:07 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 0x10be66a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be76420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb220c93390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd05e00>, '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 0x10bb6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd00b20>, '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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 05:55:07 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 05:55:07 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 05:55:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wQ6LWsMRVX/Render', 05:55:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:07 INFO - 'HOME': '/Users/cltbld', 05:55:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:07 INFO - 'LOGNAME': 'cltbld', 05:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:07 INFO - 'MOZ_NO_REMOTE': '1', 05:55:07 INFO - 'NO_EM_RESTART': '1', 05:55:07 INFO - 'PAGER': '/bin/cat', 05:55:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:07 INFO - 'PWD': '/builds/slave/test', 05:55:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:07 INFO - 'SHELL': '/bin/bash', 05:55:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wi5y7Lrncx/Listeners', 05:55:07 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:55:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:07 INFO - 'USER': 'cltbld', 05:55:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:07 INFO - 'XPC_FLAGS': '0x0', 05:55:07 INFO - 'XPC_SERVICE_NAME': '0', 05:55:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:55:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:55:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:55:10 INFO - Installing collected packages: mozsystemmonitor 05:55:10 INFO - Running setup.py install for mozsystemmonitor 05:55:10 INFO - Successfully installed mozsystemmonitor 05:55:10 INFO - Cleaning up... 05:55:10 INFO - Return code: 0 05:55:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10be66a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be76420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb220c93390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd05e00>, '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 0x10bb6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd00b20>, '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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 05:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wQ6LWsMRVX/Render', 05:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:10 INFO - 'HOME': '/Users/cltbld', 05:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:10 INFO - 'LOGNAME': 'cltbld', 05:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:10 INFO - 'MOZ_NO_REMOTE': '1', 05:55:10 INFO - 'NO_EM_RESTART': '1', 05:55:10 INFO - 'PAGER': '/bin/cat', 05:55:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:10 INFO - 'PWD': '/builds/slave/test', 05:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:10 INFO - 'SHELL': '/bin/bash', 05:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wi5y7Lrncx/Listeners', 05:55:10 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:10 INFO - 'USER': 'cltbld', 05:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:10 INFO - 'XPC_FLAGS': '0x0', 05:55:10 INFO - 'XPC_SERVICE_NAME': '0', 05:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:10 INFO - Downloading/unpacking blobuploader==1.2.4 05:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:13 INFO - Downloading blobuploader-1.2.4.tar.gz 05:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:55:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:55:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:14 INFO - Downloading docopt-0.6.1.tar.gz 05:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:55:14 INFO - Installing collected packages: blobuploader, requests, docopt 05:55:14 INFO - Running setup.py install for blobuploader 05:55:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:55:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:55:14 INFO - Running setup.py install for requests 05:55:15 INFO - Running setup.py install for docopt 05:55:15 INFO - Successfully installed blobuploader requests docopt 05:55:15 INFO - Cleaning up... 05:55:15 INFO - Return code: 0 05:55:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55: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')]} 05:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55: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')]} 05:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:15 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 0x10be66a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be76420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb220c93390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd05e00>, '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 0x10bb6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd00b20>, '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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 05:55:15 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 05:55:15 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 05:55:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wQ6LWsMRVX/Render', 05:55:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:15 INFO - 'HOME': '/Users/cltbld', 05:55:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:15 INFO - 'LOGNAME': 'cltbld', 05:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:15 INFO - 'MOZ_NO_REMOTE': '1', 05:55:15 INFO - 'NO_EM_RESTART': '1', 05:55:15 INFO - 'PAGER': '/bin/cat', 05:55:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:15 INFO - 'PWD': '/builds/slave/test', 05:55:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:15 INFO - 'SHELL': '/bin/bash', 05:55:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wi5y7Lrncx/Listeners', 05:55:15 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:55:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:15 INFO - 'USER': 'cltbld', 05:55:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:15 INFO - 'XPC_FLAGS': '0x0', 05:55:15 INFO - 'XPC_SERVICE_NAME': '0', 05:55:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:55:15 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-gm0ORM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:55:15 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-HyCwD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:55:16 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-KDtI5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:55:16 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-dvAYhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:55:16 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-PnIPFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:55:16 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-W27P3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:55:16 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-g8E68P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:55:16 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-GclCBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:55:16 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-oZdPBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:55:16 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-4EElgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:55:16 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-YIBTYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:55:16 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-MIzgKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:55:17 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-VfEgGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:55:17 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-uoITYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:17 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-YHsaag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:55:17 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-60eFC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:55:17 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Z8p4b1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:55:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:55:17 INFO - Running setup.py install for manifestparser 05:55:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:55:17 INFO - Running setup.py install for mozcrash 05:55:17 INFO - Running setup.py install for mozdebug 05:55:18 INFO - Running setup.py install for mozdevice 05:55:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:55:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:55:18 INFO - Running setup.py install for mozfile 05:55:18 INFO - Running setup.py install for mozhttpd 05:55:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:55:18 INFO - Running setup.py install for mozinfo 05:55:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:55:18 INFO - Running setup.py install for mozInstall 05:55:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:55:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:55:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:55:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:55:18 INFO - Running setup.py install for mozleak 05:55:18 INFO - Running setup.py install for mozlog 05:55:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:55:19 INFO - Running setup.py install for moznetwork 05:55:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:55:19 INFO - Running setup.py install for mozprocess 05:55:19 INFO - Running setup.py install for mozprofile 05:55:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:55:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:55:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:55:19 INFO - Running setup.py install for mozrunner 05:55:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:55:19 INFO - Running setup.py install for mozscreenshot 05:55:19 INFO - Running setup.py install for moztest 05:55:20 INFO - Running setup.py install for mozversion 05:55:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:55:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:55:20 INFO - Cleaning up... 05:55:20 INFO - Return code: 0 05:55:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:20 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')]} 05:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:20 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')]} 05:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:20 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 0x10be66a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be76420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb220c93390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd05e00>, '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 0x10bb6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd00b20>, '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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 05:55:20 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 05:55:20 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 05:55:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wQ6LWsMRVX/Render', 05:55:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:20 INFO - 'HOME': '/Users/cltbld', 05:55:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:20 INFO - 'LOGNAME': 'cltbld', 05:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:20 INFO - 'MOZ_NO_REMOTE': '1', 05:55:20 INFO - 'NO_EM_RESTART': '1', 05:55:20 INFO - 'PAGER': '/bin/cat', 05:55:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:20 INFO - 'PWD': '/builds/slave/test', 05:55:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:20 INFO - 'SHELL': '/bin/bash', 05:55:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wi5y7Lrncx/Listeners', 05:55:20 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:55:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:20 INFO - 'USER': 'cltbld', 05:55:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:20 INFO - 'XPC_FLAGS': '0x0', 05:55:20 INFO - 'XPC_SERVICE_NAME': '0', 05:55:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:55:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-qhU1Dg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:55:20 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)) 05:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:55:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-dpH0pj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:55:20 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)) 05:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:55:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Fn2loR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:55:20 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)) 05:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:55:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ReKYTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:55:20 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)) 05:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:55:20 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-wf8_D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:55:21 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)) 05:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:55:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-45u8t0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:55:21 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)) 05:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:55:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-azviWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:55:21 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)) 05:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:55:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-fgIDX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:55:21 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)) 05:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:55:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-ZLYg1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:55:21 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)) 05:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:55:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-cMxM8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:55:21 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)) 05:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:55:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-oDqPA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:55:21 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)) 05:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:55:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-5tZROf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:55:21 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)) 05:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:55:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-mOYUKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:55:21 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)) 05:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:55:21 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-Ziqbub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:55:22 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)) 05:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:22 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-CuA7Jd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:22 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)) 05:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:55:22 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-8CB4gJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:55:22 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)) 05:55:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:55:22 INFO - Running setup.py (path:/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/pip-lS247E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:55:22 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)) 05:55:22 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)) 05:55:22 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)) 05:55:22 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)) 05:55:22 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)) 05:55:22 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)) 05:55:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:24 INFO - Downloading blessings-1.6.tar.gz 05:55:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:55:24 INFO - Installing collected packages: blessings 05:55:24 INFO - Running setup.py install for blessings 05:55:25 INFO - Successfully installed blessings 05:55:25 INFO - Cleaning up... 05:55:25 INFO - Return code: 0 05:55:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:55:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:25 INFO - Reading from file tmpfile_stdout 05:55:25 INFO - Current package versions: 05:55:25 INFO - blessings == 1.6 05:55:25 INFO - blobuploader == 1.2.4 05:55:25 INFO - docopt == 0.6.1 05:55:25 INFO - manifestparser == 1.1 05:55:25 INFO - mozInstall == 1.12 05:55:25 INFO - mozcrash == 0.17 05:55:25 INFO - mozdebug == 0.1 05:55:25 INFO - mozdevice == 0.48 05:55:25 INFO - mozfile == 1.2 05:55:25 INFO - mozhttpd == 0.7 05:55:25 INFO - mozinfo == 0.9 05:55:25 INFO - mozleak == 0.1 05:55:25 INFO - mozlog == 3.1 05:55:25 INFO - moznetwork == 0.27 05:55:25 INFO - mozprocess == 0.22 05:55:25 INFO - mozprofile == 0.28 05:55:25 INFO - mozrunner == 6.11 05:55:25 INFO - mozscreenshot == 0.1 05:55:25 INFO - mozsystemmonitor == 0.0 05:55:25 INFO - moztest == 0.7 05:55:25 INFO - mozversion == 1.4 05:55:25 INFO - psutil == 3.1.1 05:55:25 INFO - requests == 1.2.3 05:55:25 INFO - wsgiref == 0.1.2 05:55:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:25 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 0x10be66a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be76420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb220c93390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd05e00>, '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 0x10bb6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd00b20>, '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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 05:55:25 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 05:55:25 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 05:55:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wQ6LWsMRVX/Render', 05:55:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:25 INFO - 'HOME': '/Users/cltbld', 05:55:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:25 INFO - 'LOGNAME': 'cltbld', 05:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:25 INFO - 'MOZ_NO_REMOTE': '1', 05:55:25 INFO - 'NO_EM_RESTART': '1', 05:55:25 INFO - 'PAGER': '/bin/cat', 05:55:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:25 INFO - 'PWD': '/builds/slave/test', 05:55:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:25 INFO - 'SHELL': '/bin/bash', 05:55:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wi5y7Lrncx/Listeners', 05:55:25 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:55:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:25 INFO - 'USER': 'cltbld', 05:55:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:25 INFO - 'XPC_FLAGS': '0x0', 05:55:25 INFO - 'XPC_SERVICE_NAME': '0', 05:55:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:25 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)) 05:55:25 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)) 05:55:25 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)) 05:55:25 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)) 05:55:25 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)) 05:55:25 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)) 05:55:25 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)) 05:55:25 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)) 05:55:25 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)) 05:55:25 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)) 05:55:25 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)) 05:55:25 INFO - Cleaning up... 05:55:25 INFO - Return code: 0 05:55:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:25 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 0x10be66a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be76420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb220c93390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd05e00>, '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 0x10bb6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd00b20>, '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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 05:55:25 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 05:55:25 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 05:55:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wQ6LWsMRVX/Render', 05:55:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:25 INFO - 'HOME': '/Users/cltbld', 05:55:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:25 INFO - 'LOGNAME': 'cltbld', 05:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:25 INFO - 'MOZ_NO_REMOTE': '1', 05:55:25 INFO - 'NO_EM_RESTART': '1', 05:55:25 INFO - 'PAGER': '/bin/cat', 05:55:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:25 INFO - 'PWD': '/builds/slave/test', 05:55:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:25 INFO - 'SHELL': '/bin/bash', 05:55:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wi5y7Lrncx/Listeners', 05:55:25 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:55:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:25 INFO - 'USER': 'cltbld', 05:55:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:25 INFO - 'XPC_FLAGS': '0x0', 05:55:25 INFO - 'XPC_SERVICE_NAME': '0', 05:55:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:26 INFO - Downloading/unpacking jsonschema 05:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:55:28 INFO - Downloading/unpacking functools32 (from jsonschema) 05:55:28 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 05:55:28 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 05:55:28 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 05:55:28 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 05:55:28 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 05:55:28 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 05:55:28 INFO - Downloading functools32-3.2.3-2.tar.gz 05:55:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:55:28 INFO - warning: no files found matching '*.txt' 05:55:28 INFO - no previously-included directories found matching 'build' 05:55:28 INFO - no previously-included directories found matching 'dist' 05:55:28 INFO - no previously-included directories found matching '.git*' 05:55:28 INFO - Installing collected packages: jsonschema, functools32 05:55:28 INFO - Running setup.py install for functools32 05:55:29 INFO - warning: no files found matching '*.txt' 05:55:29 INFO - no previously-included directories found matching 'build' 05:55:29 INFO - no previously-included directories found matching 'dist' 05:55:29 INFO - no previously-included directories found matching '.git*' 05:55:29 INFO - Successfully installed jsonschema functools32 05:55:29 INFO - Cleaning up... 05:55:29 INFO - Return code: 0 05:55:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:55: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')]} 05:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55: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')]} 05:55:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:29 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 0x10be66a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be76420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb220c93390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd05e00>, '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 0x10bb6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd00b20>, '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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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 05:55:29 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 05:55:29 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 05:55:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wQ6LWsMRVX/Render', 05:55:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:55:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:55:29 INFO - 'HOME': '/Users/cltbld', 05:55:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:55:29 INFO - 'LOGNAME': 'cltbld', 05:55:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:29 INFO - 'MOZ_NO_REMOTE': '1', 05:55:29 INFO - 'NO_EM_RESTART': '1', 05:55:29 INFO - 'PAGER': '/bin/cat', 05:55:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:55:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:29 INFO - 'PWD': '/builds/slave/test', 05:55:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:55:29 INFO - 'SHELL': '/bin/bash', 05:55:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wi5y7Lrncx/Listeners', 05:55:29 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:55:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:55:29 INFO - 'USER': 'cltbld', 05:55:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:55:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:55:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:29 INFO - 'XPC_FLAGS': '0x0', 05:55:29 INFO - 'XPC_SERVICE_NAME': '0', 05:55:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:55:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:29 INFO - Downloading/unpacking flake8 05:55:29 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 05:55:29 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 05:55:29 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 05:55:29 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 05:55:29 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 05:55:29 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 05:55:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:55:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:55:31 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 05:55:31 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 05:55:31 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 05:55:31 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 05:55:31 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 05:55:31 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 05:55:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:55:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:55:32 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 05:55:32 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 05:55:32 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 05:55:32 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 05:55:32 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 05:55:32 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 05:55:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:55:32 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 05:55:32 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 05:55:32 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 05:55:32 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 05:55:32 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 05:55:32 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 05:55:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:55:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:55:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:55:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:55:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:55:33 INFO - no previously-included directories found matching 'docs/_build' 05:55:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:55:33 INFO - Running setup.py install for pep8 05:55:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:55:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:55:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:55:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:55:33 INFO - no previously-included directories found matching 'docs/_build' 05:55:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:55:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:55:33 INFO - Cleaning up... 05:55:33 INFO - Return code: 0 05:55:33 INFO - Running post-action listener: _resource_record_post_action 05:55:33 INFO - Running post-action listener: _start_resource_monitoring 05:55:33 INFO - Starting resource monitoring. 05:55:33 INFO - ##### 05:55:33 INFO - ##### Running install step. 05:55:33 INFO - ##### 05:55:33 INFO - Running pre-action listener: _resource_record_pre_action 05:55:33 INFO - Running main action method: install 05:55:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:33 INFO - Reading from file tmpfile_stdout 05:55:33 INFO - Detecting whether we're running mozinstall >=1.0... 05:55:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:55:33 INFO - Reading from file tmpfile_stdout 05:55:33 INFO - Output received: 05:55:33 INFO - Usage: mozinstall [options] installer 05:55:33 INFO - Options: 05:55:33 INFO - -h, --help show this help message and exit 05:55:33 INFO - -d DEST, --destination=DEST 05:55:33 INFO - Directory to install application into. [default: 05:55:33 INFO - "/builds/slave/test"] 05:55:33 INFO - --app=APP Application being installed. [default: firefox] 05:55:33 INFO - mkdir: /builds/slave/test/build/application 05:55:33 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'] 05:55:33 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 05:56:06 INFO - Reading from file tmpfile_stdout 05:56:06 INFO - Output received: 05:56:06 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:56:06 INFO - Running post-action listener: _resource_record_post_action 05:56:06 INFO - ##### 05:56:06 INFO - ##### Running run-tests step. 05:56:06 INFO - ##### 05:56:06 INFO - Running pre-action listener: _resource_record_pre_action 05:56:06 INFO - Running main action method: run_tests 05:56:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:56:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:56:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:56:06 INFO - 2016-05-09 05:56:06.316 screenresolution[2310:19069] starting screenresolution argv=screenresolution get 05:56:06 INFO - 2016-05-09 05:56:06.319 screenresolution[2310:19069] Display 0: 1600x1200x32@60 05:56:06 INFO - 2016-05-09 05:56:06.324 screenresolution[2311:19072] starting screenresolution argv=screenresolution list 05:56:06 INFO - Available Modes on Display 0 05:56:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:56:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:56:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:56:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:56:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:56:06 INFO - Intel Iris: 05:56:06 INFO - Chipset Model: Intel Iris 05:56:06 INFO - Type: GPU 05:56:06 INFO - Bus: Built-In 05:56:06 INFO - VRAM (Dynamic, Max): 1536 MB 05:56:06 INFO - Vendor: Intel (0x8086) 05:56:06 INFO - Device ID: 0x0a2e 05:56:06 INFO - Revision ID: 0x0009 05:56:06 INFO - Displays: 05:56:06 INFO - Display: 05:56:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:56:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:56:06 INFO - Display Serial Number: 1600x1200 60 05:56:06 INFO - Main Display: Yes 05:56:06 INFO - Mirror: Off 05:56:06 INFO - Online: Yes 05:56:06 INFO - Rotation: Supported 05:56:06 INFO - Return code: 0 05:56:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:56:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:56:06 INFO - Python 2.7.3 05:56:06 INFO - Return code: 0 05:56:06 INFO - grabbing minidump binary from tooltool 05:56: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')]} 05:56:06 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 0x10bd05e00>, '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 0x10bb6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd00b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:56:06 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 05:56:06 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 05:56:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:56:06 INFO - Return code: 0 05:56:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:56:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:56:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:56:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:56:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:56:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:56:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:56:07 INFO - Return code: 0 05:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0117 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wQ6LWsMRVX/Render', 05:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:56:07 INFO - 'HOME': '/Users/cltbld', 05:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:56:07 INFO - 'LOGNAME': 'cltbld', 05:56:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:56:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:56:07 INFO - 'MOZ_NO_REMOTE': '1', 05:56:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:56:07 INFO - 'NO_EM_RESTART': '1', 05:56:07 INFO - 'PAGER': '/bin/cat', 05:56:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:56:07 INFO - 'PWD': '/builds/slave/test', 05:56:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:56:07 INFO - 'SHELL': '/bin/bash', 05:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wi5y7Lrncx/Listeners', 05:56:07 INFO - 'TMPDIR': '/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/', 05:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:56:07 INFO - 'USER': 'cltbld', 05:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:56:07 INFO - 'XPC_FLAGS': '0x0', 05:56:07 INFO - 'XPC_SERVICE_NAME': '0', 05:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:56:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0117', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:56:07 INFO - mozversion application_buildid: 20160509005049 05:56:07 INFO - mozversion application_changeset: 307b5eca8726f6a72a1d69ba76630a6dbf402477 05:56:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:56:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:56:07 INFO - mozversion application_name: Firefox 05:56:07 INFO - mozversion application_remotingname: firefox-dev 05:56:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:56:07 INFO - mozversion application_vendor: Mozilla 05:56:07 INFO - mozversion application_version: 48.0a2 05:56:07 INFO - mozversion platform_buildid: 20160509005049 05:56:07 INFO - mozversion platform_changeset: 307b5eca8726f6a72a1d69ba76630a6dbf402477 05:56:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:56:07 INFO - mozversion platform_version: 48.0a2 05:56:07 INFO - using testdate: 1462798567 05:56:07 INFO - actual date: 1462798567 05:56:07 INFO - starting webserver on 'localhost:51594' 05:56:07 INFO - SUITE-START | Running 2 tests 05:56:07 INFO - TEST-START | dromaeo_css 05:56:07 INFO - operating with platform_type : mac_ 05:56:07 INFO - Initialising browser for dromaeo_css test... 05:56:07 INFO - TEST-INFO | started process 2329 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmp8Z66af/profile http://localhost:51594/getInfo.html) 05:56:10 INFO - PROCESS | 2329 | __metrics Screen width/height:1600/1200 05:56:10 INFO - PROCESS | 2329 | colorDepth:24 05:56:10 INFO - PROCESS | 2329 | Browser inner width/height: 1024/689 05:56:10 INFO - PROCESS | 2329 | __metrics 05:56:10 ERROR - PROCESS | 2329 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:56:11 INFO - TEST-INFO | 2329: exit 0 05:56:11 INFO - Browser initialized. 05:56:11 INFO - Running cycle 1/1 for dromaeo_css test... 05:56:11 INFO - TEST-INFO | started process 2333 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmp8Z66af/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:56:23 INFO - PROCESS | 2333 | RSS: Main: 220770304 05:56:23 INFO - PROCESS | 2333 | 05:57:13 INFO - PROCESS | 2333 | 1462798633278 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] 05:59:24 INFO - PROCESS | 2333 | Cycle 1(1): loaded http://localhost:51594/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51594/tests/dromaeo/cssquery-ext.html) 05:59:25 INFO - PROCESS | 2333 | RSS: Main: 231624704 05:59:25 INFO - PROCESS | 2333 | 06:02:27 INFO - PROCESS | 2333 | Cycle 1(1): loaded http://localhost:51594/tests/dromaeo/cssquery-ext.html (next: http://localhost:51594/tests/dromaeo/cssquery-jquery.html) 06:02:28 INFO - PROCESS | 2333 | RSS: Main: 243527680 06:02:28 INFO - PROCESS | 2333 | 06:05:29 INFO - PROCESS | 2333 | Cycle 1(1): loaded http://localhost:51594/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51594/tests/dromaeo/cssquery-mootools.html) 06:05:30 INFO - PROCESS | 2333 | RSS: Main: 245579776 06:05:30 INFO - PROCESS | 2333 | 06:08:32 INFO - PROCESS | 2333 | Cycle 1(1): loaded http://localhost:51594/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51594/tests/dromaeo/cssquery-prototype.html) 06:08:32 INFO - PROCESS | 2333 | RSS: Main: 247140352 06:08:32 INFO - PROCESS | 2333 | 06:11:35 INFO - PROCESS | 2333 | Cycle 1(1): loaded http://localhost:51594/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51594/tests/dromaeo/cssquery-yui.html) 06:11:36 INFO - PROCESS | 2333 | RSS: Main: 250322944 06:11:36 INFO - PROCESS | 2333 | 06:14:38 INFO - PROCESS | 2333 | Cycle 1(1): loaded http://localhost:51594/tests/dromaeo/cssquery-yui.html (next: http://localhost:51594/tests/dromaeo/cssquery-dojo.html) 06:14:38 INFO - PROCESS | 2333 | RSS: Main: 250953728 06:14:38 INFO - PROCESS | 2333 | 06:14:38 INFO - PROCESS | 2333 | __start_tp_report 06:14:38 INFO - PROCESS | 2333 | _x_x_mozilla_page_load 06:14:38 INFO - PROCESS | 2333 | _x_x_mozilla_page_load_details 06:14:38 INFO - PROCESS | 2333 | |i|pagename|runs| 06:14:38 INFO - PROCESS | 2333 | |0;dojo.html;2937.882484700612;3435.70796482299;3478.3565040467515;3479.704225140864;3513.033915673188;5762.366139724627;5782.190493330937;5825.533957283418;5834.649921004742;5845.824625261238;4746.359241502396;5827.242458480402;5827.35899051104;5833.358330583633;5842.503387212092;4395.450568678915;5294.92057619137;5306.177542480922;5364.704941228231;5396.244525766396;5255.553277971358;5347.839564813043;5360.678359298435;5491.945080549183;5495.780168793243;5390.326209223847;5399.487048730364;5400.162974738922;5403;5410.48600382963;5348.866278343073;5353.116735738598;5363.8122665706705;5400.675959442446;5463.289772329572;5156.2007888776925;5232.39827419844;5241.397239317489;5253.0544501989625;5290.994711004897;4925.310456536088;5610.158476228557;5705.885882282331;5724.4561766632105;5744.195812586241;5101.336826212569;6254.0618907163835;6267.05994100884;6274.498040156776;6297;6199.194104766351;6212;6311.810645680637;6321.557490975633;6323.6521991290465;5697.886042279131;5749.568782341341;5750.1949727038245;5782.306123265235;6012.3085845127935;6352.523560732871;6483.059956306309;6504.252011018699;6510.609363438209;6514.641694706742;6131.376666649982;6190.628562286277;6233.470155036782;6291.276503202099;6320.778772742932;6269.905951410732;6271.4669253114;6295;6307.653079080695;6309.3690630936535;6255.9364907965755;6271.498280137578;6278.6546739929745;6286.4342209201395;6301.7164227609865;6212.9068063979075;6221.3156552780065;6231.563790534619;6260.123582698426;6278.968605157036;5002.649814512949;5040.218766091261;5041.168207245837;5043.949560504421;5070.923936140961;4536.682432229778;4539.72761634303;4543.818247270138;4563.178627846953;4567.429071366079;5682.289713785777;5727.341355744143;5771.278590176228;5826.417358264224;5841.7371218295275;6324.3043265240785;6331.303556608769;6339.524535659844;6343.111964324999;6348.460380867586;5947;5962.970185149046;5964.075568286922;5966.313873904556;5967.850803729942;5346.251524786533;5453.7818487260065;5502.5597952164535;5516.972415137899;5568.387477377485;6539.967300163468;6587.4071333580205;6587.50593705474;6592.670366481752;6593.3736295051895;6200.534959878118;6304.968475157778;6317.494600431863;6322.683865806784;6357.96821015892;6513.088167656437;6568.343165683394;6604.174478190226;6609.272979972296;6612.437942774918;6245.375462453719;6277.121203031488;6279.748810047547;6288.371162883675;6295.276043254901;5129.563987061141;5569.721513924206;6293.465055470337;6299.779507717208;6338.271098823694;5207.973960130175;5583.944160558342;5860.35909945216;6175.475084617858;6218;6195.721192546256;6200.658963757037;6227.782027629011;6231.688415579112;6261.68691565429;4500.4149460569915;5829.446202610745;5998.190244316961;6094.39056094387;6159.938400616115;5604.523615492566;6212;6250.562460627712;6261.78083767066;6263.718132684131;6137.263528376623;6147.508199343953;6165.630062196283;6199.814005579839;6200.503959683305 06:14:38 INFO - PROCESS | 2333 | |1;ext.html;488.77272068488145;531.6331731105535;543.2855794630061;546.7566932714942;597.1550256387211;34771.65228347716;39166.21667566654;39208;39268.41097383534;39296;42139.57860421395;42662.36006459906;42843;42861.142777144436;42911.35632965508;9409.012053734345;9601.759956001086;9633.66282180124;9670.468124253162;9684.661036863712;9600.903990960105;9609.423434593911;9646.75883102921;9671.854922176173;9678.806423871518;8794.680797880304;9206.309526785484;9618.470984095871;9691.951540242324;9693.176080033205;9598.136167744937;9613.990530994215;9626.326157169002;9661.565229564687;9672.371295865782;8155.469894456842;9422.528873556295;9517.524123793852;9551.331406801526;9559.378640388422;39332.606673933464;39452.61915832932;39979.60020399817;40116.59883401158;40347.394789078186;3298.307355455357;3301.7028467437804;3348.4642457206974;3415.8292085395633;3850.2299540092095;28506.572401413996;28653.14040578765;28676.569851452245;28806.135815925554;28979.42041159165;2287.1880482428664;2712.782977361826;2717.728227177286;2721.265258380246;2741.629879966199;380594.1940580614;461201;462192;464506;464730;62168.689156553926;62771.05843412352;63323.05015424772;63353;63551.36448635547;67001;67247.66376168087;73401;75341.49317013739;75678;15891.761623575656;16057.759133613004;18235.088245587667;18356.357527486474;18416.63166736658;13797.724045519235;13866.65333366674;13940.442382304595;13969.860301397059;13986.230757308445;20125;20376.898115509328;20396.184152634025;20404;20426.978651067686;7628.8938103974615;8545.529995850287;8665.523396213268;8665.956670216608;8701.347398945105;804.8631732605471;862.4523427623504;879.8504254276788;928.4336554701626;971.9368241064308;25877.870610647202;26054.218373448828;26172.34569135749;26288.474230515283;26310.86844565765;897.9045564411408;939.4175611121149;987.2940847294219;992.6128809764198;1006.3961623026272;12543.749125017632;13174.73650526984;13364.198148111145;13422.395992180376;13435.395407206699;641279;644485;650240;658778;660230;60893.69553152294;61572;61607.45981350502;61811;61947.69026154841;1248.5318005747845;1299.7335546213003;1512.886533509991;1529.3806008566326;1540.2991638804317;23680.763192367856;24089.51820963536;25581.872090640172;25857.87071064708;25930.740692592834;41488;50702.74648626733;51503.969920602125;51993.48006519886;52546.73726631342;384814;385157;385885;387146;387610;25996;26313;27784.583231251952;27887.16338509848;43229.91925201895;42521.57478425176;43111.35332970071;43535.346969794846;43752.343714843664;44210.67367978966;30635.77456901699;36519;38638.613613863505;38721.61278387293;39258.2148357037;37481.06297342479;37790;38143.23713525658;38244.80877595594;40825;36977.63022369849;37537.624623753414;37681.86954391373;38446.42330365103;38954.2209155821;32678.34643307168;32951;33587.49618755672;33596.83201583976;33903.491447628796;21417.822019789062;22636.886815565817;24819.883105259945;24990.75009249884;25345.49309013846 06:14:38 INFO - PROCESS | 2333 | |2;jquery.html;406.1653302463437;408.20603925365174;409.3246143862628;416.1510518542174;416.3317874810928;19892.70160947585;20588.279410220628;20650.586988260246;20679.483012924684;21925.451863703416;21074.683879741817;21232.25687100952;21448;21508.8924555377;21543.353699389045;5827.271591051129;5843.123531470271;5991.580589358747;6060.515158787309;6061.12113743507;5910.172575839386;5945.316288626798;6006.699665016754;6031.155638210654;6055.9394406056035;5724.542036637059;5980;5982.8504287392725;6000.279966404016;6017.067354560028;5958.37437069108;5989.131575921489;6016.909746353808;6034.9396506035255;6037.335893051761;5211.765470553796;5221.138512145491;5255.474452554714;5256.159014557691;5256.369235691704;19454.70817937718;25941.74058259431;26352.60471092935;26393.20820375392;26709;2007.929722459707;2161.945951351213;2312.3294244669028;2342.695449591542;2619.5546757051347;10006.849897251546;10089.545970431349;10180.931002244732;10193.49032548377;10350.58597656093;1769.5056848485397;1800.4778614201866;1804.5037614656076;1876.390173193704;1937.7577802774654;99174;106921;110383;115575;116243.83756162415;11809.704757380963;11889;11927.463264153133;11945.16383853122;11953.223040502513;12630.368481575884;15331.23343832804;15333.539993800205;15335.003224790576;15361.385544578092;9057.139571740674;9087.36388452802;9092.59083341238;9100.226480749077;9116.453012819253;7766.728164514328;8768.95615521931;9036.45781253127;9043;9073.59168837404;3816.751911125768;3879.437481565157;4706.905861882743;4741.35991641124;4791.137595232822;1692.339987404913;2003.3689387842783;2005.4084045206698;2017.7982201779705;2061.072517367191;803.148662417839;909.2180724576858;935.6444551070549;938.380668758616;961.99038009619;18692.25230990745;18911.62176756457;19279.710804337945;19352.70970935437;19665;800.231777493601;895.5566994337845;922.6032805893531;928.160015186244;930.292977337215;8283.544405057779;8298.875516867252;10233.641822536176;10259.743506412251;10404.635837745642;637651;643237;661110;661407;673633;81236;91312;92013;92632;114533.4273328628;1038.9896101039044;1053.9367637941596;1063.2132222155703;1076.9730756731144;1090.6237348114887;16230.67538649244;17285.56786080358;17344.306227751254;17602.91198543999;17611;35605.465918010734;36137.554497819794;46078;46425;47495.52504474911;64531.70936581336;72723;73221.26778732144;73506;73754;7845.5684937329;7847;8103.554304513308;8238.711645092393;8270.172982701682;8794.736157915273;11910.344931028876;12255.69360765988;12331.876681233072;12366;9368.95315523418;9951.900480995388;9995.450250236307;10095.192384609361;10125.79748405013;9762.267829912644;10024.546440766195;10227.335223210468;10611.469426528489;10970.561177552809;8764.649414023368;9053.502057386908;10203.081722644998;10296.279260451996;10646;8287.12985136552;8302.501849889026;8439.746807595782;8452.281556067803;8511.148885111686;9282.257419406566;10291.639792607222;10337.483125843828;10469.0577847991;10616.84074738895 06:14:38 INFO - PROCESS | 2333 | |3;mootools.html;1572.3710515793682;1589.0862753916501;1830.5606654402948;1909.379451678205;1975.940721778347;7930.960345198266;8781.253593444564;8793.42842715223;9007.864882026775;9013.414128081678;6838.487113466497;8641.52471614061;8774.912250877489;8788.604512796923;8791.648334066645;2463.6797216361892;2479.727230004698;2500.2874180858457;2509.5231905937903;2510.598304271317;2437.524682686877;2478.863662498562;2482.8758562071916;2483.8261321707487;2501.5497210502103;2443.731189569146;2450.803935685141;2482.6276058591266;2493.7132229793533;2506.8495890246554;2154.53677459347;2415.830891837572;2447.7919376852988;2460.2619214235747;2475.7648023437746;2440.4996975619956;2466.519028789387;2483.5653760592004;2492.239866840613;2494.8627825469594;7835.138134805167;8818.559072046372;8827.426217295919;8856.380053396264;8892.955535222347;4454.955450445486;4646.279826626878;5526.889462210734;5608.887822243532;5624.34081945827;3517.9517392541784;3602.35157671619;3617.113807117273;3646.525951626298;3655.287218992271;1053.9051485366276;1070.063694267516;1071.737424331036;1072.5334479501441;1072.6031368393667;11744.236624619372;11946.940265298705;11959.880401195964;11960.521579136825;11967;4880.41435027799;4940.851774446773;5006.07387633289;5028.472010438924;5049.419316778544;10227.539760710786;10241.23190760696;10299.433531155708;10299.639512617023;10302.175825934057;5530.087535556588;5582.609217354828;5597.188407680945;5602.747876345493;5621.80323688669;4492.618127459203;4901.313816065754;4989.301497790312;5028.798848046037;5089.033083714156;3964.980175099106;4045.9190816183514;4055.959440405617;4062.0048088218578;4166.458360413127;1196.4137572589368;1391.7773156294945;1400.621832105326;1401.8948578856423;1417.1638733147493;729.3545212486881;802.6428239433512;910.1115780897582;926.4117285523606;928.3779867488798;5471.917921231185;5519.199716041232;5555.555555555546;5678.971605141948;5851.473367396955;948.4546385828038;989.6338354808767;1001.1540248490014;1009.5507499162949;1029.583018877356;3556.5732112146707;3877.515310586177;3883.1457079442475;3921.0785465415606;3962.9603703963166;9014.647802829497;10570.365778053341;10591.629292974863;10893.128549716004;10964.451777411097;10489.527971241312;10683.03852653265;10725.249232553646;10842.078423334106;10851.837222441669;8417.28453081495;8466.703665371682;8489.363297752694;8509.65961361551;8524.190201930807;4637.8599390173395;5543.667379957215;5554;5752.741126649227;5772.5381969441505;9441.52792360393;9591.753072100582;9833.065858743408;9850;10001.099901008944;9807.166390856857;9842.065003824626;10082.092611664986;10106.595736170764;10144.54349554257;6500.609963402211;6869.862602747817;7029.26192749754;7077.292270772881;7242.6740796663225;8311.625976830936;8373.162683731822;8381.916180838114;8402.03376611673;8410.327173826201;7222.89165662505;7378.96310518444;7394.852102958018;7451.925480745123;7561.962190189014;7228.20489746117;7271.854562908818;7296.343329100407;7350.7427240046345;7361.079865016873;6917.446604271747;6925.099737034355;6945.131858517685;6962.686679099352;6974.128233970754;4662.254039353687;4669.252919532859;4685.226937555265;4773.880652983703;4782.354382158364;4712.198926182488;4756.191447453872;4769.73766442849;4783.784729687242;4863.4650188479945 06:14:38 INFO - PROCESS | 2333 | |4;prototype.html;278.77697841726615;319.06832050412794;325.77684286263894;328.9687479689429;329.09334782673744;5514.531264842493;6720.932790672092;6806.795796126112;6871.3472220139065;6911.861762764748;6894.276101009397;6900;6949.617771022605;6955.617441040741;6983.16202055754;4341.544137865526;4372.27857403528;5137;5147.356580427447;5168.32811734474;4170.958290417103;4926.704397736129;5153.768080436389;5158.92261616076;5173.844784656448;5058.140116180258;5066.088104141253;5073.264376665381;5081.974590127044;5086.008228395464;4586.014006988514;4609.147307748078;4934.703917764928;5078.822241221558;5099.872503187413;3732.104294969215;4175.436316097318;4361.214981303364;4372.147431250907;4375.71557848742;6688.5652432592215;6689.698963546603;6699.665016749192;6705.765298214588;6726.42825359845;5970.253718285215;5981.461668449817;5985.970070149665;6022.78920237794;6035.818925432244;7001.474889383266;7419.666115024783;7431.8142046449275;7443.330100291;7453.962730186315;4508;4731.243001119804;4743.075100355432;4858.927116093261;4879.878003049952;7773.183815699326;7802.33680137189;7883.448158628898;7917.010373703287;8018.83962320762;4772.188727916263;4775.140474714515;4782.928256076161;4788.928166077511;4808.519148085164;4848.563629273312;4857.247126695368;4858.368412106474;4863.781129849165;4864.951350486521;4720.834770783006;5858.414158584193;5866.384029676908;5870.178175055496;5900.852478688067;5985.820425387245;7178.892316615255;7445.36714379273;7452.590107544137;7469.991551140635;1670.8830381873152;1678.3286685325722;1696.5843368374826;1697.142942813871;1699.0970113472742;862.3963225742006;992.2211064314479;1047.8690163729534;1062.2882668612049;1068.2095249515303;6746.426553742888;6826.351496607815;6868.278830722702;6927.376536111774;6948.200956889921;7802.414818888606;7810.882836757453;7821.178776228528;7827.021622297213;7855.450118491651;6895.241523432418;6921.446284297244;6925.27284635106;6939.722411103499;6999.23008469068;3974.741641793332;4050.736702114412;4058.0869304406274;4119.0937993641355;4125.339945608688;8159.714409995621;8175.305099066527;8189.426740128253;8203.179682031749;8210.630521626541;7597.924020759832;7754.767356979187;8001.919980800234;8020.19798020205;8024.839503209903;8158.184181581913;8159.510429374256;8166.591670416456;8179.795505112301;8181.590920454072;7479.887801683088;7753.263439973193;7787.338076263581;7812.609369531387;7820.804479888048;6172.475339596184;7632.465727399029;7650.885236721342;7653.617319133909;7657.387409007155;8159.918400815915;8172.509649421054;8177.591120444073;8183.018037835498;8191.836163276582;4797.616190704805;4799.9760001199775;4814.927776083291;4830.7101573905675;4831.323614694016;7419;7754.495957762728;7760.922390776246;7764;7792.18182090895;7433.888491672521;7469.663865125974;7478.4765066447;7488.176300607038;7515.586642734702;6983;7452.217517160837;7461.738839140604;7462.514936529108;7499.512531685423;5775.884482310415;6362.872742545216;6994.685239164148;7031.1211098612675;7267.963660181666;7304.379127774198;7458.664360103917;7524.05949256343;7527.360174385239;7529.284717951786;5495.450454954633;7146.821329466805;7347.669354878936;7365.926340736524;7367.336939675455 06:14:38 INFO - PROCESS | 2333 | |5;yui.html;663.7544108679789;675.9898601520975;684.7569112964898;748.2554857916374;758.5714071549575;14770.852291477082;14980.400783968642;15091.698166036686;15187.46843860465;15224.391024359025;13854.653633659163;13869.375878085486;13901.930490347535;13903.443862245524;13906.930465347685;724.5073350121914;877.8683197520376;892.1212605583495;921.216965579259;923.7413524213231;886.1006078829993;893.5532233883058;905.483874191711;909.231699214163;909.3043821476575;974.9415035097917;977.3549457358145;977.5503268496465;977.7115750853516;981.151304121933;948.9857652135155;971.917387022104;972.0765272990652;972.1299437003877;972.3436680240809;715.3561794385043;720.8378114924152;723.3742812467231;723.9638018099074;724.8260417499762;14898.627534311621;15035.624109397244;15046.623834404118;15050.623734406727;15120.773188402183;12484.937575312066;12499.500019999188;12597.433115509732;12747.36263186837;12749.936250318782;9929.3545919515;10024.99750024999;10081.294309398345;10084.294099413044;10159.441230732307;3453.188500702333;3453.3438646657305;3460.8442620082155;3519.1026288296575;3577.606463289036;78742.60628696877;80657.79013314746;80816.19183808146;81117.59441202815;81128.59435702843;8857.247133993682;9120.087991200959;9224.7693807654;9351.953240233755;9447.811043779224;20529.384118476468;20633.96830158486;20641.277555285495;20653.896730516553;20658.58682826335;7378.74174403904;7381.224971378036;7384.2246564111065;7387.889181662279;7495.812604684817;3966.6231707987695;4012.25773231953;4015.357542793139;4024.2756303865;4029.6776257899296;3491.633378495272;3542.0613537412605;3561;3561.62602926689;3562.251927095339;568.4230506036376;627.9372062793775;644.5262731892055;652.3802387731579;662.536224642752;471.5473145780021;472.3279590250543;532.6990250508451;573.5239751006654;598.5540772124708;7348.63256837156;7855.842883142305;7866.819977003381;7925.682972681028;7970.800729981797;493.4547325205668;562.5415286541455;577.4485366475006;589.9351071382144;592.8547505861092;3610.350136975317;3677.099110717838;3686.852525898934;3692.6861216796337;3717.7955212463576;83243.75134372989;97539.51230243803;99549.50225248972;99864.00271994519;100336.49831750794;67182.65634687278;67891;68745.65627171833;69608;70234.9464758029;43647;56448.15327770087;64333;64913.35086649167;65656.67171664111;7076.22161562238;8027.4380793346245;8172.550509722022;8330.250277475056;8513.787155320919;44127;45032.324515131644;45679;46029;47458.52541474679;52584;64296.67851660712;65717.34282657113;66316.33683663295;66823.66588167028;9040.909590904006;10827.51276192589;10849;10884.40135792539;11092;9499.620015199314;11735.589254376056;11905.98799102086;11953.701157470785;12153.635390938285;11035.889641103808;11245.100391968459;11379;11379.317240965569;11779.234349767237;11074.280171788809;11231.494582743633;11304.208705390543;11330.546778128783;11355.943220283845;10117.848232276669;10990.505427255634;11299.548018079186;11430.657080287605;11621.88378116208;7743.308911669143;9403.106704863027;9460.337776355589;9556.187724043533;9584.995492870801;9286.860697089684;9313;9475.47884866339;9500.90499095028;9549.236061115233 06:14:38 INFO - PROCESS | 2333 | __end_tp_report 06:14:38 INFO - PROCESS | 2333 | __start_cc_report 06:14:38 INFO - PROCESS | 2333 | _x_x_mozilla_cycle_collect,415 06:14:38 INFO - PROCESS | 2333 | __end_cc_report 06:14:38 INFO - PROCESS | 2333 | __startTimestamp1462799678495__endTimestamp 06:14:38 INFO - PROCESS | 2333 | 06:14:38 INFO - PROCESS | 2333 | ------- Summary: start ------- 06:14:38 INFO - PROCESS | 2333 | Number of tests: 6 06:14:38 INFO - PROCESS | 2333 | 06:14:38 INFO - PROCESS | 2333 | [#0] dojo.html Cycles:165 Average:5799.01 Median:5967.85 stddev:678.93 (11.4%) stddev-sans-first:642.84 06:14:38 INFO - PROCESS | 2333 | Values: 2937.9 3435.7 3478.4 3479.7 3513.0 5762.4 5782.2 5825.5 5834.6 5845.8 4746.4 5827.2 5827.4 5833.4 5842.5 4395.5 5294.9 5306.2 5364.7 5396.2 5255.6 5347.8 5360.7 5491.9 5495.8 5390.3 5399.5 5400.2 5403.0 5410.5 5348.9 5353.1 5363.8 5400.7 5463.3 5156.2 5232.4 5241.4 5253.1 5291.0 4925.3 5610.2 5705.9 5724.5 5744.2 5101.3 6254.1 6267.1 6274.5 6297.0 6199.2 6212.0 6311.8 6321.6 6323.7 5697.9 5749.6 5750.2 5782.3 6012.3 6352.5 6483.1 6504.3 6510.6 6514.6 6131.4 6190.6 6233.5 6291.3 6320.8 6269.9 6271.5 6295.0 6307.7 6309.4 6255.9 6271.5 6278.7 6286.4 6301.7 6212.9 6221.3 6231.6 6260.1 6279.0 5002.6 5040.2 5041.2 5043.9 5070.9 4536.7 4539.7 4543.8 4563.2 4567.4 5682.3 5727.3 5771.3 5826.4 5841.7 6324.3 6331.3 6339.5 6343.1 6348.5 5947.0 5963.0 5964.1 5966.3 5967.9 5346.3 5453.8 5502.6 5517.0 5568.4 6540.0 6587.4 6587.5 6592.7 6593.4 6200.5 6305.0 6317.5 6322.7 6358.0 6513.1 6568.3 6604.2 6609.3 6612.4 6245.4 6277.1 6279.7 6288.4 6295.3 5129.6 5569.7 6293.5 6299.8 6338.3 5208.0 5583.9 5860.4 6175.5 6218.0 6195.7 6200.7 6227.8 6231.7 6261.7 4500.4 5829.4 5998.2 6094.4 6159.9 5604.5 6212.0 6250.6 6261.8 6263.7 6137.3 6147.5 6165.6 6199.8 6200.5 06:14:38 INFO - PROCESS | 2333 | 06:14:38 INFO - PROCESS | 2333 | [#1] ext.html Cycles:180 Average:64034.38 Median:39392.61 stddev:135653.63 (344.4%) stddev-sans-first:135950.28 06:14:38 INFO - PROCESS | 2333 | Values: 488.8 531.6 543.3 546.8 597.2 34771.7 39166.2 39208.0 39268.4 39296.0 42139.6 42662.4 42843.0 42861.1 42911.4 9409.0 9601.8 9633.7 9670.5 9684.7 9600.9 9609.4 9646.8 9671.9 9678.8 8794.7 9206.3 9618.5 9692.0 9693.2 9598.1 9614.0 9626.3 9661.6 9672.4 8155.5 9422.5 9517.5 9551.3 9559.4 39332.6 39452.6 39979.6 40116.6 40347.4 3298.3 3301.7 3348.5 3415.8 3850.2 28506.6 28653.1 28676.6 28806.1 28979.4 2287.2 2712.8 2717.7 2721.3 2741.6 380594.2 461201.0 462192.0 464506.0 464730.0 62168.7 62771.1 63323.1 63353.0 63551.4 67001.0 67247.7 73401.0 75341.5 75678.0 15891.8 16057.8 18235.1 18356.4 18416.6 13797.7 13866.7 13940.4 13969.9 13986.2 20125.0 20376.9 20396.2 20404.0 20427.0 7628.9 8545.5 8665.5 8666.0 8701.3 804.9 862.5 879.9 928.4 971.9 25877.9 26054.2 26172.3 26288.5 26310.9 897.9 939.4 987.3 992.6 1006.4 12543.7 13174.7 13364.2 13422.4 13435.4 641279.0 644485.0 650240.0 658778.0 660230.0 60893.7 61572.0 61607.5 61811.0 61947.7 1248.5 1299.7 1512.9 1529.4 1540.3 23680.8 24089.5 25581.9 25857.9 25930.7 41488.0 50702.7 51504.0 51993.5 52546.7 384814.0 385157.0 385885.0 387146.0 387610.0 25996.0 26313.0 27784.6 27887.2 43229.9 42521.6 43111.4 43535.3 43752.3 44210.7 30635.8 36519.0 38638.6 38721.6 39258.2 37481.1 37790.0 38143.2 38244.8 40825.0 36977.6 37537.6 37681.9 38446.4 38954.2 32678.3 32951.0 33587.5 33596.8 33903.5 21417.8 22636.9 24819.9 24990.8 25345.5 06:14:38 INFO - PROCESS | 2333 | 06:14:38 INFO - PROCESS | 2333 | [#2] jquery.html Cycles:180 Average:34779.72 Median:408.77 stddev:108055.82 (26434.7%) stddev-sans-first:108328.12 06:14:38 INFO - PROCESS | 2333 | Values: 406.2 408.2 409.3 416.2 416.3 19892.7 20588.3 20650.6 20679.5 21925.5 21074.7 21232.3 21448.0 21508.9 21543.4 5827.3 5843.1 5991.6 6060.5 6061.1 5910.2 5945.3 6006.7 6031.2 6055.9 5724.5 5980.0 5982.9 6000.3 6017.1 5958.4 5989.1 6016.9 6034.9 6037.3 5211.8 5221.1 5255.5 5256.2 5256.4 19454.7 25941.7 26352.6 26393.2 26709.0 2007.9 2161.9 2312.3 2342.7 2619.6 10006.8 10089.5 10180.9 10193.5 10350.6 1769.5 1800.5 1804.5 1876.4 1937.8 99174.0 106921.0 110383.0 115575.0 116243.8 11809.7 11889.0 11927.5 11945.2 11953.2 12630.4 15331.2 15333.5 15335.0 15361.4 9057.1 9087.4 9092.6 9100.2 9116.5 7766.7 8769.0 9036.5 9043.0 9073.6 3816.8 3879.4 4706.9 4741.4 4791.1 1692.3 2003.4 2005.4 2017.8 2061.1 803.1 909.2 935.6 938.4 962.0 18692.3 18911.6 19279.7 19352.7 19665.0 800.2 895.6 922.6 928.2 930.3 8283.5 8298.9 10233.6 10259.7 10404.6 637651.0 643237.0 661110.0 661407.0 673633.0 81236.0 91312.0 92013.0 92632.0 114533.4 1039.0 1053.9 1063.2 1077.0 1090.6 16230.7 17285.6 17344.3 17602.9 17611.0 35605.5 36137.6 46078.0 46425.0 47495.5 64531.7 72723.0 73221.3 73506.0 73754.0 7845.6 7847.0 8103.6 8238.7 8270.2 8794.7 11910.3 12255.7 12331.9 12366.0 9369.0 9951.9 9995.5 10095.2 10125.8 9762.3 10024.5 10227.3 10611.5 10970.6 8764.6 9053.5 10203.1 10296.3 10646.0 8287.1 8302.5 8439.7 8452.3 8511.1 9282.3 10291.6 10337.5 10469.1 10616.8 06:14:38 INFO - PROCESS | 2333 | 06:14:38 INFO - PROCESS | 2333 | [#3] mootools.html Cycles:180 Average:5702.83 Median:4771.81 stddev:3169.76 (66.4%) stddev-sans-first:3163.46 06:14:38 INFO - PROCESS | 2333 | Values: 1572.4 1589.1 1830.6 1909.4 1975.9 7931.0 8781.3 8793.4 9007.9 9013.4 6838.5 8641.5 8774.9 8788.6 8791.6 2463.7 2479.7 2500.3 2509.5 2510.6 2437.5 2478.9 2482.9 2483.8 2501.5 2443.7 2450.8 2482.6 2493.7 2506.8 2154.5 2415.8 2447.8 2460.3 2475.8 2440.5 2466.5 2483.6 2492.2 2494.9 7835.1 8818.6 8827.4 8856.4 8893.0 4455.0 4646.3 5526.9 5608.9 5624.3 3518.0 3602.4 3617.1 3646.5 3655.3 1053.9 1070.1 1071.7 1072.5 1072.6 11744.2 11946.9 11959.9 11960.5 11967.0 4880.4 4940.9 5006.1 5028.5 5049.4 10227.5 10241.2 10299.4 10299.6 10302.2 5530.1 5582.6 5597.2 5602.7 5621.8 4492.6 4901.3 4989.3 5028.8 5089.0 3965.0 4045.9 4056.0 4062.0 4166.5 1196.4 1391.8 1400.6 1401.9 1417.2 729.4 802.6 910.1 926.4 928.4 5471.9 5519.2 5555.6 5679.0 5851.5 948.5 989.6 1001.2 1009.6 1029.6 3556.6 3877.5 3883.1 3921.1 3963.0 9014.6 10570.4 10591.6 10893.1 10964.5 10489.5 10683.0 10725.2 10842.1 10851.8 8417.3 8466.7 8489.4 8509.7 8524.2 4637.9 5543.7 5554.0 5752.7 5772.5 9441.5 9591.8 9833.1 9850.0 10001.1 9807.2 9842.1 10082.1 10106.6 10144.5 6500.6 6869.9 7029.3 7077.3 7242.7 8311.6 8373.2 8381.9 8402.0 8410.3 7222.9 7379.0 7394.9 7451.9 7562.0 7228.2 7271.9 7296.3 7350.7 7361.1 6917.4 6925.1 6945.1 6962.7 6974.1 4662.3 4669.3 4685.2 4773.9 4782.4 4712.2 4756.2 4769.7 4783.8 4863.5 06:14:38 INFO - PROCESS | 2333 | 06:14:38 INFO - PROCESS | 2333 | [#4] prototype.html Cycles:180 Average:6031.05 Median:6894.76 stddev:1993.04 (28.9%) stddev-sans-first:1951.31 06:14:38 INFO - PROCESS | 2333 | Values: 278.8 319.1 325.8 329.0 329.1 5514.5 6720.9 6806.8 6871.3 6911.9 6894.3 6900.0 6949.6 6955.6 6983.2 4341.5 4372.3 5137.0 5147.4 5168.3 4171.0 4926.7 5153.8 5158.9 5173.8 5058.1 5066.1 5073.3 5082.0 5086.0 4586.0 4609.1 4934.7 5078.8 5099.9 3732.1 4175.4 4361.2 4372.1 4375.7 6688.6 6689.7 6699.7 6705.8 6726.4 5970.3 5981.5 5986.0 6022.8 6035.8 7001.5 7419.7 7431.8 7443.3 7454.0 4508.0 4731.2 4743.1 4858.9 4879.9 7773.2 7802.3 7883.4 7917.0 8018.8 4772.2 4775.1 4782.9 4788.9 4808.5 4848.6 4857.2 4858.4 4863.8 4865.0 4720.8 5858.4 5866.4 5870.2 5900.9 5985.8 7178.9 7445.4 7452.6 7470.0 1670.9 1678.3 1696.6 1697.1 1699.1 862.4 992.2 1047.9 1062.3 1068.2 6746.4 6826.4 6868.3 6927.4 6948.2 7802.4 7810.9 7821.2 7827.0 7855.5 6895.2 6921.4 6925.3 6939.7 6999.2 3974.7 4050.7 4058.1 4119.1 4125.3 8159.7 8175.3 8189.4 8203.2 8210.6 7597.9 7754.8 8001.9 8020.2 8024.8 8158.2 8159.5 8166.6 8179.8 8181.6 7479.9 7753.3 7787.3 7812.6 7820.8 6172.5 7632.5 7650.9 7653.6 7657.4 8159.9 8172.5 8177.6 8183.0 8191.8 4797.6 4800.0 4814.9 4830.7 4831.3 7419.0 7754.5 7760.9 7764.0 7792.2 7433.9 7469.7 7478.5 7488.2 7515.6 6983.0 7452.2 7461.7 7462.5 7499.5 5775.9 6362.9 6994.7 7031.1 7268.0 7304.4 7458.7 7524.1 7527.4 7529.3 5495.5 7146.8 7347.7 7365.9 7367.3 06:14:38 INFO - PROCESS | 2333 | 06:14:38 INFO - PROCESS | 2333 | [#5] yui.html Cycles:180 Average:17463.85 Median:595.70 stddev:24568.91 (4124.3%) stddev-sans-first:24605.45 06:14:38 INFO - PROCESS | 2333 | Values: 663.8 676.0 684.8 748.3 758.6 14770.9 14980.4 15091.7 15187.5 15224.4 13854.7 13869.4 13901.9 13903.4 13906.9 724.5 877.9 892.1 921.2 923.7 886.1 893.6 905.5 909.2 909.3 974.9 977.4 977.6 977.7 981.2 949.0 971.9 972.1 972.1 972.3 715.4 720.8 723.4 724.0 724.8 14898.6 15035.6 15046.6 15050.6 15120.8 12484.9 12499.5 12597.4 12747.4 12749.9 9929.4 10025.0 10081.3 10084.3 10159.4 3453.2 3453.3 3460.8 3519.1 3577.6 78742.6 80657.8 80816.2 81117.6 81128.6 8857.2 9120.1 9224.8 9352.0 9447.8 20529.4 20634.0 20641.3 20653.9 20658.6 7378.7 7381.2 7384.2 7387.9 7495.8 3966.6 4012.3 4015.4 4024.3 4029.7 3491.6 3542.1 3561.0 3561.6 3562.3 568.4 627.9 644.5 652.4 662.5 471.5 472.3 532.7 573.5 598.6 7348.6 7855.8 7866.8 7925.7 7970.8 493.5 562.5 577.4 589.9 592.9 3610.4 3677.1 3686.9 3692.7 3717.8 83243.8 97539.5 99549.5 99864.0 100336.5 67182.7 67891.0 68745.7 69608.0 70234.9 43647.0 56448.2 64333.0 64913.4 65656.7 7076.2 8027.4 8172.6 8330.3 8513.8 44127.0 45032.3 45679.0 46029.0 47458.5 52584.0 64296.7 65717.3 66316.3 66823.7 9040.9 10827.5 10849.0 10884.4 11092.0 9499.6 11735.6 11906.0 11953.7 12153.6 11035.9 11245.1 11379.0 11379.3 11779.2 11074.3 11231.5 11304.2 11330.5 11355.9 10117.8 10990.5 11299.5 11430.7 11621.9 7743.3 9403.1 9460.3 9556.2 9585.0 9286.9 9313.0 9475.5 9500.9 9549.2 06:14:38 INFO - PROCESS | 2333 | -------- Summary: end -------- 06:14:38 INFO - PROCESS | 2333 | 06:14:38 INFO - TEST-INFO | 2333: exit 0 06:14:39 INFO - TEST-OK | dromaeo_css | took 1111049ms 06:14:39 INFO - TEST-START | kraken 06:14:39 INFO - operating with platform_type : mac_ 06:14:39 INFO - Initialising browser for kraken test... 06:14:39 INFO - TEST-INFO | started process 2338 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpHpAynz/profile http://localhost:51594/getInfo.html) 06:14:41 INFO - PROCESS | 2338 | __metrics Screen width/height:1600/1200 06:14:41 INFO - PROCESS | 2338 | colorDepth:24 06:14:41 INFO - PROCESS | 2338 | Browser inner width/height: 1024/689 06:14:41 INFO - PROCESS | 2338 | __metrics 06:14:41 ERROR - PROCESS | 2338 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 06:14:42 INFO - TEST-INFO | 2338: exit 0 06:14:42 INFO - Browser initialized. 06:14:42 INFO - Running cycle 1/1 for kraken test... 06:14:42 INFO - TEST-INFO | started process 2341 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/tmpHpAynz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:14:54 INFO - PROCESS | 2341 | RSS: Main: 220508160 06:14:54 INFO - PROCESS | 2341 | 06:14:57 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51594/tests/kraken/driver.html?testName=audio-beat-detection) 06:14:58 INFO - PROCESS | 2341 | RSS: Main: 232681472 06:14:58 INFO - PROCESS | 2341 | 06:15:00 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51594/tests/kraken/driver.html?testName=audio-dft) 06:15:01 INFO - PROCESS | 2341 | RSS: Main: 241590272 06:15:01 INFO - PROCESS | 2341 | 06:15:04 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51594/tests/kraken/driver.html?testName=audio-fft) 06:15:05 INFO - PROCESS | 2341 | RSS: Main: 245633024 06:15:05 INFO - PROCESS | 2341 | 06:15:07 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51594/tests/kraken/driver.html?testName=audio-oscillator) 06:15:08 INFO - PROCESS | 2341 | RSS: Main: 247201792 06:15:08 INFO - PROCESS | 2341 | 06:15:10 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51594/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:15:10 INFO - PROCESS | 2341 | RSS: Main: 247623680 06:15:10 INFO - PROCESS | 2341 | 06:15:13 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51594/tests/kraken/driver.html?testName=imaging-darkroom) 06:15:14 INFO - PROCESS | 2341 | RSS: Main: 247300096 06:15:14 INFO - PROCESS | 2341 | 06:15:17 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51594/tests/kraken/driver.html?testName=imaging-desaturate) 06:15:17 INFO - PROCESS | 2341 | RSS: Main: 247414784 06:15:17 INFO - PROCESS | 2341 | 06:15:20 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51594/tests/kraken/driver.html?testName=json-parse-financial) 06:15:21 INFO - PROCESS | 2341 | RSS: Main: 247521280 06:15:21 INFO - PROCESS | 2341 | 06:15:22 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51594/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:15:23 INFO - PROCESS | 2341 | RSS: Main: 247320576 06:15:23 INFO - PROCESS | 2341 | 06:15:25 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51594/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:15:26 INFO - PROCESS | 2341 | RSS: Main: 247304192 06:15:26 INFO - PROCESS | 2341 | 06:15:28 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51594/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:15:28 INFO - PROCESS | 2341 | RSS: Main: 247517184 06:15:28 INFO - PROCESS | 2341 | 06:15:31 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51594/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:15:32 INFO - PROCESS | 2341 | RSS: Main: 247586816 06:15:32 INFO - PROCESS | 2341 | 06:15:35 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51594/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:15:35 INFO - PROCESS | 2341 | RSS: Main: 247562240 06:15:35 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | Cycle 1(1): loaded http://localhost:51594/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51594/tests/kraken/driver.html?testName=ai-astar) 06:15:37 INFO - PROCESS | 2341 | RSS: Main: 249565184 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | __start_tp_report 06:15:37 INFO - PROCESS | 2341 | _x_x_mozilla_page_load 06:15:37 INFO - PROCESS | 2341 | _x_x_mozilla_page_load_details 06:15:37 INFO - PROCESS | 2341 | |i|pagename|runs| 06:15:37 INFO - PROCESS | 2341 | |0;ai-astar;113;120;108;132;179;119;107;117;116;117 06:15:37 INFO - PROCESS | 2341 | |1;audio-beat-detection;155;121;147;188;120;142;141;136;214;156 06:15:37 INFO - PROCESS | 2341 | |2;audio-dft;198;198;241;280;192;196;196;199;191;192 06:15:37 INFO - PROCESS | 2341 | |3;audio-fft;67;80;77;148;57;57;60;63;133;59 06:15:37 INFO - PROCESS | 2341 | |4;audio-oscillator;94;122;168;81;79;80;119;79;80;120 06:15:37 INFO - PROCESS | 2341 | |5;imaging-gaussian-blur;89;127;88;87;90;86;92;86;83;120 06:15:37 INFO - PROCESS | 2341 | |6;imaging-darkroom;95;96;95;128;114;95;98;95;103;98 06:15:37 INFO - PROCESS | 2341 | |7;imaging-desaturate;91;93;99;91;91;90;90;90;90;93 06:15:37 INFO - PROCESS | 2341 | |8;json-parse-financial;62;64;66;66;64;67;66;90;64;59 06:15:37 INFO - PROCESS | 2341 | |9;json-stringify-tinderbox;65;71;67;104;86;81;74;72;72;69 06:15:37 INFO - PROCESS | 2341 | |10;stanford-crypto-aes;80;83;81;83;80;85;78;110;86;77 06:15:37 INFO - PROCESS | 2341 | |11;stanford-crypto-ccm;133;152;147;133;133;129;131;128;150;145 06:15:37 INFO - PROCESS | 2341 | |12;stanford-crypto-pbkdf2;173;224;153;191;169;160;176;222;220;176 06:15:37 INFO - PROCESS | 2341 | |13;stanford-crypto-sha256-iterative;68;83;84;71;70;69;65;65;55;82 06:15:37 INFO - PROCESS | 2341 | __end_tp_report 06:15:37 INFO - PROCESS | 2341 | __start_cc_report 06:15:37 INFO - PROCESS | 2341 | _x_x_mozilla_cycle_collect,1087 06:15:37 INFO - PROCESS | 2341 | __end_cc_report 06:15:37 INFO - PROCESS | 2341 | __startTimestamp1462799737915__endTimestamp 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | ------- Summary: start ------- 06:15:37 INFO - PROCESS | 2341 | Number of tests: 14 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#0] ai-astar Cycles:10 Average:122.80 Median:118.00 stddev:20.93 (17.7%) stddev-sans-first:21.90 06:15:37 INFO - PROCESS | 2341 | Values: 113.0 120.0 108.0 132.0 179.0 119.0 107.0 117.0 116.0 117.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#1] audio-beat-detection Cycles:10 Average:152.00 Median:151.00 stddev:29.16 (19.3%) stddev-sans-first:30.91 06:15:37 INFO - PROCESS | 2341 | Values: 155.0 121.0 147.0 188.0 120.0 142.0 141.0 136.0 214.0 156.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#2] audio-dft Cycles:10 Average:208.30 Median:198.00 stddev:29.14 (14.7%) stddev-sans-first:30.67 06:15:37 INFO - PROCESS | 2341 | Values: 198.0 198.0 241.0 280.0 192.0 196.0 196.0 199.0 191.0 192.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#3] audio-fft Cycles:10 Average:80.10 Median:61.50 stddev:33.00 (53.7%) stddev-sans-first:34.66 06:15:37 INFO - PROCESS | 2341 | Values: 67.0 80.0 77.0 148.0 57.0 57.0 60.0 63.0 133.0 59.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:79.50 stddev:29.63 (37.3%) stddev-sans-first:31.27 06:15:37 INFO - PROCESS | 2341 | Values: 94.0 122.0 168.0 81.0 79.0 80.0 119.0 79.0 80.0 120.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:87.50 stddev:15.41 (17.6%) stddev-sans-first:16.20 06:15:37 INFO - PROCESS | 2341 | Values: 89.0 127.0 88.0 87.0 90.0 86.0 92.0 86.0 83.0 120.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#6] imaging-darkroom Cycles:10 Average:101.70 Median:95.00 stddev:10.98 (11.6%) stddev-sans-first:11.37 06:15:37 INFO - PROCESS | 2341 | Values: 95.0 96.0 95.0 128.0 114.0 95.0 98.0 95.0 103.0 98.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#7] imaging-desaturate Cycles:10 Average:91.80 Median:91.00 stddev:2.78 (3.1%) stddev-sans-first:2.93 06:15:37 INFO - PROCESS | 2341 | Values: 91.0 93.0 99.0 91.0 91.0 90.0 90.0 90.0 90.0 93.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:66.00 stddev:8.48 (12.9%) stddev-sans-first:8.82 06:15:37 INFO - PROCESS | 2341 | Values: 62.0 64.0 66.0 66.0 64.0 67.0 66.0 90.0 64.0 59.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#9] json-stringify-tinderbox Cycles:10 Average:76.10 Median:72.00 stddev:11.65 (16.2%) stddev-sans-first:11.64 06:15:37 INFO - PROCESS | 2341 | Values: 65.0 71.0 67.0 104.0 86.0 81.0 74.0 72.0 72.0 69.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:82.00 stddev:9.48 (11.6%) stddev-sans-first:9.92 06:15:37 INFO - PROCESS | 2341 | Values: 80.0 83.0 81.0 83.0 80.0 85.0 78.0 110.0 86.0 77.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#11] stanford-crypto-ccm Cycles:10 Average:138.10 Median:139.00 stddev:9.28 (6.7%) stddev-sans-first:9.66 06:15:37 INFO - PROCESS | 2341 | Values: 133.0 152.0 147.0 133.0 133.0 129.0 131.0 128.0 150.0 145.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.40 Median:183.50 stddev:26.55 (14.5%) stddev-sans-first:27.71 06:15:37 INFO - PROCESS | 2341 | Values: 173.0 224.0 153.0 191.0 169.0 160.0 176.0 222.0 220.0 176.0 06:15:37 INFO - PROCESS | 2341 | 06:15:37 INFO - PROCESS | 2341 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:70.50 stddev:9.28 (13.2%) stddev-sans-first:9.77 06:15:37 INFO - PROCESS | 2341 | Values: 68.0 83.0 84.0 71.0 70.0 69.0 65.0 65.0 55.0 82.0 06:15:37 INFO - PROCESS | 2341 | -------- Summary: end -------- 06:15:37 INFO - PROCESS | 2341 | 06:15:38 INFO - TEST-INFO | 2341: exit 0 06:15:38 INFO - TEST-OK | kraken | took 59668ms 06:15:38 INFO - SUITE-END | took 1170s 06:15:39 INFO - Completed test suite (00:19:31) 06:15:39 INFO - javascript benchmark 06:15:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2937.882484700612, 3435.70796482299, 3478.3565040467515, 3479.704225140864, 3513.033915673188, 5762.366139724627, 5782.190493330937, 5825.533957283418, 5834.649921004742, 5845.824625261238, 4746.359241502396, 5827.242458480402, 5827.35899051104, 5833.358330583633, 5842.503387212092, 4395.450568678915, 5294.92057619137, 5306.177542480922, 5364.704941228231, 5396.244525766396, 5255.553277971358, 5347.839564813043, 5360.678359298435, 5491.945080549183, 5495.780168793243, 5390.326209223847, 5399.487048730364, 5400.162974738922, 5403.0, 5410.48600382963, 5348.866278343073, 5353.116735738598, 5363.8122665706705, 5400.675959442446, 5463.289772329572, 5156.2007888776925, 5232.39827419844, 5241.397239317489, 5253.0544501989625, 5290.994711004897, 4925.310456536088, 5610.158476228557, 5705.885882282331, 5724.4561766632105, 5744.195812586241, 5101.336826212569, 6254.0618907163835, 6267.05994100884, 6274.498040156776, 6297.0, 6199.194104766351, 6212.0, 6311.810645680637, 6321.557490975633, 6323.6521991290465, 5697.886042279131, 5749.568782341341, 5750.1949727038245, 5782.306123265235, 6012.3085845127935, 6352.523560732871, 6483.059956306309, 6504.252011018699, 6510.609363438209, 6514.641694706742, 6131.376666649982, 6190.628562286277, 6233.470155036782, 6291.276503202099, 6320.778772742932, 6269.905951410732, 6271.4669253114, 6295.0, 6307.653079080695, 6309.3690630936535, 6255.9364907965755, 6271.498280137578, 6278.6546739929745, 6286.4342209201395, 6301.7164227609865, 6212.9068063979075, 6221.3156552780065, 6231.563790534619, 6260.123582698426, 6278.968605157036, 5002.649814512949, 5040.218766091261, 5041.168207245837, 5043.949560504421, 5070.923936140961, 4536.682432229778, 4539.72761634303, 4543.818247270138, 4563.178627846953, 4567.429071366079, 5682.289713785777, 5727.341355744143, 5771.278590176228, 5826.417358264224, 5841.7371218295275, 6324.3043265240785, 6331.303556608769, 6339.524535659844, 6343.111964324999, 6348.460380867586, 5947.0, 5962.970185149046, 5964.075568286922, 5966.313873904556, 5967.850803729942, 5346.251524786533, 5453.7818487260065, 5502.5597952164535, 5516.972415137899, 5568.387477377485, 6539.967300163468, 6587.4071333580205, 6587.50593705474, 6592.670366481752, 6593.3736295051895, 6200.534959878118, 6304.968475157778, 6317.494600431863, 6322.683865806784, 6357.96821015892, 6513.088167656437, 6568.343165683394, 6604.174478190226, 6609.272979972296, 6612.437942774918, 6245.375462453719, 6277.121203031488, 6279.748810047547, 6288.371162883675, 6295.276043254901, 5129.563987061141, 5569.721513924206, 6293.465055470337, 6299.779507717208, 6338.271098823694, 5207.973960130175, 5583.944160558342, 5860.35909945216, 6175.475084617858, 6218.0, 6195.721192546256, 6200.658963757037, 6227.782027629011, 6231.688415579112, 6261.68691565429, 4500.4149460569915, 5829.446202610745, 5998.190244316961, 6094.39056094387, 6159.938400616115, 5604.523615492566, 6212.0, 6250.562460627712, 6261.78083767066, 6263.718132684131, 6137.263528376623, 6147.508199343953, 6165.630062196283, 6199.814005579839, 6200.503959683305], "value": 5757.021838309999, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.77272068488145, 531.6331731105535, 543.2855794630061, 546.7566932714942, 597.1550256387211, 34771.65228347716, 39166.21667566654, 39208.0, 39268.41097383534, 39296.0, 42139.57860421395, 42662.36006459906, 42843.0, 42861.142777144436, 42911.35632965508, 9409.012053734345, 9601.759956001086, 9633.66282180124, 9670.468124253162, 9684.661036863712, 9600.903990960105, 9609.423434593911, 9646.75883102921, 9671.854922176173, 9678.806423871518, 8794.680797880304, 9206.309526785484, 9618.470984095871, 9691.951540242324, 9693.176080033205, 9598.136167744937, 9613.990530994215, 9626.326157169002, 9661.565229564687, 9672.371295865782, 8155.469894456842, 9422.528873556295, 9517.524123793852, 9551.331406801526, 9559.378640388422, 39332.606673933464, 39452.61915832932, 39979.60020399817, 40116.59883401158, 40347.394789078186, 3298.307355455357, 3301.7028467437804, 3348.4642457206974, 3415.8292085395633, 3850.2299540092095, 28506.572401413996, 28653.14040578765, 28676.569851452245, 28806.135815925554, 28979.42041159165, 2287.1880482428664, 2712.782977361826, 2717.728227177286, 2721.265258380246, 2741.629879966199, 380594.1940580614, 461201.0, 462192.0, 464506.0, 464730.0, 62168.689156553926, 62771.05843412352, 63323.05015424772, 63353.0, 63551.36448635547, 67001.0, 67247.66376168087, 73401.0, 75341.49317013739, 75678.0, 15891.761623575656, 16057.759133613004, 18235.088245587667, 18356.357527486474, 18416.63166736658, 13797.724045519235, 13866.65333366674, 13940.442382304595, 13969.860301397059, 13986.230757308445, 20125.0, 20376.898115509328, 20396.184152634025, 20404.0, 20426.978651067686, 7628.8938103974615, 8545.529995850287, 8665.523396213268, 8665.956670216608, 8701.347398945105, 804.8631732605471, 862.4523427623504, 879.8504254276788, 928.4336554701626, 971.9368241064308, 25877.870610647202, 26054.218373448828, 26172.34569135749, 26288.474230515283, 26310.86844565765, 897.9045564411408, 939.4175611121149, 987.2940847294219, 992.6128809764198, 1006.3961623026272, 12543.749125017632, 13174.73650526984, 13364.198148111145, 13422.395992180376, 13435.395407206699, 641279.0, 644485.0, 650240.0, 658778.0, 660230.0, 60893.69553152294, 61572.0, 61607.45981350502, 61811.0, 61947.69026154841, 1248.5318005747845, 1299.7335546213003, 1512.886533509991, 1529.3806008566326, 1540.2991638804317, 23680.763192367856, 24089.51820963536, 25581.872090640172, 25857.87071064708, 25930.740692592834, 41488.0, 50702.74648626733, 51503.969920602125, 51993.48006519886, 52546.73726631342, 384814.0, 385157.0, 385885.0, 387146.0, 387610.0, 25996.0, 26313.0, 27784.583231251952, 27887.16338509848, 43229.91925201895, 42521.57478425176, 43111.35332970071, 43535.346969794846, 43752.343714843664, 44210.67367978966, 30635.77456901699, 36519.0, 38638.613613863505, 38721.61278387293, 39258.2148357037, 37481.06297342479, 37790.0, 38143.23713525658, 38244.80877595594, 40825.0, 36977.63022369849, 37537.624623753414, 37681.86954391373, 38446.42330365103, 38954.2209155821, 32678.34643307168, 32951.0, 33587.49618755672, 33596.83201583976, 33903.491447628796, 21417.822019789062, 22636.886815565817, 24819.883105259945, 24990.75009249884, 25345.49309013846], "value": 19494.38297909225, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.1653302463437, 408.20603925365174, 409.3246143862628, 416.1510518542174, 416.3317874810928, 19892.70160947585, 20588.279410220628, 20650.586988260246, 20679.483012924684, 21925.451863703416, 21074.683879741817, 21232.25687100952, 21448.0, 21508.8924555377, 21543.353699389045, 5827.271591051129, 5843.123531470271, 5991.580589358747, 6060.515158787309, 6061.12113743507, 5910.172575839386, 5945.316288626798, 6006.699665016754, 6031.155638210654, 6055.9394406056035, 5724.542036637059, 5980.0, 5982.8504287392725, 6000.279966404016, 6017.067354560028, 5958.37437069108, 5989.131575921489, 6016.909746353808, 6034.9396506035255, 6037.335893051761, 5211.765470553796, 5221.138512145491, 5255.474452554714, 5256.159014557691, 5256.369235691704, 19454.70817937718, 25941.74058259431, 26352.60471092935, 26393.20820375392, 26709.0, 2007.929722459707, 2161.945951351213, 2312.3294244669028, 2342.695449591542, 2619.5546757051347, 10006.849897251546, 10089.545970431349, 10180.931002244732, 10193.49032548377, 10350.58597656093, 1769.5056848485397, 1800.4778614201866, 1804.5037614656076, 1876.390173193704, 1937.7577802774654, 99174.0, 106921.0, 110383.0, 115575.0, 116243.83756162415, 11809.704757380963, 11889.0, 11927.463264153133, 11945.16383853122, 11953.223040502513, 12630.368481575884, 15331.23343832804, 15333.539993800205, 15335.003224790576, 15361.385544578092, 9057.139571740674, 9087.36388452802, 9092.59083341238, 9100.226480749077, 9116.453012819253, 7766.728164514328, 8768.95615521931, 9036.45781253127, 9043.0, 9073.59168837404, 3816.751911125768, 3879.437481565157, 4706.905861882743, 4741.35991641124, 4791.137595232822, 1692.339987404913, 2003.3689387842783, 2005.4084045206698, 2017.7982201779705, 2061.072517367191, 803.148662417839, 909.2180724576858, 935.6444551070549, 938.380668758616, 961.99038009619, 18692.25230990745, 18911.62176756457, 19279.710804337945, 19352.70970935437, 19665.0, 800.231777493601, 895.5566994337845, 922.6032805893531, 928.160015186244, 930.292977337215, 8283.544405057779, 8298.875516867252, 10233.641822536176, 10259.743506412251, 10404.635837745642, 637651.0, 643237.0, 661110.0, 661407.0, 673633.0, 81236.0, 91312.0, 92013.0, 92632.0, 114533.4273328628, 1038.9896101039044, 1053.9367637941596, 1063.2132222155703, 1076.9730756731144, 1090.6237348114887, 16230.67538649244, 17285.56786080358, 17344.306227751254, 17602.91198543999, 17611.0, 35605.465918010734, 36137.554497819794, 46078.0, 46425.0, 47495.52504474911, 64531.70936581336, 72723.0, 73221.26778732144, 73506.0, 73754.0, 7845.5684937329, 7847.0, 8103.554304513308, 8238.711645092393, 8270.172982701682, 8794.736157915273, 11910.344931028876, 12255.69360765988, 12331.876681233072, 12366.0, 9368.95315523418, 9951.900480995388, 9995.450250236307, 10095.192384609361, 10125.79748405013, 9762.267829912644, 10024.546440766195, 10227.335223210468, 10611.469426528489, 10970.561177552809, 8764.649414023368, 9053.502057386908, 10203.081722644998, 10296.279260451996, 10646.0, 8287.12985136552, 8302.501849889026, 8439.746807595782, 8452.281556067803, 8511.148885111686, 9282.257419406566, 10291.639792607222, 10337.483125843828, 10469.0577847991, 10616.84074738895], "value": 9287.386275883851, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1572.3710515793682, 1589.0862753916501, 1830.5606654402948, 1909.379451678205, 1975.940721778347, 7930.960345198266, 8781.253593444564, 8793.42842715223, 9007.864882026775, 9013.414128081678, 6838.487113466497, 8641.52471614061, 8774.912250877489, 8788.604512796923, 8791.648334066645, 2463.6797216361892, 2479.727230004698, 2500.2874180858457, 2509.5231905937903, 2510.598304271317, 2437.524682686877, 2478.863662498562, 2482.8758562071916, 2483.8261321707487, 2501.5497210502103, 2443.731189569146, 2450.803935685141, 2482.6276058591266, 2493.7132229793533, 2506.8495890246554, 2154.53677459347, 2415.830891837572, 2447.7919376852988, 2460.2619214235747, 2475.7648023437746, 2440.4996975619956, 2466.519028789387, 2483.5653760592004, 2492.239866840613, 2494.8627825469594, 7835.138134805167, 8818.559072046372, 8827.426217295919, 8856.380053396264, 8892.955535222347, 4454.955450445486, 4646.279826626878, 5526.889462210734, 5608.887822243532, 5624.34081945827, 3517.9517392541784, 3602.35157671619, 3617.113807117273, 3646.525951626298, 3655.287218992271, 1053.9051485366276, 1070.063694267516, 1071.737424331036, 1072.5334479501441, 1072.6031368393667, 11744.236624619372, 11946.940265298705, 11959.880401195964, 11960.521579136825, 11967.0, 4880.41435027799, 4940.851774446773, 5006.07387633289, 5028.472010438924, 5049.419316778544, 10227.539760710786, 10241.23190760696, 10299.433531155708, 10299.639512617023, 10302.175825934057, 5530.087535556588, 5582.609217354828, 5597.188407680945, 5602.747876345493, 5621.80323688669, 4492.618127459203, 4901.313816065754, 4989.301497790312, 5028.798848046037, 5089.033083714156, 3964.980175099106, 4045.9190816183514, 4055.959440405617, 4062.0048088218578, 4166.458360413127, 1196.4137572589368, 1391.7773156294945, 1400.621832105326, 1401.8948578856423, 1417.1638733147493, 729.3545212486881, 802.6428239433512, 910.1115780897582, 926.4117285523606, 928.3779867488798, 5471.917921231185, 5519.199716041232, 5555.555555555546, 5678.971605141948, 5851.473367396955, 948.4546385828038, 989.6338354808767, 1001.1540248490014, 1009.5507499162949, 1029.583018877356, 3556.5732112146707, 3877.515310586177, 3883.1457079442475, 3921.0785465415606, 3962.9603703963166, 9014.647802829497, 10570.365778053341, 10591.629292974863, 10893.128549716004, 10964.451777411097, 10489.527971241312, 10683.03852653265, 10725.249232553646, 10842.078423334106, 10851.837222441669, 8417.28453081495, 8466.703665371682, 8489.363297752694, 8509.65961361551, 8524.190201930807, 4637.8599390173395, 5543.667379957215, 5554.0, 5752.741126649227, 5772.5381969441505, 9441.52792360393, 9591.753072100582, 9833.065858743408, 9850.0, 10001.099901008944, 9807.166390856857, 9842.065003824626, 10082.092611664986, 10106.595736170764, 10144.54349554257, 6500.609963402211, 6869.862602747817, 7029.26192749754, 7077.292270772881, 7242.6740796663225, 8311.625976830936, 8373.162683731822, 8381.916180838114, 8402.03376611673, 8410.327173826201, 7222.89165662505, 7378.96310518444, 7394.852102958018, 7451.925480745123, 7561.962190189014, 7228.20489746117, 7271.854562908818, 7296.343329100407, 7350.7427240046345, 7361.079865016873, 6917.446604271747, 6925.099737034355, 6945.131858517685, 6962.686679099352, 6974.128233970754, 4662.254039353687, 4669.252919532859, 4685.226937555265, 4773.880652983703, 4782.354382158364, 4712.198926182488, 4756.191447453872, 4769.73766442849, 4783.784729687242, 4863.4650188479945], "value": 4620.714205527062, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.77697841726615, 319.06832050412794, 325.77684286263894, 328.9687479689429, 329.09334782673744, 5514.531264842493, 6720.932790672092, 6806.795796126112, 6871.3472220139065, 6911.861762764748, 6894.276101009397, 6900.0, 6949.617771022605, 6955.617441040741, 6983.16202055754, 4341.544137865526, 4372.27857403528, 5137.0, 5147.356580427447, 5168.32811734474, 4170.958290417103, 4926.704397736129, 5153.768080436389, 5158.92261616076, 5173.844784656448, 5058.140116180258, 5066.088104141253, 5073.264376665381, 5081.974590127044, 5086.008228395464, 4586.014006988514, 4609.147307748078, 4934.703917764928, 5078.822241221558, 5099.872503187413, 3732.104294969215, 4175.436316097318, 4361.214981303364, 4372.147431250907, 4375.71557848742, 6688.5652432592215, 6689.698963546603, 6699.665016749192, 6705.765298214588, 6726.42825359845, 5970.253718285215, 5981.461668449817, 5985.970070149665, 6022.78920237794, 6035.818925432244, 7001.474889383266, 7419.666115024783, 7431.8142046449275, 7443.330100291, 7453.962730186315, 4508.0, 4731.243001119804, 4743.075100355432, 4858.927116093261, 4879.878003049952, 7773.183815699326, 7802.33680137189, 7883.448158628898, 7917.010373703287, 8018.83962320762, 4772.188727916263, 4775.140474714515, 4782.928256076161, 4788.928166077511, 4808.519148085164, 4848.563629273312, 4857.247126695368, 4858.368412106474, 4863.781129849165, 4864.951350486521, 4720.834770783006, 5858.414158584193, 5866.384029676908, 5870.178175055496, 5900.852478688067, 5985.820425387245, 7178.892316615255, 7445.36714379273, 7452.590107544137, 7469.991551140635, 1670.8830381873152, 1678.3286685325722, 1696.5843368374826, 1697.142942813871, 1699.0970113472742, 862.3963225742006, 992.2211064314479, 1047.8690163729534, 1062.2882668612049, 1068.2095249515303, 6746.426553742888, 6826.351496607815, 6868.278830722702, 6927.376536111774, 6948.200956889921, 7802.414818888606, 7810.882836757453, 7821.178776228528, 7827.021622297213, 7855.450118491651, 6895.241523432418, 6921.446284297244, 6925.27284635106, 6939.722411103499, 6999.23008469068, 3974.741641793332, 4050.736702114412, 4058.0869304406274, 4119.0937993641355, 4125.339945608688, 8159.714409995621, 8175.305099066527, 8189.426740128253, 8203.179682031749, 8210.630521626541, 7597.924020759832, 7754.767356979187, 8001.919980800234, 8020.19798020205, 8024.839503209903, 8158.184181581913, 8159.510429374256, 8166.591670416456, 8179.795505112301, 8181.590920454072, 7479.887801683088, 7753.263439973193, 7787.338076263581, 7812.609369531387, 7820.804479888048, 6172.475339596184, 7632.465727399029, 7650.885236721342, 7653.617319133909, 7657.387409007155, 8159.918400815915, 8172.509649421054, 8177.591120444073, 8183.018037835498, 8191.836163276582, 4797.616190704805, 4799.9760001199775, 4814.927776083291, 4830.7101573905675, 4831.323614694016, 7419.0, 7754.495957762728, 7760.922390776246, 7764.0, 7792.18182090895, 7433.888491672521, 7469.663865125974, 7478.4765066447, 7488.176300607038, 7515.586642734702, 6983.0, 7452.217517160837, 7461.738839140604, 7462.514936529108, 7499.512531685423, 5775.884482310415, 6362.872742545216, 6994.685239164148, 7031.1211098612675, 7267.963660181666, 7304.379127774198, 7458.664360103917, 7524.05949256343, 7527.360174385239, 7529.284717951786, 5495.450454954633, 7146.821329466805, 7347.669354878936, 7365.926340736524, 7367.336939675455], "value": 5347.858564237663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.7544108679789, 675.9898601520975, 684.7569112964898, 748.2554857916374, 758.5714071549575, 14770.852291477082, 14980.400783968642, 15091.698166036686, 15187.46843860465, 15224.391024359025, 13854.653633659163, 13869.375878085486, 13901.930490347535, 13903.443862245524, 13906.930465347685, 724.5073350121914, 877.8683197520376, 892.1212605583495, 921.216965579259, 923.7413524213231, 886.1006078829993, 893.5532233883058, 905.483874191711, 909.231699214163, 909.3043821476575, 974.9415035097917, 977.3549457358145, 977.5503268496465, 977.7115750853516, 981.151304121933, 948.9857652135155, 971.917387022104, 972.0765272990652, 972.1299437003877, 972.3436680240809, 715.3561794385043, 720.8378114924152, 723.3742812467231, 723.9638018099074, 724.8260417499762, 14898.627534311621, 15035.624109397244, 15046.623834404118, 15050.623734406727, 15120.773188402183, 12484.937575312066, 12499.500019999188, 12597.433115509732, 12747.36263186837, 12749.936250318782, 9929.3545919515, 10024.99750024999, 10081.294309398345, 10084.294099413044, 10159.441230732307, 3453.188500702333, 3453.3438646657305, 3460.8442620082155, 3519.1026288296575, 3577.606463289036, 78742.60628696877, 80657.79013314746, 80816.19183808146, 81117.59441202815, 81128.59435702843, 8857.247133993682, 9120.087991200959, 9224.7693807654, 9351.953240233755, 9447.811043779224, 20529.384118476468, 20633.96830158486, 20641.277555285495, 20653.896730516553, 20658.58682826335, 7378.74174403904, 7381.224971378036, 7384.2246564111065, 7387.889181662279, 7495.812604684817, 3966.6231707987695, 4012.25773231953, 4015.357542793139, 4024.2756303865, 4029.6776257899296, 3491.633378495272, 3542.0613537412605, 3561.0, 3561.62602926689, 3562.251927095339, 568.4230506036376, 627.9372062793775, 644.5262731892055, 652.3802387731579, 662.536224642752, 471.5473145780021, 472.3279590250543, 532.6990250508451, 573.5239751006654, 598.5540772124708, 7348.63256837156, 7855.842883142305, 7866.819977003381, 7925.682972681028, 7970.800729981797, 493.4547325205668, 562.5415286541455, 577.4485366475006, 589.9351071382144, 592.8547505861092, 3610.350136975317, 3677.099110717838, 3686.852525898934, 3692.6861216796337, 3717.7955212463576, 83243.75134372989, 97539.51230243803, 99549.50225248972, 99864.00271994519, 100336.49831750794, 67182.65634687278, 67891.0, 68745.65627171833, 69608.0, 70234.9464758029, 43647.0, 56448.15327770087, 64333.0, 64913.35086649167, 65656.67171664111, 7076.22161562238, 8027.4380793346245, 8172.550509722022, 8330.250277475056, 8513.787155320919, 44127.0, 45032.324515131644, 45679.0, 46029.0, 47458.52541474679, 52584.0, 64296.67851660712, 65717.34282657113, 66316.33683663295, 66823.66588167028, 9040.909590904006, 10827.51276192589, 10849.0, 10884.40135792539, 11092.0, 9499.620015199314, 11735.589254376056, 11905.98799102086, 11953.701157470785, 12153.635390938285, 11035.889641103808, 11245.100391968459, 11379.0, 11379.317240965569, 11779.234349767237, 11074.280171788809, 11231.494582743633, 11304.208705390543, 11330.546778128783, 11355.943220283845, 10117.848232276669, 10990.505427255634, 11299.548018079186, 11430.657080287605, 11621.88378116208, 7743.308911669143, 9403.106704863027, 9460.337776355589, 9556.187724043533, 9584.995492870801, 9286.860697089684, 9313.0, 9475.47884866339, 9500.90499095028, 9549.236061115233], "value": 6720.617765627385, "unit": "score"}], "name": "dromaeo_css", "value": 7465.445395000307, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 120.0, 108.0, 132.0, 179.0, 119.0, 107.0, 117.0, 116.0, 117.0], "value": 122.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 147.0, 188.0, 120.0, 142.0, 141.0, 136.0, 214.0, 156.0], "value": 152.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 241.0, 280.0, 192.0, 196.0, 196.0, 199.0, 191.0, 192.0], "value": 208.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 80.0, 77.0, 148.0, 57.0, 57.0, 60.0, 63.0, 133.0, 59.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 122.0, 168.0, 81.0, 79.0, 80.0, 119.0, 79.0, 80.0, 120.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 127.0, 88.0, 87.0, 90.0, 86.0, 92.0, 86.0, 83.0, 120.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 128.0, 114.0, 95.0, 98.0, 95.0, 103.0, 98.0], "value": 101.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 99.0, 91.0, 91.0, 90.0, 90.0, 90.0, 90.0, 93.0], "value": 91.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 66.0, 66.0, 64.0, 67.0, 66.0, 90.0, 64.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 67.0, 104.0, 86.0, 81.0, 74.0, 72.0, 72.0, 69.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 81.0, 83.0, 80.0, 85.0, 78.0, 110.0, 86.0, 77.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 152.0, 147.0, 133.0, 133.0, 129.0, 131.0, 128.0, 150.0, 145.0], "value": 138.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 224.0, 153.0, 191.0, 169.0, 160.0, 176.0, 222.0, 220.0, 176.0], "value": 186.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 83.0, 84.0, 71.0, 70.0, 69.0, 65.0, 65.0, 55.0, 82.0], "value": 71.2, "unit": "score"}], "name": "kraken", "value": 1576.6, "alertThreshold": 2.0}]} 06:15:39 INFO - Return code: 0 06:15:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:15:39 ERROR - # TBPL SUCCESS # 06:15:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:15:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:15:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:15:39 INFO - 2016-05-09 06:15:39.227 screenresolution[2346:21610] starting screenresolution argv=screenresolution get 06:15:39 INFO - 2016-05-09 06:15:39.230 screenresolution[2346:21610] Display 0: 1600x1200x32@60 06:15:39 INFO - 2016-05-09 06:15:39.236 screenresolution[2347:21613] starting screenresolution argv=screenresolution list 06:15:39 INFO - Available Modes on Display 0 06:15:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:15:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:15:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:15:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:15:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:15:39 INFO - Intel Iris: 06:15:39 INFO - Chipset Model: Intel Iris 06:15:39 INFO - Type: GPU 06:15:39 INFO - Bus: Built-In 06:15:39 INFO - VRAM (Dynamic, Max): 1536 MB 06:15:39 INFO - Vendor: Intel (0x8086) 06:15:39 INFO - Device ID: 0x0a2e 06:15:39 INFO - Revision ID: 0x0009 06:15:39 INFO - Displays: 06:15:39 INFO - Display: 06:15:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:15:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:15:39 INFO - Display Serial Number: 1600x1200 60 06:15:39 INFO - Main Display: Yes 06:15:39 INFO - Mirror: Off 06:15:39 INFO - Online: Yes 06:15:39 INFO - Rotation: Supported 06:15:39 INFO - Return code: 0 06:15:39 INFO - Running post-action listener: _resource_record_post_action 06:15:39 INFO - Running post-run listener: _resource_record_post_run 06:15:40 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 909312; Write bytes: 653469184; Read time: 13; Write time: 2024 06:15:40 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 346591744; Write bytes: 345978368; Read time: 27458; Write time: 635 06:15:40 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 901120; Write bytes: 297537536; Read time: 12; Write time: 1355 06:15:40 INFO - Running post-run listener: _upload_blobber_files 06:15:40 INFO - Blob upload gear active. 06:15:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:15:40 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 06:15:40 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'] 06:15:40 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 06:15:40 INFO - (blobuploader) - INFO - Open directory for files ... 06:15:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:15:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:15:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:41 INFO - (blobuploader) - INFO - Done attempting. 06:15:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:15:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:15:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:43 INFO - (blobuploader) - INFO - Done attempting. 06:15:43 INFO - (blobuploader) - INFO - Iteration through files over. 06:15:43 INFO - Return code: 0 06:15:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:15:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:15:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdd3c688fd914cef0caee540e4c842e6fc138105ebac08f6daa9a7f9916928e5f0c8916c8e29006e3b02509827983be1dce872aebdfcaec03d91fab65e197d59", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b862ad0aa42d612d6024a494540abf2e9cc237c8ce83027a2382a9d123d14caba8ce99b71da8b3e1bc32e108cea5ab7519961e44063d7431e43f6049cf065c03"} 06:15:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:15:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:15:43 INFO - Contents: 06:15:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdd3c688fd914cef0caee540e4c842e6fc138105ebac08f6daa9a7f9916928e5f0c8916c8e29006e3b02509827983be1dce872aebdfcaec03d91fab65e197d59", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b862ad0aa42d612d6024a494540abf2e9cc237c8ce83027a2382a9d123d14caba8ce99b71da8b3e1bc32e108cea5ab7519961e44063d7431e43f6049cf065c03"} 06:15:43 INFO - Running post-run listener: copy_logs_to_upload_dir 06:15:43 INFO - Copying logs to upload dir... 06:15:43 INFO - mkdir: /builds/slave/test/build/upload/logs 06:15:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.312957 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-05-09 06:15:43.080748) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:43.084548) ========= 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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdd3c688fd914cef0caee540e4c842e6fc138105ebac08f6daa9a7f9916928e5f0c8916c8e29006e3b02509827983be1dce872aebdfcaec03d91fab65e197d59", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b862ad0aa42d612d6024a494540abf2e9cc237c8ce83027a2382a9d123d14caba8ce99b71da8b3e1bc32e108cea5ab7519961e44063d7431e43f6049cf065c03"} build_url:https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011400 build_url: 'https://queue.taskcluster.net/v1/task/J_hY6fdxQMm_M9plHZylsA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cdd3c688fd914cef0caee540e4c842e6fc138105ebac08f6daa9a7f9916928e5f0c8916c8e29006e3b02509827983be1dce872aebdfcaec03d91fab65e197d59", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b862ad0aa42d612d6024a494540abf2e9cc237c8ce83027a2382a9d123d14caba8ce99b71da8b3e1bc32e108cea5ab7519961e44063d7431e43f6049cf065c03"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:43.112008) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:43.112311) ========= 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.wQ6LWsMRVX/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.wi5y7Lrncx/Listeners TMPDIR=/var/folders/2d/l2dyrh5107v305_74qp5tzp400000w/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.004490 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:15:43.159913) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 06:15:43.160223) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 06:15:43.160541) ========= ========= Total master_lag: 0.16 =========