builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0052 starttime: 1466033321.46 results: success (0) buildid: 20160615150825 builduid: 668cb389cab34925acb4d2a53c0572db revision: 58590eb627383ea4df6dcbef985adefcac0dae91 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:41.457016) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:41.457423) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:41.457695) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:41.481195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:41.481457) ========= 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.yMPhQxvSox/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.IHrPoZynxC/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-15 16:28:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-06-15 16:28:41 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132741 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:41.635227) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:41.635523) ========= 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.yMPhQxvSox/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.IHrPoZynxC/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.028278 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:41.683624) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:41.683960) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 58590eb627383ea4df6dcbef985adefcac0dae91 --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 58590eb627383ea4df6dcbef985adefcac0dae91 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yMPhQxvSox/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.IHrPoZynxC/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-15 16:28:41,762 truncating revision to first 12 chars 2016-06-15 16:28:41,762 Setting DEBUG logging. 2016-06-15 16:28:41,762 attempt 1/10 2016-06-15 16:28:41,762 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/58590eb62738?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-15 16:28:42,406 unpacking tar archive at: mozilla-beta-58590eb62738/testing/mozharness/ program finished with exit code 0 elapsedTime=0.913672 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:42.651058) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:42.651407) ========= script_repo_revision: 58590eb627383ea4df6dcbef985adefcac0dae91 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:42.651880) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:42.652206) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-15 16:28:42.670501) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-06-15 16:28:42.670831) ========= /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.yMPhQxvSox/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.IHrPoZynxC/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:28:42 INFO - MultiFileLogger online at 20160615 16:28:42 in /builds/slave/test 16:28:42 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:28:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:28:42 INFO - {'append_to_log': False, 16:28:42 INFO - 'base_work_dir': '/builds/slave/test', 16:28:42 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:28:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:28:42 INFO - 'branch': 'Mozilla-Beta', 16:28:42 INFO - 'buildbot_json_path': 'buildprops.json', 16:28:42 INFO - 'config_files': ('talos/mac_config.py',), 16:28:42 INFO - 'default_actions': ('clobber', 16:28:42 INFO - 'read-buildbot-config', 16:28:42 INFO - 'download-and-extract', 16:28:42 INFO - 'populate-webroot', 16:28:42 INFO - 'create-virtualenv', 16:28:42 INFO - 'install', 16:28:42 INFO - 'run-tests'), 16:28:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:28:42 INFO - 'download_minidump_stackwalk': True, 16:28:42 INFO - 'download_symbols': 'ondemand', 16:28:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:28:42 INFO - 'tooltool.py': '/tools/tooltool.py', 16:28:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:28:42 INFO - '/tools/misc-python/virtualenv.py')}, 16:28:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:28:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:28:42 INFO - 'installer_path': 'installer.exe', 16:28:42 INFO - 'log_level': 'info', 16:28:42 INFO - 'log_name': 'talos', 16:28:42 INFO - 'log_to_console': True, 16:28:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:28:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:28:42 INFO - 'opt_config_files': (), 16:28:42 INFO - 'pip_index': False, 16:28:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:42 INFO - 'cmd': ('bash', 16:28:42 INFO - '-c', 16:28:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:42 INFO - 'enabled': True, 16:28:42 INFO - 'halt_on_failure': False, 16:28:42 INFO - 'name': 'check_screen_resolution'},), 16:28:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:28:42 INFO - 'cmd': ('bash', 16:28:42 INFO - '-c', 16:28:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:28:42 INFO - 'enabled': True, 16:28:42 INFO - 'halt_on_failure': False, 16:28:42 INFO - 'name': 'check_screen_resolution'},), 16:28:42 INFO - 'run_cmd_checks_enabled': True, 16:28:42 INFO - 'sps_profile': False, 16:28:42 INFO - 'sps_profile_interval': 0, 16:28:42 INFO - 'suite': 'dromaeojs', 16:28:42 INFO - 'system_bits': '32', 16:28:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:28:42 INFO - 'title': 't-yosemite-r7-0052', 16:28:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:28:42 INFO - 'use_talos_json': True, 16:28:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:28:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:28:42 INFO - 'work_dir': 'build'} 16:28:42 INFO - ##### 16:28:42 INFO - ##### Running clobber step. 16:28:42 INFO - ##### 16:28:42 INFO - Running pre-action listener: _resource_record_pre_action 16:28:42 INFO - Running main action method: clobber 16:28:42 INFO - rmtree: /builds/slave/test/build 16:28:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:28:44 INFO - Running post-action listener: _resource_record_post_action 16:28:44 INFO - ##### 16:28:44 INFO - ##### Running read-buildbot-config step. 16:28:44 INFO - ##### 16:28:44 INFO - Running pre-action listener: _resource_record_pre_action 16:28:44 INFO - Running main action method: read_buildbot_config 16:28:44 INFO - Using buildbot properties: 16:28:44 INFO - { 16:28:44 INFO - "project": "", 16:28:44 INFO - "product": "firefox", 16:28:44 INFO - "script_repo_revision": "production", 16:28:44 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 16:28:44 INFO - "repository": "", 16:28:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 16:28:44 INFO - "buildid": "20160615150825", 16:28:44 INFO - "pgo_build": "False", 16:28:44 INFO - "basedir": "/builds/slave/test", 16:28:44 INFO - "buildnumber": 37, 16:28:44 INFO - "slavename": "t-yosemite-r7-0052", 16:28:44 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:28:44 INFO - "platform": "yosemite_r7", 16:28:44 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:28:44 INFO - "branch": "mozilla-beta", 16:28:44 INFO - "repo_path": "releases/mozilla-beta", 16:28:44 INFO - "revision": "58590eb627383ea4df6dcbef985adefcac0dae91", 16:28:44 INFO - "stage_platform": "macosx64", 16:28:44 INFO - "builduid": "668cb389cab34925acb4d2a53c0572db", 16:28:44 INFO - "slavebuilddir": "test" 16:28:44 INFO - } 16:28:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 16:28:44 INFO - Running post-action listener: _resource_record_post_action 16:28:44 INFO - ##### 16:28:44 INFO - ##### Running download-and-extract step. 16:28:44 INFO - ##### 16:28:44 INFO - Running pre-action listener: _resource_record_pre_action 16:28:44 INFO - Running main action method: download_and_extract 16:28:44 INFO - mkdir: /builds/slave/test/build/tests 16:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:44 INFO - https://queue.taskcluster.net/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:28:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 16:28:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 16:28:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/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:28:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/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:28:46 INFO - Downloaded 1165 bytes. 16:28:46 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 16:28:46 INFO - Using the following test package requirements: 16:28:46 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 16:28:46 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 16:28:46 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 16:28:46 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:28:46 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 16:28:46 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 16:28:46 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 16:28:46 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 16:28:46 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 16:28:46 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 16:28:46 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 16:28:46 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 16:28:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:46 INFO - https://queue.taskcluster.net/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:28:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 16:28:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 16:28:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/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:28:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/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:28:48 INFO - Downloaded 18300366 bytes. 16:28:48 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:28:48 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:28:51 INFO - Return code: 0 16:28:51 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:28:51 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:28:51 INFO - https://queue.taskcluster.net/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:28:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 16:28:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 16:28:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/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:28:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/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:28:52 INFO - Downloaded 18300366 bytes. 16:28:52 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:28:52 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:28:56 INFO - Return code: 0 16:28:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:56 INFO - https://queue.taskcluster.net/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:28:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 16:28:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 16:28:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/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:28:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/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:28:57 INFO - Downloaded 10840145 bytes. 16:28:57 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:28:57 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:28:57 INFO - Return code: 0 16:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:28:57 INFO - https://queue.taskcluster.net/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 16:28:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 16:28:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 16:28:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 16:28:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DjgmIYz9TOGJYEuQzKRmOg/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:29:11 INFO - Downloaded 86246776 bytes. 16:29:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 16:29:11 INFO - mkdir: /builds/slave/test/properties 16:29:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:29:11 INFO - Writing to file /builds/slave/test/properties/build_url 16:29:11 INFO - Contents: 16:29:11 INFO - build_url:https://queue.taskcluster.net/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 16:29:12 INFO - Running post-action listener: _resource_record_post_action 16:29:12 INFO - Running post-action listener: set_extra_try_arguments 16:29:12 INFO - ##### 16:29:12 INFO - ##### Running populate-webroot step. 16:29:12 INFO - ##### 16:29:12 INFO - Running pre-action listener: _resource_record_pre_action 16:29:12 INFO - Running main action method: populate_webroot 16:29:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:29:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:29:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:29:12 INFO - '--tppagecycles', 16:29:12 INFO - '7'], 16:29:12 INFO - 'tests': ['tsvgm']}}, 16:29:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:29:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:29:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:29:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:29:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:29:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:29:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:12 INFO - 'talos_options': ['--disable-e10s'], 16:29:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:12 INFO - 'talos_options': ['--disable-e10s'], 16:29:12 INFO - 'tests': ['damp', 'tps']}, 16:29:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:12 INFO - 'tests': ['damp', 'tps']}, 16:29:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:29:12 INFO - 'tests': ['dromaeo_dom']}, 16:29:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:29:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:29:12 INFO - 'tests': ['a11yr', 16:29:12 INFO - 'ts_paint', 16:29:12 INFO - 'tpaint', 16:29:12 INFO - 'sessionrestore', 16:29:12 INFO - 'sessionrestore_no_auto_restore', 16:29:12 INFO - 'tabpaint']}, 16:29:12 INFO - 'other-e10s': {'tests': ['a11yr', 16:29:12 INFO - 'ts_paint', 16:29:12 INFO - 'tpaint', 16:29:12 INFO - 'sessionrestore', 16:29:12 INFO - 'sessionrestore_no_auto_restore', 16:29:12 INFO - 'tabpaint']}, 16:29:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:29:12 INFO - 'ts_paint', 16:29:12 INFO - 'tpaint', 16:29:12 INFO - 'sessionrestore', 16:29:12 INFO - 'sessionrestore_no_auto_restore', 16:29:12 INFO - 'tabpaint']}, 16:29:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:29:12 INFO - 'ts_paint', 16:29:12 INFO - 'tpaint', 16:29:12 INFO - 'sessionrestore', 16:29:12 INFO - 'sessionrestore_no_auto_restore', 16:29:12 INFO - 'tabpaint']}, 16:29:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:29:12 INFO - 'tests': ['a11yr', 16:29:12 INFO - 'ts_paint', 16:29:12 INFO - 'tpaint', 16:29:12 INFO - 'sessionrestore', 16:29:12 INFO - 'sessionrestore_no_auto_restore', 16:29:12 INFO - 'tabpaint']}, 16:29:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:29:12 INFO - 'tests': ['a11yr', 16:29:12 INFO - 'ts_paint', 16:29:12 INFO - 'tpaint', 16:29:12 INFO - 'sessionrestore', 16:29:12 INFO - 'sessionrestore_no_auto_restore', 16:29:12 INFO - 'tabpaint']}, 16:29:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:29:12 INFO - 'tests': ['tsvgx', 16:29:12 INFO - 'tsvgr_opacity', 16:29:12 INFO - 'tart', 16:29:12 INFO - 'tscrollx', 16:29:12 INFO - 'cart']}, 16:29:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:29:12 INFO - 'tsvgr_opacity', 16:29:12 INFO - 'tart', 16:29:12 INFO - 'tscrollx', 16:29:12 INFO - 'cart']}, 16:29:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:12 INFO - 'talos_options': ['--disable-e10s'], 16:29:12 INFO - 'tests': ['tp5o']}, 16:29:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:12 INFO - 'tests': ['tp5o']}, 16:29:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:12 INFO - 'talos_options': ['--disable-e10s', 16:29:12 INFO - '--xperf_path', 16:29:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:12 INFO - 'tests': ['tp5n']}, 16:29:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:12 INFO - 'talos_options': ['--xperf_path', 16:29:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:12 INFO - 'tests': ['tp5n']}}, 16:29:12 INFO - 'talos.zip': {'path': '', 16:29:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:29:12 INFO - Running post-action listener: _resource_record_post_action 16:29:12 INFO - ##### 16:29:12 INFO - ##### Running create-virtualenv step. 16:29:12 INFO - ##### 16:29:12 INFO - Running pre-action listener: _resource_record_pre_action 16:29:12 INFO - Running main action method: create_virtualenv 16:29:12 INFO - Creating virtualenv /builds/slave/test/build/venv 16:29:12 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:29:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:29:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:29:12 INFO - Using real prefix '/tools/python27' 16:29:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:29:13 INFO - Installing distribute.............................................................................................................................................................................................done. 16:29:16 INFO - Installing pip.................done. 16:29:16 INFO - Return code: 0 16:29:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:29:16 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:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:16 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:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:16 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 0x10887fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf394926b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10871ee00>, '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 0x108587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108719b20>, '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.yMPhQxvSox/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.IHrPoZynxC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:29:16 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:29:16 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:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yMPhQxvSox/Render', 16:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:16 INFO - 'HOME': '/Users/cltbld', 16:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:16 INFO - 'LOGNAME': 'cltbld', 16:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:16 INFO - 'MOZ_NO_REMOTE': '1', 16:29:16 INFO - 'NO_EM_RESTART': '1', 16:29:16 INFO - 'PAGER': '/bin/cat', 16:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:16 INFO - 'PWD': '/builds/slave/test', 16:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:16 INFO - 'SHELL': '/bin/bash', 16:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IHrPoZynxC/Listeners', 16:29:16 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 16:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:16 INFO - 'USER': 'cltbld', 16:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:16 INFO - 'XPC_FLAGS': '0x0', 16:29:16 INFO - 'XPC_SERVICE_NAME': '0', 16:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:16 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:29:16 INFO - Cleaning up... 16:29:16 INFO - Return code: 0 16:29:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:29:16 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:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:16 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:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:16 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 0x10887fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf394926b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10871ee00>, '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 0x108587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108719b20>, '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.yMPhQxvSox/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.IHrPoZynxC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:29:16 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:29:16 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:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yMPhQxvSox/Render', 16:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:16 INFO - 'HOME': '/Users/cltbld', 16:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:16 INFO - 'LOGNAME': 'cltbld', 16:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:16 INFO - 'MOZ_NO_REMOTE': '1', 16:29:16 INFO - 'NO_EM_RESTART': '1', 16:29:16 INFO - 'PAGER': '/bin/cat', 16:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:16 INFO - 'PWD': '/builds/slave/test', 16:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:16 INFO - 'SHELL': '/bin/bash', 16:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IHrPoZynxC/Listeners', 16:29:16 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 16:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:16 INFO - 'USER': 'cltbld', 16:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:16 INFO - 'XPC_FLAGS': '0x0', 16:29:16 INFO - 'XPC_SERVICE_NAME': '0', 16:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:16 INFO - Downloading/unpacking psutil>=0.7.1 16:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:29:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:19 INFO - Installing collected packages: psutil 16:29:19 INFO - Running setup.py install for psutil 16:29:19 INFO - building 'psutil._psutil_osx' extension 16:29:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:29:20 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:29:20 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:29:20 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:29:20 INFO - building 'psutil._psutil_posix' extension 16:29:20 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:29:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:29:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:29:20 INFO - ^ 16:29:20 INFO - 1 warning generated. 16:29:20 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:29:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:20 INFO - Successfully installed psutil 16:29:20 INFO - Cleaning up... 16:29:20 INFO - Return code: 0 16:29:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:21 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 0x10887fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf394926b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10871ee00>, '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 0x108587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108719b20>, '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.yMPhQxvSox/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.IHrPoZynxC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:29:21 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:29:21 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:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yMPhQxvSox/Render', 16:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:21 INFO - 'HOME': '/Users/cltbld', 16:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:21 INFO - 'LOGNAME': 'cltbld', 16:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:21 INFO - 'MOZ_NO_REMOTE': '1', 16:29:21 INFO - 'NO_EM_RESTART': '1', 16:29:21 INFO - 'PAGER': '/bin/cat', 16:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:21 INFO - 'PWD': '/builds/slave/test', 16:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:21 INFO - 'SHELL': '/bin/bash', 16:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IHrPoZynxC/Listeners', 16:29:21 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 16:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:21 INFO - 'USER': 'cltbld', 16:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:21 INFO - 'XPC_FLAGS': '0x0', 16:29:21 INFO - 'XPC_SERVICE_NAME': '0', 16:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:29: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:29: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:29: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:29: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:29: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:29: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:29:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:29:23 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:29:23 INFO - Installing collected packages: mozsystemmonitor 16:29:23 INFO - Running setup.py install for mozsystemmonitor 16:29:23 INFO - Successfully installed mozsystemmonitor 16:29:23 INFO - Cleaning up... 16:29:23 INFO - Return code: 0 16:29:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:23 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 0x10887fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf394926b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10871ee00>, '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 0x108587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108719b20>, '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.yMPhQxvSox/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.IHrPoZynxC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:29:23 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:29:23 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:29:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yMPhQxvSox/Render', 16:29:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:23 INFO - 'HOME': '/Users/cltbld', 16:29:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:23 INFO - 'LOGNAME': 'cltbld', 16:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:23 INFO - 'MOZ_NO_REMOTE': '1', 16:29:23 INFO - 'NO_EM_RESTART': '1', 16:29:23 INFO - 'PAGER': '/bin/cat', 16:29:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:23 INFO - 'PWD': '/builds/slave/test', 16:29:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:23 INFO - 'SHELL': '/bin/bash', 16:29:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IHrPoZynxC/Listeners', 16:29:23 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 16:29:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:23 INFO - 'USER': 'cltbld', 16:29:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:23 INFO - 'XPC_FLAGS': '0x0', 16:29:23 INFO - 'XPC_SERVICE_NAME': '0', 16:29:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:24 INFO - Downloading/unpacking blobuploader==1.2.4 16:29:24 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:29:24 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:29:24 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:29:24 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:29:24 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:29:24 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:29:26 INFO - Downloading blobuploader-1.2.4.tar.gz 16:29:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:29:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:29:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:29:27 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:29:27 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:29:27 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:29:27 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:29:27 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:29:27 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:29:27 INFO - Downloading docopt-0.6.1.tar.gz 16:29:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:29:27 INFO - Installing collected packages: blobuploader, requests, docopt 16:29:27 INFO - Running setup.py install for blobuploader 16:29:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:29:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:29:28 INFO - Running setup.py install for requests 16:29:28 INFO - Running setup.py install for docopt 16:29:28 INFO - Successfully installed blobuploader requests docopt 16:29:28 INFO - Cleaning up... 16:29:28 INFO - Return code: 0 16:29:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29: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:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:28 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 0x10887fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf394926b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10871ee00>, '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 0x108587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108719b20>, '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.yMPhQxvSox/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.IHrPoZynxC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:29:28 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:29:28 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:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yMPhQxvSox/Render', 16:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:28 INFO - 'HOME': '/Users/cltbld', 16:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:28 INFO - 'LOGNAME': 'cltbld', 16:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:28 INFO - 'MOZ_NO_REMOTE': '1', 16:29:28 INFO - 'NO_EM_RESTART': '1', 16:29:28 INFO - 'PAGER': '/bin/cat', 16:29:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:28 INFO - 'PWD': '/builds/slave/test', 16:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:28 INFO - 'SHELL': '/bin/bash', 16:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IHrPoZynxC/Listeners', 16:29:28 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 16:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:28 INFO - 'USER': 'cltbld', 16:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:28 INFO - 'XPC_FLAGS': '0x0', 16:29:28 INFO - 'XPC_SERVICE_NAME': '0', 16:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:29 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-yXBMvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:29 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-yaBbuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:29 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-UdNaCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:29 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-9jeJQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:29 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-5FYrVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:29 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ZDbdbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:29 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-DwSsNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:29 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Ivjt6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:29 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-4AVlVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:29 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-dSeBOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:30 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-M4r2lM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:30 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-EfrFzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:30 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-lVsSTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:30 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-lqr4V8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:30 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-_aM12V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:30 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-okPoii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:30 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-uFLuRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:29:30 INFO - Running setup.py install for manifestparser 16:29:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:29:30 INFO - Running setup.py install for mozcrash 16:29:31 INFO - Running setup.py install for mozdebug 16:29:31 INFO - Running setup.py install for mozdevice 16:29:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Running setup.py install for mozfile 16:29:31 INFO - Running setup.py install for mozhttpd 16:29:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Running setup.py install for mozinfo 16:29:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Running setup.py install for mozInstall 16:29:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:29:31 INFO - Running setup.py install for mozleak 16:29:32 INFO - Running setup.py install for mozlog 16:29:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Running setup.py install for moznetwork 16:29:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Running setup.py install for mozprocess 16:29:32 INFO - Running setup.py install for mozprofile 16:29:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Running setup.py install for mozrunner 16:29:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:29:32 INFO - Running setup.py install for mozscreenshot 16:29:33 INFO - Running setup.py install for moztest 16:29:33 INFO - Running setup.py install for mozversion 16:29:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:29:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:29:33 INFO - Cleaning up... 16:29:33 INFO - Return code: 0 16:29:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29: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:29:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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:29:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:33 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 0x10887fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf394926b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10871ee00>, '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 0x108587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108719b20>, '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.yMPhQxvSox/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.IHrPoZynxC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:29:33 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:29:33 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:29:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yMPhQxvSox/Render', 16:29:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:33 INFO - 'HOME': '/Users/cltbld', 16:29:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:33 INFO - 'LOGNAME': 'cltbld', 16:29:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:33 INFO - 'MOZ_NO_REMOTE': '1', 16:29:33 INFO - 'NO_EM_RESTART': '1', 16:29:33 INFO - 'PAGER': '/bin/cat', 16:29:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:33 INFO - 'PWD': '/builds/slave/test', 16:29:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:33 INFO - 'SHELL': '/bin/bash', 16:29:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IHrPoZynxC/Listeners', 16:29:33 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 16:29:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:33 INFO - 'USER': 'cltbld', 16:29:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:33 INFO - 'XPC_FLAGS': '0x0', 16:29:33 INFO - 'XPC_SERVICE_NAME': '0', 16:29:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:33 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-VvrhLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:33 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:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:33 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Cfu726-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:33 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:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:33 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-rg7K7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:33 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:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:33 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Mf2_gG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:34 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:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:34 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-VhkSNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:34 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:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:34 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ODMcLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:34 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:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:34 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-heb5SZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:34 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:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:34 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-h7n0o9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:34 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:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:34 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-W21ccJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:34 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:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:34 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-4WflF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:34 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:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:34 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-9Tf0HC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:34 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:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:34 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Lm4EWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:34 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:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:34 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ZaseLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:34 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:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:35 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-pJ1R72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:35 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:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:35 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-lqHZ9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:35 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:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:35 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Qji5VK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:35 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:29:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:35 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-8twlen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:35 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:29:38 INFO - Downloading blessings-1.6.tar.gz 16:29:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:29:38 INFO - Installing collected packages: blessings 16:29:38 INFO - Running setup.py install for blessings 16:29:38 INFO - Successfully installed blessings 16:29:38 INFO - Cleaning up... 16:29:38 INFO - Return code: 0 16:29:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:29:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:38 INFO - Reading from file tmpfile_stdout 16:29:38 INFO - Current package versions: 16:29:38 INFO - blessings == 1.6 16:29:38 INFO - blobuploader == 1.2.4 16:29:38 INFO - docopt == 0.6.1 16:29:38 INFO - manifestparser == 1.1 16:29:38 INFO - mozInstall == 1.12 16:29:38 INFO - mozcrash == 0.17 16:29:38 INFO - mozdebug == 0.1 16:29:38 INFO - mozdevice == 0.48 16:29:38 INFO - mozfile == 1.2 16:29:38 INFO - mozhttpd == 0.7 16:29:38 INFO - mozinfo == 0.9 16:29:38 INFO - mozleak == 0.1 16:29:38 INFO - mozlog == 3.1 16:29:38 INFO - moznetwork == 0.27 16:29:38 INFO - mozprocess == 0.22 16:29:38 INFO - mozprofile == 0.28 16:29:38 INFO - mozrunner == 6.11 16:29:38 INFO - mozscreenshot == 0.1 16:29:38 INFO - mozsystemmonitor == 0.0 16:29:38 INFO - moztest == 0.7 16:29:38 INFO - mozversion == 1.4 16:29:38 INFO - psutil == 3.1.1 16:29:38 INFO - requests == 1.2.3 16:29:38 INFO - wsgiref == 0.1.2 16:29:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:38 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:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:39 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 0x10887fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf394926b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10871ee00>, '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 0x108587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108719b20>, '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.yMPhQxvSox/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.IHrPoZynxC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:29:39 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:29:39 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:29:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yMPhQxvSox/Render', 16:29:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:39 INFO - 'HOME': '/Users/cltbld', 16:29:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:39 INFO - 'LOGNAME': 'cltbld', 16:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:39 INFO - 'MOZ_NO_REMOTE': '1', 16:29:39 INFO - 'NO_EM_RESTART': '1', 16:29:39 INFO - 'PAGER': '/bin/cat', 16:29:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:39 INFO - 'PWD': '/builds/slave/test', 16:29:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:39 INFO - 'SHELL': '/bin/bash', 16:29:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IHrPoZynxC/Listeners', 16:29:39 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 16:29:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:39 INFO - 'USER': 'cltbld', 16:29:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:39 INFO - 'XPC_FLAGS': '0x0', 16:29:39 INFO - 'XPC_SERVICE_NAME': '0', 16:29:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 INFO - Cleaning up... 16:29:39 INFO - Return code: 0 16:29:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:29:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:39 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 0x10887fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf394926b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10871ee00>, '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 0x108587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108719b20>, '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.yMPhQxvSox/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.IHrPoZynxC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:29:39 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:29:39 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:29:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yMPhQxvSox/Render', 16:29:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:39 INFO - 'HOME': '/Users/cltbld', 16:29:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:39 INFO - 'LOGNAME': 'cltbld', 16:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:39 INFO - 'MOZ_NO_REMOTE': '1', 16:29:39 INFO - 'NO_EM_RESTART': '1', 16:29:39 INFO - 'PAGER': '/bin/cat', 16:29:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:39 INFO - 'PWD': '/builds/slave/test', 16:29:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:39 INFO - 'SHELL': '/bin/bash', 16:29:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IHrPoZynxC/Listeners', 16:29:39 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 16:29:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:39 INFO - 'USER': 'cltbld', 16:29:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:39 INFO - 'XPC_FLAGS': '0x0', 16:29:39 INFO - 'XPC_SERVICE_NAME': '0', 16:29:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:39 INFO - Downloading/unpacking jsonschema 16:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:39 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:29:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:29:42 INFO - Downloading/unpacking functools32 (from jsonschema) 16:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 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:29:42 INFO - Downloading functools32-3.2.3-2.tar.gz 16:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:29:42 INFO - warning: no files found matching '*.txt' 16:29:42 INFO - no previously-included directories found matching 'build' 16:29:42 INFO - no previously-included directories found matching 'dist' 16:29:42 INFO - no previously-included directories found matching '.git*' 16:29:42 INFO - Installing collected packages: jsonschema, functools32 16:29:42 INFO - Running setup.py install for functools32 16:29:42 INFO - warning: no files found matching '*.txt' 16:29:42 INFO - no previously-included directories found matching 'build' 16:29:42 INFO - no previously-included directories found matching 'dist' 16:29:42 INFO - no previously-included directories found matching '.git*' 16:29:42 INFO - Successfully installed jsonschema functools32 16:29:42 INFO - Cleaning up... 16:29:42 INFO - Return code: 0 16:29:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:29: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:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29: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:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:42 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 0x10887fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf394926b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10871ee00>, '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 0x108587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108719b20>, '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.yMPhQxvSox/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.IHrPoZynxC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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:29:42 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:29:42 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:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yMPhQxvSox/Render', 16:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:29:42 INFO - 'HOME': '/Users/cltbld', 16:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:29:42 INFO - 'LOGNAME': 'cltbld', 16:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:42 INFO - 'MOZ_NO_REMOTE': '1', 16:29:42 INFO - 'NO_EM_RESTART': '1', 16:29:42 INFO - 'PAGER': '/bin/cat', 16:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:42 INFO - 'PWD': '/builds/slave/test', 16:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:29:42 INFO - 'SHELL': '/bin/bash', 16:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IHrPoZynxC/Listeners', 16:29:42 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 16:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:29:42 INFO - 'USER': 'cltbld', 16:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:42 INFO - 'XPC_FLAGS': '0x0', 16:29:42 INFO - 'XPC_SERVICE_NAME': '0', 16:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:43 INFO - Downloading/unpacking flake8 16:29:43 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:29:43 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:29:43 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:29:43 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:29:43 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:29:43 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:29:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:29:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:29:45 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:29:45 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:29:45 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:29:45 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:29:45 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:29:45 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:29:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:29:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:29:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:29:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:29:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:29:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:29:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:29:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:29:46 INFO - no previously-included directories found matching 'docs/_build' 16:29:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:29:46 INFO - Running setup.py install for pep8 16:29:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:29:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:29:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:29:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:29:47 INFO - no previously-included directories found matching 'docs/_build' 16:29:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:29:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:29:47 INFO - Cleaning up... 16:29:47 INFO - Return code: 0 16:29:47 INFO - Running post-action listener: _resource_record_post_action 16:29:47 INFO - Running post-action listener: _start_resource_monitoring 16:29:47 INFO - Starting resource monitoring. 16:29:47 INFO - ##### 16:29:47 INFO - ##### Running install step. 16:29:47 INFO - ##### 16:29:47 INFO - Running pre-action listener: _resource_record_pre_action 16:29:47 INFO - Running main action method: install 16:29:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:47 INFO - Reading from file tmpfile_stdout 16:29:47 INFO - Detecting whether we're running mozinstall >=1.0... 16:29:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:29:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:29:47 INFO - Reading from file tmpfile_stdout 16:29:47 INFO - Output received: 16:29:47 INFO - Usage: mozinstall [options] installer 16:29:47 INFO - Options: 16:29:47 INFO - -h, --help show this help message and exit 16:29:47 INFO - -d DEST, --destination=DEST 16:29:47 INFO - Directory to install application into. [default: 16:29:47 INFO - "/builds/slave/test"] 16:29:47 INFO - --app=APP Application being installed. [default: firefox] 16:29:47 INFO - mkdir: /builds/slave/test/build/application 16:29:47 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:29:47 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:30:09 INFO - Reading from file tmpfile_stdout 16:30:09 INFO - Output received: 16:30:09 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:30:09 INFO - Running post-action listener: _resource_record_post_action 16:30:09 INFO - ##### 16:30:09 INFO - ##### Running run-tests step. 16:30:09 INFO - ##### 16:30:09 INFO - Running pre-action listener: _resource_record_pre_action 16:30:09 INFO - Running main action method: run_tests 16:30:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:30:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:30:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:30:09 INFO - 2016-06-15 16:30:09.780 screenresolution[1642:7532] starting screenresolution argv=screenresolution get 16:30:09 INFO - 2016-06-15 16:30:09.783 screenresolution[1642:7532] Display 0: 1600x1200x32@60 16:30:09 INFO - 2016-06-15 16:30:09.789 screenresolution[1643:7535] starting screenresolution argv=screenresolution list 16:30:09 INFO - Available Modes on Display 0 16:30:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:30:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:30:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:30:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:30:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:30:10 INFO - Intel Iris: 16:30:10 INFO - Chipset Model: Intel Iris 16:30:10 INFO - Type: GPU 16:30:10 INFO - Bus: Built-In 16:30:10 INFO - VRAM (Dynamic, Max): 1536 MB 16:30:10 INFO - Vendor: Intel (0x8086) 16:30:10 INFO - Device ID: 0x0a2e 16:30:10 INFO - Revision ID: 0x0009 16:30:10 INFO - Displays: 16:30:10 INFO - Display: 16:30:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:30:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:30:10 INFO - Display Serial Number: 1600x1200 60 16:30:10 INFO - Main Display: Yes 16:30:10 INFO - Mirror: Off 16:30:10 INFO - Online: Yes 16:30:10 INFO - Rotation: Supported 16:30:10 INFO - Return code: 0 16:30:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:30:10 INFO - Python 2.7.3 16:30:10 INFO - Return code: 0 16:30:10 INFO - grabbing minidump binary from tooltool 16:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:30:10 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 0x10871ee00>, '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 0x108587db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108719b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:30:10 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:30:10 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:30:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:30:10 INFO - Return code: 0 16:30:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:30:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:30:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:30:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:30:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:30:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:30:11 INFO - Return code: 0 16:30:11 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/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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:30:11 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/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --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:30:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yMPhQxvSox/Render', 16:30:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:30:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:30:11 INFO - 'HOME': '/Users/cltbld', 16:30:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:30:11 INFO - 'LOGNAME': 'cltbld', 16:30:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:30:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:30:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:11 INFO - 'MOZ_NO_REMOTE': '1', 16:30:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:30:11 INFO - 'NO_EM_RESTART': '1', 16:30:11 INFO - 'PAGER': '/bin/cat', 16:30:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:30:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:11 INFO - 'PWD': '/builds/slave/test', 16:30:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:30:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:30:11 INFO - 'SHELL': '/bin/bash', 16:30:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IHrPoZynxC/Listeners', 16:30:11 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 16:30:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:30:11 INFO - 'USER': 'cltbld', 16:30:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:30:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:30:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:11 INFO - 'XPC_FLAGS': '0x0', 16:30:11 INFO - 'XPC_SERVICE_NAME': '0', 16:30:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:30:11 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/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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:30:11 INFO - mozversion application_buildid: 20160615150825 16:30:11 INFO - mozversion application_changeset: 58590eb627383ea4df6dcbef985adefcac0dae91 16:30:11 INFO - mozversion application_display_name: Firefox 16:30:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:30:11 INFO - mozversion application_name: Firefox 16:30:11 INFO - mozversion application_remotingname: firefox 16:30:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:30:11 INFO - mozversion application_vendor: Mozilla 16:30:11 INFO - mozversion application_version: 48.0 16:30:11 INFO - mozversion platform_buildid: 20160615150825 16:30:11 INFO - mozversion platform_changeset: 58590eb627383ea4df6dcbef985adefcac0dae91 16:30:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:30:11 INFO - mozversion platform_version: 48.0 16:30:11 INFO - using testdate: 1466033411 16:30:11 INFO - actual date: 1466033411 16:30:11 INFO - starting webserver on 'localhost:49275' 16:30:11 INFO - SUITE-START | Running 2 tests 16:30:11 INFO - TEST-START | dromaeo_css 16:30:11 INFO - operating with platform_type : mac_ 16:30:11 INFO - Initialising browser for dromaeo_css test... 16:30:11 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmp_o6LPD/profile http://localhost:49275/getInfo.html) 16:30:13 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 16:30:13 INFO - PROCESS | 1661 | colorDepth:24 16:30:13 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 16:30:13 INFO - PROCESS | 1661 | __metrics 16:30:13 INFO - PROCESS | 1661 | 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:30:13 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:30:14 INFO - TEST-INFO | 1661: exit 0 16:30:14 INFO - Browser initialized. 16:30:14 INFO - Running cycle 1/1 for dromaeo_css test... 16:30:14 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmp_o6LPD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:26 INFO - PROCESS | 1664 | RSS: Main: 161603584 16:30:26 INFO - PROCESS | 1664 | 16:31:17 INFO - PROCESS | 1664 | 1466033477476 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:33:27 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 16:33:28 INFO - PROCESS | 1664 | RSS: Main: 288153600 16:33:28 INFO - PROCESS | 1664 | 16:36:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 16:36:32 INFO - PROCESS | 1664 | RSS: Main: 302870528 16:36:32 INFO - PROCESS | 1664 | 16:39:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 16:39:34 INFO - PROCESS | 1664 | RSS: Main: 311689216 16:39:34 INFO - PROCESS | 1664 | 16:42:36 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:42:37 INFO - PROCESS | 1664 | RSS: Main: 304615424 16:42:37 INFO - PROCESS | 1664 | 16:45:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:45:41 INFO - PROCESS | 1664 | RSS: Main: 281538560 16:45:41 INFO - PROCESS | 1664 | 16:48:43 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:48:43 INFO - PROCESS | 1664 | RSS: Main: 315981824 16:48:43 INFO - PROCESS | 1664 | 16:48:43 INFO - PROCESS | 1664 | __start_tp_report 16:48:43 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 16:48:43 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 16:48:43 INFO - PROCESS | 1664 | |i|pagename|runs| 16:48:43 INFO - PROCESS | 1664 | |0;dojo.html;3570.250247448036;3624.5831729351135;3626.8911932642013;3646.981765091174;3693.316736403764;5770.798022069229;5779.855503612412;5806.506446952014;5824.679642619654;5826.533877289818;5821.214136091637;5841.3574506804325;5848.356680765112;5849.766009359631;5863.061910094386;5195.350581177353;5198.168293073109;5215.530602245797;5259.31628888244;5285.286486324355;5276.92084618731;5317.627766056377;5334.946650533503;5335.5198032177095;5336.866578335534;5182.378114626231;5251.369835619712;5268.130758424855;5273;5302.3902251241025;5280.075986702312;5295.443978382253;5312.04252831639;5335.573154147698;5342.545883599899;5101.311322971388;5110.66780659256;5243.659162154448;5258.0535503609335;5263.815766448156;5577.637453565546;5637.098064309732;5651.632643878134;5661.179129026309;5685.914711279334;6167.722452489649;6171.13604095427;6187.412195841388;6208.565400421972;6217.844553886144;6100.877982440326;6156.230471191101;6209.9068513972325;6218.751249949996;6222.5644204905675;5845.88308233833;5877.764889404419;5883.0881213411985;5897.351291357947;5925.555583331181;6223.284322302993;6235.688215589294;6236.469900058455;6244.500439964792;6253.343398943137;6030.427109424598;6066.757329706762;6094.39056094387;6121;6136.815895523141;6174.691265436711;6177.660228687376;6188.195534580475;6199.070139479114;6208.875822483616;6100.359462256399;6109.663968481687;6127.448529632355;6156.07658851167;6192.566520343623;6107.370879189147;6116.571839971249;6156.199694039746;6168.383161683796;6182.443580077815;4956.801727930842;4975.154223781966;4984.37695288089;4988.950110498921;5021.372328458942;4461.219286624828;4478.126765280804;4487.439070116236;4488.820447182142;4510.368548403226;5015.448300686921;5801.970990145107;5926.407359264039;5941.079132734433;5948.643081415129;5251.711155886463;6168.784092556739;6175.536834737412;6180.47465965389;6189.81430557084;5959.731812068468;5963.224780778558;5977.252843394575;5980.730867110903;5986.431289027535;5366.356037275552;5385.5960802939935;5457.426970168154;5460.972695136499;5467.589930755209;6426.775062872777;6568.6058836469965;6572.704228309737;6580.276169621337;6580.374864387875;6115.296740874678;6174.320824709189;6179.073139029181;6186.443220110212;6205.503559715302;6373.458256048288;6599.241087274832;6627.602343859384;6630.436412904764;6640.50196235294;6033.064874944391;6119.755209791559;6128.203333566607;6180.536459765445;6186.19579454668;6149.569530132848;6150.692465376624;6151.815445536641;6161.661108639068;6168.290646575701;5924.526037917042;5986.191864098291;6013.458788709037;6043.576949613484;6058.799270598741;5880.500157486662;5970.492508136857;5975.43233392827;6037.094435834484;6064.575479716377;5926.614770039933;5998.34018258;6005.129256257906;6022.45797878193;6044.274687037584;6036.275646922398;6038.909416358671;6052.273727152769;6090.056041313604;6097.237845269341;5969.73136208863;5984.820455386345;6006.579539432198;6018.699065046818;6057.636541807506 16:48:43 INFO - PROCESS | 1664 | |1;ext.html;543.4891202269869;552.5634748548646;561.8595351162209;580.3673995345073;582.970851457427;26791;33074.007779766616;38991.83024509266;39413.80293098531;39431.211375772466;43507.56492435082;44004.33993490092;44013;44031;44084.33873491892;9758.951205243964;9957.850632240523;9958.751031224241;9971.302008859382;9977.75055623608;9186.816263674722;11885.821712674317;12129.272243665408;12131.150819442599;12160.51357945681;11850.170488065838;11950.7609847803;11957.701057473589;11966.940165299162;11969.700757481089;11846.7630647387;12019.098567607378;12028.977536909286;12040.157188996818;12045.879541204564;11775.528978840835;11844.88155118455;11886.881131188664;11929.403529823474;12149.696257593543;39827.40258896119;40100.19799603992;40186.39720404196;40219.39670904939;40241.79879100615;3252.6259480159847;3297.472404415282;3297.884574039921;3414.7609667323295;3428.9142771430666;27878.721212787816;27902.720972790215;27913.581296280572;28167;28781.712182878113;2688.3509758435807;2852.343960889007;2883.3656595548946;2887.595736596878;2911.213972227487;343503;345617.27191364137;346237;346524.267378664;347851.2607436947;63388;63978.68010659917;67428.6628566854;67430.651386972;67484.66257668681;70558;70587;70679.64660176667;70685.29314706889;70975.64512177405;17039.233234504478;19042.524471818575;19191;19208.615827683367;19214;12822.102452828212;12867.935660321638;12907.612771616867;14538;14829.40682372693;19332.553675494575;19761.90119049424;20174;20212.09045592952;20278.667953185573;7502.062242219723;8338.291245244221;8473.830523389497;8487.344967731357;8506.78733031679;1054.2567489919588;1056.693558867935;1061.7345663584103;1086.6305456144948;1206.5053328135423;28445;30028.54957175644;30560.08319750411;31068.844655776575;31917;912.2154946745793;1155.826626006089;1203.1337437045313;1238.9690257743628;1274.0826604844497;13484.25836578978;14852.183129927958;15471.762259019251;15654.765178522332;15863.920680396524;646540;654804;657055.7147214329;660338;660424;62638.686806565674;63104;63111;63328.36671633317;63424.682876586245;1496.91018538888;1548.3187397545043;1574.1656921831448;1588.3329001819495;1592.6575786206022;27113;29041.83832646755;29602.259943501584;29954.70045299606;30308.696913030588;48353;62633.373666262756;63814;63886.68056659687;64059.718805622695;472414;473824.6308768434;477922;478803;478880;37336.626633733315;44455.11089778251;44888.55111448844;45649.51650251973;47641;45706.54293457023;46293.53706462892;47268;47891;48359.758201208766;38759.418608721455;38917.41623875701;38951.63669271562;38960.80519597384;39380;38350.80824595859;38772.612273878025;38847.69648775382;38934.6106538931;39117;37828.05429864275;37869.62130378661;38341;38742.418863717634;38852.2229555413;33247.83632572848;33299;34303.4854477178;34361.48457773085;34652.30695386128;23143.958521866894;25162.119325823507;25476.872615636803;25836.483270334866;25922.35194120162 16:48:43 INFO - PROCESS | 1664 | |2;jquery.html;406.6705968165786;409.4308910614247;413.99586004139985;417.49274631322936;422.2020381479005;15293.69412611747;20778.6883196752;21439.035243414044;21450.141994320227;21544.784552154473;17350.652986940255;18056.729149062776;21070.15719371223;21483.03326350314;22050;5745.540356771469;5902.970485147579;6060.818175454743;6071.878562428748;6100.267967843843;5991.400859914017;5995.13070604762;6004.399560044004;6025.9096113558335;6043.063325184604;4158.60493253142;4377;5177.223416487519;6065.636061836281;6075.756969721206;6003.849903752419;6042.214512113441;6042.57701960863;6051.848703782397;6059.151718759377;5613.494785469327;5924.58527903047;5925.170476133345;5927.170196172539;5935.10973353996;13447.66380840477;13751.037427380088;15603.765943510856;25826.354341141432;25875.0943716969;2258.254775923941;2306.642470417088;2508.7742103210617;2586.79305655549;2890.4797136515417;8099.55452450115;9664.468454235015;9994.650187243484;10397.168226541859;10417.531211095518;1836.4398858348197;1882.3129557711475;1882.7552418185549;1903.200655724599;1914.1290712725677;116620;119404;120717.1533850297;124128;128808.71191288061;11736.88263117358;11798.764024719458;11807.114466415052;11819.645410637695;11825.645230642922;13875.583732488041;14052.086614370237;14057.22685252321;15210.619734506505;15255.771163432557;6438.967805160944;6482.643454610042;6525.2169739631545;9004.63981440735;9144.634214631473;9196.402233854911;9197.264218862609;9202.677906273248;9244;9283.860742088738;4672.57946784791;4739.312799644033;4747.881302967385;4758;4758.833440829554;1974.871633343828;2009.4674911148556;2025.6961455781457;2038.215287114442;2066.953715821356;937.6108914800418;997.2271489595621;1007.9042490963346;1035.1615191694752;1061.3313612424124;20556;21047.368578942656;21183.78816211818;21214.681779773317;21320.270122864094;975.7804493988796;1019.5310157327713;1046.638909576195;1079.6167360587035;1097.8243481042996;10718.678439646823;11232.438378081064;11253.66239012831;11258.493367798468;11269;637768;639294;648220.7588962025;656826;657227;128285;129784.351078244;129863;131768.3411582936;132593.33703331614;1047.9161667066464;1097.928634638746;1119.255694962857;1122.1752012270974;1123.7134530694784;18119.039952443778;18532.166052527937;18839.71740423922;18913;18924.14841332171;55563;55673;56838.431615683316;58016.12975805451;58814.705926470095;80510.389792205;83317.58341208255;88545.22909541902;91562;95393.5230323844;8048.87926681112;8183.590820458834;8290.58547072656;8405.243528082501;8683.86974195375;7509.586972716553;10900.400477973788;11712.653044899605;13111.475540978254;13258.867411325764;11550.653480395602;11579.131565132817;11631.47658355393;11658.88341116578;11895.940520297343;11192.104631629287;11481.081513479068;11677.474513646737;11679.883201167879;11745.530178792753;6162;8604.956975215084;9423.952880235829;11853.051755859684;11942.582009629621;8443.155684431507;8464.111268316738;8588.742337729878;8628.611712473079;8807.603657835285;9874.067418853618;11645.301281923112;11680.649580512598;11880.34658093813;11942.761144777229 16:48:43 INFO - PROCESS | 1664 | |3;mootools.html;1486.1900264355925;1615.4830454254638;1637.6724655068986;1958.6474434601776;2058.722072520209;7413.184549699528;8180.590970451476;8377.790555236123;8543.57282135894;8714.25928796053;8764.43031202973;8842.95578522108;8927.95536022319;8948.50783206925;8967.551622418869;2523.7602427769334;2546.8726563671835;2552.540542702313;2554.0805310088354;2558.5906254999204;2480.578301688717;2550.2731721459386;2554.348641096518;2557.4373637799654;2558.027949379233;2519.0805356045003;2552.668153140089;2556.7187609362954;2562.308176792265;2562.8206025578215;2511.2717311979595;2532.873356332176;2543.0844895837486;2550.4644024754825;2553.74462553745;2517.1064272183476;2540.047482194177;2549.592065269548;2555.3356127406823;2566.4995325911455;9008.909910900873;9034.86447703285;9043.773905652411;9047.140521650434;9086.363954523185;5538.169274608801;5556.444355564452;5687.800926967537;5702.77188912443;5732.254806875121;3625.456181572759;3736.1220113273357;3749.1189570450924;3780.281746468162;3783.9621603783885;1044.926855120142;1065.7495488560182;1095.6274866545414;1104.3705088099787;1118.2004866520397;11974.700632484257;12079.758404831855;12105.394730263451;12107.152499324962;12136.393180340947;4668.766561671903;4718.2214934536105;5097.235414687752;5105.183170692671;5116.181410974226;9414.764630884145;9453.574589143505;9467.621295148117;9561.426314421156;9580;5514.338279406497;5565.1374037024325;5567.638103523258;5573.637713548606;5575.107982722745;4606.055758569485;4678.7660616969015;4752.976235118871;5092.185250359925;5173.663711858717;4032.395140728914;4071.7353372030725;4085.7957102144774;4124.896877578085;4154.252234597741;1264.2351377416715;1266.1643315411968;1272.2748033620746;1281.88463038327;1285.0683254640312;913.3834661603391;951.923846092311;958.3195930889008;974.6052848596188;991.2020823237219;5868.94131058684;5946.583739138218;5986.790462333797;6002.639841609517;6154.261488621394;940.7836197674574;959.6209497248688;968.4334664221509;974.7465658928588;1030.124394264869;3522.0314413536485;3870.787106709102;3913.8630147944687;3932.5870783567884;4071.42999980003;10761;10868.510917008754;11088.112950963903;11240.49417776202;11251.606193783342;10829.783404331869;10996.725081873017;11010.064144547643;11016.449177541092;11018.834717479243;8055.113937466874;8357.164283571594;8397.790055248546;8646;8731.126887311346;5994.28068631767;6016.639001659914;6080.179175811209;6083.935311320412;6124.326324104434;7852.97911271531;9728.610855565981;9873.308868379145;9890.505474726378;9914.801703966024;9472.52637368115;9688.951555242178;9969.35199212049;10014.449205293779;10088.949555252177;7052.188998265266;7221.783346499614;7229.421646268181;7232.240614735377;7257.600831954293;8328.833423331378;8421.19998600132;8496.192861678132;8520.318374530147;8550.486970781772;7494.55032698018;7569.886451703339;7603.657835397531;7700.19147989453;7721.845563088595;7370.152432470334;7455.888161677471;7495.737649182252;7564.659590318559;7588.241175882367;7134;7169.318914703095;7189.604571748604;7205.279472052753;7214.567125972459;4860.659753817199;4922.433920099091;4987.576056035277;5075.264086707481;5075.9848030394805;4978.726170060681;4992.450830408579;5091.465396133352;5097.643164978416;5267.789288428573 16:48:43 INFO - PROCESS | 1664 | |4;prototype.html;327.0385067900373;331.4481388488167;337.3253493013973;339.5907930943214;342.4554957617391;6402.935970640298;6424.196975378078;6425.485961123117;6439.452646525046;6441.742330306782;6357.268914074883;6371.426571608555;6390.297067322603;6399.456046236076;6400.807975760723;4597.60920321773;4748.620110391161;4757.333973243749;4871.098846713369;4912.435069966964;4806.639502037361;4875.97604503053;4885.291632713254;4894.681845680037;4899.975500122495;4835.081334546425;4838.104950584136;4841.2496063110275;4853.296272040552;4858.076965376585;4324.762138082404;4464.062546865162;4587.816487340502;4624.260118381059;4816.638752093572;3600.225951420457;3971.9672885050095;4135.338345864678;4139.275626765304;4165.187788381267;6073.787417440412;6080.604760690541;6100.542459315525;6139;6153.01551751722;5556.194351818964;5610.831675049714;5611.466910643482;5661.2923384576925;5693.715314234313;6874.725010999554;6881.346272104142;6887.139107611549;6887.311268873123;6905.930940690579;3998.9200215995515;4209.94751312172;4311.202427550913;4466.1737578547745;4471.128130014616;6977;7180.17427995782;7267.600281984489;7273.054502914641;7280.8179795505;4584.312353147055;4589.380433641416;4592.586667199968;4592.724436533818;4594.33382159585;4163.562825903236;4457.175422546839;4628.722276663411;4644.3265726470145;4649.116667833101;5517.2551705519245;5524.14375771756;5526;5528.861778455571;5536.612437129363;5568.916466253009;6683.866322673616;6923.446124310144;6930.168379794457;6933.895991560131;1562.9765553516706;1578.952631421059;1589.9682006359808;1591.9283632236577;1594.8803839711836;964.71058682395;967.9758006049905;1005.5771083916177;1013.1489548779061;1027.2963020331097;6467.223933127961;6629.370209830058;6632.767853125118;6636;6667.533272670964;6121.204243448323;7175.497715159993;7186.784396468114;7188.353048225685;7198.136223653195;6656.201255849232;6660.134182556333;6676;6803.2856550062525;6829.248782633907;3738.4392341149046;3768.7550309229814;3770.868019619355;3771.717121215865;3773.7924414156937;7504.962475187589;7774;7784.805379865548;7831.021122359705;7839.804004899923;5989.460948514655;6319.55763096588;7124.358807707331;7405.5926924019695;7464;7726.613669316512;7743.225677432211;7767.456278060482;7779.2220777922885;7795.610219489116;6908.205556360882;7139.286071392819;7168.713251469882;7174.3184397482155;7183.676734546853;7076.433885289061;7080.858382832212;7141.321574450419;7141.928580714126;7146.857062858609;6225.782097626561;7790.8831367530665;7796.610169491616;7810.648520816463;7814.53112813233;4517.616002639812;4567.885802854955;4576.107659006462;4590.88522786933;4607.516210797818;6901;7149.678264478215;7151.53515021522;7152;7153.4992550520965;5931.614445061057;6691.598504089576;6872.347127022925;6916.481263905127;6926.272741362084;6817.250102488623;6878.002689610079;6895.620740859301;6909.372579395857;6929.826754331182;6738.090357801634;6739.73041078371;6744.291849355747;6764.93235067643;6772.288909664414;6607.438367738796;6826.522143449911;6834.2140653825445;6837.076994605664;6853.828654283683;6495.220573531206;6557.2131344239;6696.129503164558;6699.39705426514;6742.348124709478 16:48:43 INFO - PROCESS | 1664 | |5;yui.html;683.7470136049662;707.9752208672697;707.9787606371809;725.9419246460284;791.6002418778515;15063.17152556609;15386.923065384686;15431.074135551875;15448.69102617947;15470.071795692267;14179;14287.499937502189;14376.424943002268;14378.568642940729;14378.928105359459;783.0759703549809;794.400227827989;949.0651708067548;953.6280850468323;970.8301047316727;949.6248981652243;952.7046615549167;953.3374304858127;954.5656726189567;958.6644674363952;1033.958641654333;1034.9120324772402;1036.0364860679565;1037.486444210117;1037.9325343852663;827.4745536584252;840.0968958369716;840.8024114333127;842.1830824100643;1013.5844303835465;768.3046842607394;771.6450432800918;772.7874834420523;775.9883601745978;776.0493395590356;15453.613659658486;15538.378464861393;15577.454789082327;15595.220238988006;15688.058716477048;13118.672033199151;13239.801402978963;13440.932795336059;13443.529476468277;13465.461381544726;10304.433256170907;10454;10499.580016799318;10809.567617295299;10968.341899485977;3616.2405894762132;3654.28741395428;3662.468942003421;3717.7955212463303;3881.5536213335263;84645;86858;88721.11278887258;89190.10809891883;89559.55220223923;9874;9888.901110988942;9915.702528924145;9930.354526955725;10165.695029149138;22887.885560572424;22953.589873063087;22954.311370658907;22959.196428124935;22994.42513937165;7543.358814500829;7641.083070031632;7643.082830060428;7644.312011918954;7705.537667739954;4162.105147393265;4249.2988656871885;4275.850345237902;4332.371806088099;4556.97721511397;3693.8153092345274;3762.736608437438;3765.4351847223134;3765.981170094132;3768.0391500167284;673.0846049372849;680.1498127340824;688.896665500169;693.5665209244222;699.5173330402006;604.2416766957529;617.7220250887119;637.5887552528593;642.922849258092;651.1209866679944;8000.479968802126;8321.66713331472;8368.696240504309;8533.829323413496;8639.95680021596;544.4609836262072;547.4197350808076;575.4964406144624;604.8941435248814;650.6616559389091;3582.444721068239;3918.502323311297;4003.499562554681;4005.1989602079702;4252.213340532012;88057;88575;89317.55341223252;89590;89658;63092;79105;79117;79854;81322.18677813146;66627;66772.33227667758;67251.66374168098;67576.6621166891;68151.3184868145;7919.208079192034;8032.4778889372;8100.2304781045705;8103.959480202797;8118.228768267005;38017.61982380141;48248.03503929832;49608;50897.236541451166;51655.225171623206;65258.34741652523;68076;69880;70731;71252;10487.161027117965;10744.785104298027;10828.296160749525;10835.241533092609;11007.284526505751;12046.337451439904;12052.75894482123;12287;12289.07831912628;12866.742665146458;8667.91332086671;10290.12533934594;11492.310461372344;12117.939410302892;12138.514459421523;9298.395604285699;10412.947935260276;10670.46647667629;11840.171188016757;11980.161388702707;9709.320347575602;10646.361218326925;11514.848515148418;11720.589779358022;12305.569305074638;10128.139108175594;10151.137153342048;10238.897611024093;10274.589016439259;10320;10098.19214462856;10254;10288.2798204128;10290.331128476626;10291.176705863663 16:48:43 INFO - PROCESS | 1664 | __end_tp_report 16:48:43 INFO - PROCESS | 1664 | __start_cc_report 16:48:43 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1128 16:48:43 INFO - PROCESS | 1664 | __end_cc_report 16:48:43 INFO - PROCESS | 1664 | __startTimestamp1466034523865__endTimestamp 16:48:43 INFO - PROCESS | 1664 | 16:48:43 INFO - PROCESS | 1664 | ------- Summary: start ------- 16:48:43 INFO - PROCESS | 1664 | Number of tests: 6 16:48:43 INFO - PROCESS | 1664 | 16:48:43 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5773.54 Median:5986.19 stddev:599.84 (10.0%) stddev-sans-first:576.23 16:48:43 INFO - PROCESS | 1664 | Values: 3570.3 3624.6 3626.9 3647.0 3693.3 5770.8 5779.9 5806.5 5824.7 5826.5 5821.2 5841.4 5848.4 5849.8 5863.1 5195.4 5198.2 5215.5 5259.3 5285.3 5276.9 5317.6 5334.9 5335.5 5336.9 5182.4 5251.4 5268.1 5273.0 5302.4 5280.1 5295.4 5312.0 5335.6 5342.5 5101.3 5110.7 5243.7 5258.1 5263.8 5577.6 5637.1 5651.6 5661.2 5685.9 6167.7 6171.1 6187.4 6208.6 6217.8 6100.9 6156.2 6209.9 6218.8 6222.6 5845.9 5877.8 5883.1 5897.4 5925.6 6223.3 6235.7 6236.5 6244.5 6253.3 6030.4 6066.8 6094.4 6121.0 6136.8 6174.7 6177.7 6188.2 6199.1 6208.9 6100.4 6109.7 6127.4 6156.1 6192.6 6107.4 6116.6 6156.2 6168.4 6182.4 4956.8 4975.2 4984.4 4989.0 5021.4 4461.2 4478.1 4487.4 4488.8 4510.4 5015.4 5802.0 5926.4 5941.1 5948.6 5251.7 6168.8 6175.5 6180.5 6189.8 5959.7 5963.2 5977.3 5980.7 5986.4 5366.4 5385.6 5457.4 5461.0 5467.6 6426.8 6568.6 6572.7 6580.3 6580.4 6115.3 6174.3 6179.1 6186.4 6205.5 6373.5 6599.2 6627.6 6630.4 6640.5 6033.1 6119.8 6128.2 6180.5 6186.2 6149.6 6150.7 6151.8 6161.7 6168.3 5924.5 5986.2 6013.5 6043.6 6058.8 5880.5 5970.5 5975.4 6037.1 6064.6 5926.6 5998.3 6005.1 6022.5 6044.3 6036.3 6038.9 6052.3 6090.1 6097.2 5969.7 5984.8 6006.6 6018.7 6057.6 16:48:43 INFO - PROCESS | 1664 | 16:48:43 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:65431.29 Median:345927.14 stddev:135720.16 (39.2%) stddev-sans-first:136013.45 16:48:43 INFO - PROCESS | 1664 | Values: 543.5 552.6 561.9 580.4 583.0 26791.0 33074.0 38991.8 39413.8 39431.2 43507.6 44004.3 44013.0 44031.0 44084.3 9759.0 9957.9 9958.8 9971.3 9977.8 9186.8 11885.8 12129.3 12131.2 12160.5 11850.2 11950.8 11957.7 11966.9 11969.7 11846.8 12019.1 12029.0 12040.2 12045.9 11775.5 11844.9 11886.9 11929.4 12149.7 39827.4 40100.2 40186.4 40219.4 40241.8 3252.6 3297.5 3297.9 3414.8 3428.9 27878.7 27902.7 27913.6 28167.0 28781.7 2688.4 2852.3 2883.4 2887.6 2911.2 343503.0 345617.3 346237.0 346524.3 347851.3 63388.0 63978.7 67428.7 67430.7 67484.7 70558.0 70587.0 70679.6 70685.3 70975.6 17039.2 19042.5 19191.0 19208.6 19214.0 12822.1 12867.9 12907.6 14538.0 14829.4 19332.6 19761.9 20174.0 20212.1 20278.7 7502.1 8338.3 8473.8 8487.3 8506.8 1054.3 1056.7 1061.7 1086.6 1206.5 28445.0 30028.5 30560.1 31068.8 31917.0 912.2 1155.8 1203.1 1239.0 1274.1 13484.3 14852.2 15471.8 15654.8 15863.9 646540.0 654804.0 657055.7 660338.0 660424.0 62638.7 63104.0 63111.0 63328.4 63424.7 1496.9 1548.3 1574.2 1588.3 1592.7 27113.0 29041.8 29602.3 29954.7 30308.7 48353.0 62633.4 63814.0 63886.7 64059.7 472414.0 473824.6 477922.0 478803.0 478880.0 37336.6 44455.1 44888.6 45649.5 47641.0 45706.5 46293.5 47268.0 47891.0 48359.8 38759.4 38917.4 38951.6 38960.8 39380.0 38350.8 38772.6 38847.7 38934.6 39117.0 37828.1 37869.6 38341.0 38742.4 38852.2 33247.8 33299.0 34303.5 34361.5 34652.3 23144.0 25162.1 25476.9 25836.5 25922.4 16:48:43 INFO - PROCESS | 1664 | 16:48:43 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:36776.33 Median:21797.39 stddev:108017.52 (495.6%) stddev-sans-first:108286.02 16:48:43 INFO - PROCESS | 1664 | Values: 406.7 409.4 414.0 417.5 422.2 15293.7 20778.7 21439.0 21450.1 21544.8 17350.7 18056.7 21070.2 21483.0 22050.0 5745.5 5903.0 6060.8 6071.9 6100.3 5991.4 5995.1 6004.4 6025.9 6043.1 4158.6 4377.0 5177.2 6065.6 6075.8 6003.8 6042.2 6042.6 6051.8 6059.2 5613.5 5924.6 5925.2 5927.2 5935.1 13447.7 13751.0 15603.8 25826.4 25875.1 2258.3 2306.6 2508.8 2586.8 2890.5 8099.6 9664.5 9994.7 10397.2 10417.5 1836.4 1882.3 1882.8 1903.2 1914.1 116620.0 119404.0 120717.2 124128.0 128808.7 11736.9 11798.8 11807.1 11819.6 11825.6 13875.6 14052.1 14057.2 15210.6 15255.8 6439.0 6482.6 6525.2 9004.6 9144.6 9196.4 9197.3 9202.7 9244.0 9283.9 4672.6 4739.3 4747.9 4758.0 4758.8 1974.9 2009.5 2025.7 2038.2 2067.0 937.6 997.2 1007.9 1035.2 1061.3 20556.0 21047.4 21183.8 21214.7 21320.3 975.8 1019.5 1046.6 1079.6 1097.8 10718.7 11232.4 11253.7 11258.5 11269.0 637768.0 639294.0 648220.8 656826.0 657227.0 128285.0 129784.4 129863.0 131768.3 132593.3 1047.9 1097.9 1119.3 1122.2 1123.7 18119.0 18532.2 18839.7 18913.0 18924.1 55563.0 55673.0 56838.4 58016.1 58814.7 80510.4 83317.6 88545.2 91562.0 95393.5 8048.9 8183.6 8290.6 8405.2 8683.9 7509.6 10900.4 11712.7 13111.5 13258.9 11550.7 11579.1 11631.5 11658.9 11895.9 11192.1 11481.1 11677.5 11679.9 11745.5 6162.0 8605.0 9424.0 11853.1 11942.6 8443.2 8464.1 8588.7 8628.6 8807.6 9874.1 11645.3 11680.6 11880.3 11942.8 16:48:43 INFO - PROCESS | 1664 | 16:48:43 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5806.93 Median:5144.92 stddev:3176.47 (61.7%) stddev-sans-first:3168.78 16:48:43 INFO - PROCESS | 1664 | Values: 1486.2 1615.5 1637.7 1958.6 2058.7 7413.2 8180.6 8377.8 8543.6 8714.3 8764.4 8843.0 8928.0 8948.5 8967.6 2523.8 2546.9 2552.5 2554.1 2558.6 2480.6 2550.3 2554.3 2557.4 2558.0 2519.1 2552.7 2556.7 2562.3 2562.8 2511.3 2532.9 2543.1 2550.5 2553.7 2517.1 2540.0 2549.6 2555.3 2566.5 9008.9 9034.9 9043.8 9047.1 9086.4 5538.2 5556.4 5687.8 5702.8 5732.3 3625.5 3736.1 3749.1 3780.3 3784.0 1044.9 1065.7 1095.6 1104.4 1118.2 11974.7 12079.8 12105.4 12107.2 12136.4 4668.8 4718.2 5097.2 5105.2 5116.2 9414.8 9453.6 9467.6 9561.4 9580.0 5514.3 5565.1 5567.6 5573.6 5575.1 4606.1 4678.8 4753.0 5092.2 5173.7 4032.4 4071.7 4085.8 4124.9 4154.3 1264.2 1266.2 1272.3 1281.9 1285.1 913.4 951.9 958.3 974.6 991.2 5868.9 5946.6 5986.8 6002.6 6154.3 940.8 959.6 968.4 974.7 1030.1 3522.0 3870.8 3913.9 3932.6 4071.4 10761.0 10868.5 11088.1 11240.5 11251.6 10829.8 10996.7 11010.1 11016.4 11018.8 8055.1 8357.2 8397.8 8646.0 8731.1 5994.3 6016.6 6080.2 6083.9 6124.3 7853.0 9728.6 9873.3 9890.5 9914.8 9472.5 9689.0 9969.4 10014.4 10088.9 7052.2 7221.8 7229.4 7232.2 7257.6 8328.8 8421.2 8496.2 8520.3 8550.5 7494.6 7569.9 7603.7 7700.2 7721.8 7370.2 7455.9 7495.7 7564.7 7588.2 7134.0 7169.3 7189.6 7205.3 7214.6 4860.7 4922.4 4987.6 5075.3 5076.0 4978.7 4992.5 5091.5 5097.6 5267.8 16:48:43 INFO - PROCESS | 1664 | 16:48:43 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5658.34 Median:6424.84 stddev:1824.15 (28.4%) stddev-sans-first:1784.84 16:48:43 INFO - PROCESS | 1664 | Values: 327.0 331.4 337.3 339.6 342.5 6402.9 6424.2 6425.5 6439.5 6441.7 6357.3 6371.4 6390.3 6399.5 6400.8 4597.6 4748.6 4757.3 4871.1 4912.4 4806.6 4876.0 4885.3 4894.7 4900.0 4835.1 4838.1 4841.2 4853.3 4858.1 4324.8 4464.1 4587.8 4624.3 4816.6 3600.2 3972.0 4135.3 4139.3 4165.2 6073.8 6080.6 6100.5 6139.0 6153.0 5556.2 5610.8 5611.5 5661.3 5693.7 6874.7 6881.3 6887.1 6887.3 6905.9 3998.9 4209.9 4311.2 4466.2 4471.1 6977.0 7180.2 7267.6 7273.1 7280.8 4584.3 4589.4 4592.6 4592.7 4594.3 4163.6 4457.2 4628.7 4644.3 4649.1 5517.3 5524.1 5526.0 5528.9 5536.6 5568.9 6683.9 6923.4 6930.2 6933.9 1563.0 1579.0 1590.0 1591.9 1594.9 964.7 968.0 1005.6 1013.1 1027.3 6467.2 6629.4 6632.8 6636.0 6667.5 6121.2 7175.5 7186.8 7188.4 7198.1 6656.2 6660.1 6676.0 6803.3 6829.2 3738.4 3768.8 3770.9 3771.7 3773.8 7505.0 7774.0 7784.8 7831.0 7839.8 5989.5 6319.6 7124.4 7405.6 7464.0 7726.6 7743.2 7767.5 7779.2 7795.6 6908.2 7139.3 7168.7 7174.3 7183.7 7076.4 7080.9 7141.3 7141.9 7146.9 6225.8 7790.9 7796.6 7810.6 7814.5 4517.6 4567.9 4576.1 4590.9 4607.5 6901.0 7149.7 7151.5 7152.0 7153.5 5931.6 6691.6 6872.3 6916.5 6926.3 6817.3 6878.0 6895.6 6909.4 6929.8 6738.1 6739.7 6744.3 6764.9 6772.3 6607.4 6826.5 6834.2 6837.1 6853.8 6495.2 6557.2 6696.1 6699.4 6742.3 16:48:43 INFO - PROCESS | 1664 | 16:48:43 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18413.73 Median:48928.02 stddev:25692.23 (52.5%) stddev-sans-first:25729.81 16:48:43 INFO - PROCESS | 1664 | Values: 683.7 708.0 708.0 725.9 791.6 15063.2 15386.9 15431.1 15448.7 15470.1 14179.0 14287.5 14376.4 14378.6 14378.9 783.1 794.4 949.1 953.6 970.8 949.6 952.7 953.3 954.6 958.7 1034.0 1034.9 1036.0 1037.5 1037.9 827.5 840.1 840.8 842.2 1013.6 768.3 771.6 772.8 776.0 776.0 15453.6 15538.4 15577.5 15595.2 15688.1 13118.7 13239.8 13440.9 13443.5 13465.5 10304.4 10454.0 10499.6 10809.6 10968.3 3616.2 3654.3 3662.5 3717.8 3881.6 84645.0 86858.0 88721.1 89190.1 89559.6 9874.0 9888.9 9915.7 9930.4 10165.7 22887.9 22953.6 22954.3 22959.2 22994.4 7543.4 7641.1 7643.1 7644.3 7705.5 4162.1 4249.3 4275.9 4332.4 4557.0 3693.8 3762.7 3765.4 3766.0 3768.0 673.1 680.1 688.9 693.6 699.5 604.2 617.7 637.6 642.9 651.1 8000.5 8321.7 8368.7 8533.8 8640.0 544.5 547.4 575.5 604.9 650.7 3582.4 3918.5 4003.5 4005.2 4252.2 88057.0 88575.0 89317.6 89590.0 89658.0 63092.0 79105.0 79117.0 79854.0 81322.2 66627.0 66772.3 67251.7 67576.7 68151.3 7919.2 8032.5 8100.2 8104.0 8118.2 38017.6 48248.0 49608.0 50897.2 51655.2 65258.3 68076.0 69880.0 70731.0 71252.0 10487.2 10744.8 10828.3 10835.2 11007.3 12046.3 12052.8 12287.0 12289.1 12866.7 8667.9 10290.1 11492.3 12117.9 12138.5 9298.4 10412.9 10670.5 11840.2 11980.2 9709.3 10646.4 11514.8 11720.6 12305.6 10128.1 10151.1 10238.9 10274.6 10320.0 10098.2 10254.0 10288.3 10290.3 10291.2 16:48:43 INFO - PROCESS | 1664 | -------- Summary: end -------- 16:48:43 INFO - PROCESS | 1664 | 16:48:44 INFO - TEST-INFO | 1664: exit 0 16:48:44 INFO - TEST-OK | dromaeo_css | took 1112965ms 16:48:44 INFO - TEST-START | kraken 16:48:44 INFO - operating with platform_type : mac_ 16:48:44 INFO - Initialising browser for kraken test... 16:48:44 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpQ67lf6/profile http://localhost:49275/getInfo.html) 16:48:46 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 16:48:46 INFO - PROCESS | 1674 | colorDepth:24 16:48:46 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 16:48:46 INFO - PROCESS | 1674 | __metrics 16:48:46 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:48:46 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:48:47 INFO - TEST-INFO | 1674: exit 0 16:48:47 INFO - Browser initialized. 16:48:47 INFO - Running cycle 1/1 for kraken test... 16:48:47 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpQ67lf6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:48:58 INFO - PROCESS | 1677 | RSS: Main: 161161216 16:48:58 INFO - PROCESS | 1677 | 16:49:01 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 16:49:02 INFO - PROCESS | 1677 | RSS: Main: 223358976 16:49:02 INFO - PROCESS | 1677 | 16:49:04 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 16:49:05 INFO - PROCESS | 1677 | RSS: Main: 238825472 16:49:05 INFO - PROCESS | 1677 | 16:49:08 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 16:49:09 INFO - PROCESS | 1677 | RSS: Main: 325513216 16:49:09 INFO - PROCESS | 1677 | 16:49:11 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 16:49:12 INFO - PROCESS | 1677 | RSS: Main: 230281216 16:49:12 INFO - PROCESS | 1677 | 16:49:14 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:49:14 INFO - PROCESS | 1677 | RSS: Main: 230723584 16:49:14 INFO - PROCESS | 1677 | 16:49:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 16:49:18 INFO - PROCESS | 1677 | RSS: Main: 269156352 16:49:18 INFO - PROCESS | 1677 | 16:49:20 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 16:49:21 INFO - PROCESS | 1677 | RSS: Main: 269692928 16:49:21 INFO - PROCESS | 1677 | 16:49:24 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 16:49:24 INFO - PROCESS | 1677 | RSS: Main: 269410304 16:49:24 INFO - PROCESS | 1677 | 16:49:26 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:26 INFO - PROCESS | 1677 | RSS: Main: 259121152 16:49:26 INFO - PROCESS | 1677 | 16:49:28 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:29 INFO - PROCESS | 1677 | RSS: Main: 266158080 16:49:29 INFO - PROCESS | 1677 | 16:49:31 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:49:32 INFO - PROCESS | 1677 | RSS: Main: 270606336 16:49:32 INFO - PROCESS | 1677 | 16:49:34 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:49:35 INFO - PROCESS | 1677 | RSS: Main: 277475328 16:49:35 INFO - PROCESS | 1677 | 16:49:38 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:49:38 INFO - PROCESS | 1677 | RSS: Main: 274640896 16:49:38 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 16:49:40 INFO - PROCESS | 1677 | RSS: Main: 335585280 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | __start_tp_report 16:49:40 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 16:49:40 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 16:49:40 INFO - PROCESS | 1677 | |i|pagename|runs| 16:49:40 INFO - PROCESS | 1677 | |0;ai-astar;114;113;117;111;112;112;114;114;113;134 16:49:40 INFO - PROCESS | 1677 | |1;audio-beat-detection;177;119;142;163;118;135;138;116;165;138 16:49:40 INFO - PROCESS | 1677 | |2;audio-dft;185;187;184;185;184;186;184;185;181;184 16:49:40 INFO - PROCESS | 1677 | |3;audio-fft;76;101;100;187;55;55;55;61;176;79 16:49:40 INFO - PROCESS | 1677 | |4;audio-oscillator;92;90;139;76;78;83;122;73;76;125 16:49:40 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;89;86;90;87;89;85;90;87;88;85 16:49:40 INFO - PROCESS | 1677 | |6;imaging-darkroom;93;93;95;94;94;92;94;95;94;94 16:49:40 INFO - PROCESS | 1677 | |7;imaging-desaturate;86;84;84;85;86;84;84;84;83;88 16:49:40 INFO - PROCESS | 1677 | |8;json-parse-financial;58;61;61;61;62;62;63;78;57;55 16:49:40 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;61;63;61;78;70;73;70;64;65;66 16:49:40 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;77;94;78;79;78;78;76;78;95;107 16:49:40 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;130;125;125;139;136;144;138;110;164;156 16:49:40 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;170;161;161;160;164;161;161;166;159;162 16:49:40 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;67;66;67;66;68;65;67;65;66;64 16:49:40 INFO - PROCESS | 1677 | __end_tp_report 16:49:40 INFO - PROCESS | 1677 | __start_cc_report 16:49:40 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1868 16:49:40 INFO - PROCESS | 1677 | __end_cc_report 16:49:40 INFO - PROCESS | 1677 | __startTimestamp1466034580837__endTimestamp 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | ------- Summary: start ------- 16:49:40 INFO - PROCESS | 1677 | Number of tests: 14 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:115.40 Median:114.00 stddev:6.74 (5.9%) stddev-sans-first:7.13 16:49:40 INFO - PROCESS | 1677 | Values: 114.0 113.0 117.0 111.0 112.0 112.0 114.0 114.0 113.0 134.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:140.00 stddev:21.21 (15.2%) stddev-sans-first:18.09 16:49:40 INFO - PROCESS | 1677 | Values: 177.0 119.0 142.0 163.0 118.0 135.0 138.0 116.0 165.0 138.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.58 (0.9%) stddev-sans-first:1.67 16:49:40 INFO - PROCESS | 1677 | Values: 185.0 187.0 184.0 185.0 184.0 186.0 184.0 185.0 181.0 184.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:94.50 Median:55.00 stddev:49.05 (89.2%) stddev-sans-first:51.57 16:49:40 INFO - PROCESS | 1677 | Values: 76.0 101.0 100.0 187.0 55.0 55.0 55.0 61.0 176.0 79.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:77.00 stddev:24.12 (31.3%) stddev-sans-first:25.55 16:49:40 INFO - PROCESS | 1677 | Values: 92.0 90.0 139.0 76.0 78.0 83.0 122.0 73.0 76.0 125.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.50 stddev:1.90 (2.1%) stddev-sans-first:1.94 16:49:40 INFO - PROCESS | 1677 | Values: 89.0 86.0 90.0 87.0 89.0 85.0 90.0 87.0 88.0 85.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.92 (1.0%) stddev-sans-first:0.93 16:49:40 INFO - PROCESS | 1677 | Values: 93.0 93.0 95.0 94.0 94.0 92.0 94.0 95.0 94.0 94.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:84.50 stddev:1.48 (1.7%) stddev-sans-first:1.50 16:49:40 INFO - PROCESS | 1677 | Values: 86.0 84.0 84.0 85.0 86.0 84.0 84.0 84.0 83.0 88.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:61.50 stddev:6.23 (10.1%) stddev-sans-first:6.46 16:49:40 INFO - PROCESS | 1677 | Values: 58.0 61.0 61.0 61.0 62.0 62.0 63.0 78.0 57.0 55.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:68.00 stddev:5.55 (8.2%) stddev-sans-first:5.43 16:49:40 INFO - PROCESS | 1677 | Values: 61.0 63.0 61.0 78.0 70.0 73.0 70.0 64.0 65.0 66.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:78.00 stddev:10.71 (13.7%) stddev-sans-first:11.05 16:49:40 INFO - PROCESS | 1677 | Values: 77.0 94.0 78.0 79.0 78.0 78.0 76.0 78.0 95.0 107.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:136.70 Median:138.50 stddev:15.67 (11.3%) stddev-sans-first:16.43 16:49:40 INFO - PROCESS | 1677 | Values: 130.0 125.0 125.0 139.0 136.0 144.0 138.0 110.0 164.0 156.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:161.50 stddev:3.31 (2.0%) stddev-sans-first:2.12 16:49:40 INFO - PROCESS | 1677 | Values: 170.0 161.0 161.0 160.0 164.0 161.0 161.0 166.0 159.0 162.0 16:49:40 INFO - PROCESS | 1677 | 16:49:40 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:1.20 (1.8%) stddev-sans-first:1.22 16:49:40 INFO - PROCESS | 1677 | Values: 67.0 66.0 67.0 66.0 68.0 65.0 67.0 65.0 66.0 64.0 16:49:40 INFO - PROCESS | 1677 | -------- Summary: end -------- 16:49:40 INFO - PROCESS | 1677 | 16:49:41 INFO - TEST-INFO | 1677: exit 0 16:49:41 INFO - TEST-OK | kraken | took 57204ms 16:49:41 INFO - SUITE-END | took 1170s 16:49:42 INFO - Completed test suite (00:19:30) 16:49:42 INFO - javascript benchmark 16:49:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3570.250247448036, 3624.5831729351135, 3626.8911932642013, 3646.981765091174, 3693.316736403764, 5770.798022069229, 5779.855503612412, 5806.506446952014, 5824.679642619654, 5826.533877289818, 5821.214136091637, 5841.3574506804325, 5848.356680765112, 5849.766009359631, 5863.061910094386, 5195.350581177353, 5198.168293073109, 5215.530602245797, 5259.31628888244, 5285.286486324355, 5276.92084618731, 5317.627766056377, 5334.946650533503, 5335.5198032177095, 5336.866578335534, 5182.378114626231, 5251.369835619712, 5268.130758424855, 5273.0, 5302.3902251241025, 5280.075986702312, 5295.443978382253, 5312.04252831639, 5335.573154147698, 5342.545883599899, 5101.311322971388, 5110.66780659256, 5243.659162154448, 5258.0535503609335, 5263.815766448156, 5577.637453565546, 5637.098064309732, 5651.632643878134, 5661.179129026309, 5685.914711279334, 6167.722452489649, 6171.13604095427, 6187.412195841388, 6208.565400421972, 6217.844553886144, 6100.877982440326, 6156.230471191101, 6209.9068513972325, 6218.751249949996, 6222.5644204905675, 5845.88308233833, 5877.764889404419, 5883.0881213411985, 5897.351291357947, 5925.555583331181, 6223.284322302993, 6235.688215589294, 6236.469900058455, 6244.500439964792, 6253.343398943137, 6030.427109424598, 6066.757329706762, 6094.39056094387, 6121.0, 6136.815895523141, 6174.691265436711, 6177.660228687376, 6188.195534580475, 6199.070139479114, 6208.875822483616, 6100.359462256399, 6109.663968481687, 6127.448529632355, 6156.07658851167, 6192.566520343623, 6107.370879189147, 6116.571839971249, 6156.199694039746, 6168.383161683796, 6182.443580077815, 4956.801727930842, 4975.154223781966, 4984.37695288089, 4988.950110498921, 5021.372328458942, 4461.219286624828, 4478.126765280804, 4487.439070116236, 4488.820447182142, 4510.368548403226, 5015.448300686921, 5801.970990145107, 5926.407359264039, 5941.079132734433, 5948.643081415129, 5251.711155886463, 6168.784092556739, 6175.536834737412, 6180.47465965389, 6189.81430557084, 5959.731812068468, 5963.224780778558, 5977.252843394575, 5980.730867110903, 5986.431289027535, 5366.356037275552, 5385.5960802939935, 5457.426970168154, 5460.972695136499, 5467.589930755209, 6426.775062872777, 6568.6058836469965, 6572.704228309737, 6580.276169621337, 6580.374864387875, 6115.296740874678, 6174.320824709189, 6179.073139029181, 6186.443220110212, 6205.503559715302, 6373.458256048288, 6599.241087274832, 6627.602343859384, 6630.436412904764, 6640.50196235294, 6033.064874944391, 6119.755209791559, 6128.203333566607, 6180.536459765445, 6186.19579454668, 6149.569530132848, 6150.692465376624, 6151.815445536641, 6161.661108639068, 6168.290646575701, 5924.526037917042, 5986.191864098291, 6013.458788709037, 6043.576949613484, 6058.799270598741, 5880.500157486662, 5970.492508136857, 5975.43233392827, 6037.094435834484, 6064.575479716377, 5926.614770039933, 5998.34018258, 6005.129256257906, 6022.45797878193, 6044.274687037584, 6036.275646922398, 6038.909416358671, 6052.273727152769, 6090.056041313604, 6097.237845269341, 5969.73136208863, 5984.820455386345, 6006.579539432198, 6018.699065046818, 6057.636541807506], "value": 5738.6448307677065, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.4891202269869, 552.5634748548646, 561.8595351162209, 580.3673995345073, 582.970851457427, 26791.0, 33074.007779766616, 38991.83024509266, 39413.80293098531, 39431.211375772466, 43507.56492435082, 44004.33993490092, 44013.0, 44031.0, 44084.33873491892, 9758.951205243964, 9957.850632240523, 9958.751031224241, 9971.302008859382, 9977.75055623608, 9186.816263674722, 11885.821712674317, 12129.272243665408, 12131.150819442599, 12160.51357945681, 11850.170488065838, 11950.7609847803, 11957.701057473589, 11966.940165299162, 11969.700757481089, 11846.7630647387, 12019.098567607378, 12028.977536909286, 12040.157188996818, 12045.879541204564, 11775.528978840835, 11844.88155118455, 11886.881131188664, 11929.403529823474, 12149.696257593543, 39827.40258896119, 40100.19799603992, 40186.39720404196, 40219.39670904939, 40241.79879100615, 3252.6259480159847, 3297.472404415282, 3297.884574039921, 3414.7609667323295, 3428.9142771430666, 27878.721212787816, 27902.720972790215, 27913.581296280572, 28167.0, 28781.712182878113, 2688.3509758435807, 2852.343960889007, 2883.3656595548946, 2887.595736596878, 2911.213972227487, 343503.0, 345617.27191364137, 346237.0, 346524.267378664, 347851.2607436947, 63388.0, 63978.68010659917, 67428.6628566854, 67430.651386972, 67484.66257668681, 70558.0, 70587.0, 70679.64660176667, 70685.29314706889, 70975.64512177405, 17039.233234504478, 19042.524471818575, 19191.0, 19208.615827683367, 19214.0, 12822.102452828212, 12867.935660321638, 12907.612771616867, 14538.0, 14829.40682372693, 19332.553675494575, 19761.90119049424, 20174.0, 20212.09045592952, 20278.667953185573, 7502.062242219723, 8338.291245244221, 8473.830523389497, 8487.344967731357, 8506.78733031679, 1054.2567489919588, 1056.693558867935, 1061.7345663584103, 1086.6305456144948, 1206.5053328135423, 28445.0, 30028.54957175644, 30560.08319750411, 31068.844655776575, 31917.0, 912.2154946745793, 1155.826626006089, 1203.1337437045313, 1238.9690257743628, 1274.0826604844497, 13484.25836578978, 14852.183129927958, 15471.762259019251, 15654.765178522332, 15863.920680396524, 646540.0, 654804.0, 657055.7147214329, 660338.0, 660424.0, 62638.686806565674, 63104.0, 63111.0, 63328.36671633317, 63424.682876586245, 1496.91018538888, 1548.3187397545043, 1574.1656921831448, 1588.3329001819495, 1592.6575786206022, 27113.0, 29041.83832646755, 29602.259943501584, 29954.70045299606, 30308.696913030588, 48353.0, 62633.373666262756, 63814.0, 63886.68056659687, 64059.718805622695, 472414.0, 473824.6308768434, 477922.0, 478803.0, 478880.0, 37336.626633733315, 44455.11089778251, 44888.55111448844, 45649.51650251973, 47641.0, 45706.54293457023, 46293.53706462892, 47268.0, 47891.0, 48359.758201208766, 38759.418608721455, 38917.41623875701, 38951.63669271562, 38960.80519597384, 39380.0, 38350.80824595859, 38772.612273878025, 38847.69648775382, 38934.6106538931, 39117.0, 37828.05429864275, 37869.62130378661, 38341.0, 38742.418863717634, 38852.2229555413, 33247.83632572848, 33299.0, 34303.4854477178, 34361.48457773085, 34652.30695386128, 23143.958521866894, 25162.119325823507, 25476.872615636803, 25836.483270334866, 25922.35194120162], "value": 21008.676715311183, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.6705968165786, 409.4308910614247, 413.99586004139985, 417.49274631322936, 422.2020381479005, 15293.69412611747, 20778.6883196752, 21439.035243414044, 21450.141994320227, 21544.784552154473, 17350.652986940255, 18056.729149062776, 21070.15719371223, 21483.03326350314, 22050.0, 5745.540356771469, 5902.970485147579, 6060.818175454743, 6071.878562428748, 6100.267967843843, 5991.400859914017, 5995.13070604762, 6004.399560044004, 6025.9096113558335, 6043.063325184604, 4158.60493253142, 4377.0, 5177.223416487519, 6065.636061836281, 6075.756969721206, 6003.849903752419, 6042.214512113441, 6042.57701960863, 6051.848703782397, 6059.151718759377, 5613.494785469327, 5924.58527903047, 5925.170476133345, 5927.170196172539, 5935.10973353996, 13447.66380840477, 13751.037427380088, 15603.765943510856, 25826.354341141432, 25875.0943716969, 2258.254775923941, 2306.642470417088, 2508.7742103210617, 2586.79305655549, 2890.4797136515417, 8099.55452450115, 9664.468454235015, 9994.650187243484, 10397.168226541859, 10417.531211095518, 1836.4398858348197, 1882.3129557711475, 1882.7552418185549, 1903.200655724599, 1914.1290712725677, 116620.0, 119404.0, 120717.1533850297, 124128.0, 128808.71191288061, 11736.88263117358, 11798.764024719458, 11807.114466415052, 11819.645410637695, 11825.645230642922, 13875.583732488041, 14052.086614370237, 14057.22685252321, 15210.619734506505, 15255.771163432557, 6438.967805160944, 6482.643454610042, 6525.2169739631545, 9004.63981440735, 9144.634214631473, 9196.402233854911, 9197.264218862609, 9202.677906273248, 9244.0, 9283.860742088738, 4672.57946784791, 4739.312799644033, 4747.881302967385, 4758.0, 4758.833440829554, 1974.871633343828, 2009.4674911148556, 2025.6961455781457, 2038.215287114442, 2066.953715821356, 937.6108914800418, 997.2271489595621, 1007.9042490963346, 1035.1615191694752, 1061.3313612424124, 20556.0, 21047.368578942656, 21183.78816211818, 21214.681779773317, 21320.270122864094, 975.7804493988796, 1019.5310157327713, 1046.638909576195, 1079.6167360587035, 1097.8243481042996, 10718.678439646823, 11232.438378081064, 11253.66239012831, 11258.493367798468, 11269.0, 637768.0, 639294.0, 648220.7588962025, 656826.0, 657227.0, 128285.0, 129784.351078244, 129863.0, 131768.3411582936, 132593.33703331614, 1047.9161667066464, 1097.928634638746, 1119.255694962857, 1122.1752012270974, 1123.7134530694784, 18119.039952443778, 18532.166052527937, 18839.71740423922, 18913.0, 18924.14841332171, 55563.0, 55673.0, 56838.431615683316, 58016.12975805451, 58814.705926470095, 80510.389792205, 83317.58341208255, 88545.22909541902, 91562.0, 95393.5230323844, 8048.87926681112, 8183.590820458834, 8290.58547072656, 8405.243528082501, 8683.86974195375, 7509.586972716553, 10900.400477973788, 11712.653044899605, 13111.475540978254, 13258.867411325764, 11550.653480395602, 11579.131565132817, 11631.47658355393, 11658.88341116578, 11895.940520297343, 11192.104631629287, 11481.081513479068, 11677.474513646737, 11679.883201167879, 11745.530178792753, 6162.0, 8604.956975215084, 9423.952880235829, 11853.051755859684, 11942.582009629621, 8443.155684431507, 8464.111268316738, 8588.742337729878, 8628.611712473079, 8807.603657835285, 9874.067418853618, 11645.301281923112, 11680.649580512598, 11880.34658093813, 11942.761144777229], "value": 9704.234957933082, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1486.1900264355925, 1615.4830454254638, 1637.6724655068986, 1958.6474434601776, 2058.722072520209, 7413.184549699528, 8180.590970451476, 8377.790555236123, 8543.57282135894, 8714.25928796053, 8764.43031202973, 8842.95578522108, 8927.95536022319, 8948.50783206925, 8967.551622418869, 2523.7602427769334, 2546.8726563671835, 2552.540542702313, 2554.0805310088354, 2558.5906254999204, 2480.578301688717, 2550.2731721459386, 2554.348641096518, 2557.4373637799654, 2558.027949379233, 2519.0805356045003, 2552.668153140089, 2556.7187609362954, 2562.308176792265, 2562.8206025578215, 2511.2717311979595, 2532.873356332176, 2543.0844895837486, 2550.4644024754825, 2553.74462553745, 2517.1064272183476, 2540.047482194177, 2549.592065269548, 2555.3356127406823, 2566.4995325911455, 9008.909910900873, 9034.86447703285, 9043.773905652411, 9047.140521650434, 9086.363954523185, 5538.169274608801, 5556.444355564452, 5687.800926967537, 5702.77188912443, 5732.254806875121, 3625.456181572759, 3736.1220113273357, 3749.1189570450924, 3780.281746468162, 3783.9621603783885, 1044.926855120142, 1065.7495488560182, 1095.6274866545414, 1104.3705088099787, 1118.2004866520397, 11974.700632484257, 12079.758404831855, 12105.394730263451, 12107.152499324962, 12136.393180340947, 4668.766561671903, 4718.2214934536105, 5097.235414687752, 5105.183170692671, 5116.181410974226, 9414.764630884145, 9453.574589143505, 9467.621295148117, 9561.426314421156, 9580.0, 5514.338279406497, 5565.1374037024325, 5567.638103523258, 5573.637713548606, 5575.107982722745, 4606.055758569485, 4678.7660616969015, 4752.976235118871, 5092.185250359925, 5173.663711858717, 4032.395140728914, 4071.7353372030725, 4085.7957102144774, 4124.896877578085, 4154.252234597741, 1264.2351377416715, 1266.1643315411968, 1272.2748033620746, 1281.88463038327, 1285.0683254640312, 913.3834661603391, 951.923846092311, 958.3195930889008, 974.6052848596188, 991.2020823237219, 5868.94131058684, 5946.583739138218, 5986.790462333797, 6002.639841609517, 6154.261488621394, 940.7836197674574, 959.6209497248688, 968.4334664221509, 974.7465658928588, 1030.124394264869, 3522.0314413536485, 3870.787106709102, 3913.8630147944687, 3932.5870783567884, 4071.42999980003, 10761.0, 10868.510917008754, 11088.112950963903, 11240.49417776202, 11251.606193783342, 10829.783404331869, 10996.725081873017, 11010.064144547643, 11016.449177541092, 11018.834717479243, 8055.113937466874, 8357.164283571594, 8397.790055248546, 8646.0, 8731.126887311346, 5994.28068631767, 6016.639001659914, 6080.179175811209, 6083.935311320412, 6124.326324104434, 7852.97911271531, 9728.610855565981, 9873.308868379145, 9890.505474726378, 9914.801703966024, 9472.52637368115, 9688.951555242178, 9969.35199212049, 10014.449205293779, 10088.949555252177, 7052.188998265266, 7221.783346499614, 7229.421646268181, 7232.240614735377, 7257.600831954293, 8328.833423331378, 8421.19998600132, 8496.192861678132, 8520.318374530147, 8550.486970781772, 7494.55032698018, 7569.886451703339, 7603.657835397531, 7700.19147989453, 7721.845563088595, 7370.152432470334, 7455.888161677471, 7495.737649182252, 7564.659590318559, 7588.241175882367, 7134.0, 7169.318914703095, 7189.604571748604, 7205.279472052753, 7214.567125972459, 4860.659753817199, 4922.433920099091, 4987.576056035277, 5075.264086707481, 5075.9848030394805, 4978.726170060681, 4992.450830408579, 5091.465396133352, 5097.643164978416, 5267.789288428573], "value": 4717.346251060223, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.0385067900373, 331.4481388488167, 337.3253493013973, 339.5907930943214, 342.4554957617391, 6402.935970640298, 6424.196975378078, 6425.485961123117, 6439.452646525046, 6441.742330306782, 6357.268914074883, 6371.426571608555, 6390.297067322603, 6399.456046236076, 6400.807975760723, 4597.60920321773, 4748.620110391161, 4757.333973243749, 4871.098846713369, 4912.435069966964, 4806.639502037361, 4875.97604503053, 4885.291632713254, 4894.681845680037, 4899.975500122495, 4835.081334546425, 4838.104950584136, 4841.2496063110275, 4853.296272040552, 4858.076965376585, 4324.762138082404, 4464.062546865162, 4587.816487340502, 4624.260118381059, 4816.638752093572, 3600.225951420457, 3971.9672885050095, 4135.338345864678, 4139.275626765304, 4165.187788381267, 6073.787417440412, 6080.604760690541, 6100.542459315525, 6139.0, 6153.01551751722, 5556.194351818964, 5610.831675049714, 5611.466910643482, 5661.2923384576925, 5693.715314234313, 6874.725010999554, 6881.346272104142, 6887.139107611549, 6887.311268873123, 6905.930940690579, 3998.9200215995515, 4209.94751312172, 4311.202427550913, 4466.1737578547745, 4471.128130014616, 6977.0, 7180.17427995782, 7267.600281984489, 7273.054502914641, 7280.8179795505, 4584.312353147055, 4589.380433641416, 4592.586667199968, 4592.724436533818, 4594.33382159585, 4163.562825903236, 4457.175422546839, 4628.722276663411, 4644.3265726470145, 4649.116667833101, 5517.2551705519245, 5524.14375771756, 5526.0, 5528.861778455571, 5536.612437129363, 5568.916466253009, 6683.866322673616, 6923.446124310144, 6930.168379794457, 6933.895991560131, 1562.9765553516706, 1578.952631421059, 1589.9682006359808, 1591.9283632236577, 1594.8803839711836, 964.71058682395, 967.9758006049905, 1005.5771083916177, 1013.1489548779061, 1027.2963020331097, 6467.223933127961, 6629.370209830058, 6632.767853125118, 6636.0, 6667.533272670964, 6121.204243448323, 7175.497715159993, 7186.784396468114, 7188.353048225685, 7198.136223653195, 6656.201255849232, 6660.134182556333, 6676.0, 6803.2856550062525, 6829.248782633907, 3738.4392341149046, 3768.7550309229814, 3770.868019619355, 3771.717121215865, 3773.7924414156937, 7504.962475187589, 7774.0, 7784.805379865548, 7831.021122359705, 7839.804004899923, 5989.460948514655, 6319.55763096588, 7124.358807707331, 7405.5926924019695, 7464.0, 7726.613669316512, 7743.225677432211, 7767.456278060482, 7779.2220777922885, 7795.610219489116, 6908.205556360882, 7139.286071392819, 7168.713251469882, 7174.3184397482155, 7183.676734546853, 7076.433885289061, 7080.858382832212, 7141.321574450419, 7141.928580714126, 7146.857062858609, 6225.782097626561, 7790.8831367530665, 7796.610169491616, 7810.648520816463, 7814.53112813233, 4517.616002639812, 4567.885802854955, 4576.107659006462, 4590.88522786933, 4607.516210797818, 6901.0, 7149.678264478215, 7151.53515021522, 7152.0, 7153.4992550520965, 5931.614445061057, 6691.598504089576, 6872.347127022925, 6916.481263905127, 6926.272741362084, 6817.250102488623, 6878.002689610079, 6895.620740859301, 6909.372579395857, 6929.826754331182, 6738.090357801634, 6739.73041078371, 6744.291849355747, 6764.93235067643, 6772.288909664414, 6607.438367738796, 6826.522143449911, 6834.2140653825445, 6837.076994605664, 6853.828654283683, 6495.220573531206, 6557.2131344239, 6696.129503164558, 6699.39705426514, 6742.348124709478], "value": 5048.038566496988, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.7470136049662, 707.9752208672697, 707.9787606371809, 725.9419246460284, 791.6002418778515, 15063.17152556609, 15386.923065384686, 15431.074135551875, 15448.69102617947, 15470.071795692267, 14179.0, 14287.499937502189, 14376.424943002268, 14378.568642940729, 14378.928105359459, 783.0759703549809, 794.400227827989, 949.0651708067548, 953.6280850468323, 970.8301047316727, 949.6248981652243, 952.7046615549167, 953.3374304858127, 954.5656726189567, 958.6644674363952, 1033.958641654333, 1034.9120324772402, 1036.0364860679565, 1037.486444210117, 1037.9325343852663, 827.4745536584252, 840.0968958369716, 840.8024114333127, 842.1830824100643, 1013.5844303835465, 768.3046842607394, 771.6450432800918, 772.7874834420523, 775.9883601745978, 776.0493395590356, 15453.613659658486, 15538.378464861393, 15577.454789082327, 15595.220238988006, 15688.058716477048, 13118.672033199151, 13239.801402978963, 13440.932795336059, 13443.529476468277, 13465.461381544726, 10304.433256170907, 10454.0, 10499.580016799318, 10809.567617295299, 10968.341899485977, 3616.2405894762132, 3654.28741395428, 3662.468942003421, 3717.7955212463303, 3881.5536213335263, 84645.0, 86858.0, 88721.11278887258, 89190.10809891883, 89559.55220223923, 9874.0, 9888.901110988942, 9915.702528924145, 9930.354526955725, 10165.695029149138, 22887.885560572424, 22953.589873063087, 22954.311370658907, 22959.196428124935, 22994.42513937165, 7543.358814500829, 7641.083070031632, 7643.082830060428, 7644.312011918954, 7705.537667739954, 4162.105147393265, 4249.2988656871885, 4275.850345237902, 4332.371806088099, 4556.97721511397, 3693.8153092345274, 3762.736608437438, 3765.4351847223134, 3765.981170094132, 3768.0391500167284, 673.0846049372849, 680.1498127340824, 688.896665500169, 693.5665209244222, 699.5173330402006, 604.2416766957529, 617.7220250887119, 637.5887552528593, 642.922849258092, 651.1209866679944, 8000.479968802126, 8321.66713331472, 8368.696240504309, 8533.829323413496, 8639.95680021596, 544.4609836262072, 547.4197350808076, 575.4964406144624, 604.8941435248814, 650.6616559389091, 3582.444721068239, 3918.502323311297, 4003.499562554681, 4005.1989602079702, 4252.213340532012, 88057.0, 88575.0, 89317.55341223252, 89590.0, 89658.0, 63092.0, 79105.0, 79117.0, 79854.0, 81322.18677813146, 66627.0, 66772.33227667758, 67251.66374168098, 67576.6621166891, 68151.3184868145, 7919.208079192034, 8032.4778889372, 8100.2304781045705, 8103.959480202797, 8118.228768267005, 38017.61982380141, 48248.03503929832, 49608.0, 50897.236541451166, 51655.225171623206, 65258.34741652523, 68076.0, 69880.0, 70731.0, 71252.0, 10487.161027117965, 10744.785104298027, 10828.296160749525, 10835.241533092609, 11007.284526505751, 12046.337451439904, 12052.75894482123, 12287.0, 12289.07831912628, 12866.742665146458, 8667.91332086671, 10290.12533934594, 11492.310461372344, 12117.939410302892, 12138.514459421523, 9298.395604285699, 10412.947935260276, 10670.46647667629, 11840.171188016757, 11980.161388702707, 9709.320347575602, 10646.361218326925, 11514.848515148418, 11720.589779358022, 12305.569305074638, 10128.139108175594, 10151.137153342048, 10238.897611024093, 10274.589016439259, 10320.0, 10098.19214462856, 10254.0, 10288.2798204128, 10290.331128476626, 10291.176705863663], "value": 7060.916685458809, "unit": "score"}], "name": "dromaeo_css", "value": 7626.319192601848, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 117.0, 111.0, 112.0, 112.0, 114.0, 114.0, 113.0, 134.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 119.0, 142.0, 163.0, 118.0, 135.0, 138.0, 116.0, 165.0, 138.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 184.0, 185.0, 184.0, 186.0, 184.0, 185.0, 181.0, 184.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 101.0, 100.0, 187.0, 55.0, 55.0, 55.0, 61.0, 176.0, 79.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 139.0, 76.0, 78.0, 83.0, 122.0, 73.0, 76.0, 125.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 89.0, 85.0, 90.0, 87.0, 88.0, 85.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 95.0, 94.0, 94.0, 92.0, 94.0, 95.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 85.0, 86.0, 84.0, 84.0, 84.0, 83.0, 88.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 61.0, 61.0, 61.0, 62.0, 62.0, 63.0, 78.0, 57.0, 55.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 61.0, 78.0, 70.0, 73.0, 70.0, 64.0, 65.0, 66.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 94.0, 78.0, 79.0, 78.0, 78.0, 76.0, 78.0, 95.0, 107.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 125.0, 125.0, 139.0, 136.0, 144.0, 138.0, 110.0, 164.0, 156.0], "value": 136.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 161.0, 161.0, 160.0, 164.0, 161.0, 161.0, 166.0, 159.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 68.0, 65.0, 67.0, 65.0, 66.0, 64.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1475.2999999999997, "alertThreshold": 2.0}]} 16:49:42 INFO - Return code: 0 16:49:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:49:42 ERROR - # TBPL SUCCESS # 16:49:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:49:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:49:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:49:42 INFO - 2016-06-15 16:49:42.170 screenresolution[1681:9827] starting screenresolution argv=screenresolution get 16:49:42 INFO - 2016-06-15 16:49:42.172 screenresolution[1681:9827] Display 0: 1600x1200x32@60 16:49:42 INFO - 2016-06-15 16:49:42.178 screenresolution[1682:9830] starting screenresolution argv=screenresolution list 16:49:42 INFO - Available Modes on Display 0 16:49:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:49:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:49:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:49:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:49:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:49:42 INFO - Intel Iris: 16:49:42 INFO - Chipset Model: Intel Iris 16:49:42 INFO - Type: GPU 16:49:42 INFO - Bus: Built-In 16:49:42 INFO - VRAM (Dynamic, Max): 1536 MB 16:49:42 INFO - Vendor: Intel (0x8086) 16:49:42 INFO - Device ID: 0x0a2e 16:49:42 INFO - Revision ID: 0x0009 16:49:42 INFO - Displays: 16:49:42 INFO - Display: 16:49:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:49:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:49:42 INFO - Display Serial Number: 1600x1200 60 16:49:42 INFO - Main Display: Yes 16:49:42 INFO - Mirror: Off 16:49:42 INFO - Online: Yes 16:49:42 INFO - Rotation: Supported 16:49:42 INFO - Return code: 0 16:49:42 INFO - Running post-action listener: _resource_record_post_action 16:49:42 INFO - Running post-run listener: _resource_record_post_run 16:49:43 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 37725696; Write bytes: 494023168; Read time: 388; Write time: 1671 16:49:43 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 189239808; Write bytes: 185870336; Read time: 17485; Write time: 362 16:49:43 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 35870208; Write bytes: 297318912; Read time: 366; Write time: 1277 16:49:43 INFO - Running post-run listener: _upload_blobber_files 16:49:43 INFO - Blob upload gear active. 16:49:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:49:43 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 16:49:43 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'] 16:49:43 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 16:49:43 INFO - (blobuploader) - INFO - Open directory for files ... 16:49:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:49:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:49:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:45 INFO - (blobuploader) - INFO - Done attempting. 16:49:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:49:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:49:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:49:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:49:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:49:46 INFO - (blobuploader) - INFO - Done attempting. 16:49:46 INFO - (blobuploader) - INFO - Iteration through files over. 16:49:46 INFO - Return code: 0 16:49:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:49:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:49:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93fe4fd19cd3f0ab2134eecd4417d0bd18c770065da21a122b8010e96c94a6a66ac027750d0e080c4f6e7dc44ea1794e126fe39138a6581449d8223bd73a4cce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4cdeb7f3a4687d87718105390595c7e0ee95caee079373f39cd680c5e011c6f562b7e4007d23101f2d09a26606d4a8b8cedf3215f4e0880fee563f5015f5168f"} 16:49:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:49:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:49:46 INFO - Contents: 16:49:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93fe4fd19cd3f0ab2134eecd4417d0bd18c770065da21a122b8010e96c94a6a66ac027750d0e080c4f6e7dc44ea1794e126fe39138a6581449d8223bd73a4cce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4cdeb7f3a4687d87718105390595c7e0ee95caee079373f39cd680c5e011c6f562b7e4007d23101f2d09a26606d4a8b8cedf3215f4e0880fee563f5015f5168f"} 16:49:46 INFO - Running post-run listener: copy_logs_to_upload_dir 16:49:46 INFO - Copying logs to upload dir... 16:49:46 INFO - mkdir: /builds/slave/test/build/upload/logs 16:49:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.747194 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-06-15 16:49:46.455766) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-15 16:49:46.459450) ========= 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.yMPhQxvSox/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.IHrPoZynxC/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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/93fe4fd19cd3f0ab2134eecd4417d0bd18c770065da21a122b8010e96c94a6a66ac027750d0e080c4f6e7dc44ea1794e126fe39138a6581449d8223bd73a4cce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4cdeb7f3a4687d87718105390595c7e0ee95caee079373f39cd680c5e011c6f562b7e4007d23101f2d09a26606d4a8b8cedf3215f4e0880fee563f5015f5168f"} build_url:https://queue.taskcluster.net/v1/task/DjgmIYz9TOGJYEuQzKRmOg/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/DjgmIYz9TOGJYEuQzKRmOg/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/93fe4fd19cd3f0ab2134eecd4417d0bd18c770065da21a122b8010e96c94a6a66ac027750d0e080c4f6e7dc44ea1794e126fe39138a6581449d8223bd73a4cce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4cdeb7f3a4687d87718105390595c7e0ee95caee079373f39cd680c5e011c6f562b7e4007d23101f2d09a26606d4a8b8cedf3215f4e0880fee563f5015f5168f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-15 16:49:46.491169) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 16:49:46.491484) ========= 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.yMPhQxvSox/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.IHrPoZynxC/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.005154 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-15 16:49:46.539922) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-15 16:49:46.540249) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-15 16:49:46.540585) ========= ========= Total master_lag: 0.20 =========