builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0009 starttime: 1466725572.0 results: success (0) buildid: 20160623153657 builduid: 46d2a3a5549d444bbfa748bec8b2d166 revision: b449af6b87414aa2ed035aaaa5043642e9a4ac06 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:12.003132) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:12.004684) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:12.005013) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:12.080541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:12.081132) ========= 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.8udhIDrLih/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.EnN4EzRihj/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-23 16:46:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.34M=0.001s 2016-06-23 16:46:12 (8.34 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.584436 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:12.700888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:12.701239) ========= 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.8udhIDrLih/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.EnN4EzRihj/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.020226 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:12.753430) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-23 16:46:12.754281) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b449af6b87414aa2ed035aaaa5043642e9a4ac06 --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-beta --rev b449af6b87414aa2ed035aaaa5043642e9a4ac06 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8udhIDrLih/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.EnN4EzRihj/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-23 16:46:12,829 truncating revision to first 12 chars 2016-06-23 16:46:12,830 Setting DEBUG logging. 2016-06-23 16:46:12,830 attempt 1/10 2016-06-23 16:46:12,830 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b449af6b8741?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-23 16:46:15,380 unpacking tar archive at: mozilla-beta-b449af6b8741/testing/mozharness/ program finished with exit code 0 elapsedTime=2.802777 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-23 16:46:15.582364) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:15.582736) ========= script_repo_revision: b449af6b87414aa2ed035aaaa5043642e9a4ac06 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:15.583223) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:15.583548) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-23 16:46:15.602685) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-06-23 16:46:15.603196) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8udhIDrLih/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.EnN4EzRihj/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 16:46:15 INFO - MultiFileLogger online at 20160623 16:46:15 in /builds/slave/test 16:46:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:46:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:46:15 INFO - {'append_to_log': False, 16:46:15 INFO - 'base_work_dir': '/builds/slave/test', 16:46:15 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:46:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:46:15 INFO - 'branch': 'Mozilla-Beta', 16:46:15 INFO - 'buildbot_json_path': 'buildprops.json', 16:46:15 INFO - 'config_files': ('talos/mac_config.py',), 16:46:15 INFO - 'default_actions': ('clobber', 16:46:15 INFO - 'read-buildbot-config', 16:46:15 INFO - 'download-and-extract', 16:46:15 INFO - 'populate-webroot', 16:46:15 INFO - 'create-virtualenv', 16:46:15 INFO - 'install', 16:46:15 INFO - 'run-tests'), 16:46:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:46:15 INFO - 'download_minidump_stackwalk': True, 16:46:15 INFO - 'download_symbols': 'ondemand', 16:46:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:46:15 INFO - 'tooltool.py': '/tools/tooltool.py', 16:46:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:46:15 INFO - '/tools/misc-python/virtualenv.py')}, 16:46:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:46:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:46:15 INFO - 'installer_path': 'installer.exe', 16:46:15 INFO - 'log_level': 'info', 16:46:15 INFO - 'log_name': 'talos', 16:46:15 INFO - 'log_to_console': True, 16:46:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:46:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:46:15 INFO - 'opt_config_files': (), 16:46:15 INFO - 'pip_index': False, 16:46:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:46:15 INFO - 'cmd': ('bash', 16:46:15 INFO - '-c', 16:46:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:46:15 INFO - 'enabled': True, 16:46:15 INFO - 'halt_on_failure': False, 16:46:15 INFO - 'name': 'check_screen_resolution'},), 16:46:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:46:15 INFO - 'cmd': ('bash', 16:46:15 INFO - '-c', 16:46:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:46:15 INFO - 'enabled': True, 16:46:15 INFO - 'halt_on_failure': False, 16:46:15 INFO - 'name': 'check_screen_resolution'},), 16:46:15 INFO - 'run_cmd_checks_enabled': True, 16:46:15 INFO - 'sps_profile': False, 16:46:15 INFO - 'sps_profile_interval': 0, 16:46:15 INFO - 'suite': 'dromaeojs', 16:46:15 INFO - 'system_bits': '32', 16:46:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:46:15 INFO - 'title': 't-yosemite-r7-0009', 16:46:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:46:15 INFO - 'use_talos_json': True, 16:46:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:46:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:46:15 INFO - 'work_dir': 'build'} 16:46:15 INFO - ##### 16:46:15 INFO - ##### Running clobber step. 16:46:15 INFO - ##### 16:46:15 INFO - Running pre-action listener: _resource_record_pre_action 16:46:15 INFO - Running main action method: clobber 16:46:15 INFO - rmtree: /builds/slave/test/build 16:46:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:46:17 INFO - Running post-action listener: _resource_record_post_action 16:46:17 INFO - ##### 16:46:17 INFO - ##### Running read-buildbot-config step. 16:46:17 INFO - ##### 16:46:17 INFO - Running pre-action listener: _resource_record_pre_action 16:46:17 INFO - Running main action method: read_buildbot_config 16:46:17 INFO - Using buildbot properties: 16:46:17 INFO - { 16:46:17 INFO - "project": "", 16:46:17 INFO - "product": "firefox", 16:46:17 INFO - "script_repo_revision": "production", 16:46:17 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 16:46:17 INFO - "repository": "", 16:46:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 16:46:17 INFO - "buildid": "20160623153657", 16:46:17 INFO - "pgo_build": "False", 16:46:17 INFO - "basedir": "/builds/slave/test", 16:46:17 INFO - "buildnumber": 9, 16:46:17 INFO - "slavename": "t-yosemite-r7-0009", 16:46:17 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:46:17 INFO - "platform": "yosemite_r7", 16:46:17 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:46:17 INFO - "branch": "mozilla-beta", 16:46:17 INFO - "repo_path": "releases/mozilla-beta", 16:46:17 INFO - "revision": "b449af6b87414aa2ed035aaaa5043642e9a4ac06", 16:46:17 INFO - "stage_platform": "macosx64", 16:46:17 INFO - "builduid": "46d2a3a5549d444bbfa748bec8b2d166", 16:46:17 INFO - "slavebuilddir": "test" 16:46:17 INFO - } 16:46:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 16:46:17 INFO - Running post-action listener: _resource_record_post_action 16:46:17 INFO - ##### 16:46:17 INFO - ##### Running download-and-extract step. 16:46:17 INFO - ##### 16:46:17 INFO - Running pre-action listener: _resource_record_pre_action 16:46:17 INFO - Running main action method: download_and_extract 16:46:17 INFO - mkdir: /builds/slave/test/build/tests 16:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:46:17 INFO - https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:46:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 16:46:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 16:46:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 16:46:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 16:46:28 INFO - Downloaded 1165 bytes. 16:46:28 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 16:46:28 INFO - Using the following test package requirements: 16:46:28 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 16:46:28 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:46:28 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 16:46:28 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:46:28 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 16:46:28 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:46:28 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:46:28 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 16:46:28 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 16:46:28 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:46:28 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 16:46:28 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:46:28 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 16:46:28 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:46:28 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 16:46:28 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:46:28 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 16:46:28 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 16:46:28 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')]} 16:46:28 INFO - https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:46:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 16:46:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 16:46:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 16:46:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 16:46:30 INFO - Downloaded 18344058 bytes. 16:46:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:46:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:46:33 INFO - Return code: 0 16:46:33 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 16:46:33 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')]} 16:46:33 INFO - https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:46:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 16:46:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 16:46:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 16:46:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 16:46:38 INFO - Downloaded 18344058 bytes. 16:46:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:46:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:46:41 INFO - Return code: 0 16:46: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')]} 16:46:41 INFO - https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:46:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 16:46:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 16:46:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 16:46:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 16:46:42 INFO - Downloaded 10840145 bytes. 16:46:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:46:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:46:42 INFO - Return code: 0 16:46: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')]} 16:46:42 INFO - https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 16:46:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 16:46:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 16:46:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 16:46:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 16:46:45 INFO - Downloaded 86226761 bytes. 16:46:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 16:46:45 INFO - mkdir: /builds/slave/test/properties 16:46:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:46:45 INFO - Writing to file /builds/slave/test/properties/build_url 16:46:45 INFO - Contents: 16:46:45 INFO - build_url:https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 16:46:46 INFO - Running post-action listener: _resource_record_post_action 16:46:46 INFO - Running post-action listener: set_extra_try_arguments 16:46:46 INFO - ##### 16:46:46 INFO - ##### Running populate-webroot step. 16:46:46 INFO - ##### 16:46:46 INFO - Running pre-action listener: _resource_record_pre_action 16:46:46 INFO - Running main action method: populate_webroot 16:46:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:46:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:46:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:46:46 INFO - '--tppagecycles', 16:46:46 INFO - '7'], 16:46:46 INFO - 'tests': ['tsvgm']}}, 16:46:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:46:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:46:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:46:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:46:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:46:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:46:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:46 INFO - 'talos_options': ['--disable-e10s'], 16:46:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:46:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:46:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:46 INFO - 'talos_options': ['--disable-e10s'], 16:46:46 INFO - 'tests': ['damp', 'tps']}, 16:46:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:46 INFO - 'tests': ['damp', 'tps']}, 16:46:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:46:46 INFO - 'tests': ['dromaeo_dom']}, 16:46:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:46:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:46:46 INFO - 'tests': ['a11yr', 16:46:46 INFO - 'ts_paint', 16:46:46 INFO - 'tpaint', 16:46:46 INFO - 'sessionrestore', 16:46:46 INFO - 'sessionrestore_no_auto_restore', 16:46:46 INFO - 'tabpaint']}, 16:46:46 INFO - 'other-e10s': {'tests': ['a11yr', 16:46:46 INFO - 'ts_paint', 16:46:46 INFO - 'tpaint', 16:46:46 INFO - 'sessionrestore', 16:46:46 INFO - 'sessionrestore_no_auto_restore', 16:46:46 INFO - 'tabpaint']}, 16:46:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:46:46 INFO - 'ts_paint', 16:46:46 INFO - 'tpaint', 16:46:46 INFO - 'sessionrestore', 16:46:46 INFO - 'sessionrestore_no_auto_restore', 16:46:46 INFO - 'tabpaint']}, 16:46:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:46:46 INFO - 'ts_paint', 16:46:46 INFO - 'tpaint', 16:46:46 INFO - 'sessionrestore', 16:46:46 INFO - 'sessionrestore_no_auto_restore', 16:46:46 INFO - 'tabpaint']}, 16:46:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:46:46 INFO - 'tests': ['a11yr', 16:46:46 INFO - 'ts_paint', 16:46:46 INFO - 'tpaint', 16:46:46 INFO - 'sessionrestore', 16:46:46 INFO - 'sessionrestore_no_auto_restore', 16:46:46 INFO - 'tabpaint']}, 16:46:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:46:46 INFO - 'tests': ['a11yr', 16:46:46 INFO - 'ts_paint', 16:46:46 INFO - 'tpaint', 16:46:46 INFO - 'sessionrestore', 16:46:46 INFO - 'sessionrestore_no_auto_restore', 16:46:46 INFO - 'tabpaint']}, 16:46:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:46:46 INFO - 'tests': ['tsvgx', 16:46:46 INFO - 'tsvgr_opacity', 16:46:46 INFO - 'tart', 16:46:46 INFO - 'tscrollx', 16:46:46 INFO - 'cart']}, 16:46:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:46:46 INFO - 'tsvgr_opacity', 16:46:46 INFO - 'tart', 16:46:46 INFO - 'tscrollx', 16:46:46 INFO - 'cart']}, 16:46:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:46 INFO - 'talos_options': ['--disable-e10s'], 16:46:46 INFO - 'tests': ['tp5o']}, 16:46:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:46 INFO - 'tests': ['tp5o']}, 16:46:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:46 INFO - 'talos_options': ['--disable-e10s', 16:46:46 INFO - '--xperf_path', 16:46:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:46:46 INFO - 'tests': ['tp5n']}, 16:46:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:46:46 INFO - 'talos_options': ['--xperf_path', 16:46:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:46:46 INFO - 'tests': ['tp5n']}}, 16:46:46 INFO - 'talos.zip': {'path': '', 16:46:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:46:46 INFO - Running post-action listener: _resource_record_post_action 16:46:46 INFO - ##### 16:46:46 INFO - ##### Running create-virtualenv step. 16:46:46 INFO - ##### 16:46:46 INFO - Running pre-action listener: _resource_record_pre_action 16:46:46 INFO - Running main action method: create_virtualenv 16:46:46 INFO - Creating virtualenv /builds/slave/test/build/venv 16:46:46 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 16:46:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:46:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:46:46 INFO - Using real prefix '/tools/python27' 16:46:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:46:47 INFO - Installing distribute.............................................................................................................................................................................................done. 16:46:50 INFO - Installing pip.................done. 16:46:50 INFO - Return code: 0 16:46:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101f4aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f5a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac51c5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101de9e00>, '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 0x101c52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de4b20>, '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.8udhIDrLih/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.EnN4EzRihj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 16:46:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:46:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8udhIDrLih/Render', 16:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:50 INFO - 'HOME': '/Users/cltbld', 16:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:50 INFO - 'LOGNAME': 'cltbld', 16:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:50 INFO - 'MOZ_NO_REMOTE': '1', 16:46:50 INFO - 'NO_EM_RESTART': '1', 16:46:50 INFO - 'PAGER': '/bin/cat', 16:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:50 INFO - 'PWD': '/builds/slave/test', 16:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:50 INFO - 'SHELL': '/bin/bash', 16:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EnN4EzRihj/Listeners', 16:46:50 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 16:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:50 INFO - 'USER': 'cltbld', 16:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:50 INFO - 'XPC_FLAGS': '0x0', 16:46:50 INFO - 'XPC_SERVICE_NAME': '0', 16:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:50 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 16:46:50 INFO - Cleaning up... 16:46:50 INFO - Return code: 0 16:46:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101f4aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f5a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac51c5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101de9e00>, '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 0x101c52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de4b20>, '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.8udhIDrLih/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.EnN4EzRihj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 16:46:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 16:46:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8udhIDrLih/Render', 16:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:50 INFO - 'HOME': '/Users/cltbld', 16:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:50 INFO - 'LOGNAME': 'cltbld', 16:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:50 INFO - 'MOZ_NO_REMOTE': '1', 16:46:50 INFO - 'NO_EM_RESTART': '1', 16:46:50 INFO - 'PAGER': '/bin/cat', 16:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:50 INFO - 'PWD': '/builds/slave/test', 16:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:50 INFO - 'SHELL': '/bin/bash', 16:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EnN4EzRihj/Listeners', 16:46:50 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 16:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:50 INFO - 'USER': 'cltbld', 16:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:50 INFO - 'XPC_FLAGS': '0x0', 16:46:50 INFO - 'XPC_SERVICE_NAME': '0', 16:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:51 INFO - Downloading/unpacking psutil>=0.7.1 16:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:46:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:46:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:46:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:46:54 INFO - Installing collected packages: psutil 16:46:54 INFO - Running setup.py install for psutil 16:46:54 INFO - building 'psutil._psutil_osx' extension 16:46:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:46:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:46:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:46:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:46:54 INFO - building 'psutil._psutil_posix' extension 16:46:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:46:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:46:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:46:54 INFO - ^ 16:46:54 INFO - 1 warning generated. 16:46:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:46:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:46:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:46:55 INFO - Successfully installed psutil 16:46:55 INFO - Cleaning up... 16:46:55 INFO - Return code: 0 16:46:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:46:55 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')]} 16:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:55 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')]} 16:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:55 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 0x101f4aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f5a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac51c5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101de9e00>, '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 0x101c52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de4b20>, '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.8udhIDrLih/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.EnN4EzRihj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 16:46:55 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 16:46:55 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 16:46:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8udhIDrLih/Render', 16:46:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:55 INFO - 'HOME': '/Users/cltbld', 16:46:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:55 INFO - 'LOGNAME': 'cltbld', 16:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:55 INFO - 'MOZ_NO_REMOTE': '1', 16:46:55 INFO - 'NO_EM_RESTART': '1', 16:46:55 INFO - 'PAGER': '/bin/cat', 16:46:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:55 INFO - 'PWD': '/builds/slave/test', 16:46:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:55 INFO - 'SHELL': '/bin/bash', 16:46:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EnN4EzRihj/Listeners', 16:46:55 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 16:46:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:55 INFO - 'USER': 'cltbld', 16:46:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:55 INFO - 'XPC_FLAGS': '0x0', 16:46:55 INFO - 'XPC_SERVICE_NAME': '0', 16:46:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:46:55 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 16:46:55 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 16:46:55 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 16:46:55 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 16:46:55 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 16:46:55 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 16:46:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:46:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:46:58 INFO - Installing collected packages: mozsystemmonitor 16:46:58 INFO - Running setup.py install for mozsystemmonitor 16:46:59 INFO - Successfully installed mozsystemmonitor 16:46:59 INFO - Cleaning up... 16:46:59 INFO - Return code: 0 16:46:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:46:59 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')]} 16:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:59 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')]} 16:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:46:59 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 0x101f4aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f5a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac51c5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101de9e00>, '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 0x101c52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de4b20>, '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.8udhIDrLih/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.EnN4EzRihj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 16:46:59 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 16:46:59 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 16:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8udhIDrLih/Render', 16:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:46:59 INFO - 'HOME': '/Users/cltbld', 16:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:46:59 INFO - 'LOGNAME': 'cltbld', 16:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:46:59 INFO - 'MOZ_NO_REMOTE': '1', 16:46:59 INFO - 'NO_EM_RESTART': '1', 16:46:59 INFO - 'PAGER': '/bin/cat', 16:46:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:46:59 INFO - 'PWD': '/builds/slave/test', 16:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:46:59 INFO - 'SHELL': '/bin/bash', 16:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EnN4EzRihj/Listeners', 16:46:59 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 16:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:46:59 INFO - 'USER': 'cltbld', 16:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:46:59 INFO - 'XPC_FLAGS': '0x0', 16:46:59 INFO - 'XPC_SERVICE_NAME': '0', 16:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:46:59 INFO - Downloading/unpacking blobuploader==1.2.4 16:46:59 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 16:46:59 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 16:46:59 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 16:46:59 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 16:46:59 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 16:46:59 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 16:47:02 INFO - Downloading blobuploader-1.2.4.tar.gz 16:47:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:47:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:47: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 16:47: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 16:47: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 16:47: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 16:47: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 16:47: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 16:47:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:47:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:47: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 16:47: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 16:47: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 16:47: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 16:47: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 16:47: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 16:47:04 INFO - Downloading docopt-0.6.1.tar.gz 16:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:47:04 INFO - Installing collected packages: blobuploader, requests, docopt 16:47:04 INFO - Running setup.py install for blobuploader 16:47:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:47:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:47:04 INFO - Running setup.py install for requests 16:47:04 INFO - Running setup.py install for docopt 16:47:04 INFO - Successfully installed blobuploader requests docopt 16:47:04 INFO - Cleaning up... 16:47:04 INFO - Return code: 0 16:47:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:47:04 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')]} 16:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:47:04 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')]} 16:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:47:04 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 0x101f4aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f5a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac51c5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101de9e00>, '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 0x101c52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de4b20>, '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.8udhIDrLih/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.EnN4EzRihj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 16:47:04 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 16:47:04 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 16:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8udhIDrLih/Render', 16:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:47:04 INFO - 'HOME': '/Users/cltbld', 16:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:47:04 INFO - 'LOGNAME': 'cltbld', 16:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:47:04 INFO - 'MOZ_NO_REMOTE': '1', 16:47:04 INFO - 'NO_EM_RESTART': '1', 16:47:04 INFO - 'PAGER': '/bin/cat', 16:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:47:04 INFO - 'PWD': '/builds/slave/test', 16:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:47:04 INFO - 'SHELL': '/bin/bash', 16:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EnN4EzRihj/Listeners', 16:47:04 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 16:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:47:04 INFO - 'USER': 'cltbld', 16:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:47:04 INFO - 'XPC_FLAGS': '0x0', 16:47:04 INFO - 'XPC_SERVICE_NAME': '0', 16:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:47:05 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-OQmJTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:47:05 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-FScF2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:47:05 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-SzVYSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:47:05 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-nBEJxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:47:05 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-xwHimF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:47:05 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-O9Vg7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:47:05 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-tcnZ3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:47:05 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-o3WWNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:47:06 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-hpPYOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:47:06 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-nlkD2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:47:06 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-K0Z1uM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:47:06 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-1SqPnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:47:06 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-JjnFTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:47:06 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-KU19Uv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:47:06 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-NVeCAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:47:06 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-FgwMzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:47:06 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-ZAXTp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:47:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:47:06 INFO - Running setup.py install for manifestparser 16:47:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:47:07 INFO - Running setup.py install for mozcrash 16:47:07 INFO - Running setup.py install for mozdebug 16:47:07 INFO - Running setup.py install for mozdevice 16:47:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:47:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:47:07 INFO - Running setup.py install for mozfile 16:47:07 INFO - Running setup.py install for mozhttpd 16:47:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:47:07 INFO - Running setup.py install for mozinfo 16:47:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:47:07 INFO - Running setup.py install for mozInstall 16:47:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:47:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:47:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:47:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:47:08 INFO - Running setup.py install for mozleak 16:47:08 INFO - Running setup.py install for mozlog 16:47:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:47:08 INFO - Running setup.py install for moznetwork 16:47:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:47:08 INFO - Running setup.py install for mozprocess 16:47:08 INFO - Running setup.py install for mozprofile 16:47:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:47:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:47:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:47:08 INFO - Running setup.py install for mozrunner 16:47:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:47:09 INFO - Running setup.py install for mozscreenshot 16:47:09 INFO - Running setup.py install for moztest 16:47:09 INFO - Running setup.py install for mozversion 16:47:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:47:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:47:09 INFO - Cleaning up... 16:47:09 INFO - Return code: 0 16:47:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:47:09 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')]} 16:47:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:47:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:47:09 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')]} 16:47:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:47:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:47:09 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 0x101f4aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f5a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac51c5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101de9e00>, '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 0x101c52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de4b20>, '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.8udhIDrLih/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.EnN4EzRihj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 16:47:09 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 16:47:09 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 16:47:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8udhIDrLih/Render', 16:47:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:47:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:47:09 INFO - 'HOME': '/Users/cltbld', 16:47:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:47:09 INFO - 'LOGNAME': 'cltbld', 16:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:47:09 INFO - 'MOZ_NO_REMOTE': '1', 16:47:09 INFO - 'NO_EM_RESTART': '1', 16:47:09 INFO - 'PAGER': '/bin/cat', 16:47:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:47:09 INFO - 'PWD': '/builds/slave/test', 16:47:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:47:09 INFO - 'SHELL': '/bin/bash', 16:47:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EnN4EzRihj/Listeners', 16:47:09 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 16:47:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:47:09 INFO - 'USER': 'cltbld', 16:47:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:47:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:47:09 INFO - 'XPC_FLAGS': '0x0', 16:47:09 INFO - 'XPC_SERVICE_NAME': '0', 16:47:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:47:09 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Nxg0l_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:47:09 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)) 16:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:47:09 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-k7GGbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:47:10 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)) 16:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:47:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-ALlmpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:47:10 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)) 16:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:47:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-TiXd6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:47:10 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)) 16:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:47:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-CK3lhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:47:10 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)) 16:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:47:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-gz0hAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:47:10 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)) 16:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:47:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-mewEyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:47:10 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)) 16:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:47:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-8sksEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:47:10 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)) 16:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:47:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-98JOWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:47:10 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)) 16:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:47:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-nYLPLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:47:10 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)) 16:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:47:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-vj4Mv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:47:10 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)) 16:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:47:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-U1cEPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:47:11 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)) 16:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:47:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-H6WJO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:47:11 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)) 16:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:47:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-dLeCLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:47:11 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)) 16:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:47:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-EAgKI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:47:11 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)) 16:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:47:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-x0FIiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:47:11 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)) 16:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:47:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-aDAuUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:47:11 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)) 16:47:11 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)) 16:47:11 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)) 16:47:11 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)) 16:47:11 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)) 16:47:11 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)) 16:47:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:47:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:47:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:47:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:47:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:47:14 INFO - Downloading blessings-1.6.tar.gz 16:47:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:47:14 INFO - Installing collected packages: blessings 16:47:14 INFO - Running setup.py install for blessings 16:47:14 INFO - Successfully installed blessings 16:47:14 INFO - Cleaning up... 16:47:14 INFO - Return code: 0 16:47:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:47:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:47:14 INFO - Reading from file tmpfile_stdout 16:47:14 INFO - Current package versions: 16:47:14 INFO - blessings == 1.6 16:47:14 INFO - blobuploader == 1.2.4 16:47:14 INFO - docopt == 0.6.1 16:47:14 INFO - manifestparser == 1.1 16:47:14 INFO - mozInstall == 1.12 16:47:14 INFO - mozcrash == 0.17 16:47:14 INFO - mozdebug == 0.1 16:47:14 INFO - mozdevice == 0.48 16:47:14 INFO - mozfile == 1.2 16:47:14 INFO - mozhttpd == 0.7 16:47:14 INFO - mozinfo == 0.9 16:47:14 INFO - mozleak == 0.1 16:47:14 INFO - mozlog == 3.1 16:47:14 INFO - moznetwork == 0.27 16:47:14 INFO - mozprocess == 0.22 16:47:14 INFO - mozprofile == 0.28 16:47:14 INFO - mozrunner == 6.11 16:47:14 INFO - mozscreenshot == 0.1 16:47:14 INFO - mozsystemmonitor == 0.0 16:47:14 INFO - moztest == 0.7 16:47:14 INFO - mozversion == 1.4 16:47:14 INFO - psutil == 3.1.1 16:47:14 INFO - requests == 1.2.3 16:47:14 INFO - wsgiref == 0.1.2 16:47:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:47:14 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')]} 16:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:47:14 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')]} 16:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:47:14 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 0x101f4aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f5a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac51c5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101de9e00>, '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 0x101c52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de4b20>, '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.8udhIDrLih/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.EnN4EzRihj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 16:47:14 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 16:47:14 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 16:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8udhIDrLih/Render', 16:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:47:14 INFO - 'HOME': '/Users/cltbld', 16:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:47:14 INFO - 'LOGNAME': 'cltbld', 16:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:47:14 INFO - 'MOZ_NO_REMOTE': '1', 16:47:14 INFO - 'NO_EM_RESTART': '1', 16:47:14 INFO - 'PAGER': '/bin/cat', 16:47:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:47:14 INFO - 'PWD': '/builds/slave/test', 16:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:47:14 INFO - 'SHELL': '/bin/bash', 16:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EnN4EzRihj/Listeners', 16:47:14 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 16:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:47:14 INFO - 'USER': 'cltbld', 16:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:47:14 INFO - 'XPC_FLAGS': '0x0', 16:47:14 INFO - 'XPC_SERVICE_NAME': '0', 16:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:47:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:47:14 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)) 16:47:14 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)) 16:47:14 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)) 16:47:14 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)) 16:47:14 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)) 16:47:14 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)) 16:47:14 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)) 16:47:14 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)) 16:47:14 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)) 16:47:14 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)) 16:47:14 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)) 16:47:14 INFO - Cleaning up... 16:47:14 INFO - Return code: 0 16:47:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:47:14 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')]} 16:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:47:14 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')]} 16:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:47:14 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 0x101f4aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f5a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac51c5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101de9e00>, '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 0x101c52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de4b20>, '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.8udhIDrLih/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.EnN4EzRihj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 16:47:14 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 16:47:14 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 16:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8udhIDrLih/Render', 16:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:47:14 INFO - 'HOME': '/Users/cltbld', 16:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:47:14 INFO - 'LOGNAME': 'cltbld', 16:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:47:14 INFO - 'MOZ_NO_REMOTE': '1', 16:47:14 INFO - 'NO_EM_RESTART': '1', 16:47:14 INFO - 'PAGER': '/bin/cat', 16:47:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:47:14 INFO - 'PWD': '/builds/slave/test', 16:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:47:14 INFO - 'SHELL': '/bin/bash', 16:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EnN4EzRihj/Listeners', 16:47:14 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 16:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:47:14 INFO - 'USER': 'cltbld', 16:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:47:14 INFO - 'XPC_FLAGS': '0x0', 16:47:14 INFO - 'XPC_SERVICE_NAME': '0', 16:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:47:15 INFO - Downloading/unpacking jsonschema 16:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:47:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:47:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:47:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:47:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:47:17 INFO - Downloading/unpacking functools32 (from jsonschema) 16:47:17 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 16:47:17 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 16:47:17 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 16:47:17 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 16:47:17 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 16:47:17 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 16:47:18 INFO - Downloading functools32-3.2.3-2.tar.gz 16:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:47:18 INFO - warning: no files found matching '*.txt' 16:47:18 INFO - no previously-included directories found matching 'build' 16:47:18 INFO - no previously-included directories found matching 'dist' 16:47:18 INFO - no previously-included directories found matching '.git*' 16:47:18 INFO - Installing collected packages: jsonschema, functools32 16:47:18 INFO - Running setup.py install for functools32 16:47:18 INFO - warning: no files found matching '*.txt' 16:47:18 INFO - no previously-included directories found matching 'build' 16:47:18 INFO - no previously-included directories found matching 'dist' 16:47:18 INFO - no previously-included directories found matching '.git*' 16:47:18 INFO - Successfully installed jsonschema functools32 16:47:18 INFO - Cleaning up... 16:47:18 INFO - Return code: 0 16:47:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:47:18 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 0x101f4aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f5a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac51c5edb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101de9e00>, '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 0x101c52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de4b20>, '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.8udhIDrLih/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.EnN4EzRihj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 16:47:18 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 16:47:18 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 16:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8udhIDrLih/Render', 16:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:47:18 INFO - 'HOME': '/Users/cltbld', 16:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:47:18 INFO - 'LOGNAME': 'cltbld', 16:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:47:18 INFO - 'MOZ_NO_REMOTE': '1', 16:47:18 INFO - 'NO_EM_RESTART': '1', 16:47:18 INFO - 'PAGER': '/bin/cat', 16:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:47:18 INFO - 'PWD': '/builds/slave/test', 16:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:47:18 INFO - 'SHELL': '/bin/bash', 16:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EnN4EzRihj/Listeners', 16:47:18 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 16:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:47:18 INFO - 'USER': 'cltbld', 16:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:47:18 INFO - 'XPC_FLAGS': '0x0', 16:47:18 INFO - 'XPC_SERVICE_NAME': '0', 16:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:47:19 INFO - Downloading/unpacking flake8 16:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:47:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:47:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:47:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:47:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:47:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:47:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:47:21 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 16:47:21 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 16:47:21 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 16:47:21 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 16:47:21 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 16:47:21 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 16:47:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:47:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:47: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 16:47: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 16:47: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 16:47: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 16:47: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 16:47: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 16:47:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:47: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 16:47: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 16:47: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 16:47: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 16:47: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 16:47: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 16:47:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:47:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:47:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:47:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:47:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:47:23 INFO - no previously-included directories found matching 'docs/_build' 16:47:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:47:23 INFO - Running setup.py install for pep8 16:47:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:47:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:47:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:47:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:47:23 INFO - no previously-included directories found matching 'docs/_build' 16:47:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:47:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:47:23 INFO - Cleaning up... 16:47:23 INFO - Return code: 0 16:47:23 INFO - Running post-action listener: _resource_record_post_action 16:47:23 INFO - Running post-action listener: _start_resource_monitoring 16:47:23 INFO - Starting resource monitoring. 16:47:23 INFO - ##### 16:47:23 INFO - ##### Running install step. 16:47:23 INFO - ##### 16:47:23 INFO - Running pre-action listener: _resource_record_pre_action 16:47:23 INFO - Running main action method: install 16:47:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:47:23 INFO - Reading from file tmpfile_stdout 16:47:23 INFO - Detecting whether we're running mozinstall >=1.0... 16:47:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:47:23 INFO - Reading from file tmpfile_stdout 16:47:23 INFO - Output received: 16:47:23 INFO - Usage: mozinstall [options] installer 16:47:23 INFO - Options: 16:47:23 INFO - -h, --help show this help message and exit 16:47:23 INFO - -d DEST, --destination=DEST 16:47:23 INFO - Directory to install application into. [default: 16:47:23 INFO - "/builds/slave/test"] 16:47:23 INFO - --app=APP Application being installed. [default: firefox] 16:47:23 INFO - mkdir: /builds/slave/test/build/application 16:47:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 16:47:45 INFO - Reading from file tmpfile_stdout 16:47:45 INFO - Output received: 16:47:45 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:47:45 INFO - Running post-action listener: _resource_record_post_action 16:47:45 INFO - ##### 16:47:45 INFO - ##### Running run-tests step. 16:47:45 INFO - ##### 16:47:45 INFO - Running pre-action listener: _resource_record_pre_action 16:47:45 INFO - Running main action method: run_tests 16:47:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:47:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:47:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:47:45 INFO - 2016-06-23 16:47:45.622 screenresolution[1921:9670] starting screenresolution argv=screenresolution get 16:47:45 INFO - 2016-06-23 16:47:45.625 screenresolution[1921:9670] Display 0: 1600x1200x32@60 16:47:45 INFO - 2016-06-23 16:47:45.630 screenresolution[1922:9673] starting screenresolution argv=screenresolution list 16:47:45 INFO - Available Modes on Display 0 16:47:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:47:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:47:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:47:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:47:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:47:45 INFO - Intel Iris: 16:47:45 INFO - Chipset Model: Intel Iris 16:47:45 INFO - Type: GPU 16:47:45 INFO - Bus: Built-In 16:47:45 INFO - VRAM (Dynamic, Max): 1536 MB 16:47:45 INFO - Vendor: Intel (0x8086) 16:47:45 INFO - Device ID: 0x0a2e 16:47:45 INFO - Revision ID: 0x0009 16:47:45 INFO - Displays: 16:47:45 INFO - Display: 16:47:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:47:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:47:45 INFO - Display Serial Number: 1600x1200 60 16:47:45 INFO - Main Display: Yes 16:47:45 INFO - Mirror: Off 16:47:45 INFO - Online: Yes 16:47:45 INFO - Rotation: Supported 16:47:45 INFO - Return code: 0 16:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:47:45 INFO - Python 2.7.3 16:47:45 INFO - Return code: 0 16:47:45 INFO - grabbing minidump binary from tooltool 16:47:45 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')]} 16:47:45 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 0x101de9e00>, '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 0x101c52db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101de4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:47:45 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 16:47:45 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 16:47:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:47:45 INFO - Return code: 0 16:47:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:47:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:47:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:47:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:47:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:47:47 INFO - Return code: 0 16:47:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8udhIDrLih/Render', 16:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:47:47 INFO - 'HOME': '/Users/cltbld', 16:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:47:47 INFO - 'LOGNAME': 'cltbld', 16:47:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:47:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:47:47 INFO - 'MOZ_NO_REMOTE': '1', 16:47:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:47:47 INFO - 'NO_EM_RESTART': '1', 16:47:47 INFO - 'PAGER': '/bin/cat', 16:47:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:47:47 INFO - 'PWD': '/builds/slave/test', 16:47:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:47:47 INFO - 'SHELL': '/bin/bash', 16:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EnN4EzRihj/Listeners', 16:47:47 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 16:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:47:47 INFO - 'USER': 'cltbld', 16:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:47:47 INFO - 'XPC_FLAGS': '0x0', 16:47:47 INFO - 'XPC_SERVICE_NAME': '0', 16:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:47:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:47:47 INFO - mozversion application_buildid: 20160623153657 16:47:47 INFO - mozversion application_changeset: b449af6b87414aa2ed035aaaa5043642e9a4ac06 16:47:47 INFO - mozversion application_display_name: Firefox 16:47:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:47:47 INFO - mozversion application_name: Firefox 16:47:47 INFO - mozversion application_remotingname: firefox 16:47:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:47:47 INFO - mozversion application_vendor: Mozilla 16:47:47 INFO - mozversion application_version: 48.0 16:47:47 INFO - mozversion platform_buildid: 20160623153657 16:47:47 INFO - mozversion platform_changeset: b449af6b87414aa2ed035aaaa5043642e9a4ac06 16:47:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:47:47 INFO - mozversion platform_version: 48.0 16:47:47 INFO - using testdate: 1466725667 16:47:47 INFO - actual date: 1466725667 16:47:47 INFO - starting webserver on 'localhost:49747' 16:47:47 INFO - SUITE-START | Running 2 tests 16:47:47 INFO - TEST-START | dromaeo_css 16:47:47 INFO - operating with platform_type : mac_ 16:47:47 INFO - Initialising browser for dromaeo_css test... 16:47:47 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpvzqj9f/profile http://localhost:49747/getInfo.html) 16:47:49 INFO - PROCESS | 1940 | __metrics Screen width/height:1600/1200 16:47:49 INFO - PROCESS | 1940 | colorDepth:24 16:47:49 INFO - PROCESS | 1940 | Browser inner width/height: 1024/689 16:47:49 INFO - PROCESS | 1940 | __metrics 16:47:49 INFO - PROCESS | 1940 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:47:49 ERROR - PROCESS | 1940 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:47:50 INFO - TEST-INFO | 1940: exit 0 16:47:50 INFO - Browser initialized. 16:47:50 INFO - Running cycle 1/1 for dromaeo_css test... 16:47:50 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpvzqj9f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:01 INFO - PROCESS | 1943 | RSS: Main: 161177600 16:48:01 INFO - PROCESS | 1943 | 16:48:52 INFO - PROCESS | 1943 | 1466725732865 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] 16:51:02 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49747/tests/dromaeo/cssquery-ext.html) 16:51:03 INFO - PROCESS | 1943 | RSS: Main: 317669376 16:51:03 INFO - PROCESS | 1943 | 16:54:06 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-ext.html (next: http://localhost:49747/tests/dromaeo/cssquery-jquery.html) 16:54:07 INFO - PROCESS | 1943 | RSS: Main: 308424704 16:54:07 INFO - PROCESS | 1943 | 16:57:09 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49747/tests/dromaeo/cssquery-mootools.html) 16:57:09 INFO - PROCESS | 1943 | RSS: Main: 308105216 16:57:09 INFO - PROCESS | 1943 | 17:00:11 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49747/tests/dromaeo/cssquery-prototype.html) 17:00:12 INFO - PROCESS | 1943 | RSS: Main: 308256768 17:00:12 INFO - PROCESS | 1943 | 17:03:15 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49747/tests/dromaeo/cssquery-yui.html) 17:03:16 INFO - PROCESS | 1943 | RSS: Main: 271974400 17:03:16 INFO - PROCESS | 1943 | 17:06:18 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:49747/tests/dromaeo/cssquery-yui.html (next: http://localhost:49747/tests/dromaeo/cssquery-dojo.html) 17:06:19 INFO - PROCESS | 1943 | RSS: Main: 315187200 17:06:19 INFO - PROCESS | 1943 | 17:06:19 INFO - PROCESS | 1943 | __start_tp_report 17:06:19 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 17:06:19 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 17:06:19 INFO - PROCESS | 1943 | |i|pagename|runs| 17:06:19 INFO - PROCESS | 1943 | |0;dojo.html;2806.705295943925;3535.57573091229;3537;3562.786232826029;3608.188157664526;5128.846009647837;5194.454582268864;5729.656220626765;5757.309122905247;5764.221830052941;5672.347680016799;5718.485336319741;5729.026065568853;5732.5127364173995;5736.311642602883;5196.480351964792;5196.974015129919;5198.168293073109;5226.634135610509;5255.343082114736;5245.79016839326;5293.841184764444;5295.867603309909;5303.071962406582;5304.946950530503;5195.66228195168;5251.842444726645;5268.841934741964;5278.604104692145;5289.259503669489;5279.63042587019;5321.973390133063;5322.0686379883755;5334.03987282289;5345.946540534584;5143.177091665353;5153.896922061575;5172;5246.291750613656;5246.97376513115;5597.888042239173;5641.915371269434;5653;5656.8020119295625;5675.177099320616;6233.470155036782;6258.561900666955;6264.1543391642;6265.937340626581;6270.153529273581;6116.235470566179;6170.96914515429;6239;6239.812805615838;6242.344553821828;5855.414458554195;5877.147813567009;5885.411458854123;5889;5905.055191169392;6260.749570017239;6293.1504246927;6308.495320374359;6310.558260921692;6319.05214217871;6081.300650425169;6141.508679305645;6186.938130618636;6200.906986395207;6217.347178546278;6231.283402408781;6240.375962403723;6252.3122456529745;6265.530085243625;6267.4986001119805;6184.245183156763;6235.282942461675;6246.718897649616;6252;6261.217347831521;6126.975740326312;6176.320604733476;6236.656983865931;6248.718807653667;6272.435480806749;4941.061198372297;4975.477574854661;4977.377827771528;4983.800647974041;4997.800087996439;4484.529124441952;4495.25828238337;4495.505494395614;4499.122671079173;4520.977395113004;4905.8037678492465;5807.216025836458;5878.647281163144;5922.052471604608;5990.430909063632;6179.84550386244;6203.96898015507;6205.627662340274;6219.689015549295;6219.782307619212;5877.412258774174;5891.49922256613;5913.586048976616;5915.970420147958;5933.910991335133;4453.977730111394;5387.057264978613;5446.618736688394;5462.371827239839;5485.067538518461;6307;6375.234971803413;6378.075179099097;6403.423691867754;6416.935830641727;6170.598911070766;6201.286852012076;6235.532335074797;6242.500599951902;6246.469050130608;6326.335734747785;6380.521460890359;6407.967960160169;6418.679066046586;6429.453496452854;5150.97424512875;6193.907091393543;6206.255249369924;6248.656323902229;6255.687215639291;6132.171952247831;6146.016637338004;6216.222972128484;6242.687865606611;6275.843103922439;6162.691865406801;6198.566100372931;6208.4722798562625;6211.627302361872;6220.533459990428;6137.7851775187655;6148.047052706837;6194.349593292639;6194.752209911733;6202.4417802398;5898.557608179318;6018.699065046818;6019.72911219005;6065.909011364922;6118.357572455006;4966.279889415942;5977.731002104829;6048.092786082123;6119.103262359172;6125.798903363081;5982.581219314607;6019.72911219005;6047.9395206047375;6075.058365953284;6078.696065196634 17:06:19 INFO - PROCESS | 1943 | |1;ext.html;514.6423235851084;534.4521865088284;545.9399466058735;552.585560829378;558.723431901209;26483;32958.83520582397;41119.58880411199;41939.37090943631;42217.36673949893;45276;45689;45756;45849;45907.77046114765;9639.903600963971;9856.965018673043;9879.703608891708;9891.208703303719;9893.010698930086;9147.451152930813;11903.404829758521;12133.575324863632;12180.269183848954;12180.939095304511;11834.526618935231;11895.345755983417;11910.225835320676;11911.40442977852;11925.821112683318;11817.64547063585;11881;11895.464704088337;11901.642950711492;11907.880921190765;11683.299002059903;11806.40967951599;11807.468663910146;11824.467898944567;11828.645140645795;43152.352714709305;43692.781536092116;43713.78143109296;43745;43755.124897501875;3303;3397.286569820341;3466.185446420089;3500.6149323574386;3528.6647768461953;29267.560986585217;29845.70154298451;31329.68670313313;31439.21401964969;31580.73677052915;2792.0786140573564;2815.6480439945008;2826.053272153831;2830.994996776156;2879;449067;526533;531546;531602;532801;38138.04654883595;38682.41976370357;38714;38790.6120938787;71139;37183;37491.4376284356;37624.811875940446;37704.245915082094;37899.24201515954;16308.26612802403;16468.917655411646;16485.17574121289;16497.91751041261;16513;12922.806157907638;13017.023723220607;13733.587992360246;13814.309284535733;14397.99214055006;8624.310055195569;8650.783730406665;8665.13348665141;8712.128787121237;8720.956395217983;3499.3001399720156;3537.1510837399355;3538.7876727396438;3548.822558872046;3574.2315402188656;984.9901500985042;997.001012969007;1055.2402270365324;1145.387217838469;1171.292858104108;26565.406075635525;26622.733772662414;30968.225794355323;31792.841035794674;32329.35341293161;1112.477135746198;1162.3026184289529;1163.1741463560797;1201.201201201209;1217.3122185965078;16159.111248881425;16393.91803040977;16452;16474.26145233617;16512.174391280627;653283;655482;662186;662680;664524;66236.66881665561;66889.99665005029;66960.33039669541;66977.32090716415;67300.32699673038;1442.235615123986;1483.1027228526802;1561.3364320163657;1562.5234303537302;1563.0855949269805;24204.39489012789;30656.693433066277;30677.77288908508;31118.066458006295;31377.529337059506;54701.72649136729;54935.72532137314;55006.72496637491;55072.44927550673;55141;400021;404310;405389;405984.9401505947;406196;24114.035438582963;28409;29219.415611688073;29538.113856584336;29960.550591740706;49379;49457.752711236215;50626.49373506218;50634.98730025305;51210;39933.60066399299;40654.39018414785;40694.79652601718;40971.79514102411;42097.579024210594;33520.83239583884;40408.3938740925;40509.59490405176;40913;41821.37267940922;40086.79956600198;40913.56802512017;41036.1792764149;41863.58136418597;41903.58096419119;30450.695493044786;33454;34125.82937085299;35351.14271608883;35702.28595428025;22437.990290436643;26554.601680975185;26591.936322547655;26668.733312666624;26792.732072679024 17:06:19 INFO - PROCESS | 1943 | |2;jquery.html;411.12840777551594;411.45687692246236;416.25698128840025;421.481577659479;427.63864534468365;19606.411807645756;20212.59574808503;20966.475838104037;20995.790042099576;21130;19607;19966.001699915018;20281.985900704978;20407;20411.897940510313;5920.171176035358;6010.248718910137;6026.758929642809;6030.517558595302;6040.788572399967;5840.678762668052;6053.364396738345;6073;6076.756929722806;6079.908801367983;5776.277965254343;6032.185654936571;6035.6076855004285;6042.305134909498;6051.27384713835;5937.139114828369;5977.16319715238;5998;6002.039673652216;6037.215162028908;5276.498732620395;5622.859428514279;5903.675297858616;5916;5923.407659234085;13829;13949.372278247503;14019.369128389248;21916.780832191635;26288.21135365923;2170.902309396081;2192.550527141932;2273.056681477185;2318.698569186012;2392.043182726906;10069.043440873105;10085.747856303578;10474.214433917487;13171.69495338312;14149.504767333197;1786.5980154465271;1787.7664411555984;1810.2216047099741;1811.2030706489234;1851.3612803582741;104219.478902605;118632.40683796612;119278;122335.38832305871;128030;11636.76726465466;11730.4163937869;11734.295942243492;11766.705832354259;11907.583234586747;14717.779233311509;14870.628234294012;14871.776923346159;14876.330565124379;14973.70052598942;6854.657267136623;6962.885938249856;8890.86663700045;9167.587458564381;9272.860907086399;9182.586783594754;9213.078692130734;9237.815243695222;9315.230106279922;9324.8601270981;4220.936685949713;4247.617714405718;4696.154692155376;4707.152712511781;4757.595604373598;1799.9321533334758;1950.8053439880134;1996.8602197846012;2006.7190593316948;2015.6069566434405;927.679950417105;964.7925695975399;975.8731364922656;988.4316518002178;1045.8535804987307;19511.317103901296;19902.203911843364;20524.589508209752;20616.69074963877;21444.92775361257;974.7806743482802;991.504247876062;998.2213873178932;1018.760591261053;1095.8904109588977;10126.189904807597;10433.373997560171;10650.467476626138;10679.679609611723;10732.892671073345;666924;670934;672303;674806;686992;109280.90719092866;136492;138353.3082334582;141611;144445;1056.0759335581367;1060.4061725433783;1088.1784252889177;1138.1975707126455;1139.1228753859482;18129;18141.818581814012;18177.454676359732;18239.635207296044;18975.24099036023;55110.44889551053;56021;56351;57074.57313567194;58105.41894581;82575.17424825915;83311;83481;84408.57795710982;85103.29793404222;7947.761567152995;8011.759647210593;8069.475484093515;8270.37972152079;8382.664693412195;9315.813683726152;11304.660860174208;12176.817347739969;12177.756444871231;13051.673708157372;8070.798230044061;8095.676172953016;8137.267645911897;11610.883891161318;11711;6788.660566971533;7682.6926922924695;7869.134395216407;8009.078955920144;8069.152738962324;8202.83594328122;8386.496810191407;8489.193526614961;8496.957515212385;8738.475691458532;9685.854712179464;12379.814302785646;12386.876131238932;12521.792995269365;12767.61697149087;11884.227525210834;11896;12039.03687704999;12077.335746534025;12131.45408456604 17:06:19 INFO - PROCESS | 1943 | |3;mootools.html;1370.5271681269958;1436.554668052903;1587;1806.6206096719689;1990.6018796240755;8198.344132469396;8809.515476648781;8948.686795962143;8959.820803583925;8963.910360896381;8669.869951950726;8913.153250441213;8930.062343453941;8932.151445612673;8953.149450802179;2540.0601777342363;2566.781823545001;2570.473053024135;2571.524268010424;2575.4720282342073;1862.9627407451842;2132.5841460915126;2215.3575463115676;2229.609818281794;2239.9328020159423;2520.4581015081753;2555.3100662821025;2559.948801023988;2568;2569.460413313197;2532.911348102826;2539.7968162546954;2551.9106831260815;2560.078371786165;2573.4981678572685;2513.6983561972497;2548.961765573518;2562.013624754464;2567.396661784479;2567.9229623111337;8835.911640883573;9084.7274581762;9168.862467063;9173.908260917373;9183.943846457609;5578.163275508666;5764.596478246524;5792.942070579283;5799.681017544033;5804.100364443518;3554.5556805399324;3642.453631955201;3645.1798345372345;3722.7207959403154;3769.6230376962358;1046.095127714529;1051.1800795379615;1053.9156867450586;1068.6206396729126;1070.855434516346;12109.93945030278;12206.08454365926;12376;12421;12424.937875310656;4729.787159577827;4808.877318194589;4824.155772739757;4881.780319885614;4899.559039686445;9645.131938125602;9667.226621870373;9676.806463870824;9688.564014619358;9693.51532423376;5532.529734972572;5554.416786237387;5596.104623260258;5601.131824567198;5601.383847776741;4564.908701825945;4623.242796888798;4692.976535117303;4726.338312636233;5206.114960456732;4033.354663253924;4064.0246340878566;4162.812673429642;4170.687198460128;4178.561251068655;1239.6962744127748;1243.502598960405;1245.2715161630547;1253.730447953676;1259.8425196850394;901.1124042817819;906.3112034853559;953.8330792111492;962.1244667352815;1030;5916.083007133901;5967.373425790316;6021.819345419644;6157.84605384869;6209.161763162006;944.7213072143734;968.2157452463528;978.1148061004802;981.5877331520778;991.3110388280076;3921.686265098785;3979;3995.760254384746;4019.9950012496874;4081.69387295954;10811.78376432467;11249.43752812356;11295.604653837076;11460.369679667698;11466.197366184288;9132.721419001346;11244;11265.662030139109;11267.042301404472;11317.207795454238;8449.57752112392;8598.742037738877;8598.828023439497;8603.440776349516;8670.86993695095;5149.918517111449;5780.104083867007;5834.15404766315;5962.25471816023;6057.0077184440015;9743.561539730586;9989.700308990741;10106.595736170471;10107.292489525662;10113.443760593238;9863.852042219229;10247.282690211614;10251.077403033763;10304.793904122025;10411.323263987817;6598.8350291243105;7221.53060051095;7260;7326.560406375635;7352.411807055316;7980.162082981436;8323.625436855476;8508.489490630582;8516.70191543293;8527.616257268313;7447.925520744723;7534.0582427196605;7569.659365328684;7609.847803044019;7717.344025757873;7359.668814903449;7380.298871607189;7419.517731347445;7448.925510744823;7485.47601667878;7078.504504684829;7147.571145731273;7162.677679504331;7176.605286709281;7215.891761623685;4953.628477864247;4954.355933728592;4955.331030311006;5049.873753156054;5058.848234552822;4983.825566105169;5027.824026159214;5100.464451232716;5187.610929180251;5330.413654498079 17:06:19 INFO - PROCESS | 1943 | |4;prototype.html;334.3847320929489;339.8470688190315;346.3177540245715;349.6241540344129;352.5628221005953;5789.39211382805;6711.127553418055;6712.228093769212;6738.955461903401;6782.863398029617;5274.947250527493;5794.82615521534;6304.747810087591;6650.268470468244;6672.899906501407;4415.933760993587;4794.280857871312;4819;4902.338184345103;4906.950930490685;4224;4799.496052914446;4938.135826230413;4944.703317800925;4944.87637809054;3967.960320396802;4924.064427758733;4928.654994150411;4930.4083509978855;4937.061958227943;4672.532746725335;4749.311349854269;4837.612990960714;4850.442199147073;4871.269309603553;4177.206330797138;4178.958210417887;4191.811368488426;4200.831966721327;4205.306124489455;5664.150377443375;6277.089821975788;6301.842453938642;6334.556581039329;6353.332900045475;5819.214406055171;5825.679587622679;5826.242588463515;5833.2416785817995;5847.795327163529;6846.5892046477375;7037.33145351196;7056.364927156581;7069.762791511465;7091.964540177266;4776.546228108324;4778.80884764609;4787.473377928425;4807.471178170398;4810.591099756525;6552.705128269239;7063.117110361205;7456.440766942501;7512.8121796954965;7512.962435187789;4823.252395878644;4834.323194752737;4850.199717046718;4857.635677324144;4860.9513904861205;4908.975455122773;4928.654994150375;4931;4934.9013019739405;4938.333325001151;5634.098544232903;5844.912326315109;5857.004309267435;5858.970705146447;5861.677607731531;7129.607871567114;7138.357547820721;7141.821454463576;7147.392471639969;7154.856902861914;1494.7234761569146;1678.6558755455103;1718.957026074358;1722.5176950453892;1722.6985277576373;1040.401768982838;1055.8469021991768;1091.1761619976867;1095.7315457712912;1118.972025699364;6773.950037744157;6899.689513971783;6901;6905.482088843256;6925.826854328581;7495.812604684927;7499.2500749924575;7517.060367454068;7530.698772049166;7557.224052347694;6865.759698410531;6897.862042759217;6899.379055884994;6903.896441553381;6905.516613837083;3782.1868298315994;3908.687305015592;3933.7639741615594;3944.585818489075;3952.3873799561115;5653.378128405872;7752.0697516298405;7760.223977602308;7770.961145194238;7832.295093441618;7668.271514206142;7671.424643151786;7677.30904218623;7681.116671582839;7685.269899359552;7786.299233069051;7796.883046754303;7822.882656760153;7826.686932522749;7861.882071768814;5921.940780592139;6808.217055038734;7206.135263768381;7468.701251949861;7469.439792015512;6882.242953275036;7048.577085374894;7226.385757210696;7282.45381596393;7309.853802924018;6287.2140982377205;7781.144074151952;7793.454458187873;7800.024996875391;7821.335186509211;3887.8444862205197;4661.9976705008585;4720.12677654635;4742.288656701522;4755.595774359218;6010.909836352371;7180.7845764627145;7446.367058799846;7465.1788303287685;7481.738139165103;6249.593841385652;7076.469264805057;7123.572585644878;7192.244814294633;7218.494705370574;7131.893021604784;7143.749968751276;7158.713651453883;7158.749443769443;7185.694580492823;6975.162980442379;6978.581285122909;6997.7200911962955;7002.614856182959;7007.544509606859;6851.48613853953;7146.321099495747;7149.428045756431;7151.60666163366;7153.99844021827;6723.125993620798;6822.11312529368;6832.795016149524;6904.551204171713;6970.581765094111 17:06:19 INFO - PROCESS | 1943 | |5;yui.html;729.7081167532987;762.2415696382101;791.6239786101603;796.0924546017541;807.0275318241518;15632.687346253082;15839.287232074556;15861.968972016812;15897.841021589795;15898.920505397486;14481.275936203201;14486.130832150078;14494.927525362358;14525;14558.126512409237;761.4479502360772;773.5242825662233;927.5964955244457;933.4119504712039;952.2715122930963;948.0803620488116;950.4297421547051;961.2838435365662;963.7927845513213;965.4738167698586;975.3465178330536;1011.3274672342883;1023.8361862102065;1026.5483187397522;1029.830078037123;800.383704547498;813.1461964936793;815.6696537902159;816.33876559986;1012.9240306976969;743.2418932688628;747.2826086956528;747.667288056815;748.4349316266239;750.7635094945075;12747.980161587051;15418.997765145341;15589.922050389789;15603.687926241526;15801.841981580152;13054.477820887154;13397.464101436028;13487.284128801055;13601;13683.931580342034;10130.594776208944;10332.63835765752;10333;10582.9470852646;10788.352698838016;3546.2198316370614;3578.320119177348;3609.06164397256;3647.106458917582;3812.8474861005525;84597.57701211455;84952;84966.57516712439;86400.56799716024;86516.8370790729;9448.811023779623;9483.525823708787;9595.808083838285;9828.4102953823;9962.402255864672;22040.559188816133;22255.22106726257;22266.10935562592;22267.22064727759;22379.77620223777;7727.65225564851;7769.339606133429;8074.798130046795;8082.959585202037;8160.224778646019;4244.427002354704;4257.510386305553;4274.487061552633;4314.22343978087;4363.345498175299;3803.4675145479655;3829.7893615851394;3855.9421608675893;3856.0745421098736;3858.459815625815;678.25392068725;678.6844117485294;686.2828344380134;697.5047716121509;702.2415790945767;597.5100417657479;599.8260504453747;609.5245708347496;630.8233694565529;635.1933045032782;7166.605836678928;7968.7609371718945;8286.212809783061;8373.330133589297;8436.78908027292;501.8795489082594;605.5246631394423;614.1923370767427;614.2996983438883;622.9252489701174;3938.4092386141733;3956.9208615827524;4000.4199391088127;4057.918841623151;4060.898477538026;89775;94265.58601620981;99429;99449;99955;76522;77101.6869493916;77508;84765.30469390578;85297;73987;74499;74848.25151748412;74857.25142748612;76022.61988690021;7584.810379740551;8715.782105447415;8866;8932.151445612657;9259.888813342443;36274;47586;48103.518964809904;48731.75634121807;50187;62385.68807155935;65682.67158664177;67410.32589674236;67668;69092.654536727;10799.244052916534;10983.231173817756;11154.330740155616;11276.548938042386;11359.148063895076;11757.529698812294;11777.941110294394;11916.880831191576;11975.520979161085;12600.684982875135;11214.943925280322;11242.718932026764;11397.829032564352;11479.713007174887;11797.351145686725;11280.435978201222;11509.194356395306;11563.132765042486;11673.941630292135;11874.703132421759;10985.285956412808;11580.42097895085;11595.826062609236;11623.360715160408;11685.76628467443;9437.245020398488;9674.564644591152;9737.853932191165;9830.4101753895;10178.389296642225;9713.174380177481;9718.951405242928;9737.951310243687;9805.41167529922;9828.950855245677 17:06:19 INFO - PROCESS | 1943 | __end_tp_report 17:06:19 INFO - PROCESS | 1943 | __start_cc_report 17:06:19 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,1485 17:06:19 INFO - PROCESS | 1943 | __end_cc_report 17:06:19 INFO - PROCESS | 1943 | __startTimestamp1466726779168__endTimestamp 17:06:19 INFO - PROCESS | 1943 | 17:06:19 INFO - PROCESS | 1943 | ------- Summary: start ------- 17:06:19 INFO - PROCESS | 1943 | Number of tests: 6 17:06:19 INFO - PROCESS | 1943 | 17:06:19 INFO - PROCESS | 1943 | [#0] dojo.html Cycles:165 Average:5760.35 Median:6019.73 stddev:649.68 (10.8%) stddev-sans-first:608.96 17:06:19 INFO - PROCESS | 1943 | Values: 2806.7 3535.6 3537.0 3562.8 3608.2 5128.8 5194.5 5729.7 5757.3 5764.2 5672.3 5718.5 5729.0 5732.5 5736.3 5196.5 5197.0 5198.2 5226.6 5255.3 5245.8 5293.8 5295.9 5303.1 5304.9 5195.7 5251.8 5268.8 5278.6 5289.3 5279.6 5322.0 5322.1 5334.0 5345.9 5143.2 5153.9 5172.0 5246.3 5247.0 5597.9 5641.9 5653.0 5656.8 5675.2 6233.5 6258.6 6264.2 6265.9 6270.2 6116.2 6171.0 6239.0 6239.8 6242.3 5855.4 5877.1 5885.4 5889.0 5905.1 6260.7 6293.2 6308.5 6310.6 6319.1 6081.3 6141.5 6186.9 6200.9 6217.3 6231.3 6240.4 6252.3 6265.5 6267.5 6184.2 6235.3 6246.7 6252.0 6261.2 6127.0 6176.3 6236.7 6248.7 6272.4 4941.1 4975.5 4977.4 4983.8 4997.8 4484.5 4495.3 4495.5 4499.1 4521.0 4905.8 5807.2 5878.6 5922.1 5990.4 6179.8 6204.0 6205.6 6219.7 6219.8 5877.4 5891.5 5913.6 5916.0 5933.9 4454.0 5387.1 5446.6 5462.4 5485.1 6307.0 6375.2 6378.1 6403.4 6416.9 6170.6 6201.3 6235.5 6242.5 6246.5 6326.3 6380.5 6408.0 6418.7 6429.5 5151.0 6193.9 6206.3 6248.7 6255.7 6132.2 6146.0 6216.2 6242.7 6275.8 6162.7 6198.6 6208.5 6211.6 6220.5 6137.8 6148.0 6194.3 6194.8 6202.4 5898.6 6018.7 6019.7 6065.9 6118.4 4966.3 5977.7 6048.1 6119.1 6125.8 5982.6 6019.7 6047.9 6075.1 6078.7 17:06:19 INFO - PROCESS | 1943 | 17:06:19 INFO - PROCESS | 1943 | [#1] ext.html Cycles:180 Average:66243.68 Median:3537.97 stddev:143485.03 (4055.6%) stddev-sans-first:143802.68 17:06:19 INFO - PROCESS | 1943 | Values: 514.6 534.5 545.9 552.6 558.7 26483.0 32958.8 41119.6 41939.4 42217.4 45276.0 45689.0 45756.0 45849.0 45907.8 9639.9 9857.0 9879.7 9891.2 9893.0 9147.5 11903.4 12133.6 12180.3 12180.9 11834.5 11895.3 11910.2 11911.4 11925.8 11817.6 11881.0 11895.5 11901.6 11907.9 11683.3 11806.4 11807.5 11824.5 11828.6 43152.4 43692.8 43713.8 43745.0 43755.1 3303.0 3397.3 3466.2 3500.6 3528.7 29267.6 29845.7 31329.7 31439.2 31580.7 2792.1 2815.6 2826.1 2831.0 2879.0 449067.0 526533.0 531546.0 531602.0 532801.0 38138.0 38682.4 38714.0 38790.6 71139.0 37183.0 37491.4 37624.8 37704.2 37899.2 16308.3 16468.9 16485.2 16497.9 16513.0 12922.8 13017.0 13733.6 13814.3 14398.0 8624.3 8650.8 8665.1 8712.1 8721.0 3499.3 3537.2 3538.8 3548.8 3574.2 985.0 997.0 1055.2 1145.4 1171.3 26565.4 26622.7 30968.2 31792.8 32329.4 1112.5 1162.3 1163.2 1201.2 1217.3 16159.1 16393.9 16452.0 16474.3 16512.2 653283.0 655482.0 662186.0 662680.0 664524.0 66236.7 66890.0 66960.3 66977.3 67300.3 1442.2 1483.1 1561.3 1562.5 1563.1 24204.4 30656.7 30677.8 31118.1 31377.5 54701.7 54935.7 55006.7 55072.4 55141.0 400021.0 404310.0 405389.0 405984.9 406196.0 24114.0 28409.0 29219.4 29538.1 29960.6 49379.0 49457.8 50626.5 50635.0 51210.0 39933.6 40654.4 40694.8 40971.8 42097.6 33520.8 40408.4 40509.6 40913.0 41821.4 40086.8 40913.6 41036.2 41863.6 41903.6 30450.7 33454.0 34125.8 35351.1 35702.3 22438.0 26554.6 26591.9 26668.7 26792.7 17:06:19 INFO - PROCESS | 1943 | 17:06:19 INFO - PROCESS | 1943 | [#2] jquery.html Cycles:180 Average:37335.42 Median:2355.37 stddev:112207.26 (4763.9%) stddev-sans-first:112487.78 17:06:19 INFO - PROCESS | 1943 | Values: 411.1 411.5 416.3 421.5 427.6 19606.4 20212.6 20966.5 20995.8 21130.0 19607.0 19966.0 20282.0 20407.0 20411.9 5920.2 6010.2 6026.8 6030.5 6040.8 5840.7 6053.4 6073.0 6076.8 6079.9 5776.3 6032.2 6035.6 6042.3 6051.3 5937.1 5977.2 5998.0 6002.0 6037.2 5276.5 5622.9 5903.7 5916.0 5923.4 13829.0 13949.4 14019.4 21916.8 26288.2 2170.9 2192.6 2273.1 2318.7 2392.0 10069.0 10085.7 10474.2 13171.7 14149.5 1786.6 1787.8 1810.2 1811.2 1851.4 104219.5 118632.4 119278.0 122335.4 128030.0 11636.8 11730.4 11734.3 11766.7 11907.6 14717.8 14870.6 14871.8 14876.3 14973.7 6854.7 6962.9 8890.9 9167.6 9272.9 9182.6 9213.1 9237.8 9315.2 9324.9 4220.9 4247.6 4696.2 4707.2 4757.6 1799.9 1950.8 1996.9 2006.7 2015.6 927.7 964.8 975.9 988.4 1045.9 19511.3 19902.2 20524.6 20616.7 21444.9 974.8 991.5 998.2 1018.8 1095.9 10126.2 10433.4 10650.5 10679.7 10732.9 666924.0 670934.0 672303.0 674806.0 686992.0 109280.9 136492.0 138353.3 141611.0 144445.0 1056.1 1060.4 1088.2 1138.2 1139.1 18129.0 18141.8 18177.5 18239.6 18975.2 55110.4 56021.0 56351.0 57074.6 58105.4 82575.2 83311.0 83481.0 84408.6 85103.3 7947.8 8011.8 8069.5 8270.4 8382.7 9315.8 11304.7 12176.8 12177.8 13051.7 8070.8 8095.7 8137.3 11610.9 11711.0 6788.7 7682.7 7869.1 8009.1 8069.2 8202.8 8386.5 8489.2 8497.0 8738.5 9685.9 12379.8 12386.9 12521.8 12767.6 11884.2 11896.0 12039.0 12077.3 12131.5 17:06:19 INFO - PROCESS | 1943 | 17:06:19 INFO - PROCESS | 1943 | [#3] mootools.html Cycles:180 Average:5838.31 Median:5038.85 stddev:3261.67 (64.7%) stddev-sans-first:3253.54 17:06:19 INFO - PROCESS | 1943 | Values: 1370.5 1436.6 1587.0 1806.6 1990.6 8198.3 8809.5 8948.7 8959.8 8963.9 8669.9 8913.2 8930.1 8932.2 8953.1 2540.1 2566.8 2570.5 2571.5 2575.5 1863.0 2132.6 2215.4 2229.6 2239.9 2520.5 2555.3 2559.9 2568.0 2569.5 2532.9 2539.8 2551.9 2560.1 2573.5 2513.7 2549.0 2562.0 2567.4 2567.9 8835.9 9084.7 9168.9 9173.9 9183.9 5578.2 5764.6 5792.9 5799.7 5804.1 3554.6 3642.5 3645.2 3722.7 3769.6 1046.1 1051.2 1053.9 1068.6 1070.9 12109.9 12206.1 12376.0 12421.0 12424.9 4729.8 4808.9 4824.2 4881.8 4899.6 9645.1 9667.2 9676.8 9688.6 9693.5 5532.5 5554.4 5596.1 5601.1 5601.4 4564.9 4623.2 4693.0 4726.3 5206.1 4033.4 4064.0 4162.8 4170.7 4178.6 1239.7 1243.5 1245.3 1253.7 1259.8 901.1 906.3 953.8 962.1 1030.0 5916.1 5967.4 6021.8 6157.8 6209.2 944.7 968.2 978.1 981.6 991.3 3921.7 3979.0 3995.8 4020.0 4081.7 10811.8 11249.4 11295.6 11460.4 11466.2 9132.7 11244.0 11265.7 11267.0 11317.2 8449.6 8598.7 8598.8 8603.4 8670.9 5149.9 5780.1 5834.2 5962.3 6057.0 9743.6 9989.7 10106.6 10107.3 10113.4 9863.9 10247.3 10251.1 10304.8 10411.3 6598.8 7221.5 7260.0 7326.6 7352.4 7980.2 8323.6 8508.5 8516.7 8527.6 7447.9 7534.1 7569.7 7609.8 7717.3 7359.7 7380.3 7419.5 7448.9 7485.5 7078.5 7147.6 7162.7 7176.6 7215.9 4953.6 4954.4 4955.3 5049.9 5058.8 4983.8 5027.8 5100.5 5187.6 5330.4 17:06:19 INFO - PROCESS | 1943 | 17:06:19 INFO - PROCESS | 1943 | [#4] prototype.html Cycles:180 Average:5811.68 Median:6661.58 stddev:1857.00 (27.9%) stddev-sans-first:1816.13 17:06:19 INFO - PROCESS | 1943 | Values: 334.4 339.8 346.3 349.6 352.6 5789.4 6711.1 6712.2 6739.0 6782.9 5274.9 5794.8 6304.7 6650.3 6672.9 4415.9 4794.3 4819.0 4902.3 4907.0 4224.0 4799.5 4938.1 4944.7 4944.9 3968.0 4924.1 4928.7 4930.4 4937.1 4672.5 4749.3 4837.6 4850.4 4871.3 4177.2 4179.0 4191.8 4200.8 4205.3 5664.2 6277.1 6301.8 6334.6 6353.3 5819.2 5825.7 5826.2 5833.2 5847.8 6846.6 7037.3 7056.4 7069.8 7092.0 4776.5 4778.8 4787.5 4807.5 4810.6 6552.7 7063.1 7456.4 7512.8 7513.0 4823.3 4834.3 4850.2 4857.6 4861.0 4909.0 4928.7 4931.0 4934.9 4938.3 5634.1 5844.9 5857.0 5859.0 5861.7 7129.6 7138.4 7141.8 7147.4 7154.9 1494.7 1678.7 1719.0 1722.5 1722.7 1040.4 1055.8 1091.2 1095.7 1119.0 6774.0 6899.7 6901.0 6905.5 6925.8 7495.8 7499.3 7517.1 7530.7 7557.2 6865.8 6897.9 6899.4 6903.9 6905.5 3782.2 3908.7 3933.8 3944.6 3952.4 5653.4 7752.1 7760.2 7771.0 7832.3 7668.3 7671.4 7677.3 7681.1 7685.3 7786.3 7796.9 7822.9 7826.7 7861.9 5921.9 6808.2 7206.1 7468.7 7469.4 6882.2 7048.6 7226.4 7282.5 7309.9 6287.2 7781.1 7793.5 7800.0 7821.3 3887.8 4662.0 4720.1 4742.3 4755.6 6010.9 7180.8 7446.4 7465.2 7481.7 6249.6 7076.5 7123.6 7192.2 7218.5 7131.9 7143.7 7158.7 7158.7 7185.7 6975.2 6978.6 6997.7 7002.6 7007.5 6851.5 7146.3 7149.4 7151.6 7154.0 6723.1 6822.1 6832.8 6904.6 6970.6 17:06:19 INFO - PROCESS | 1943 | 17:06:19 INFO - PROCESS | 1943 | [#5] yui.html Cycles:180 Average:18767.76 Median:611.86 stddev:26583.16 (4344.7%) stddev-sans-first:26623.23 17:06:19 INFO - PROCESS | 1943 | Values: 729.7 762.2 791.6 796.1 807.0 15632.7 15839.3 15862.0 15897.8 15898.9 14481.3 14486.1 14494.9 14525.0 14558.1 761.4 773.5 927.6 933.4 952.3 948.1 950.4 961.3 963.8 965.5 975.3 1011.3 1023.8 1026.5 1029.8 800.4 813.1 815.7 816.3 1012.9 743.2 747.3 747.7 748.4 750.8 12748.0 15419.0 15589.9 15603.7 15801.8 13054.5 13397.5 13487.3 13601.0 13683.9 10130.6 10332.6 10333.0 10582.9 10788.4 3546.2 3578.3 3609.1 3647.1 3812.8 84597.6 84952.0 84966.6 86400.6 86516.8 9448.8 9483.5 9595.8 9828.4 9962.4 22040.6 22255.2 22266.1 22267.2 22379.8 7727.7 7769.3 8074.8 8083.0 8160.2 4244.4 4257.5 4274.5 4314.2 4363.3 3803.5 3829.8 3855.9 3856.1 3858.5 678.3 678.7 686.3 697.5 702.2 597.5 599.8 609.5 630.8 635.2 7166.6 7968.8 8286.2 8373.3 8436.8 501.9 605.5 614.2 614.3 622.9 3938.4 3956.9 4000.4 4057.9 4060.9 89775.0 94265.6 99429.0 99449.0 99955.0 76522.0 77101.7 77508.0 84765.3 85297.0 73987.0 74499.0 74848.3 74857.3 76022.6 7584.8 8715.8 8866.0 8932.2 9259.9 36274.0 47586.0 48103.5 48731.8 50187.0 62385.7 65682.7 67410.3 67668.0 69092.7 10799.2 10983.2 11154.3 11276.5 11359.1 11757.5 11777.9 11916.9 11975.5 12600.7 11214.9 11242.7 11397.8 11479.7 11797.4 11280.4 11509.2 11563.1 11673.9 11874.7 10985.3 11580.4 11595.8 11623.4 11685.8 9437.2 9674.6 9737.9 9830.4 10178.4 9713.2 9719.0 9738.0 9805.4 9829.0 17:06:19 INFO - PROCESS | 1943 | -------- Summary: end -------- 17:06:19 INFO - PROCESS | 1943 | 17:06:19 INFO - TEST-INFO | 1943: exit 0 17:06:19 INFO - TEST-OK | dromaeo_css | took 1112464ms 17:06:19 INFO - TEST-START | kraken 17:06:19 INFO - operating with platform_type : mac_ 17:06:19 INFO - Initialising browser for kraken test... 17:06:19 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpYhOBQD/profile http://localhost:49747/getInfo.html) 17:06:21 INFO - PROCESS | 1949 | __metrics Screen width/height:1600/1200 17:06:21 INFO - PROCESS | 1949 | colorDepth:24 17:06:21 INFO - PROCESS | 1949 | Browser inner width/height: 1024/689 17:06:21 INFO - PROCESS | 1949 | __metrics 17:06:21 INFO - PROCESS | 1949 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:06:21 ERROR - PROCESS | 1949 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:06:22 INFO - TEST-INFO | 1949: exit 0 17:06:22 INFO - Browser initialized. 17:06:22 INFO - Running cycle 1/1 for kraken test... 17:06:22 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpYhOBQD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:06:34 INFO - PROCESS | 1952 | RSS: Main: 160808960 17:06:34 INFO - PROCESS | 1952 | 17:06:36 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49747/tests/kraken/driver.html?testName=audio-beat-detection) 17:06:37 INFO - PROCESS | 1952 | RSS: Main: 223567872 17:06:37 INFO - PROCESS | 1952 | 17:06:40 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49747/tests/kraken/driver.html?testName=audio-dft) 17:06:41 INFO - PROCESS | 1952 | RSS: Main: 239108096 17:06:41 INFO - PROCESS | 1952 | 17:06:44 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49747/tests/kraken/driver.html?testName=audio-fft) 17:06:44 INFO - PROCESS | 1952 | RSS: Main: 323743744 17:06:44 INFO - PROCESS | 1952 | 17:06:47 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49747/tests/kraken/driver.html?testName=audio-oscillator) 17:06:47 INFO - PROCESS | 1952 | RSS: Main: 230232064 17:06:47 INFO - PROCESS | 1952 | 17:06:49 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49747/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:06:50 INFO - PROCESS | 1952 | RSS: Main: 230027264 17:06:50 INFO - PROCESS | 1952 | 17:06:53 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49747/tests/kraken/driver.html?testName=imaging-darkroom) 17:06:53 INFO - PROCESS | 1952 | RSS: Main: 267792384 17:06:53 INFO - PROCESS | 1952 | 17:06:56 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49747/tests/kraken/driver.html?testName=imaging-desaturate) 17:06:57 INFO - PROCESS | 1952 | RSS: Main: 270045184 17:06:57 INFO - PROCESS | 1952 | 17:06:59 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49747/tests/kraken/driver.html?testName=json-parse-financial) 17:07:00 INFO - PROCESS | 1952 | RSS: Main: 269148160 17:07:00 INFO - PROCESS | 1952 | 17:07:02 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49747/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:07:02 INFO - PROCESS | 1952 | RSS: Main: 260235264 17:07:02 INFO - PROCESS | 1952 | 17:07:04 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:07:05 INFO - PROCESS | 1952 | RSS: Main: 266366976 17:07:05 INFO - PROCESS | 1952 | 17:07:07 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:07:08 INFO - PROCESS | 1952 | RSS: Main: 270471168 17:07:08 INFO - PROCESS | 1952 | 17:07:10 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:07:11 INFO - PROCESS | 1952 | RSS: Main: 277684224 17:07:11 INFO - PROCESS | 1952 | 17:07:14 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:07:15 INFO - PROCESS | 1952 | RSS: Main: 274808832 17:07:15 INFO - PROCESS | 1952 | 17:07:16 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49747/tests/kraken/driver.html?testName=ai-astar) 17:07:17 INFO - PROCESS | 1952 | RSS: Main: 335736832 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | __start_tp_report 17:07:17 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 17:07:17 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 17:07:17 INFO - PROCESS | 1952 | |i|pagename|runs| 17:07:17 INFO - PROCESS | 1952 | |0;ai-astar;112;113;118;114;112;120;165;159;113;133 17:07:17 INFO - PROCESS | 1952 | |1;audio-beat-detection;154;121;123;197;145;172;151;114;166;117 17:07:17 INFO - PROCESS | 1952 | |2;audio-dft;187;186;184;183;184;185;204;264;191;184 17:07:17 INFO - PROCESS | 1952 | |3;audio-fft;69;79;72;155;74;82;86;90;128;56 17:07:17 INFO - PROCESS | 1952 | |4;audio-oscillator;93;90;139;80;82;90;123;79;76;123 17:07:17 INFO - PROCESS | 1952 | |5;imaging-gaussian-blur;89;85;88;85;87;83;89;85;87;84 17:07:17 INFO - PROCESS | 1952 | |6;imaging-darkroom;93;93;94;93;93;93;95;93;94;92 17:07:17 INFO - PROCESS | 1952 | |7;imaging-desaturate;85;83;86;86;83;98;124;83;86;86 17:07:17 INFO - PROCESS | 1952 | |8;json-parse-financial;57;64;63;62;63;63;63;79;57;56 17:07:17 INFO - PROCESS | 1952 | |9;json-stringify-tinderbox;60;62;60;72;72;67;65;63;64;69 17:07:17 INFO - PROCESS | 1952 | |10;stanford-crypto-aes;79;78;96;102;79;79;78;79;81;97 17:07:17 INFO - PROCESS | 1952 | |11;stanford-crypto-ccm;144;143;116;114;131;143;142;134;143;147 17:07:17 INFO - PROCESS | 1952 | |12;stanford-crypto-pbkdf2;166;164;163;143;193;217;163;173;211;206 17:07:17 INFO - PROCESS | 1952 | |13;stanford-crypto-sha256-iterative;68;72;57;66;72;88;95;98;86;66 17:07:17 INFO - PROCESS | 1952 | __end_tp_report 17:07:17 INFO - PROCESS | 1952 | __start_cc_report 17:07:17 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,2041 17:07:17 INFO - PROCESS | 1952 | __end_cc_report 17:07:17 INFO - PROCESS | 1952 | __startTimestamp1466726837147__endTimestamp 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | ------- Summary: start ------- 17:07:17 INFO - PROCESS | 1952 | Number of tests: 14 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#0] ai-astar Cycles:10 Average:125.90 Median:119.00 stddev:20.09 (16.9%) stddev-sans-first:20.67 17:07:17 INFO - PROCESS | 1952 | Values: 112.0 113.0 118.0 114.0 112.0 120.0 165.0 159.0 113.0 133.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#1] audio-beat-detection Cycles:10 Average:146.00 Median:152.50 stddev:27.46 (18.0%) stddev-sans-first:28.97 17:07:17 INFO - PROCESS | 1952 | Values: 154.0 121.0 123.0 197.0 145.0 172.0 151.0 114.0 166.0 117.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#2] audio-dft Cycles:10 Average:195.20 Median:186.50 stddev:24.97 (13.4%) stddev-sans-first:26.30 17:07:17 INFO - PROCESS | 1952 | Values: 187.0 186.0 184.0 183.0 184.0 185.0 204.0 264.0 191.0 184.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#3] audio-fft Cycles:10 Average:89.10 Median:76.50 stddev:29.89 (39.1%) stddev-sans-first:30.80 17:07:17 INFO - PROCESS | 1952 | Values: 69.0 79.0 72.0 155.0 74.0 82.0 86.0 90.0 128.0 56.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:81.00 stddev:22.38 (27.6%) stddev-sans-first:23.67 17:07:17 INFO - PROCESS | 1952 | Values: 93.0 90.0 139.0 80.0 82.0 90.0 123.0 79.0 76.0 123.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:2.10 (2.4%) stddev-sans-first:1.96 17:07:17 INFO - PROCESS | 1952 | Values: 89.0 85.0 88.0 85.0 87.0 83.0 89.0 85.0 87.0 84.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 17:07:17 INFO - PROCESS | 1952 | Values: 93.0 93.0 94.0 93.0 93.0 93.0 95.0 93.0 94.0 92.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#7] imaging-desaturate Cycles:10 Average:90.00 Median:86.00 stddev:12.72 (14.8%) stddev-sans-first:13.36 17:07:17 INFO - PROCESS | 1952 | Values: 85.0 83.0 86.0 86.0 83.0 98.0 124.0 83.0 86.0 86.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.00 stddev:6.48 (10.3%) stddev-sans-first:6.54 17:07:17 INFO - PROCESS | 1952 | Values: 57.0 64.0 63.0 62.0 63.0 63.0 63.0 79.0 57.0 56.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:66.00 stddev:4.48 (6.8%) stddev-sans-first:4.30 17:07:17 INFO - PROCESS | 1952 | Values: 60.0 62.0 60.0 72.0 72.0 67.0 65.0 63.0 64.0 69.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:79.00 stddev:9.50 (12.0%) stddev-sans-first:9.84 17:07:17 INFO - PROCESS | 1952 | Values: 79.0 78.0 96.0 102.0 79.0 79.0 78.0 79.0 81.0 97.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:143.00 stddev:11.93 (8.3%) stddev-sans-first:12.27 17:07:17 INFO - PROCESS | 1952 | Values: 144.0 143.0 116.0 114.0 131.0 143.0 142.0 134.0 143.0 147.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.90 Median:183.00 stddev:25.00 (13.7%) stddev-sans-first:26.00 17:07:17 INFO - PROCESS | 1952 | Values: 166.0 164.0 163.0 143.0 193.0 217.0 163.0 173.0 211.0 206.0 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - PROCESS | 1952 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.80 Median:79.00 stddev:13.90 (17.6%) stddev-sans-first:14.38 17:07:17 INFO - PROCESS | 1952 | Values: 68.0 72.0 57.0 66.0 72.0 88.0 95.0 98.0 86.0 66.0 17:07:17 INFO - PROCESS | 1952 | -------- Summary: end -------- 17:07:17 INFO - PROCESS | 1952 | 17:07:17 INFO - TEST-INFO | 1952: exit 0 17:07:17 INFO - TEST-OK | kraken | took 58195ms 17:07:17 INFO - SUITE-END | took 1170s 17:07:18 INFO - Completed test suite (00:19:31) 17:07:18 INFO - javascript benchmark 17:07:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2806.705295943925, 3535.57573091229, 3537.0, 3562.786232826029, 3608.188157664526, 5128.846009647837, 5194.454582268864, 5729.656220626765, 5757.309122905247, 5764.221830052941, 5672.347680016799, 5718.485336319741, 5729.026065568853, 5732.5127364173995, 5736.311642602883, 5196.480351964792, 5196.974015129919, 5198.168293073109, 5226.634135610509, 5255.343082114736, 5245.79016839326, 5293.841184764444, 5295.867603309909, 5303.071962406582, 5304.946950530503, 5195.66228195168, 5251.842444726645, 5268.841934741964, 5278.604104692145, 5289.259503669489, 5279.63042587019, 5321.973390133063, 5322.0686379883755, 5334.03987282289, 5345.946540534584, 5143.177091665353, 5153.896922061575, 5172.0, 5246.291750613656, 5246.97376513115, 5597.888042239173, 5641.915371269434, 5653.0, 5656.8020119295625, 5675.177099320616, 6233.470155036782, 6258.561900666955, 6264.1543391642, 6265.937340626581, 6270.153529273581, 6116.235470566179, 6170.96914515429, 6239.0, 6239.812805615838, 6242.344553821828, 5855.414458554195, 5877.147813567009, 5885.411458854123, 5889.0, 5905.055191169392, 6260.749570017239, 6293.1504246927, 6308.495320374359, 6310.558260921692, 6319.05214217871, 6081.300650425169, 6141.508679305645, 6186.938130618636, 6200.906986395207, 6217.347178546278, 6231.283402408781, 6240.375962403723, 6252.3122456529745, 6265.530085243625, 6267.4986001119805, 6184.245183156763, 6235.282942461675, 6246.718897649616, 6252.0, 6261.217347831521, 6126.975740326312, 6176.320604733476, 6236.656983865931, 6248.718807653667, 6272.435480806749, 4941.061198372297, 4975.477574854661, 4977.377827771528, 4983.800647974041, 4997.800087996439, 4484.529124441952, 4495.25828238337, 4495.505494395614, 4499.122671079173, 4520.977395113004, 4905.8037678492465, 5807.216025836458, 5878.647281163144, 5922.052471604608, 5990.430909063632, 6179.84550386244, 6203.96898015507, 6205.627662340274, 6219.689015549295, 6219.782307619212, 5877.412258774174, 5891.49922256613, 5913.586048976616, 5915.970420147958, 5933.910991335133, 4453.977730111394, 5387.057264978613, 5446.618736688394, 5462.371827239839, 5485.067538518461, 6307.0, 6375.234971803413, 6378.075179099097, 6403.423691867754, 6416.935830641727, 6170.598911070766, 6201.286852012076, 6235.532335074797, 6242.500599951902, 6246.469050130608, 6326.335734747785, 6380.521460890359, 6407.967960160169, 6418.679066046586, 6429.453496452854, 5150.97424512875, 6193.907091393543, 6206.255249369924, 6248.656323902229, 6255.687215639291, 6132.171952247831, 6146.016637338004, 6216.222972128484, 6242.687865606611, 6275.843103922439, 6162.691865406801, 6198.566100372931, 6208.4722798562625, 6211.627302361872, 6220.533459990428, 6137.7851775187655, 6148.047052706837, 6194.349593292639, 6194.752209911733, 6202.4417802398, 5898.557608179318, 6018.699065046818, 6019.72911219005, 6065.909011364922, 6118.357572455006, 4966.279889415942, 5977.731002104829, 6048.092786082123, 6119.103262359172, 6125.798903363081, 5982.581219314607, 6019.72911219005, 6047.9395206047375, 6075.058365953284, 6078.696065196634], "value": 5720.0386342287775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.6423235851084, 534.4521865088284, 545.9399466058735, 552.585560829378, 558.723431901209, 26483.0, 32958.83520582397, 41119.58880411199, 41939.37090943631, 42217.36673949893, 45276.0, 45689.0, 45756.0, 45849.0, 45907.77046114765, 9639.903600963971, 9856.965018673043, 9879.703608891708, 9891.208703303719, 9893.010698930086, 9147.451152930813, 11903.404829758521, 12133.575324863632, 12180.269183848954, 12180.939095304511, 11834.526618935231, 11895.345755983417, 11910.225835320676, 11911.40442977852, 11925.821112683318, 11817.64547063585, 11881.0, 11895.464704088337, 11901.642950711492, 11907.880921190765, 11683.299002059903, 11806.40967951599, 11807.468663910146, 11824.467898944567, 11828.645140645795, 43152.352714709305, 43692.781536092116, 43713.78143109296, 43745.0, 43755.124897501875, 3303.0, 3397.286569820341, 3466.185446420089, 3500.6149323574386, 3528.6647768461953, 29267.560986585217, 29845.70154298451, 31329.68670313313, 31439.21401964969, 31580.73677052915, 2792.0786140573564, 2815.6480439945008, 2826.053272153831, 2830.994996776156, 2879.0, 449067.0, 526533.0, 531546.0, 531602.0, 532801.0, 38138.04654883595, 38682.41976370357, 38714.0, 38790.6120938787, 71139.0, 37183.0, 37491.4376284356, 37624.811875940446, 37704.245915082094, 37899.24201515954, 16308.26612802403, 16468.917655411646, 16485.17574121289, 16497.91751041261, 16513.0, 12922.806157907638, 13017.023723220607, 13733.587992360246, 13814.309284535733, 14397.99214055006, 8624.310055195569, 8650.783730406665, 8665.13348665141, 8712.128787121237, 8720.956395217983, 3499.3001399720156, 3537.1510837399355, 3538.7876727396438, 3548.822558872046, 3574.2315402188656, 984.9901500985042, 997.001012969007, 1055.2402270365324, 1145.387217838469, 1171.292858104108, 26565.406075635525, 26622.733772662414, 30968.225794355323, 31792.841035794674, 32329.35341293161, 1112.477135746198, 1162.3026184289529, 1163.1741463560797, 1201.201201201209, 1217.3122185965078, 16159.111248881425, 16393.91803040977, 16452.0, 16474.26145233617, 16512.174391280627, 653283.0, 655482.0, 662186.0, 662680.0, 664524.0, 66236.66881665561, 66889.99665005029, 66960.33039669541, 66977.32090716415, 67300.32699673038, 1442.235615123986, 1483.1027228526802, 1561.3364320163657, 1562.5234303537302, 1563.0855949269805, 24204.39489012789, 30656.693433066277, 30677.77288908508, 31118.066458006295, 31377.529337059506, 54701.72649136729, 54935.72532137314, 55006.72496637491, 55072.44927550673, 55141.0, 400021.0, 404310.0, 405389.0, 405984.9401505947, 406196.0, 24114.035438582963, 28409.0, 29219.415611688073, 29538.113856584336, 29960.550591740706, 49379.0, 49457.752711236215, 50626.49373506218, 50634.98730025305, 51210.0, 39933.60066399299, 40654.39018414785, 40694.79652601718, 40971.79514102411, 42097.579024210594, 33520.83239583884, 40408.3938740925, 40509.59490405176, 40913.0, 41821.37267940922, 40086.79956600198, 40913.56802512017, 41036.1792764149, 41863.58136418597, 41903.58096419119, 30450.695493044786, 33454.0, 34125.82937085299, 35351.14271608883, 35702.28595428025, 22437.990290436643, 26554.601680975185, 26591.936322547655, 26668.733312666624, 26792.732072679024], "value": 19542.313278843325, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.12840777551594, 411.45687692246236, 416.25698128840025, 421.481577659479, 427.63864534468365, 19606.411807645756, 20212.59574808503, 20966.475838104037, 20995.790042099576, 21130.0, 19607.0, 19966.001699915018, 20281.985900704978, 20407.0, 20411.897940510313, 5920.171176035358, 6010.248718910137, 6026.758929642809, 6030.517558595302, 6040.788572399967, 5840.678762668052, 6053.364396738345, 6073.0, 6076.756929722806, 6079.908801367983, 5776.277965254343, 6032.185654936571, 6035.6076855004285, 6042.305134909498, 6051.27384713835, 5937.139114828369, 5977.16319715238, 5998.0, 6002.039673652216, 6037.215162028908, 5276.498732620395, 5622.859428514279, 5903.675297858616, 5916.0, 5923.407659234085, 13829.0, 13949.372278247503, 14019.369128389248, 21916.780832191635, 26288.21135365923, 2170.902309396081, 2192.550527141932, 2273.056681477185, 2318.698569186012, 2392.043182726906, 10069.043440873105, 10085.747856303578, 10474.214433917487, 13171.69495338312, 14149.504767333197, 1786.5980154465271, 1787.7664411555984, 1810.2216047099741, 1811.2030706489234, 1851.3612803582741, 104219.478902605, 118632.40683796612, 119278.0, 122335.38832305871, 128030.0, 11636.76726465466, 11730.4163937869, 11734.295942243492, 11766.705832354259, 11907.583234586747, 14717.779233311509, 14870.628234294012, 14871.776923346159, 14876.330565124379, 14973.70052598942, 6854.657267136623, 6962.885938249856, 8890.86663700045, 9167.587458564381, 9272.860907086399, 9182.586783594754, 9213.078692130734, 9237.815243695222, 9315.230106279922, 9324.8601270981, 4220.936685949713, 4247.617714405718, 4696.154692155376, 4707.152712511781, 4757.595604373598, 1799.9321533334758, 1950.8053439880134, 1996.8602197846012, 2006.7190593316948, 2015.6069566434405, 927.679950417105, 964.7925695975399, 975.8731364922656, 988.4316518002178, 1045.8535804987307, 19511.317103901296, 19902.203911843364, 20524.589508209752, 20616.69074963877, 21444.92775361257, 974.7806743482802, 991.504247876062, 998.2213873178932, 1018.760591261053, 1095.8904109588977, 10126.189904807597, 10433.373997560171, 10650.467476626138, 10679.679609611723, 10732.892671073345, 666924.0, 670934.0, 672303.0, 674806.0, 686992.0, 109280.90719092866, 136492.0, 138353.3082334582, 141611.0, 144445.0, 1056.0759335581367, 1060.4061725433783, 1088.1784252889177, 1138.1975707126455, 1139.1228753859482, 18129.0, 18141.818581814012, 18177.454676359732, 18239.635207296044, 18975.24099036023, 55110.44889551053, 56021.0, 56351.0, 57074.57313567194, 58105.41894581, 82575.17424825915, 83311.0, 83481.0, 84408.57795710982, 85103.29793404222, 7947.761567152995, 8011.759647210593, 8069.475484093515, 8270.37972152079, 8382.664693412195, 9315.813683726152, 11304.660860174208, 12176.817347739969, 12177.756444871231, 13051.673708157372, 8070.798230044061, 8095.676172953016, 8137.267645911897, 11610.883891161318, 11711.0, 6788.660566971533, 7682.6926922924695, 7869.134395216407, 8009.078955920144, 8069.152738962324, 8202.83594328122, 8386.496810191407, 8489.193526614961, 8496.957515212385, 8738.475691458532, 9685.854712179464, 12379.814302785646, 12386.876131238932, 12521.792995269365, 12767.61697149087, 11884.227525210834, 11896.0, 12039.03687704999, 12077.335746534025, 12131.45408456604], "value": 9609.818386874575, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.5271681269958, 1436.554668052903, 1587.0, 1806.6206096719689, 1990.6018796240755, 8198.344132469396, 8809.515476648781, 8948.686795962143, 8959.820803583925, 8963.910360896381, 8669.869951950726, 8913.153250441213, 8930.062343453941, 8932.151445612673, 8953.149450802179, 2540.0601777342363, 2566.781823545001, 2570.473053024135, 2571.524268010424, 2575.4720282342073, 1862.9627407451842, 2132.5841460915126, 2215.3575463115676, 2229.609818281794, 2239.9328020159423, 2520.4581015081753, 2555.3100662821025, 2559.948801023988, 2568.0, 2569.460413313197, 2532.911348102826, 2539.7968162546954, 2551.9106831260815, 2560.078371786165, 2573.4981678572685, 2513.6983561972497, 2548.961765573518, 2562.013624754464, 2567.396661784479, 2567.9229623111337, 8835.911640883573, 9084.7274581762, 9168.862467063, 9173.908260917373, 9183.943846457609, 5578.163275508666, 5764.596478246524, 5792.942070579283, 5799.681017544033, 5804.100364443518, 3554.5556805399324, 3642.453631955201, 3645.1798345372345, 3722.7207959403154, 3769.6230376962358, 1046.095127714529, 1051.1800795379615, 1053.9156867450586, 1068.6206396729126, 1070.855434516346, 12109.93945030278, 12206.08454365926, 12376.0, 12421.0, 12424.937875310656, 4729.787159577827, 4808.877318194589, 4824.155772739757, 4881.780319885614, 4899.559039686445, 9645.131938125602, 9667.226621870373, 9676.806463870824, 9688.564014619358, 9693.51532423376, 5532.529734972572, 5554.416786237387, 5596.104623260258, 5601.131824567198, 5601.383847776741, 4564.908701825945, 4623.242796888798, 4692.976535117303, 4726.338312636233, 5206.114960456732, 4033.354663253924, 4064.0246340878566, 4162.812673429642, 4170.687198460128, 4178.561251068655, 1239.6962744127748, 1243.502598960405, 1245.2715161630547, 1253.730447953676, 1259.8425196850394, 901.1124042817819, 906.3112034853559, 953.8330792111492, 962.1244667352815, 1030.0, 5916.083007133901, 5967.373425790316, 6021.819345419644, 6157.84605384869, 6209.161763162006, 944.7213072143734, 968.2157452463528, 978.1148061004802, 981.5877331520778, 991.3110388280076, 3921.686265098785, 3979.0, 3995.760254384746, 4019.9950012496874, 4081.69387295954, 10811.78376432467, 11249.43752812356, 11295.604653837076, 11460.369679667698, 11466.197366184288, 9132.721419001346, 11244.0, 11265.662030139109, 11267.042301404472, 11317.207795454238, 8449.57752112392, 8598.742037738877, 8598.828023439497, 8603.440776349516, 8670.86993695095, 5149.918517111449, 5780.104083867007, 5834.15404766315, 5962.25471816023, 6057.0077184440015, 9743.561539730586, 9989.700308990741, 10106.595736170471, 10107.292489525662, 10113.443760593238, 9863.852042219229, 10247.282690211614, 10251.077403033763, 10304.793904122025, 10411.323263987817, 6598.8350291243105, 7221.53060051095, 7260.0, 7326.560406375635, 7352.411807055316, 7980.162082981436, 8323.625436855476, 8508.489490630582, 8516.70191543293, 8527.616257268313, 7447.925520744723, 7534.0582427196605, 7569.659365328684, 7609.847803044019, 7717.344025757873, 7359.668814903449, 7380.298871607189, 7419.517731347445, 7448.925510744823, 7485.47601667878, 7078.504504684829, 7147.571145731273, 7162.677679504331, 7176.605286709281, 7215.891761623685, 4953.628477864247, 4954.355933728592, 4955.331030311006, 5049.873753156054, 5058.848234552822, 4983.825566105169, 5027.824026159214, 5100.464451232716, 5187.610929180251, 5330.413654498079], "value": 4701.232013890126, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.3847320929489, 339.8470688190315, 346.3177540245715, 349.6241540344129, 352.5628221005953, 5789.39211382805, 6711.127553418055, 6712.228093769212, 6738.955461903401, 6782.863398029617, 5274.947250527493, 5794.82615521534, 6304.747810087591, 6650.268470468244, 6672.899906501407, 4415.933760993587, 4794.280857871312, 4819.0, 4902.338184345103, 4906.950930490685, 4224.0, 4799.496052914446, 4938.135826230413, 4944.703317800925, 4944.87637809054, 3967.960320396802, 4924.064427758733, 4928.654994150411, 4930.4083509978855, 4937.061958227943, 4672.532746725335, 4749.311349854269, 4837.612990960714, 4850.442199147073, 4871.269309603553, 4177.206330797138, 4178.958210417887, 4191.811368488426, 4200.831966721327, 4205.306124489455, 5664.150377443375, 6277.089821975788, 6301.842453938642, 6334.556581039329, 6353.332900045475, 5819.214406055171, 5825.679587622679, 5826.242588463515, 5833.2416785817995, 5847.795327163529, 6846.5892046477375, 7037.33145351196, 7056.364927156581, 7069.762791511465, 7091.964540177266, 4776.546228108324, 4778.80884764609, 4787.473377928425, 4807.471178170398, 4810.591099756525, 6552.705128269239, 7063.117110361205, 7456.440766942501, 7512.8121796954965, 7512.962435187789, 4823.252395878644, 4834.323194752737, 4850.199717046718, 4857.635677324144, 4860.9513904861205, 4908.975455122773, 4928.654994150375, 4931.0, 4934.9013019739405, 4938.333325001151, 5634.098544232903, 5844.912326315109, 5857.004309267435, 5858.970705146447, 5861.677607731531, 7129.607871567114, 7138.357547820721, 7141.821454463576, 7147.392471639969, 7154.856902861914, 1494.7234761569146, 1678.6558755455103, 1718.957026074358, 1722.5176950453892, 1722.6985277576373, 1040.401768982838, 1055.8469021991768, 1091.1761619976867, 1095.7315457712912, 1118.972025699364, 6773.950037744157, 6899.689513971783, 6901.0, 6905.482088843256, 6925.826854328581, 7495.812604684927, 7499.2500749924575, 7517.060367454068, 7530.698772049166, 7557.224052347694, 6865.759698410531, 6897.862042759217, 6899.379055884994, 6903.896441553381, 6905.516613837083, 3782.1868298315994, 3908.687305015592, 3933.7639741615594, 3944.585818489075, 3952.3873799561115, 5653.378128405872, 7752.0697516298405, 7760.223977602308, 7770.961145194238, 7832.295093441618, 7668.271514206142, 7671.424643151786, 7677.30904218623, 7681.116671582839, 7685.269899359552, 7786.299233069051, 7796.883046754303, 7822.882656760153, 7826.686932522749, 7861.882071768814, 5921.940780592139, 6808.217055038734, 7206.135263768381, 7468.701251949861, 7469.439792015512, 6882.242953275036, 7048.577085374894, 7226.385757210696, 7282.45381596393, 7309.853802924018, 6287.2140982377205, 7781.144074151952, 7793.454458187873, 7800.024996875391, 7821.335186509211, 3887.8444862205197, 4661.9976705008585, 4720.12677654635, 4742.288656701522, 4755.595774359218, 6010.909836352371, 7180.7845764627145, 7446.367058799846, 7465.1788303287685, 7481.738139165103, 6249.593841385652, 7076.469264805057, 7123.572585644878, 7192.244814294633, 7218.494705370574, 7131.893021604784, 7143.749968751276, 7158.713651453883, 7158.749443769443, 7185.694580492823, 6975.162980442379, 6978.581285122909, 6997.7200911962955, 7002.614856182959, 7007.544509606859, 6851.48613853953, 7146.321099495747, 7149.428045756431, 7151.60666163366, 7153.99844021827, 6723.125993620798, 6822.11312529368, 6832.795016149524, 6904.551204171713, 6970.581765094111], "value": 5199.240655513121, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.7081167532987, 762.2415696382101, 791.6239786101603, 796.0924546017541, 807.0275318241518, 15632.687346253082, 15839.287232074556, 15861.968972016812, 15897.841021589795, 15898.920505397486, 14481.275936203201, 14486.130832150078, 14494.927525362358, 14525.0, 14558.126512409237, 761.4479502360772, 773.5242825662233, 927.5964955244457, 933.4119504712039, 952.2715122930963, 948.0803620488116, 950.4297421547051, 961.2838435365662, 963.7927845513213, 965.4738167698586, 975.3465178330536, 1011.3274672342883, 1023.8361862102065, 1026.5483187397522, 1029.830078037123, 800.383704547498, 813.1461964936793, 815.6696537902159, 816.33876559986, 1012.9240306976969, 743.2418932688628, 747.2826086956528, 747.667288056815, 748.4349316266239, 750.7635094945075, 12747.980161587051, 15418.997765145341, 15589.922050389789, 15603.687926241526, 15801.841981580152, 13054.477820887154, 13397.464101436028, 13487.284128801055, 13601.0, 13683.931580342034, 10130.594776208944, 10332.63835765752, 10333.0, 10582.9470852646, 10788.352698838016, 3546.2198316370614, 3578.320119177348, 3609.06164397256, 3647.106458917582, 3812.8474861005525, 84597.57701211455, 84952.0, 84966.57516712439, 86400.56799716024, 86516.8370790729, 9448.811023779623, 9483.525823708787, 9595.808083838285, 9828.4102953823, 9962.402255864672, 22040.559188816133, 22255.22106726257, 22266.10935562592, 22267.22064727759, 22379.77620223777, 7727.65225564851, 7769.339606133429, 8074.798130046795, 8082.959585202037, 8160.224778646019, 4244.427002354704, 4257.510386305553, 4274.487061552633, 4314.22343978087, 4363.345498175299, 3803.4675145479655, 3829.7893615851394, 3855.9421608675893, 3856.0745421098736, 3858.459815625815, 678.25392068725, 678.6844117485294, 686.2828344380134, 697.5047716121509, 702.2415790945767, 597.5100417657479, 599.8260504453747, 609.5245708347496, 630.8233694565529, 635.1933045032782, 7166.605836678928, 7968.7609371718945, 8286.212809783061, 8373.330133589297, 8436.78908027292, 501.8795489082594, 605.5246631394423, 614.1923370767427, 614.2996983438883, 622.9252489701174, 3938.4092386141733, 3956.9208615827524, 4000.4199391088127, 4057.918841623151, 4060.898477538026, 89775.0, 94265.58601620981, 99429.0, 99449.0, 99955.0, 76522.0, 77101.6869493916, 77508.0, 84765.30469390578, 85297.0, 73987.0, 74499.0, 74848.25151748412, 74857.25142748612, 76022.61988690021, 7584.810379740551, 8715.782105447415, 8866.0, 8932.151445612657, 9259.888813342443, 36274.0, 47586.0, 48103.518964809904, 48731.75634121807, 50187.0, 62385.68807155935, 65682.67158664177, 67410.32589674236, 67668.0, 69092.654536727, 10799.244052916534, 10983.231173817756, 11154.330740155616, 11276.548938042386, 11359.148063895076, 11757.529698812294, 11777.941110294394, 11916.880831191576, 11975.520979161085, 12600.684982875135, 11214.943925280322, 11242.718932026764, 11397.829032564352, 11479.713007174887, 11797.351145686725, 11280.435978201222, 11509.194356395306, 11563.132765042486, 11673.941630292135, 11874.703132421759, 10985.285956412808, 11580.42097895085, 11595.826062609236, 11623.360715160408, 11685.76628467443, 9437.245020398488, 9674.564644591152, 9737.853932191165, 9830.4101753895, 10178.389296642225, 9713.174380177481, 9718.951405242928, 9737.951310243687, 9805.41167529922, 9828.950855245677], "value": 7095.35143231498, "unit": "score"}], "name": "dromaeo_css", "value": 7557.448807822947, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 118.0, 114.0, 112.0, 120.0, 165.0, 159.0, 113.0, 133.0], "value": 125.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 121.0, 123.0, 197.0, 145.0, 172.0, 151.0, 114.0, 166.0, 117.0], "value": 146.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 184.0, 183.0, 184.0, 185.0, 204.0, 264.0, 191.0, 184.0], "value": 195.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 72.0, 155.0, 74.0, 82.0, 86.0, 90.0, 128.0, 56.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 139.0, 80.0, 82.0, 90.0, 123.0, 79.0, 76.0, 123.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 85.0, 87.0, 83.0, 89.0, 85.0, 87.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 95.0, 93.0, 94.0, 92.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 86.0, 83.0, 98.0, 124.0, 83.0, 86.0, 86.0], "value": 90.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 63.0, 62.0, 63.0, 63.0, 63.0, 79.0, 57.0, 56.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 60.0, 72.0, 72.0, 67.0, 65.0, 63.0, 64.0, 69.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 96.0, 102.0, 79.0, 79.0, 78.0, 79.0, 81.0, 97.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 143.0, 116.0, 114.0, 131.0, 143.0, 142.0, 134.0, 143.0, 147.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 163.0, 143.0, 193.0, 217.0, 163.0, 173.0, 211.0, 206.0], "value": 179.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 57.0, 66.0, 72.0, 88.0, 95.0, 98.0, 86.0, 66.0], "value": 76.8, "unit": "score"}], "name": "kraken", "value": 1528.5, "alertThreshold": 2.0}]} 17:07:18 INFO - Return code: 0 17:07:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:07:18 ERROR - # TBPL SUCCESS # 17:07:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:07:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:07:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:07:18 INFO - 2016-06-23 17:07:18.452 screenresolution[1956:11646] starting screenresolution argv=screenresolution get 17:07:18 INFO - 2016-06-23 17:07:18.455 screenresolution[1956:11646] Display 0: 1600x1200x32@60 17:07:18 INFO - 2016-06-23 17:07:18.461 screenresolution[1957:11649] starting screenresolution argv=screenresolution list 17:07:18 INFO - Available Modes on Display 0 17:07:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:07:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:07:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:07:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:07:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:07:18 INFO - Intel Iris: 17:07:18 INFO - Chipset Model: Intel Iris 17:07:18 INFO - Type: GPU 17:07:18 INFO - Bus: Built-In 17:07:18 INFO - VRAM (Dynamic, Max): 1536 MB 17:07:18 INFO - Vendor: Intel (0x8086) 17:07:18 INFO - Device ID: 0x0a2e 17:07:18 INFO - Revision ID: 0x0009 17:07:18 INFO - Displays: 17:07:18 INFO - Display: 17:07:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:07:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:07:18 INFO - Display Serial Number: 1600x1200 60 17:07:18 INFO - Main Display: Yes 17:07:18 INFO - Mirror: Off 17:07:18 INFO - Online: Yes 17:07:18 INFO - Rotation: Supported 17:07:18 INFO - Return code: 0 17:07:18 INFO - Running post-action listener: _resource_record_post_action 17:07:18 INFO - Running post-run listener: _resource_record_post_run 17:07:19 INFO - Total resource usage - Wall time: 1194s; CPU: 52.0%; Read bytes: 1216512; Write bytes: 495344640; Read time: 17; Write time: 1824 17:07:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 194540032; Write bytes: 195962368; Read time: 17637; Write time: 429 17:07:19 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 1216512; Write bytes: 298374656; Read time: 17; Write time: 1390 17:07:19 INFO - Running post-run listener: _upload_blobber_files 17:07:19 INFO - Blob upload gear active. 17:07:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:07:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:07:19 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:07:19 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:07:20 INFO - (blobuploader) - INFO - Open directory for files ... 17:07:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:07:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:07:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:07:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:07:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:07:21 INFO - (blobuploader) - INFO - Done attempting. 17:07:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:07:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:07:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:07:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:07:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:07:23 INFO - (blobuploader) - INFO - Done attempting. 17:07:23 INFO - (blobuploader) - INFO - Iteration through files over. 17:07:23 INFO - Return code: 0 17:07:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:07:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:07:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4decd15e6299138db324b6fd5e4ed8ae30c28164b22bbed9791d25ca85109736500ed5f4f634dc8e8330b4c221360074622c6d6c7ea8945737597e43b3d035a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f14cc642688ccb0f34687d13af12ee68326de9414bdd37f92c2208dcba0cbfccb308ab4870b07532993ec8f7794b3278495cad914cdd51c4b392b296b0f9995"} 17:07:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:07:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:07:23 INFO - Contents: 17:07:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4decd15e6299138db324b6fd5e4ed8ae30c28164b22bbed9791d25ca85109736500ed5f4f634dc8e8330b4c221360074622c6d6c7ea8945737597e43b3d035a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f14cc642688ccb0f34687d13af12ee68326de9414bdd37f92c2208dcba0cbfccb308ab4870b07532993ec8f7794b3278495cad914cdd51c4b392b296b0f9995"} 17:07:23 INFO - Running post-run listener: copy_logs_to_upload_dir 17:07:23 INFO - Copying logs to upload dir... 17:07:23 INFO - mkdir: /builds/slave/test/build/upload/logs 17:07:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.865397 ========= master_lag: 2.40 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-06-23 17:07:25.870028) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 17:07:25.875051) ========= 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.8udhIDrLih/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.EnN4EzRihj/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4decd15e6299138db324b6fd5e4ed8ae30c28164b22bbed9791d25ca85109736500ed5f4f634dc8e8330b4c221360074622c6d6c7ea8945737597e43b3d035a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f14cc642688ccb0f34687d13af12ee68326de9414bdd37f92c2208dcba0cbfccb308ab4870b07532993ec8f7794b3278495cad914cdd51c4b392b296b0f9995"} build_url:https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011273 build_url: 'https://queue.taskcluster.net/v1/task/UwI6viZxQ2StUcNp3N4WnQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4decd15e6299138db324b6fd5e4ed8ae30c28164b22bbed9791d25ca85109736500ed5f4f634dc8e8330b4c221360074622c6d6c7ea8945737597e43b3d035a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f14cc642688ccb0f34687d13af12ee68326de9414bdd37f92c2208dcba0cbfccb308ab4870b07532993ec8f7794b3278495cad914cdd51c4b392b296b0f9995"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-23 17:07:25.915432) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 17:07:25.915760) ========= 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.8udhIDrLih/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.EnN4EzRihj/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.005407 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-23 17:07:25.966569) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 17:07:25.966869) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-23 17:07:25.969200) ========= ========= Total master_lag: 2.57 =========