builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0046 starttime: 1463047446.98 results: success (0) buildid: 20160512004026 builduid: f1831c3703254ebe8afb2ea6ab6d7fe7 revision: 901b6248af7d34012dc519cef40ec38c034f1c39 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:06.981086) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:06.981509) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:06.981865) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:07.002682) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:07.002966) ========= 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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-12 03:04:07-- 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% 40.6M=0s 2016-05-12 03:04:07 (40.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:07.145473) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:07.145781) ========= 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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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.026005 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:07.193864) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 03:04:07.194249) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 901b6248af7d34012dc519cef40ec38c034f1c39 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 901b6248af7d34012dc519cef40ec38c034f1c39 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zz0Vr7XjvT/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.VtlDhZROzF/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-12 03:04:07,268 truncating revision to first 12 chars 2016-05-12 03:04:07,268 Setting DEBUG logging. 2016-05-12 03:04:07,268 attempt 1/10 2016-05-12 03:04:07,268 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/901b6248af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 03:04:08,042 unpacking tar archive at: mozilla-aurora-901b6248af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.026385 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 03:04:08.238236) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:08.238626) ========= script_repo_revision: 901b6248af7d34012dc519cef40ec38c034f1c39 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:08.239072) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:08.239401) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 03:04:08.254115) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-12 03:04:08.254446) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zz0Vr7XjvT/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.VtlDhZROzF/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 03:04:08 INFO - MultiFileLogger online at 20160512 03:04:08 in /builds/slave/test 03:04:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:04:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:04:08 INFO - {'append_to_log': False, 03:04:08 INFO - 'base_work_dir': '/builds/slave/test', 03:04:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:04:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:04:08 INFO - 'branch': 'Mozilla-Aurora', 03:04:08 INFO - 'buildbot_json_path': 'buildprops.json', 03:04:08 INFO - 'config_files': ('talos/mac_config.py',), 03:04:08 INFO - 'default_actions': ('clobber', 03:04:08 INFO - 'read-buildbot-config', 03:04:08 INFO - 'download-and-extract', 03:04:08 INFO - 'populate-webroot', 03:04:08 INFO - 'create-virtualenv', 03:04:08 INFO - 'install', 03:04:08 INFO - 'run-tests'), 03:04:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:04:08 INFO - 'download_minidump_stackwalk': True, 03:04:08 INFO - 'download_symbols': 'ondemand', 03:04:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:04:08 INFO - 'tooltool.py': '/tools/tooltool.py', 03:04:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:04:08 INFO - '/tools/misc-python/virtualenv.py')}, 03:04:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:04:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:04:08 INFO - 'installer_path': 'installer.exe', 03:04:08 INFO - 'log_level': 'info', 03:04:08 INFO - 'log_name': 'talos', 03:04:08 INFO - 'log_to_console': True, 03:04:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:04:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:04:08 INFO - 'opt_config_files': (), 03:04:08 INFO - 'pip_index': False, 03:04:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:04:08 INFO - 'cmd': ('bash', 03:04:08 INFO - '-c', 03:04:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:04:08 INFO - 'enabled': True, 03:04:08 INFO - 'halt_on_failure': False, 03:04:08 INFO - 'name': 'check_screen_resolution'},), 03:04:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:04:08 INFO - 'cmd': ('bash', 03:04:08 INFO - '-c', 03:04:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:04:08 INFO - 'enabled': True, 03:04:08 INFO - 'halt_on_failure': False, 03:04:08 INFO - 'name': 'check_screen_resolution'},), 03:04:08 INFO - 'run_cmd_checks_enabled': True, 03:04:08 INFO - 'sps_profile': False, 03:04:08 INFO - 'sps_profile_interval': 0, 03:04:08 INFO - 'suite': 'dromaeojs-e10s', 03:04:08 INFO - 'system_bits': '32', 03:04:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:04:08 INFO - 'title': 't-yosemite-r7-0046', 03:04:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:04:08 INFO - 'use_talos_json': True, 03:04:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:04:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:04:08 INFO - 'work_dir': 'build'} 03:04:08 INFO - ##### 03:04:08 INFO - ##### Running clobber step. 03:04:08 INFO - ##### 03:04:08 INFO - Running pre-action listener: _resource_record_pre_action 03:04:08 INFO - Running main action method: clobber 03:04:08 INFO - rmtree: /builds/slave/test/build 03:04:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:04:11 INFO - Running post-action listener: _resource_record_post_action 03:04:11 INFO - ##### 03:04:11 INFO - ##### Running read-buildbot-config step. 03:04:11 INFO - ##### 03:04:11 INFO - Running pre-action listener: _resource_record_pre_action 03:04:11 INFO - Running main action method: read_buildbot_config 03:04:11 INFO - Using buildbot properties: 03:04:11 INFO - { 03:04:11 INFO - "project": "", 03:04:11 INFO - "product": "firefox", 03:04:11 INFO - "script_repo_revision": "production", 03:04:11 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:04:11 INFO - "repository": "", 03:04:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:04:11 INFO - "buildid": "20160512004026", 03:04:11 INFO - "stage_platform": "macosx64", 03:04:11 INFO - "basedir": "/builds/slave/test", 03:04:11 INFO - "nightly_build": "True", 03:04:11 INFO - "buildnumber": 4, 03:04:11 INFO - "slavename": "t-yosemite-r7-0046", 03:04:11 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:04:11 INFO - "platform": "yosemite_r7", 03:04:11 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:04:11 INFO - "branch": "mozilla-aurora", 03:04:11 INFO - "repo_path": "releases/mozilla-aurora", 03:04:11 INFO - "revision": "901b6248af7d34012dc519cef40ec38c034f1c39", 03:04:11 INFO - "pgo_build": "False", 03:04:11 INFO - "builduid": "f1831c3703254ebe8afb2ea6ab6d7fe7", 03:04:11 INFO - "slavebuilddir": "test" 03:04:11 INFO - } 03:04:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:04:11 INFO - Running post-action listener: _resource_record_post_action 03:04:11 INFO - ##### 03:04:11 INFO - ##### Running download-and-extract step. 03:04:11 INFO - ##### 03:04:11 INFO - Running pre-action listener: _resource_record_pre_action 03:04:11 INFO - Running main action method: download_and_extract 03:04:11 INFO - mkdir: /builds/slave/test/build/tests 03:04:11 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')]} 03:04:11 INFO - https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:04:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:04:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:04:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:04:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:04:12 INFO - Downloaded 1199 bytes. 03:04:12 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:04:12 INFO - Using the following test package requirements: 03:04:12 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:04:12 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:04:12 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:04:12 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:04:12 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:04:12 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:04:12 INFO - u'jsshell-mac.zip'], 03:04:12 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:04:12 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:04:12 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:04:12 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:04:12 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:04:12 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:04:12 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:04:12 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:04:12 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:04:12 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:04:12 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:04:12 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:04:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:12 INFO - https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:04:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:04:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:04:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:04:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:04:13 INFO - Downloaded 18089587 bytes. 03:04:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:04:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:04:16 INFO - Return code: 0 03:04:16 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:04: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')]} 03:04:16 INFO - https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:04:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:04:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:04:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:04:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:04:17 INFO - Downloaded 18089587 bytes. 03:04:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:04:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:04:21 INFO - Return code: 0 03:04:21 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')]} 03:04:21 INFO - https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:04:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:04:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:04:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:04:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:04:22 INFO - Downloaded 10840145 bytes. 03:04:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:04:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:04:22 INFO - Return code: 0 03:04:22 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')]} 03:04:22 INFO - https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:04:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:04:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:04:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:04:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:04:26 INFO - Downloaded 114149144 bytes. 03:04:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:04:26 INFO - mkdir: /builds/slave/test/properties 03:04:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:04:26 INFO - Writing to file /builds/slave/test/properties/build_url 03:04:26 INFO - Contents: 03:04:26 INFO - build_url:https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:04:27 INFO - Running post-action listener: _resource_record_post_action 03:04:27 INFO - Running post-action listener: set_extra_try_arguments 03:04:27 INFO - ##### 03:04:27 INFO - ##### Running populate-webroot step. 03:04:27 INFO - ##### 03:04:27 INFO - Running pre-action listener: _resource_record_pre_action 03:04:27 INFO - Running main action method: populate_webroot 03:04:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:04:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:04:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:04:27 INFO - '--tppagecycles', 03:04:27 INFO - '7'], 03:04:27 INFO - 'tests': ['tsvgm']}}, 03:04:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:04:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:04:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:04:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:04:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:04:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:04:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:04:27 INFO - 'talos_options': ['--disable-e10s'], 03:04:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:04:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:04:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:04:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:04:27 INFO - 'talos_options': ['--disable-e10s'], 03:04:27 INFO - 'tests': ['damp', 'tps']}, 03:04:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:04:27 INFO - 'tests': ['damp', 'tps']}, 03:04:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:04:27 INFO - 'tests': ['dromaeo_dom']}, 03:04:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:04:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:04:27 INFO - 'tests': ['a11yr', 03:04:27 INFO - 'ts_paint', 03:04:27 INFO - 'tpaint', 03:04:27 INFO - 'sessionrestore', 03:04:27 INFO - 'sessionrestore_no_auto_restore', 03:04:27 INFO - 'tabpaint']}, 03:04:27 INFO - 'other-e10s': {'tests': ['a11yr', 03:04:27 INFO - 'ts_paint', 03:04:27 INFO - 'tpaint', 03:04:27 INFO - 'sessionrestore', 03:04:27 INFO - 'sessionrestore_no_auto_restore', 03:04:27 INFO - 'tabpaint']}, 03:04:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:04:27 INFO - 'ts_paint', 03:04:27 INFO - 'tpaint', 03:04:27 INFO - 'sessionrestore', 03:04:27 INFO - 'sessionrestore_no_auto_restore', 03:04:27 INFO - 'tabpaint']}, 03:04:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:04:27 INFO - 'ts_paint', 03:04:27 INFO - 'tpaint', 03:04:27 INFO - 'sessionrestore', 03:04:27 INFO - 'sessionrestore_no_auto_restore', 03:04:27 INFO - 'tabpaint']}, 03:04:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:04:27 INFO - 'tests': ['a11yr', 03:04:27 INFO - 'ts_paint', 03:04:27 INFO - 'tpaint', 03:04:27 INFO - 'sessionrestore', 03:04:27 INFO - 'sessionrestore_no_auto_restore', 03:04:27 INFO - 'tabpaint']}, 03:04:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:04:27 INFO - 'tests': ['a11yr', 03:04:27 INFO - 'ts_paint', 03:04:27 INFO - 'tpaint', 03:04:27 INFO - 'sessionrestore', 03:04:27 INFO - 'sessionrestore_no_auto_restore', 03:04:27 INFO - 'tabpaint']}, 03:04:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:04:27 INFO - 'tests': ['tsvgx', 03:04:27 INFO - 'tsvgr_opacity', 03:04:27 INFO - 'tart', 03:04:27 INFO - 'tscrollx', 03:04:27 INFO - 'cart']}, 03:04:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:04:27 INFO - 'tsvgr_opacity', 03:04:27 INFO - 'tart', 03:04:27 INFO - 'tscrollx', 03:04:27 INFO - 'cart']}, 03:04:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:04:27 INFO - 'talos_options': ['--disable-e10s'], 03:04:27 INFO - 'tests': ['tp5o']}, 03:04:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:04:27 INFO - 'tests': ['tp5o']}, 03:04:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:04:27 INFO - 'talos_options': ['--disable-e10s', 03:04:27 INFO - '--xperf_path', 03:04:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:04:27 INFO - 'tests': ['tp5n']}, 03:04:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:04:27 INFO - 'talos_options': ['--xperf_path', 03:04:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:04:27 INFO - 'tests': ['tp5n']}}, 03:04:27 INFO - 'talos.zip': {'path': '', 03:04:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:04:27 INFO - Running post-action listener: _resource_record_post_action 03:04:27 INFO - ##### 03:04:27 INFO - ##### Running create-virtualenv step. 03:04:27 INFO - ##### 03:04:27 INFO - Running pre-action listener: _resource_record_pre_action 03:04:27 INFO - Running main action method: create_virtualenv 03:04:27 INFO - Creating virtualenv /builds/slave/test/build/venv 03:04:27 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 03:04:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:04:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:04:27 INFO - Using real prefix '/tools/python27' 03:04:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:04:28 INFO - Installing distribute.............................................................................................................................................................................................done. 03:04:30 INFO - Installing pip.................done. 03:04:30 INFO - Return code: 0 03:04:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:04:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:30 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 0x10fe86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe96420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f2e03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd25e00>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd20b20>, '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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 03:04:30 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 03:04:30 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 03:04:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zz0Vr7XjvT/Render', 03:04:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:30 INFO - 'HOME': '/Users/cltbld', 03:04:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:30 INFO - 'LOGNAME': 'cltbld', 03:04:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:30 INFO - 'MOZ_NO_REMOTE': '1', 03:04:30 INFO - 'NO_EM_RESTART': '1', 03:04:30 INFO - 'PAGER': '/bin/cat', 03:04:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:30 INFO - 'PWD': '/builds/slave/test', 03:04:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:30 INFO - 'SHELL': '/bin/bash', 03:04:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtlDhZROzF/Listeners', 03:04:30 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 03:04:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:30 INFO - 'USER': 'cltbld', 03:04:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:30 INFO - 'XPC_FLAGS': '0x0', 03:04:30 INFO - 'XPC_SERVICE_NAME': '0', 03:04:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:31 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 03:04:31 INFO - Cleaning up... 03:04:31 INFO - Return code: 0 03:04:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:04:31 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')]} 03:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:31 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')]} 03:04:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:31 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 0x10fe86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe96420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f2e03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd25e00>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd20b20>, '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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 03:04:31 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 03:04:31 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 03:04:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zz0Vr7XjvT/Render', 03:04:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:31 INFO - 'HOME': '/Users/cltbld', 03:04:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:31 INFO - 'LOGNAME': 'cltbld', 03:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:31 INFO - 'MOZ_NO_REMOTE': '1', 03:04:31 INFO - 'NO_EM_RESTART': '1', 03:04:31 INFO - 'PAGER': '/bin/cat', 03:04:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:31 INFO - 'PWD': '/builds/slave/test', 03:04:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:31 INFO - 'SHELL': '/bin/bash', 03:04:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtlDhZROzF/Listeners', 03:04:31 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 03:04:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:31 INFO - 'USER': 'cltbld', 03:04:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:31 INFO - 'XPC_FLAGS': '0x0', 03:04:31 INFO - 'XPC_SERVICE_NAME': '0', 03:04:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:31 INFO - Downloading/unpacking psutil>=0.7.1 03:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:04:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:04:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:04:34 INFO - Installing collected packages: psutil 03:04:34 INFO - Running setup.py install for psutil 03:04:34 INFO - building 'psutil._psutil_osx' extension 03:04:34 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 03:04:34 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 03:04:35 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 03:04:35 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 03:04:35 INFO - building 'psutil._psutil_posix' extension 03:04:35 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 03:04:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:04:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:04:35 INFO - ^ 03:04:35 INFO - 1 warning generated. 03:04:35 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 03:04:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:04:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:04:35 INFO - Successfully installed psutil 03:04:35 INFO - Cleaning up... 03:04:35 INFO - Return code: 0 03:04:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:04:35 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')]} 03:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:35 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')]} 03:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:35 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 0x10fe86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe96420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f2e03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd25e00>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd20b20>, '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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 03:04:35 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 03:04:35 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 03:04:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zz0Vr7XjvT/Render', 03:04:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:35 INFO - 'HOME': '/Users/cltbld', 03:04:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:35 INFO - 'LOGNAME': 'cltbld', 03:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:35 INFO - 'MOZ_NO_REMOTE': '1', 03:04:35 INFO - 'NO_EM_RESTART': '1', 03:04:35 INFO - 'PAGER': '/bin/cat', 03:04:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:35 INFO - 'PWD': '/builds/slave/test', 03:04:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:35 INFO - 'SHELL': '/bin/bash', 03:04:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtlDhZROzF/Listeners', 03:04:35 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 03:04:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:35 INFO - 'USER': 'cltbld', 03:04:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:35 INFO - 'XPC_FLAGS': '0x0', 03:04:35 INFO - 'XPC_SERVICE_NAME': '0', 03:04:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:04:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:04:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:04:38 INFO - Installing collected packages: mozsystemmonitor 03:04:38 INFO - Running setup.py install for mozsystemmonitor 03:04:38 INFO - Successfully installed mozsystemmonitor 03:04:38 INFO - Cleaning up... 03:04:38 INFO - Return code: 0 03:04:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:04: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')]} 03:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04: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')]} 03:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:38 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 0x10fe86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe96420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f2e03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd25e00>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd20b20>, '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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 03:04:38 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 03:04:38 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 03:04:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zz0Vr7XjvT/Render', 03:04:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:38 INFO - 'HOME': '/Users/cltbld', 03:04:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:38 INFO - 'LOGNAME': 'cltbld', 03:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:38 INFO - 'MOZ_NO_REMOTE': '1', 03:04:38 INFO - 'NO_EM_RESTART': '1', 03:04:38 INFO - 'PAGER': '/bin/cat', 03:04:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:38 INFO - 'PWD': '/builds/slave/test', 03:04:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:38 INFO - 'SHELL': '/bin/bash', 03:04:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtlDhZROzF/Listeners', 03:04:38 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 03:04:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:38 INFO - 'USER': 'cltbld', 03:04:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:38 INFO - 'XPC_FLAGS': '0x0', 03:04:38 INFO - 'XPC_SERVICE_NAME': '0', 03:04:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:38 INFO - Downloading/unpacking blobuploader==1.2.4 03:04:38 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 03:04:38 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 03:04:38 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 03:04:38 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 03:04:38 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 03:04:38 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 03:04:41 INFO - Downloading blobuploader-1.2.4.tar.gz 03:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:04:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:04:41 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 03:04:41 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 03:04:41 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 03:04:41 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 03:04:41 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 03:04:41 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 03:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:04:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:04:41 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 03:04:41 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 03:04:41 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 03:04:41 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 03:04:41 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 03:04:41 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 03:04:42 INFO - Downloading docopt-0.6.1.tar.gz 03:04:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:04:42 INFO - Installing collected packages: blobuploader, requests, docopt 03:04:42 INFO - Running setup.py install for blobuploader 03:04:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:04:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:04:42 INFO - Running setup.py install for requests 03:04:42 INFO - Running setup.py install for docopt 03:04:43 INFO - Successfully installed blobuploader requests docopt 03:04:43 INFO - Cleaning up... 03:04:43 INFO - Return code: 0 03:04:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:04:43 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')]} 03:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:43 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')]} 03:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:43 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 0x10fe86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe96420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f2e03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd25e00>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd20b20>, '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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 03:04:43 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 03:04:43 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 03:04:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zz0Vr7XjvT/Render', 03:04:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:43 INFO - 'HOME': '/Users/cltbld', 03:04:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:43 INFO - 'LOGNAME': 'cltbld', 03:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:43 INFO - 'MOZ_NO_REMOTE': '1', 03:04:43 INFO - 'NO_EM_RESTART': '1', 03:04:43 INFO - 'PAGER': '/bin/cat', 03:04:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:43 INFO - 'PWD': '/builds/slave/test', 03:04:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:43 INFO - 'SHELL': '/bin/bash', 03:04:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtlDhZROzF/Listeners', 03:04:43 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 03:04:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:43 INFO - 'USER': 'cltbld', 03:04:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:43 INFO - 'XPC_FLAGS': '0x0', 03:04:43 INFO - 'XPC_SERVICE_NAME': '0', 03:04:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:04:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-nAQRlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:04:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-ZQjIdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:04:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:04:43 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-Jhe0hf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:04:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-0PYt7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:04:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-VKGasp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:04:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-YYLLim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:04:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-bpvSux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:04:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-00uAUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:04:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-VN6z32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:04:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-_OciPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:04:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-HMqdfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:04:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:04:44 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-iv17TC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:04:45 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-OQ8WH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:04:45 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-ZeBPhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:04:45 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-bWjvQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:04:45 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-hARUkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:04:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:04:45 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-GsyUcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:04:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:04:45 INFO - Running setup.py install for manifestparser 03:04:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:04:45 INFO - Running setup.py install for mozcrash 03:04:45 INFO - Running setup.py install for mozdebug 03:04:45 INFO - Running setup.py install for mozdevice 03:04:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:04:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:04:46 INFO - Running setup.py install for mozfile 03:04:46 INFO - Running setup.py install for mozhttpd 03:04:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:04:46 INFO - Running setup.py install for mozinfo 03:04:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:04:46 INFO - Running setup.py install for mozInstall 03:04:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:04:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:04:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:04:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:04:46 INFO - Running setup.py install for mozleak 03:04:46 INFO - Running setup.py install for mozlog 03:04:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:04:47 INFO - Running setup.py install for moznetwork 03:04:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:04:47 INFO - Running setup.py install for mozprocess 03:04:47 INFO - Running setup.py install for mozprofile 03:04:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:04:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:04:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:04:47 INFO - Running setup.py install for mozrunner 03:04:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:04:47 INFO - Running setup.py install for mozscreenshot 03:04:47 INFO - Running setup.py install for moztest 03:04:47 INFO - Running setup.py install for mozversion 03:04:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:04:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:04:48 INFO - Cleaning up... 03:04:48 INFO - Return code: 0 03:04:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:04:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:48 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 0x10fe86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe96420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f2e03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd25e00>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd20b20>, '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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 03:04:48 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 03:04:48 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 03:04:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zz0Vr7XjvT/Render', 03:04:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:48 INFO - 'HOME': '/Users/cltbld', 03:04:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:48 INFO - 'LOGNAME': 'cltbld', 03:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:48 INFO - 'MOZ_NO_REMOTE': '1', 03:04:48 INFO - 'NO_EM_RESTART': '1', 03:04:48 INFO - 'PAGER': '/bin/cat', 03:04:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:48 INFO - 'PWD': '/builds/slave/test', 03:04:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:48 INFO - 'SHELL': '/bin/bash', 03:04:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtlDhZROzF/Listeners', 03:04:48 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 03:04:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:48 INFO - 'USER': 'cltbld', 03:04:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:48 INFO - 'XPC_FLAGS': '0x0', 03:04:48 INFO - 'XPC_SERVICE_NAME': '0', 03:04:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:04:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-dXQLr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:04:48 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)) 03:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:04:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-8u3OlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:04:48 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)) 03:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:04:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-NtA7zI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:04:48 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)) 03:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:04:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-KLP1Qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:04:48 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)) 03:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:04:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-W3clSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:04:48 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)) 03:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:04:48 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-9gVlAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:04:49 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)) 03:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:04:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-eQQf0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:04:49 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)) 03:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:04:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-sqVJs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:04:49 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)) 03:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:04:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-zgL7IA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:04:49 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)) 03:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:04:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-Ey_Ct4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:04:49 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)) 03:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:04:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-cuHJUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:04:49 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)) 03:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:04:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-wYKsNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:04:49 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)) 03:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:04:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-QWyWm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:04:49 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)) 03:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:04:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-9SAlot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:04:49 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)) 03:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:04:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-9VUhYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:04:49 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)) 03:04:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:04:49 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-pTHVhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:04:50 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)) 03:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:04:50 INFO - Running setup.py (path:/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/pip-akF9mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:04:50 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)) 03:04:50 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)) 03:04:50 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)) 03:04:50 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)) 03:04:50 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)) 03:04:50 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)) 03:04:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:04:50 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 03:04:50 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 03:04:50 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 03:04:50 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 03:04:50 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 03:04:50 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 03:04:52 INFO - Downloading blessings-1.6.tar.gz 03:04:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:04:53 INFO - Installing collected packages: blessings 03:04:53 INFO - Running setup.py install for blessings 03:04:53 INFO - Successfully installed blessings 03:04:53 INFO - Cleaning up... 03:04:53 INFO - Return code: 0 03:04:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:04:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:04:53 INFO - Reading from file tmpfile_stdout 03:04:53 INFO - Current package versions: 03:04:53 INFO - blessings == 1.6 03:04:53 INFO - blobuploader == 1.2.4 03:04:53 INFO - docopt == 0.6.1 03:04:53 INFO - manifestparser == 1.1 03:04:53 INFO - mozInstall == 1.12 03:04:53 INFO - mozcrash == 0.17 03:04:53 INFO - mozdebug == 0.1 03:04:53 INFO - mozdevice == 0.48 03:04:53 INFO - mozfile == 1.2 03:04:53 INFO - mozhttpd == 0.7 03:04:53 INFO - mozinfo == 0.9 03:04:53 INFO - mozleak == 0.1 03:04:53 INFO - mozlog == 3.1 03:04:53 INFO - moznetwork == 0.27 03:04:53 INFO - mozprocess == 0.22 03:04:53 INFO - mozprofile == 0.28 03:04:53 INFO - mozrunner == 6.11 03:04:53 INFO - mozscreenshot == 0.1 03:04:53 INFO - mozsystemmonitor == 0.0 03:04:53 INFO - moztest == 0.7 03:04:53 INFO - mozversion == 1.4 03:04:53 INFO - psutil == 3.1.1 03:04:53 INFO - requests == 1.2.3 03:04:53 INFO - wsgiref == 0.1.2 03:04:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:53 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 0x10fe86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe96420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f2e03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd25e00>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd20b20>, '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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 03:04:53 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 03:04:53 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 03:04:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zz0Vr7XjvT/Render', 03:04:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:53 INFO - 'HOME': '/Users/cltbld', 03:04:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:53 INFO - 'LOGNAME': 'cltbld', 03:04:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:53 INFO - 'MOZ_NO_REMOTE': '1', 03:04:53 INFO - 'NO_EM_RESTART': '1', 03:04:53 INFO - 'PAGER': '/bin/cat', 03:04:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:53 INFO - 'PWD': '/builds/slave/test', 03:04:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:53 INFO - 'SHELL': '/bin/bash', 03:04:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtlDhZROzF/Listeners', 03:04:53 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 03:04:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:53 INFO - 'USER': 'cltbld', 03:04:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:53 INFO - 'XPC_FLAGS': '0x0', 03:04:53 INFO - 'XPC_SERVICE_NAME': '0', 03:04:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:53 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)) 03:04:53 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)) 03:04:53 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)) 03:04:53 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)) 03:04:53 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)) 03:04:53 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)) 03:04:53 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)) 03:04:53 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)) 03:04:53 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)) 03:04:53 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)) 03:04:53 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)) 03:04:53 INFO - Cleaning up... 03:04:53 INFO - Return code: 0 03:04:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe96420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f2e03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd25e00>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd20b20>, '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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 03:04:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:04:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:04:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zz0Vr7XjvT/Render', 03:04:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:53 INFO - 'HOME': '/Users/cltbld', 03:04:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:53 INFO - 'LOGNAME': 'cltbld', 03:04:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:53 INFO - 'MOZ_NO_REMOTE': '1', 03:04:53 INFO - 'NO_EM_RESTART': '1', 03:04:53 INFO - 'PAGER': '/bin/cat', 03:04:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:53 INFO - 'PWD': '/builds/slave/test', 03:04:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:53 INFO - 'SHELL': '/bin/bash', 03:04:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtlDhZROzF/Listeners', 03:04:53 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 03:04:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:53 INFO - 'USER': 'cltbld', 03:04:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:53 INFO - 'XPC_FLAGS': '0x0', 03:04:53 INFO - 'XPC_SERVICE_NAME': '0', 03:04:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:54 INFO - Downloading/unpacking jsonschema 03:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:04:56 INFO - Downloading/unpacking functools32 (from jsonschema) 03:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:56 INFO - Downloading functools32-3.2.3-2.tar.gz 03:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:04:57 INFO - warning: no files found matching '*.txt' 03:04:57 INFO - no previously-included directories found matching 'build' 03:04:57 INFO - no previously-included directories found matching 'dist' 03:04:57 INFO - no previously-included directories found matching '.git*' 03:04:57 INFO - Installing collected packages: jsonschema, functools32 03:04:57 INFO - Running setup.py install for functools32 03:04:57 INFO - warning: no files found matching '*.txt' 03:04:57 INFO - no previously-included directories found matching 'build' 03:04:57 INFO - no previously-included directories found matching 'dist' 03:04:57 INFO - no previously-included directories found matching '.git*' 03:04:57 INFO - Successfully installed jsonschema functools32 03:04:57 INFO - Cleaning up... 03:04:57 INFO - Return code: 0 03:04:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:04: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')]} 03:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04: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')]} 03:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe96420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4f2e03b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd25e00>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd20b20>, '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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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 03:04:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:04:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zz0Vr7XjvT/Render', 03:04:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:57 INFO - 'HOME': '/Users/cltbld', 03:04:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:57 INFO - 'LOGNAME': 'cltbld', 03:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:57 INFO - 'MOZ_NO_REMOTE': '1', 03:04:57 INFO - 'NO_EM_RESTART': '1', 03:04:57 INFO - 'PAGER': '/bin/cat', 03:04:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:57 INFO - 'PWD': '/builds/slave/test', 03:04:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:57 INFO - 'SHELL': '/bin/bash', 03:04:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtlDhZROzF/Listeners', 03:04:57 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 03:04:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:57 INFO - 'USER': 'cltbld', 03:04:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:57 INFO - 'XPC_FLAGS': '0x0', 03:04:57 INFO - 'XPC_SERVICE_NAME': '0', 03:04:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:57 INFO - Downloading/unpacking flake8 03:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:05:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:05:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:05:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:05:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:05:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:05:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:05:01 INFO - no previously-included directories found matching 'docs/_build' 03:05:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:05:01 INFO - Running setup.py install for pep8 03:05:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:05:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:05:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:05:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:05:01 INFO - no previously-included directories found matching 'docs/_build' 03:05:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:05:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:05:01 INFO - Cleaning up... 03:05:01 INFO - Return code: 0 03:05:01 INFO - Running post-action listener: _resource_record_post_action 03:05:01 INFO - Running post-action listener: _start_resource_monitoring 03:05:01 INFO - Starting resource monitoring. 03:05:01 INFO - ##### 03:05:01 INFO - ##### Running install step. 03:05:01 INFO - ##### 03:05:01 INFO - Running pre-action listener: _resource_record_pre_action 03:05:01 INFO - Running main action method: install 03:05:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:01 INFO - Reading from file tmpfile_stdout 03:05:01 INFO - Detecting whether we're running mozinstall >=1.0... 03:05:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:05:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:05:02 INFO - Reading from file tmpfile_stdout 03:05:02 INFO - Output received: 03:05:02 INFO - Usage: mozinstall [options] installer 03:05:02 INFO - Options: 03:05:02 INFO - -h, --help show this help message and exit 03:05:02 INFO - -d DEST, --destination=DEST 03:05:02 INFO - Directory to install application into. [default: 03:05:02 INFO - "/builds/slave/test"] 03:05:02 INFO - --app=APP Application being installed. [default: firefox] 03:05:02 INFO - mkdir: /builds/slave/test/build/application 03:05:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:05:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:05:34 INFO - Reading from file tmpfile_stdout 03:05:34 INFO - Output received: 03:05:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:05:34 INFO - Running post-action listener: _resource_record_post_action 03:05:34 INFO - ##### 03:05:34 INFO - ##### Running run-tests step. 03:05:34 INFO - ##### 03:05:34 INFO - Running pre-action listener: _resource_record_pre_action 03:05:34 INFO - Running main action method: run_tests 03:05:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:05:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:05:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:05:34 INFO - 2016-05-12 03:05:34.056 screenresolution[1643:7630] starting screenresolution argv=screenresolution get 03:05:34 INFO - 2016-05-12 03:05:34.059 screenresolution[1643:7630] Display 0: 1600x1200x32@60 03:05:34 INFO - 2016-05-12 03:05:34.064 screenresolution[1644:7632] starting screenresolution argv=screenresolution list 03:05:34 INFO - Available Modes on Display 0 03:05:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:05:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:05:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:05:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:05:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:05:34 INFO - Intel Iris: 03:05:34 INFO - Chipset Model: Intel Iris 03:05:34 INFO - Type: GPU 03:05:34 INFO - Bus: Built-In 03:05:34 INFO - VRAM (Dynamic, Max): 1536 MB 03:05:34 INFO - Vendor: Intel (0x8086) 03:05:34 INFO - Device ID: 0x0a2e 03:05:34 INFO - Revision ID: 0x0009 03:05:34 INFO - Displays: 03:05:34 INFO - Display: 03:05:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:05:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:05:34 INFO - Display Serial Number: 1600x1200 60 03:05:34 INFO - Main Display: Yes 03:05:34 INFO - Mirror: Off 03:05:34 INFO - Online: Yes 03:05:34 INFO - Rotation: Supported 03:05:34 INFO - Return code: 0 03:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:05:34 INFO - Python 2.7.3 03:05:34 INFO - Return code: 0 03:05:34 INFO - grabbing minidump binary from tooltool 03:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:05:34 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 0x10fd25e00>, '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 0x10fb8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fd20b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:05:34 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 03:05:34 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 03:05:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:05:34 INFO - Return code: 0 03:05:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:05:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:05:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:05:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:05:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:05:35 INFO - Return code: 0 03:05:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:05:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0046 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:05:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zz0Vr7XjvT/Render', 03:05:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:05:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:05:35 INFO - 'HOME': '/Users/cltbld', 03:05:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:05:35 INFO - 'LOGNAME': 'cltbld', 03:05:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:05:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:35 INFO - 'MOZ_NO_REMOTE': '1', 03:05:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:05:35 INFO - 'NO_EM_RESTART': '1', 03:05:35 INFO - 'PAGER': '/bin/cat', 03:05:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:35 INFO - 'PWD': '/builds/slave/test', 03:05:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:05:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:05:35 INFO - 'SHELL': '/bin/bash', 03:05:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VtlDhZROzF/Listeners', 03:05:35 INFO - 'TMPDIR': '/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/', 03:05:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:05:35 INFO - 'USER': 'cltbld', 03:05:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:05:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:35 INFO - 'XPC_FLAGS': '0x0', 03:05:35 INFO - 'XPC_SERVICE_NAME': '0', 03:05:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:05:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0046', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:05:35 INFO - mozversion application_buildid: 20160512004026 03:05:35 INFO - mozversion application_changeset: 901b6248af7d34012dc519cef40ec38c034f1c39 03:05:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:05:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:05:35 INFO - mozversion application_name: Firefox 03:05:35 INFO - mozversion application_remotingname: firefox-dev 03:05:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:05:35 INFO - mozversion application_vendor: Mozilla 03:05:35 INFO - mozversion application_version: 48.0a2 03:05:35 INFO - mozversion platform_buildid: 20160512004026 03:05:35 INFO - mozversion platform_changeset: 901b6248af7d34012dc519cef40ec38c034f1c39 03:05:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:05:35 INFO - mozversion platform_version: 48.0a2 03:05:35 INFO - using testdate: 1463047535 03:05:35 INFO - actual date: 1463047535 03:05:35 INFO - starting webserver on 'localhost:49278' 03:05:35 INFO - SUITE-START | Running 2 tests 03:05:35 INFO - TEST-START | dromaeo_css 03:05:35 INFO - operating with platform_type : mac_ 03:05:35 INFO - Initialising browser for dromaeo_css test... 03:05:35 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpwkxpg0/profile http://localhost:49278/getInfo.html) 03:05:38 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 03:05:38 INFO - PROCESS | 1662 | colorDepth:24 03:05:38 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 03:05:38 INFO - PROCESS | 1662 | __metrics 03:05:38 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 03:05:39 INFO - TEST-INFO | 1662: exit 0 03:05:39 INFO - Browser initialized. 03:05:39 INFO - Running cycle 1/1 for dromaeo_css test... 03:05:39 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmpwkxpg0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:05:51 INFO - PROCESS | 1666 | RSS: Main: 221204480 03:05:51 INFO - PROCESS | 1666 | 03:06:41 INFO - PROCESS | 1666 | 1463047601062 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] 03:08:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 03:08:54 INFO - PROCESS | 1666 | RSS: Main: 231170048 03:08:54 INFO - PROCESS | 1666 | 03:11:56 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 03:11:57 INFO - PROCESS | 1666 | RSS: Main: 243322880 03:11:57 INFO - PROCESS | 1666 | 03:14:58 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 03:14:59 INFO - PROCESS | 1666 | RSS: Main: 245817344 03:14:59 INFO - PROCESS | 1666 | 03:18:01 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 03:18:01 INFO - PROCESS | 1666 | RSS: Main: 247033856 03:18:01 INFO - PROCESS | 1666 | 03:21:04 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 03:21:05 INFO - PROCESS | 1666 | RSS: Main: 249929728 03:21:05 INFO - PROCESS | 1666 | 03:24:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 03:24:07 INFO - PROCESS | 1666 | RSS: Main: 250826752 03:24:07 INFO - PROCESS | 1666 | 03:24:07 INFO - PROCESS | 1666 | __start_tp_report 03:24:07 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 03:24:07 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 03:24:07 INFO - PROCESS | 1666 | |i|pagename|runs| 03:24:07 INFO - PROCESS | 1666 | |0;dojo.html;3279.1638132276275;3477;3497.737669674775;3513.47297905314;3530.399832028555;5791.07342825148;5808.680522571257;5819.156222347757;5826.592138550298;5847.152162936377;5766.452187042224;5774.191613174159;5807.767689292434;5813.157092221625;5823.621464604808;4742.434996451163;5077.162268225738;5244.108501554726;5288.788448462057;5342.679439233639;5220.034293655667;5376.704281264529;5406.351237851443;5448.509634132927;5469.890602187954;5286.286351342577;5289.550388217006;5321.36143662761;5328.786848526054;5388.353397592295;5306.946930530684;5371.838844834622;5374.193870919355;5375.032494151052;5418;5195.506426889439;5304.522592966613;5307.416184219733;5329.6002799789785;5355.143177091685;5521.972390138024;5584.553235741131;5600.243967064435;5694.373618901917;5718.370979192286;6196.380361963813;6267.028610565324;6272.937270627326;6297.937020629781;6306;6255.311915689271;6257.43683068526;6258.499320054385;6267.5299352548745;6271.686415679198;5680.460356266107;5857.88284234313;5858;5875.8531036724;5939.46544810969;6278.717457714414;6315.747370105144;6317.96841015792;6335.556511044183;6361.109444677749;6204.162438070893;6219.440250377488;6234.5635805494085;6239.282482514478;6260.530460215502;4989.276554899519;6192.721327540272;6246.562740608114;6269.78055768055;6291.02489114188;6188.226471691039;6253.499720022387;6277.027060805583;6283.497320214372;6283.654399008098;6244.219472565929;6272.874542509125;6309.3690630936535;6324.08300796382;6333.334999825044;4883.780229889663;4917.237828136645;4964.3298154748645;4968.105740966661;5001.774920128603;3998.7800670962793;4409.735415875058;4417.823287068545;4443.977885086449;4495.056038231943;4988.775505102279;5794.739236734357;5847;5864.296284445893;5898.675572843449;6164.352742961924;6201.906971395432;6205.162303089115;6219.751209951552;6274.247090349188;5837.795677151279;5839.445252700986;5847.649141051551;5856.297244330707;5865.530757539386;5434.3207099112615;5453.918191227135;5459.836204913859;5465.781368745205;5472.343318801769;5699.373068962328;6256.937430625727;6304.054391841169;6337.651429171347;6363.840903977438;5073.7716802743225;5709.772398934249;5990.221271234712;6248.347097128351;6251.124842521959;6263.373662633882;6382.106505089198;6397.904031439439;6398.680065996588;6410.1987251593555;6168;6201.503879689524;6226.003839385677;6232.875342493034;6236.75052997875;6256.530760192913;6264.248290205204;6265.561410701207;6298;6302.991521356561;5505.596073001359;6089.2997305310455;6135.785247516315;6226.564140510302;6233.065040243819;6133.601315914451;6137.5302083125;6190.876182476415;6198.442140207403;6202.4417802398;4669.696469729457;5275.393329766973;5988.736138804275;6050.515958723379;6082.3613520581475;6015.488683461955;6124.6325220486915;6139.263288405241;6214.8135555933395;6240.344763799736;6015.097735339734;6049.51603871698;6122.234720659918;6144.41628045335;6172.969135154475 03:24:07 INFO - PROCESS | 1666 | |1;ext.html;493.48430889720214;517.8575891629802;554.1853475391176;566.6600039976017;621.8383220362707;38651.80674096629;39567.40648890269;39609.80195099027;39636.81089567314;39693.80918572447;40707.18585628293;41096;41244.793776031154;41437.792811035906;41632.16735665285;8199.83600327993;9559.474228917425;9761.658341958033;9811.558479868387;9823.999999999964;9619.663311784088;9739.951300243489;9789.55946982384;9866.950665246699;9876;9797.559109840075;9829.21366290695;9851.113399794018;9871.60513579456;9882.752931176743;9771.951140244324;9783.315167938212;9835.114839664464;9844.458554779485;9852.408855468695;9680.612775488971;9765.218782497383;9815.312928095036;9820.65627703027;9855.507224638739;39787.80636580907;39973.40039899404;40147.59852401468;40295.7985210075;40354;3187.984060079708;3292.96707032929;3396.3886500429912;3738.1776009277914;3894.3963685628773;22651.660225096635;28821.711782882114;28940.27649308763;29091.854540727374;29342.853285733436;2752.2431331383827;2762.309422644339;2820.732030457103;2830.1933948824694;2859.9714002859914;248851;306352;307722;310594;311715.99999999773;59370;59697;59856.503587409796;59918.40081599215;60070.69964650236;71262.64368678123;72192.27807721855;72599;72807.27192728111;73160.63419682867;17211.483655490356;17268.827311726975;17375.870568413266;17431.651366972845;17433.651326973388;12881.162724422882;12894.548690795777;12992.285424301564;13211.53759618428;13299.800502992462;19877.701834472493;20239.886806225506;20283;20801.479963001046;20806.895965520074;8273.33813294947;8319.542425166674;8346.457480263764;8390.454620449651;8419.937115406641;1000.0749306891097;1000.4547521600745;1072.222638587018;1072.6781965410346;1074.3929679730927;30228.84885575558;30415.162902913766;30751.923682671448;31020.68979310178;31227;1125.9549618015187;1150;1157.8089615213396;1201.4653479201845;1261.1928365845865;14549.92725036368;15696.450624228097;15784.526464206092;15856.841431585768;16049.358025679076;627913;640788.796056026;645855;646603;649880;55707.60730981677;59741.701291493264;62496.687516562124;63066.68466657729;63254;1459.802926604916;1528.808898887639;1547.2341191110247;1559.812822461312;1564.608847788053;29626;30343.696563034086;30600.49766656301;30697;30898.382032359677;54734.726326368116;55188.44811551994;55202.895942081734;55733.49199286123;55873.44126558682;556043;569154;573213;574930;574930;27456.901723931616;27593.310167245178;27636.789056875;27758.72241277642;28113;47302.526974729815;47303.526964729914;48454.757726211144;48894.75552622356;49024.754876225394;39182.78181863385;39378.80310598428;39510.80244598874;39533.40699889562;40188.397174043;38611.806940965114;38875.80562097171;38946;38990;39874;32451.188720281236;38082.809585951894;38126.428103579026;38457.88813794702;38488.80755596316;30270;32372.86694965759;32417.675823241465;32452.675473244966;32487.837560812044;22482.763448010515;23800.40498987539;24145.348084564182;24478.265652030466;25142.62286065747 03:24:07 INFO - PROCESS | 1666 | |2;jquery.html;352.233891286452;402.6436603605808;404.6519992806188;411.6768336855569;414.21092818181364;13610.795838062411;18826;20469.385918422453;21156.471088222785;21181.152753889844;20256.797432025676;20500.794992050076;20792.168313267488;21057.947102644885;21451;5738.483536481715;5797.82606521804;6066.908996365036;6097.054956481752;6139.263288405397;5831.212786273863;6021.879562408749;6066.878662426749;6071.726772295236;6123.846903827418;6105.419985101408;6111.113888486167;6120;6135.8772824543485;6153.969230153843;5878.764849406019;5986.551008674345;6049.30433000204;6107.664078475682;6147.631142131487;5939.554533409968;5992.760289588455;6034.818955431344;6045.12345709874;6067.544934129958;13541.796873046913;24998.37504062395;25054.745072943522;25098.99604015837;25946.351341216432;1980.5246740782109;2111.9577608447744;2153.4616345913523;2254.4138523983884;2298.7816157465013;9412.764680882965;9437.858432123523;10251.128654064414;12334.383280835922;15132.697346053128;1647.6045749020268;1751.1244377811095;1761.5067781021335;1764.5765016396101;1793.4440323499625;111010.44494777556;114327.42836285848;117007;121361;123807.3809630946;11850.407479625985;11930.582429614922;12064.396780160956;12103.213291136048;12142.817857732141;13551.59345219645;14317.498887539094;15038.32327545263;15047.172405517802;15071.944963852644;8765.737027889174;8860.468371897578;8942.239909607757;8970.192682658591;9040.909590904006;8991.235744961752;9023.954880225689;9064.184223419923;9080.772980675536;9114.675757681882;4672.32251323563;4673.392458980379;4676.859694209179;4715.717056976592;4728.905421891543;1773.9201735921915;1953.335865805633;1966.3117908731829;1971.4578490915112;2055.342290467036;931.3945935142211;960.6157536985262;985.9014098590084;1026.2610920137488;1062.877769056553;19966;20407;20471.897640512;20834;20862.95685215733;969.4813274897896;976.4824642939111;998.3410948773775;1057.2757661002238;1058.6665200461985;8514.233718965323;8966.282697384193;10803.675889723321;10967.287126336922;11006.779864402668;246272;354887.4511254906;616693;626275;627282;118534.40732796454;123165.38417307856;127034;138250;139977;896.6727144592177;1004.6533945788691;1049.5434485998462;1051.6319288249204;1057.8413238014205;17757.46727598174;18085.90957045259;18203.817961820212;18867.622647547247;18879;38357.80821095876;50804;51899.74050129725;55144.62138446571;56178.752849885546;71008.64495677488;73080.63459682881;75176;75544;75591.62204188944;8086.272235498598;8193.795155120932;8217.671293148207;8263.00843898736;8355.03917049547;11592.420378980849;12160.939195303967;12320.815187772012;12673.746525069631;12758.425870835648;10722.87833561279;10790.67627971162;10870.923403723604;10928.94535527349;10954.123670106213;8124.63439145228;10452.216083793593;10508.527116279633;10519.21105917044;10519.526621302213;10004.399736015863;10176.829664588668;10479.738006549896;10591.947040265057;10886.94556527244;6957.408620267334;8185.467944583821;8230.465019773696;8279.83440331178;8321.83356332882;8046.959765201136;9626;10992.340459572451;11079.889201107886;11142.387168705474 03:24:07 INFO - PROCESS | 1666 | |3;mootools.html;1553.9145347005915;1557.8831587630928;1790.6508230894972;1882.614064116856;1941.1361943934955;7949.085855126662;8532.91467085329;8719.735638332446;8923.616839389897;8947.32895032872;8630.352723545744;8638.308935285178;8679;8757.474551526897;8822;2075.6471399861985;2447.571674956884;2459.3359792855917;2469.76537228963;2474.1711526638596;2432.1730611592056;2440.6217036359303;2466.6300054991807;2482.5407299649623;2493.376655836041;2408.927732168038;2433.914812981546;2437.5856104462196;2438.5854404751235;2452.9509409811794;2402.423418379585;2415.5168966206743;2420.903163873452;2425.466397392571;2469.8641574713383;2449.0326321103244;2455.7789798918006;2476.418041760185;2480.503899220163;2485.1302044284357;8857.202851743374;8866.202041816203;8888;8896.777580560472;8928.017918029012;5014.623903207237;5194.11700010999;5390.894866552387;5537.640053396516;5568.49883510482;3491.0574144980715;3493.056874643832;3493.5982362028185;3554.0937061049526;3590.856365745367;950.6102497975796;1042.880068792091;1050.4905121016302;1062.3814969381085;1073.6081330314457;11756.1182911282;11806.763864722658;11938.462769175409;12010.759784804342;12041.397930103547;4843.128236917319;4865.9026819463415;4865.95134048662;4893.192623217129;4922;10086;10201.591936322613;10273.589056437659;10297.794044119075;10303.53634086453;5420.810271640474;5434.538064264581;5479.726013699299;5480.506754392124;5536.474034966753;4441.467023957146;4880.365552478155;4986.301917731521;4990.2764099205415;5011.373578302712;4064.6748260139116;4098.4896161107945;4112.136451345191;4126.42230087788;4143;1344.126317893377;1354.1401210231575;1360.0411709744606;1365.9317034148253;1370.7464119137992;814.7800093974594;817.3992115794886;857.322715055112;935.9953200233955;945.7021038373028;5918.496927761103;5993.190919225936;5996.580239383201;6036.909446358308;6146.661933593606;891.527490430073;985.4826216236534;988.906053924876;994.9303548751662;1003.3578509753763;3952.446657467957;4031.657309128757;4033.9596604034173;4047.4738284022887;4050.6151915567975;10659.84010239847;11071.944640276746;11094.278871873303;11095.001449869551;11152.888471115184;10675.786484270271;10712.875148109337;10771.730706732395;10830.429587709737;10869.510872010778;7959.761207163679;8346.457480263764;8508;8564.914350856536;8621.525816080175;5635.98552260597;5670.177824215548;5789.450002249948;5845.18167456565;5848.5028772553105;9477.383970041925;9507.619695212114;9601.519924003633;9696.70909872705;9716.514174291116;9379.296552758708;9785.36395134314;9954.303198776017;10169.542370593193;10183.032611901857;6838.111045564045;7354.191038985814;7570.886436703564;7571.772846814604;7705.1524332324525;8226.0540037894;8332.416730828783;8342.457740246864;8401.95799021001;8406.495610263404;7206.315400036988;7333.193348731528;7397.26027397256;7410.18487966334;7477.401807855467;7222.0611320528;7222.566646001257;7257.0565826442225;7306.7077316906725;7350.448716346188;5856.443637854397;6976.860462790614;6999.720011199495;7089.964550177217;7107;4588.875725447287;4658.510856360033;4900.558949694402;4939.975300123477;5021.5229553192385;4971.105201063843;5011.273365362075;5014.473480284518;5068.163752980717;5225.372955245243 03:24:07 INFO - PROCESS | 1666 | |4;prototype.html;325.4678600488202;326.61622593452694;331.6269197153201;332.307139613905;337.9155211197201;5472.398036216018;5518.91721624176;6769.39075483207;6809.514527197581;6914.619695916723;5348.866278343044;5387.787747756753;6341.763356145553;6738.494612904027;6825.488088393366;3863.517060367454;4033.1530378620523;4784.282357646355;4865.099956508039;5017;4975.104481193383;5050.444451110375;5084.51697088776;5087.364079490064;5099.898002039957;5023.271625614284;5035.194368900977;5038.470960549144;5052.671576347544;5054.898902021957;5044.747762611873;5051.343325367697;5054.620903432257;5062.721550314731;5063.873403164914;3675.889723308305;4164.167166566668;4308.870733877989;4309.4397728295435;4314.223439780838;5274.55166310864;5651.886962260732;6403.167588213549;6522.543421960465;6672.366125218096;5906.5570082244;5977.940220597782;5986.880262394771;5993.790217342415;5999.310079340888;7330.120385553768;7352.48532602718;7367.5211111277595;7383.852322953565;7385.29839665231;4757.833475828329;4820.325154478376;4825.131476334259;4830.1064303103685;4841.9031619367415;7524.510906791096;7791.844163116706;7800.18098099697;7816.296533311973;7834.921650783476;4856.39295088114;4896.46138924718;4918.434380046269;4920.3603531541385;4921.212605983097;4118.670506359484;4955.2567114933045;5005.249212618063;5027.648064635514;5031.798728050837;5934.406559344031;5934.8813023739285;5937.643741375531;5943.881122377528;5962.37395073511;7385.483016188923;7388.815279617945;7390.667419966115;7391.778246652501;7398.519096258726;1719.9914000429917;1745.1448790092763;1747.3622127923215;1748.064785339837;1757.2882982392152;1026.5431882812077;1068.7755571330108;1070.7002039428971;1071.9624813131502;1120.3613940054254;6624.768133115318;6703.597784132968;6706.865862682816;6717.328267173243;6739.93260067393;7469.663865126083;7552.697892084365;7647.57938313387;7666.54000759945;7672.079350477867;6681.331866813377;6835.384815366641;6888.207856096613;6925.515213934977;6943.479239057091;3324.817135057595;4072.226277007359;4117.75293482392;4127.669786417079;4135.669146468275;8137.186281371815;8144.267015968591;8153.796155096052;8162.102168761431;8164.918350816416;7735.458517903805;8002.239787220205;8057.153998830156;8058.274755272054;8088.676452941934;6734.4949128816515;7914.683412663544;8138.145494723206;8151.30713889326;8155.714549990722;6434.839129021625;7743.883841742266;7805.726799561988;7869.134395216636;7870.425914816945;6209.472194863487;7331.926680733124;7634.46558740905;7650.655720492702;7810.0237470316215;6185.474234690102;8141.470804397695;8160.959195203986;8172.141925097779;8201.09787923316;4735.100330937111;4757.881052973565;4782.258749893772;4799.976000120117;4802.639802014933;7786.987691600282;7798.103218129988;7800.063992320957;7813.726519571787;7814.6483408247905;7496.025516683015;7499.62501874915;7506.512076714996;7516.3986881048295;7520.699172033057;7209.603471808891;7467.253274672489;7477.738279160203;7482.625868706652;7495.287947644965;7079.787606371817;7189.352958233784;7268.146622611201;7295.781126566211;7323.963380183065;6275.6548389839;7459.328660420588;7476;7504.962475187589;7524.021877156154;7023.297670232936;7282.708691652275;7291.453141014551;7312.451566132455;7320.121585409572 03:24:07 INFO - PROCESS | 1666 | |5;yui.html;692.5325405351388;709.1206903439733;717.7487879242265;743.1824992508239;749.145973590107;14507.92746036271;14626.268686565669;14794.112353258812;14801;14819.110853348806;13537.390817413216;13839.169649821019;13966.650833729136;13994.300284985735;14019.088759230643;727.3599232675239;882.5851849630664;889.2752406788479;908.1418059933366;930.8696782450462;899.7750562359411;918.669279059538;918.8162367526488;920.1442658327752;922.9584668689891;933.747888070224;951.0584521323909;964.7057647417535;972.863799068131;974.2790335151972;929.6699671616581;931.5388882503204;941.1106504353389;958.988267377916;965.7730433348157;682.8975653651943;687.1547995964941;688.8071340024801;691.498663468986;693.9583624982517;14423;14577.708445831024;14743.336549855283;14748.557543273719;14823.77764333536;12562.30907300098;12598.622041338684;12705.872941270654;12724.936375318064;12815.423305951255;9936.95031524845;10059.748506287327;10157.23820713442;10168;10212.693619191436;3495.8252087395526;3517.84169712361;3544.805035723034;3570.0182449826248;3614.5662520497444;82145.58927205326;82463;82831.5858420704;84151.15848841495;84217.99999999939;9272.907270927204;9347.345685801929;9504.42973421597;9560;9592.184664303473;20207.898960505103;20584.588308233753;20726.689099663516;20911.79088209129;21014.15943362279;7383.593902335423;7406.07424071991;7430;7473.140588832354;7564.773056808304;3850.942235866464;3916.118873253495;3964.940525892114;3968.4245784361683;3971.7418367806;3632.9818350908076;3695.90760230991;3702.0004598758183;3708.8331025103935;3720.4233343831747;663.8838203314401;668.9799306020827;679.3580066836792;680.7447207297264;681.1383599746325;501.34574380433156;551.1622334052219;591.8402031451484;593.3828818029201;596.406575457414;8144.185581441927;8193.46742461738;8223.21879421454;8243.629036693363;8460.619272132768;523.3170712220589;586.9325027621793;587.8765459253605;611.8837420890017;612.4824523277823;3738.102855314705;3797.810109494514;3806.352920003606;3830.195658911604;3873.4577159197734;87572;91775.5411222953;92476;92637;93171;72280.63859680668;75018.62490687621;75254.62372688102;75398.86901696479;75798;66576.00135997964;66581.66709166423;67066.66466667733;67477;68456.31543684499;8186.2632363087605;8436.578171091543;8501.447405918596;8509.787255318666;8638.82722345562;30835;35588;46385.53614463812;46570.06859862716;48300;50933;68235.65882170557;68431;69743.25641858994;71052.93420598592;10895.346279223271;10898.945505272422;10999.283952581864;11277.154213434189;11354.205205635857;9934.254930880068;10098.747531311483;12089.274643521418;12136.089793265364;12277.38613069325;11523.366214857928;11587.594434194763;11644.546397946418;11861.525538978343;11904.464299106388;11450.198486105892;11493.138014649103;11521.423928803693;12050.397480126134;12132.818007729715;9314.72055838326;10625.25623206368;10798.730031748955;11040.723981900517;11606.94196529012;9515.809683806423;9634.421934683942;9687.757806054906;9974.301798874008;10051.949740251252;7531.510451820614;8408.327333813158;9235.953820230856;9355.48544830041;9417 03:24:07 INFO - PROCESS | 1666 | __end_tp_report 03:24:07 INFO - PROCESS | 1666 | __start_cc_report 03:24:07 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,415 03:24:07 INFO - PROCESS | 1666 | __end_cc_report 03:24:07 INFO - PROCESS | 1666 | __startTimestamp1463048647510__endTimestamp 03:24:07 INFO - PROCESS | 1666 | 03:24:07 INFO - PROCESS | 1666 | ------- Summary: start ------- 03:24:07 INFO - PROCESS | 1666 | Number of tests: 6 03:24:07 INFO - PROCESS | 1666 | 03:24:07 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5762.69 Median:5988.74 stddev:646.92 (10.8%) stddev-sans-first:618.87 03:24:07 INFO - PROCESS | 1666 | Values: 3279.2 3477.0 3497.7 3513.5 3530.4 5791.1 5808.7 5819.2 5826.6 5847.2 5766.5 5774.2 5807.8 5813.2 5823.6 4742.4 5077.2 5244.1 5288.8 5342.7 5220.0 5376.7 5406.4 5448.5 5469.9 5286.3 5289.6 5321.4 5328.8 5388.4 5306.9 5371.8 5374.2 5375.0 5418.0 5195.5 5304.5 5307.4 5329.6 5355.1 5522.0 5584.6 5600.2 5694.4 5718.4 6196.4 6267.0 6272.9 6297.9 6306.0 6255.3 6257.4 6258.5 6267.5 6271.7 5680.5 5857.9 5858.0 5875.9 5939.5 6278.7 6315.7 6318.0 6335.6 6361.1 6204.2 6219.4 6234.6 6239.3 6260.5 4989.3 6192.7 6246.6 6269.8 6291.0 6188.2 6253.5 6277.0 6283.5 6283.7 6244.2 6272.9 6309.4 6324.1 6333.3 4883.8 4917.2 4964.3 4968.1 5001.8 3998.8 4409.7 4417.8 4444.0 4495.1 4988.8 5794.7 5847.0 5864.3 5898.7 6164.4 6201.9 6205.2 6219.8 6274.2 5837.8 5839.4 5847.6 5856.3 5865.5 5434.3 5453.9 5459.8 5465.8 5472.3 5699.4 6256.9 6304.1 6337.7 6363.8 5073.8 5709.8 5990.2 6248.3 6251.1 6263.4 6382.1 6397.9 6398.7 6410.2 6168.0 6201.5 6226.0 6232.9 6236.8 6256.5 6264.2 6265.6 6298.0 6303.0 5505.6 6089.3 6135.8 6226.6 6233.1 6133.6 6137.5 6190.9 6198.4 6202.4 4669.7 5275.4 5988.7 6050.5 6082.4 6015.5 6124.6 6139.3 6214.8 6240.3 6015.1 6049.5 6122.2 6144.4 6173.0 03:24:07 INFO - PROCESS | 1666 | 03:24:07 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:65240.72 Median:39280.79 stddev:140491.08 (357.7%) stddev-sans-first:140801.09 03:24:07 INFO - PROCESS | 1666 | Values: 493.5 517.9 554.2 566.7 621.8 38651.8 39567.4 39609.8 39636.8 39693.8 40707.2 41096.0 41244.8 41437.8 41632.2 8199.8 9559.5 9761.7 9811.6 9824.0 9619.7 9740.0 9789.6 9867.0 9876.0 9797.6 9829.2 9851.1 9871.6 9882.8 9772.0 9783.3 9835.1 9844.5 9852.4 9680.6 9765.2 9815.3 9820.7 9855.5 39787.8 39973.4 40147.6 40295.8 40354.0 3188.0 3293.0 3396.4 3738.2 3894.4 22651.7 28821.7 28940.3 29091.9 29342.9 2752.2 2762.3 2820.7 2830.2 2860.0 248851.0 306352.0 307722.0 310594.0 311716.0 59370.0 59697.0 59856.5 59918.4 60070.7 71262.6 72192.3 72599.0 72807.3 73160.6 17211.5 17268.8 17375.9 17431.7 17433.7 12881.2 12894.5 12992.3 13211.5 13299.8 19877.7 20239.9 20283.0 20801.5 20806.9 8273.3 8319.5 8346.5 8390.5 8419.9 1000.1 1000.5 1072.2 1072.7 1074.4 30228.8 30415.2 30751.9 31020.7 31227.0 1126.0 1150.0 1157.8 1201.5 1261.2 14549.9 15696.5 15784.5 15856.8 16049.4 627913.0 640788.8 645855.0 646603.0 649880.0 55707.6 59741.7 62496.7 63066.7 63254.0 1459.8 1528.8 1547.2 1559.8 1564.6 29626.0 30343.7 30600.5 30697.0 30898.4 54734.7 55188.4 55202.9 55733.5 55873.4 556043.0 569154.0 573213.0 574930.0 574930.0 27456.9 27593.3 27636.8 27758.7 28113.0 47302.5 47303.5 48454.8 48894.8 49024.8 39182.8 39378.8 39510.8 39533.4 40188.4 38611.8 38875.8 38946.0 38990.0 39874.0 32451.2 38082.8 38126.4 38457.9 38488.8 30270.0 32372.9 32417.7 32452.7 32487.8 22482.8 23800.4 24145.3 24478.3 25142.6 03:24:07 INFO - PROCESS | 1666 | 03:24:07 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:31874.29 Median:2276.60 stddev:87878.06 (3860.1%) stddev-sans-first:88092.71 03:24:07 INFO - PROCESS | 1666 | Values: 352.2 402.6 404.7 411.7 414.2 13610.8 18826.0 20469.4 21156.5 21181.2 20256.8 20500.8 20792.2 21057.9 21451.0 5738.5 5797.8 6066.9 6097.1 6139.3 5831.2 6021.9 6066.9 6071.7 6123.8 6105.4 6111.1 6120.0 6135.9 6154.0 5878.8 5986.6 6049.3 6107.7 6147.6 5939.6 5992.8 6034.8 6045.1 6067.5 13541.8 24998.4 25054.7 25099.0 25946.4 1980.5 2112.0 2153.5 2254.4 2298.8 9412.8 9437.9 10251.1 12334.4 15132.7 1647.6 1751.1 1761.5 1764.6 1793.4 111010.4 114327.4 117007.0 121361.0 123807.4 11850.4 11930.6 12064.4 12103.2 12142.8 13551.6 14317.5 15038.3 15047.2 15071.9 8765.7 8860.5 8942.2 8970.2 9040.9 8991.2 9024.0 9064.2 9080.8 9114.7 4672.3 4673.4 4676.9 4715.7 4728.9 1773.9 1953.3 1966.3 1971.5 2055.3 931.4 960.6 985.9 1026.3 1062.9 19966.0 20407.0 20471.9 20834.0 20863.0 969.5 976.5 998.3 1057.3 1058.7 8514.2 8966.3 10803.7 10967.3 11006.8 246272.0 354887.5 616693.0 626275.0 627282.0 118534.4 123165.4 127034.0 138250.0 139977.0 896.7 1004.7 1049.5 1051.6 1057.8 17757.5 18085.9 18203.8 18867.6 18879.0 38357.8 50804.0 51899.7 55144.6 56178.8 71008.6 73080.6 75176.0 75544.0 75591.6 8086.3 8193.8 8217.7 8263.0 8355.0 11592.4 12160.9 12320.8 12673.7 12758.4 10722.9 10790.7 10870.9 10928.9 10954.1 8124.6 10452.2 10508.5 10519.2 10519.5 10004.4 10176.8 10479.7 10591.9 10886.9 6957.4 8185.5 8230.5 8279.8 8321.8 8047.0 9626.0 10992.3 11079.9 11142.4 03:24:07 INFO - PROCESS | 1666 | 03:24:07 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5757.08 Median:5000.77 stddev:3197.76 (63.9%) stddev-sans-first:3191.13 03:24:07 INFO - PROCESS | 1666 | Values: 1553.9 1557.9 1790.7 1882.6 1941.1 7949.1 8532.9 8719.7 8923.6 8947.3 8630.4 8638.3 8679.0 8757.5 8822.0 2075.6 2447.6 2459.3 2469.8 2474.2 2432.2 2440.6 2466.6 2482.5 2493.4 2408.9 2433.9 2437.6 2438.6 2453.0 2402.4 2415.5 2420.9 2425.5 2469.9 2449.0 2455.8 2476.4 2480.5 2485.1 8857.2 8866.2 8888.0 8896.8 8928.0 5014.6 5194.1 5390.9 5537.6 5568.5 3491.1 3493.1 3493.6 3554.1 3590.9 950.6 1042.9 1050.5 1062.4 1073.6 11756.1 11806.8 11938.5 12010.8 12041.4 4843.1 4865.9 4866.0 4893.2 4922.0 10086.0 10201.6 10273.6 10297.8 10303.5 5420.8 5434.5 5479.7 5480.5 5536.5 4441.5 4880.4 4986.3 4990.3 5011.4 4064.7 4098.5 4112.1 4126.4 4143.0 1344.1 1354.1 1360.0 1365.9 1370.7 814.8 817.4 857.3 936.0 945.7 5918.5 5993.2 5996.6 6036.9 6146.7 891.5 985.5 988.9 994.9 1003.4 3952.4 4031.7 4034.0 4047.5 4050.6 10659.8 11071.9 11094.3 11095.0 11152.9 10675.8 10712.9 10771.7 10830.4 10869.5 7959.8 8346.5 8508.0 8564.9 8621.5 5636.0 5670.2 5789.5 5845.2 5848.5 9477.4 9507.6 9601.5 9696.7 9716.5 9379.3 9785.4 9954.3 10169.5 10183.0 6838.1 7354.2 7570.9 7571.8 7705.2 8226.1 8332.4 8342.5 8402.0 8406.5 7206.3 7333.2 7397.3 7410.2 7477.4 7222.1 7222.6 7257.1 7306.7 7350.4 5856.4 6976.9 6999.7 7090.0 7107.0 4588.9 4658.5 4900.6 4940.0 5021.5 4971.1 5011.3 5014.5 5068.2 5225.4 03:24:07 INFO - PROCESS | 1666 | 03:24:07 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5983.93 Median:6692.46 stddev:1964.79 (29.4%) stddev-sans-first:1923.85 03:24:07 INFO - PROCESS | 1666 | Values: 325.5 326.6 331.6 332.3 337.9 5472.4 5518.9 6769.4 6809.5 6914.6 5348.9 5387.8 6341.8 6738.5 6825.5 3863.5 4033.2 4784.3 4865.1 5017.0 4975.1 5050.4 5084.5 5087.4 5099.9 5023.3 5035.2 5038.5 5052.7 5054.9 5044.7 5051.3 5054.6 5062.7 5063.9 3675.9 4164.2 4308.9 4309.4 4314.2 5274.6 5651.9 6403.2 6522.5 6672.4 5906.6 5977.9 5986.9 5993.8 5999.3 7330.1 7352.5 7367.5 7383.9 7385.3 4757.8 4820.3 4825.1 4830.1 4841.9 7524.5 7791.8 7800.2 7816.3 7834.9 4856.4 4896.5 4918.4 4920.4 4921.2 4118.7 4955.3 5005.2 5027.6 5031.8 5934.4 5934.9 5937.6 5943.9 5962.4 7385.5 7388.8 7390.7 7391.8 7398.5 1720.0 1745.1 1747.4 1748.1 1757.3 1026.5 1068.8 1070.7 1072.0 1120.4 6624.8 6703.6 6706.9 6717.3 6739.9 7469.7 7552.7 7647.6 7666.5 7672.1 6681.3 6835.4 6888.2 6925.5 6943.5 3324.8 4072.2 4117.8 4127.7 4135.7 8137.2 8144.3 8153.8 8162.1 8164.9 7735.5 8002.2 8057.2 8058.3 8088.7 6734.5 7914.7 8138.1 8151.3 8155.7 6434.8 7743.9 7805.7 7869.1 7870.4 6209.5 7331.9 7634.5 7650.7 7810.0 6185.5 8141.5 8161.0 8172.1 8201.1 4735.1 4757.9 4782.3 4800.0 4802.6 7787.0 7798.1 7800.1 7813.7 7814.6 7496.0 7499.6 7506.5 7516.4 7520.7 7209.6 7467.3 7477.7 7482.6 7495.3 7079.8 7189.4 7268.1 7295.8 7324.0 6275.7 7459.3 7476.0 7505.0 7524.0 7023.3 7282.7 7291.5 7312.5 7320.1 03:24:07 INFO - PROCESS | 1666 | 03:24:07 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17811.02 Median:66578.83 stddev:25128.84 (37.7%) stddev-sans-first:25166.46 03:24:07 INFO - PROCESS | 1666 | Values: 692.5 709.1 717.7 743.2 749.1 14507.9 14626.3 14794.1 14801.0 14819.1 13537.4 13839.2 13966.7 13994.3 14019.1 727.4 882.6 889.3 908.1 930.9 899.8 918.7 918.8 920.1 923.0 933.7 951.1 964.7 972.9 974.3 929.7 931.5 941.1 959.0 965.8 682.9 687.2 688.8 691.5 694.0 14423.0 14577.7 14743.3 14748.6 14823.8 12562.3 12598.6 12705.9 12724.9 12815.4 9937.0 10059.7 10157.2 10168.0 10212.7 3495.8 3517.8 3544.8 3570.0 3614.6 82145.6 82463.0 82831.6 84151.2 84218.0 9272.9 9347.3 9504.4 9560.0 9592.2 20207.9 20584.6 20726.7 20911.8 21014.2 7383.6 7406.1 7430.0 7473.1 7564.8 3850.9 3916.1 3964.9 3968.4 3971.7 3633.0 3695.9 3702.0 3708.8 3720.4 663.9 669.0 679.4 680.7 681.1 501.3 551.2 591.8 593.4 596.4 8144.2 8193.5 8223.2 8243.6 8460.6 523.3 586.9 587.9 611.9 612.5 3738.1 3797.8 3806.4 3830.2 3873.5 87572.0 91775.5 92476.0 92637.0 93171.0 72280.6 75018.6 75254.6 75398.9 75798.0 66576.0 66581.7 67066.7 67477.0 68456.3 8186.3 8436.6 8501.4 8509.8 8638.8 30835.0 35588.0 46385.5 46570.1 48300.0 50933.0 68235.7 68431.0 69743.3 71052.9 10895.3 10898.9 10999.3 11277.2 11354.2 9934.3 10098.7 12089.3 12136.1 12277.4 11523.4 11587.6 11644.5 11861.5 11904.5 11450.2 11493.1 11521.4 12050.4 12132.8 9314.7 10625.3 10798.7 11040.7 11606.9 9515.8 9634.4 9687.8 9974.3 10051.9 7531.5 8408.3 9236.0 9355.5 9417.0 03:24:07 INFO - PROCESS | 1666 | -------- Summary: end -------- 03:24:07 INFO - PROCESS | 1666 | 03:24:08 INFO - TEST-INFO | 1666: exit 0 03:24:08 INFO - TEST-OK | dromaeo_css | took 1112366ms 03:24:08 INFO - TEST-START | kraken 03:24:08 INFO - operating with platform_type : mac_ 03:24:08 INFO - Initialising browser for kraken test... 03:24:08 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmp_W3qAX/profile http://localhost:49278/getInfo.html) 03:24:11 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 03:24:11 INFO - PROCESS | 1677 | colorDepth:24 03:24:11 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 03:24:11 INFO - PROCESS | 1677 | __metrics 03:24:11 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 03:24:11 INFO - TEST-INFO | 1677: exit 0 03:24:11 INFO - Browser initialized. 03:24:11 INFO - Running cycle 1/1 for kraken test... 03:24:11 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/tmp_W3qAX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:24:23 INFO - PROCESS | 1681 | RSS: Main: 221167616 03:24:23 INFO - PROCESS | 1681 | 03:24:26 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 03:24:27 INFO - PROCESS | 1681 | RSS: Main: 233005056 03:24:27 INFO - PROCESS | 1681 | 03:24:29 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 03:24:30 INFO - PROCESS | 1681 | RSS: Main: 241721344 03:24:30 INFO - PROCESS | 1681 | 03:24:33 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 03:24:34 INFO - PROCESS | 1681 | RSS: Main: 245321728 03:24:34 INFO - PROCESS | 1681 | 03:24:36 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 03:24:37 INFO - PROCESS | 1681 | RSS: Main: 247341056 03:24:37 INFO - PROCESS | 1681 | 03:24:39 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:24:39 INFO - PROCESS | 1681 | RSS: Main: 247574528 03:24:39 INFO - PROCESS | 1681 | 03:24:42 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 03:24:43 INFO - PROCESS | 1681 | RSS: Main: 247472128 03:24:43 INFO - PROCESS | 1681 | 03:24:46 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 03:24:46 INFO - PROCESS | 1681 | RSS: Main: 247664640 03:24:46 INFO - PROCESS | 1681 | 03:24:49 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 03:24:50 INFO - PROCESS | 1681 | RSS: Main: 247799808 03:24:50 INFO - PROCESS | 1681 | 03:24:52 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:24:52 INFO - PROCESS | 1681 | RSS: Main: 247402496 03:24:52 INFO - PROCESS | 1681 | 03:24:54 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:24:55 INFO - PROCESS | 1681 | RSS: Main: 247521280 03:24:55 INFO - PROCESS | 1681 | 03:24:57 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:24:58 INFO - PROCESS | 1681 | RSS: Main: 247537664 03:24:58 INFO - PROCESS | 1681 | 03:25:01 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:25:01 INFO - PROCESS | 1681 | RSS: Main: 247603200 03:25:01 INFO - PROCESS | 1681 | 03:25:04 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:25:05 INFO - PROCESS | 1681 | RSS: Main: 247496704 03:25:05 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 03:25:07 INFO - PROCESS | 1681 | RSS: Main: 249511936 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | __start_tp_report 03:25:07 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 03:25:07 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 03:25:07 INFO - PROCESS | 1681 | |i|pagename|runs| 03:25:07 INFO - PROCESS | 1681 | |0;ai-astar;111;120;109;118;119;117;107;116;116;119 03:25:07 INFO - PROCESS | 1681 | |1;audio-beat-detection;148;125;158;178;120;141;123;151;156;122 03:25:07 INFO - PROCESS | 1681 | |2;audio-dft;191;195;194;196;208;237;255;195;198;193 03:25:07 INFO - PROCESS | 1681 | |3;audio-fft;63;68;79;140;73;56;56;60;127;75 03:25:07 INFO - PROCESS | 1681 | |4;audio-oscillator;80;87;135;107;111;86;121;79;79;118 03:25:07 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;90;88;91;87;90;86;92;87;80;130 03:25:07 INFO - PROCESS | 1681 | |6;imaging-darkroom;95;97;95;96;96;116;97;95;95;96 03:25:07 INFO - PROCESS | 1681 | |7;imaging-desaturate;91;92;125;79;139;142;135;92;118;149 03:25:07 INFO - PROCESS | 1681 | |8;json-parse-financial;62;64;62;96;56;62;65;112;94;88 03:25:07 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;65;75;97;101;93;78;72;66;71;66 03:25:07 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;81;83;80;81;82;83;82;84;84;85 03:25:07 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;131;129;127;130;156;149;129;132;187;148 03:25:07 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;160;221;214;189;172;163;165;167;169;161 03:25:07 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;71;67;71;70;68;76;83;79;71;61 03:25:07 INFO - PROCESS | 1681 | __end_tp_report 03:25:07 INFO - PROCESS | 1681 | __start_cc_report 03:25:07 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1088 03:25:07 INFO - PROCESS | 1681 | __end_cc_report 03:25:07 INFO - PROCESS | 1681 | __startTimestamp1463048707262__endTimestamp 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | ------- Summary: start ------- 03:25:07 INFO - PROCESS | 1681 | Number of tests: 14 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:115.20 Median:117.50 stddev:4.57 (3.9%) stddev-sans-first:4.58 03:25:07 INFO - PROCESS | 1681 | Values: 111.0 120.0 109.0 118.0 119.0 117.0 107.0 116.0 116.0 119.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:142.20 Median:149.50 stddev:19.44 (13.0%) stddev-sans-first:20.50 03:25:07 INFO - PROCESS | 1681 | Values: 148.0 125.0 158.0 178.0 120.0 141.0 123.0 151.0 156.0 122.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:206.20 Median:197.00 stddev:21.88 (11.1%) stddev-sans-first:22.51 03:25:07 INFO - PROCESS | 1681 | Values: 191.0 195.0 194.0 196.0 208.0 237.0 255.0 195.0 198.0 193.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:79.70 Median:65.50 stddev:29.57 (45.1%) stddev-sans-first:30.74 03:25:07 INFO - PROCESS | 1681 | Values: 63.0 68.0 79.0 140.0 73.0 56.0 56.0 60.0 127.0 75.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:100.30 Median:79.00 stddev:20.56 (26.0%) stddev-sans-first:20.46 03:25:07 INFO - PROCESS | 1681 | Values: 80.0 87.0 135.0 107.0 111.0 86.0 121.0 79.0 79.0 118.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:89.00 stddev:13.74 (15.4%) stddev-sans-first:14.55 03:25:07 INFO - PROCESS | 1681 | Values: 90.0 88.0 91.0 87.0 90.0 86.0 92.0 87.0 80.0 130.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:96.00 stddev:6.44 (6.7%) stddev-sans-first:6.75 03:25:07 INFO - PROCESS | 1681 | Values: 95.0 97.0 95.0 96.0 96.0 116.0 97.0 95.0 95.0 96.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:116.20 Median:114.00 stddev:25.57 (22.4%) stddev-sans-first:25.45 03:25:07 INFO - PROCESS | 1681 | Values: 91.0 92.0 125.0 79.0 139.0 142.0 135.0 92.0 118.0 149.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:76.10 Median:64.50 stddev:19.48 (30.2%) stddev-sans-first:19.99 03:25:07 INFO - PROCESS | 1681 | Values: 62.0 64.0 62.0 96.0 56.0 62.0 65.0 112.0 94.0 88.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:78.40 Median:73.50 stddev:13.60 (18.5%) stddev-sans-first:13.53 03:25:07 INFO - PROCESS | 1681 | Values: 65.0 75.0 97.0 101.0 93.0 78.0 72.0 66.0 71.0 66.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:1.58 (1.9%) stddev-sans-first:1.58 03:25:07 INFO - PROCESS | 1681 | Values: 81.0 83.0 80.0 81.0 82.0 83.0 82.0 84.0 84.0 85.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:141.80 Median:140.00 stddev:18.95 (13.5%) stddev-sans-first:19.70 03:25:07 INFO - PROCESS | 1681 | Values: 131.0 129.0 127.0 130.0 156.0 149.0 129.0 132.0 187.0 148.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.10 Median:170.50 stddev:22.39 (13.1%) stddev-sans-first:22.77 03:25:07 INFO - PROCESS | 1681 | Values: 160.0 221.0 214.0 189.0 172.0 163.0 165.0 167.0 169.0 161.0 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:71.00 stddev:6.27 (8.8%) stddev-sans-first:6.65 03:25:07 INFO - PROCESS | 1681 | Values: 71.0 67.0 71.0 70.0 68.0 76.0 83.0 79.0 71.0 61.0 03:25:07 INFO - PROCESS | 1681 | -------- Summary: end -------- 03:25:07 INFO - PROCESS | 1681 | 03:25:07 INFO - TEST-INFO | 1681: exit 0 03:25:08 INFO - TEST-OK | kraken | took 59907ms 03:25:08 INFO - SUITE-END | took 1172s 03:25:08 INFO - Completed test suite (00:19:32) 03:25:08 INFO - javascript benchmark 03:25:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3279.1638132276275, 3477.0, 3497.737669674775, 3513.47297905314, 3530.399832028555, 5791.07342825148, 5808.680522571257, 5819.156222347757, 5826.592138550298, 5847.152162936377, 5766.452187042224, 5774.191613174159, 5807.767689292434, 5813.157092221625, 5823.621464604808, 4742.434996451163, 5077.162268225738, 5244.108501554726, 5288.788448462057, 5342.679439233639, 5220.034293655667, 5376.704281264529, 5406.351237851443, 5448.509634132927, 5469.890602187954, 5286.286351342577, 5289.550388217006, 5321.36143662761, 5328.786848526054, 5388.353397592295, 5306.946930530684, 5371.838844834622, 5374.193870919355, 5375.032494151052, 5418.0, 5195.506426889439, 5304.522592966613, 5307.416184219733, 5329.6002799789785, 5355.143177091685, 5521.972390138024, 5584.553235741131, 5600.243967064435, 5694.373618901917, 5718.370979192286, 6196.380361963813, 6267.028610565324, 6272.937270627326, 6297.937020629781, 6306.0, 6255.311915689271, 6257.43683068526, 6258.499320054385, 6267.5299352548745, 6271.686415679198, 5680.460356266107, 5857.88284234313, 5858.0, 5875.8531036724, 5939.46544810969, 6278.717457714414, 6315.747370105144, 6317.96841015792, 6335.556511044183, 6361.109444677749, 6204.162438070893, 6219.440250377488, 6234.5635805494085, 6239.282482514478, 6260.530460215502, 4989.276554899519, 6192.721327540272, 6246.562740608114, 6269.78055768055, 6291.02489114188, 6188.226471691039, 6253.499720022387, 6277.027060805583, 6283.497320214372, 6283.654399008098, 6244.219472565929, 6272.874542509125, 6309.3690630936535, 6324.08300796382, 6333.334999825044, 4883.780229889663, 4917.237828136645, 4964.3298154748645, 4968.105740966661, 5001.774920128603, 3998.7800670962793, 4409.735415875058, 4417.823287068545, 4443.977885086449, 4495.056038231943, 4988.775505102279, 5794.739236734357, 5847.0, 5864.296284445893, 5898.675572843449, 6164.352742961924, 6201.906971395432, 6205.162303089115, 6219.751209951552, 6274.247090349188, 5837.795677151279, 5839.445252700986, 5847.649141051551, 5856.297244330707, 5865.530757539386, 5434.3207099112615, 5453.918191227135, 5459.836204913859, 5465.781368745205, 5472.343318801769, 5699.373068962328, 6256.937430625727, 6304.054391841169, 6337.651429171347, 6363.840903977438, 5073.7716802743225, 5709.772398934249, 5990.221271234712, 6248.347097128351, 6251.124842521959, 6263.373662633882, 6382.106505089198, 6397.904031439439, 6398.680065996588, 6410.1987251593555, 6168.0, 6201.503879689524, 6226.003839385677, 6232.875342493034, 6236.75052997875, 6256.530760192913, 6264.248290205204, 6265.561410701207, 6298.0, 6302.991521356561, 5505.596073001359, 6089.2997305310455, 6135.785247516315, 6226.564140510302, 6233.065040243819, 6133.601315914451, 6137.5302083125, 6190.876182476415, 6198.442140207403, 6202.4417802398, 4669.696469729457, 5275.393329766973, 5988.736138804275, 6050.515958723379, 6082.3613520581475, 6015.488683461955, 6124.6325220486915, 6139.263288405241, 6214.8135555933395, 6240.344763799736, 6015.097735339734, 6049.51603871698, 6122.234720659918, 6144.41628045335, 6172.969135154475], "value": 5723.450969738518, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.48430889720214, 517.8575891629802, 554.1853475391176, 566.6600039976017, 621.8383220362707, 38651.80674096629, 39567.40648890269, 39609.80195099027, 39636.81089567314, 39693.80918572447, 40707.18585628293, 41096.0, 41244.793776031154, 41437.792811035906, 41632.16735665285, 8199.83600327993, 9559.474228917425, 9761.658341958033, 9811.558479868387, 9823.999999999964, 9619.663311784088, 9739.951300243489, 9789.55946982384, 9866.950665246699, 9876.0, 9797.559109840075, 9829.21366290695, 9851.113399794018, 9871.60513579456, 9882.752931176743, 9771.951140244324, 9783.315167938212, 9835.114839664464, 9844.458554779485, 9852.408855468695, 9680.612775488971, 9765.218782497383, 9815.312928095036, 9820.65627703027, 9855.507224638739, 39787.80636580907, 39973.40039899404, 40147.59852401468, 40295.7985210075, 40354.0, 3187.984060079708, 3292.96707032929, 3396.3886500429912, 3738.1776009277914, 3894.3963685628773, 22651.660225096635, 28821.711782882114, 28940.27649308763, 29091.854540727374, 29342.853285733436, 2752.2431331383827, 2762.309422644339, 2820.732030457103, 2830.1933948824694, 2859.9714002859914, 248851.0, 306352.0, 307722.0, 310594.0, 311715.99999999773, 59370.0, 59697.0, 59856.503587409796, 59918.40081599215, 60070.69964650236, 71262.64368678123, 72192.27807721855, 72599.0, 72807.27192728111, 73160.63419682867, 17211.483655490356, 17268.827311726975, 17375.870568413266, 17431.651366972845, 17433.651326973388, 12881.162724422882, 12894.548690795777, 12992.285424301564, 13211.53759618428, 13299.800502992462, 19877.701834472493, 20239.886806225506, 20283.0, 20801.479963001046, 20806.895965520074, 8273.33813294947, 8319.542425166674, 8346.457480263764, 8390.454620449651, 8419.937115406641, 1000.0749306891097, 1000.4547521600745, 1072.222638587018, 1072.6781965410346, 1074.3929679730927, 30228.84885575558, 30415.162902913766, 30751.923682671448, 31020.68979310178, 31227.0, 1125.9549618015187, 1150.0, 1157.8089615213396, 1201.4653479201845, 1261.1928365845865, 14549.92725036368, 15696.450624228097, 15784.526464206092, 15856.841431585768, 16049.358025679076, 627913.0, 640788.796056026, 645855.0, 646603.0, 649880.0, 55707.60730981677, 59741.701291493264, 62496.687516562124, 63066.68466657729, 63254.0, 1459.802926604916, 1528.808898887639, 1547.2341191110247, 1559.812822461312, 1564.608847788053, 29626.0, 30343.696563034086, 30600.49766656301, 30697.0, 30898.382032359677, 54734.726326368116, 55188.44811551994, 55202.895942081734, 55733.49199286123, 55873.44126558682, 556043.0, 569154.0, 573213.0, 574930.0, 574930.0, 27456.901723931616, 27593.310167245178, 27636.789056875, 27758.72241277642, 28113.0, 47302.526974729815, 47303.526964729914, 48454.757726211144, 48894.75552622356, 49024.754876225394, 39182.78181863385, 39378.80310598428, 39510.80244598874, 39533.40699889562, 40188.397174043, 38611.806940965114, 38875.80562097171, 38946.0, 38990.0, 39874.0, 32451.188720281236, 38082.809585951894, 38126.428103579026, 38457.88813794702, 38488.80755596316, 30270.0, 32372.86694965759, 32417.675823241465, 32452.675473244966, 32487.837560812044, 22482.763448010515, 23800.40498987539, 24145.348084564182, 24478.265652030466, 25142.62286065747], "value": 20117.95552217469, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.233891286452, 402.6436603605808, 404.6519992806188, 411.6768336855569, 414.21092818181364, 13610.795838062411, 18826.0, 20469.385918422453, 21156.471088222785, 21181.152753889844, 20256.797432025676, 20500.794992050076, 20792.168313267488, 21057.947102644885, 21451.0, 5738.483536481715, 5797.82606521804, 6066.908996365036, 6097.054956481752, 6139.263288405397, 5831.212786273863, 6021.879562408749, 6066.878662426749, 6071.726772295236, 6123.846903827418, 6105.419985101408, 6111.113888486167, 6120.0, 6135.8772824543485, 6153.969230153843, 5878.764849406019, 5986.551008674345, 6049.30433000204, 6107.664078475682, 6147.631142131487, 5939.554533409968, 5992.760289588455, 6034.818955431344, 6045.12345709874, 6067.544934129958, 13541.796873046913, 24998.37504062395, 25054.745072943522, 25098.99604015837, 25946.351341216432, 1980.5246740782109, 2111.9577608447744, 2153.4616345913523, 2254.4138523983884, 2298.7816157465013, 9412.764680882965, 9437.858432123523, 10251.128654064414, 12334.383280835922, 15132.697346053128, 1647.6045749020268, 1751.1244377811095, 1761.5067781021335, 1764.5765016396101, 1793.4440323499625, 111010.44494777556, 114327.42836285848, 117007.0, 121361.0, 123807.3809630946, 11850.407479625985, 11930.582429614922, 12064.396780160956, 12103.213291136048, 12142.817857732141, 13551.59345219645, 14317.498887539094, 15038.32327545263, 15047.172405517802, 15071.944963852644, 8765.737027889174, 8860.468371897578, 8942.239909607757, 8970.192682658591, 9040.909590904006, 8991.235744961752, 9023.954880225689, 9064.184223419923, 9080.772980675536, 9114.675757681882, 4672.32251323563, 4673.392458980379, 4676.859694209179, 4715.717056976592, 4728.905421891543, 1773.9201735921915, 1953.335865805633, 1966.3117908731829, 1971.4578490915112, 2055.342290467036, 931.3945935142211, 960.6157536985262, 985.9014098590084, 1026.2610920137488, 1062.877769056553, 19966.0, 20407.0, 20471.897640512, 20834.0, 20862.95685215733, 969.4813274897896, 976.4824642939111, 998.3410948773775, 1057.2757661002238, 1058.6665200461985, 8514.233718965323, 8966.282697384193, 10803.675889723321, 10967.287126336922, 11006.779864402668, 246272.0, 354887.4511254906, 616693.0, 626275.0, 627282.0, 118534.40732796454, 123165.38417307856, 127034.0, 138250.0, 139977.0, 896.6727144592177, 1004.6533945788691, 1049.5434485998462, 1051.6319288249204, 1057.8413238014205, 17757.46727598174, 18085.90957045259, 18203.817961820212, 18867.622647547247, 18879.0, 38357.80821095876, 50804.0, 51899.74050129725, 55144.62138446571, 56178.752849885546, 71008.64495677488, 73080.63459682881, 75176.0, 75544.0, 75591.62204188944, 8086.272235498598, 8193.795155120932, 8217.671293148207, 8263.00843898736, 8355.03917049547, 11592.420378980849, 12160.939195303967, 12320.815187772012, 12673.746525069631, 12758.425870835648, 10722.87833561279, 10790.67627971162, 10870.923403723604, 10928.94535527349, 10954.123670106213, 8124.63439145228, 10452.216083793593, 10508.527116279633, 10519.21105917044, 10519.526621302213, 10004.399736015863, 10176.829664588668, 10479.738006549896, 10591.947040265057, 10886.94556527244, 6957.408620267334, 8185.467944583821, 8230.465019773696, 8279.83440331178, 8321.83356332882, 8046.959765201136, 9626.0, 10992.340459572451, 11079.889201107886, 11142.387168705474], "value": 9506.244399083153, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1553.9145347005915, 1557.8831587630928, 1790.6508230894972, 1882.614064116856, 1941.1361943934955, 7949.085855126662, 8532.91467085329, 8719.735638332446, 8923.616839389897, 8947.32895032872, 8630.352723545744, 8638.308935285178, 8679.0, 8757.474551526897, 8822.0, 2075.6471399861985, 2447.571674956884, 2459.3359792855917, 2469.76537228963, 2474.1711526638596, 2432.1730611592056, 2440.6217036359303, 2466.6300054991807, 2482.5407299649623, 2493.376655836041, 2408.927732168038, 2433.914812981546, 2437.5856104462196, 2438.5854404751235, 2452.9509409811794, 2402.423418379585, 2415.5168966206743, 2420.903163873452, 2425.466397392571, 2469.8641574713383, 2449.0326321103244, 2455.7789798918006, 2476.418041760185, 2480.503899220163, 2485.1302044284357, 8857.202851743374, 8866.202041816203, 8888.0, 8896.777580560472, 8928.017918029012, 5014.623903207237, 5194.11700010999, 5390.894866552387, 5537.640053396516, 5568.49883510482, 3491.0574144980715, 3493.056874643832, 3493.5982362028185, 3554.0937061049526, 3590.856365745367, 950.6102497975796, 1042.880068792091, 1050.4905121016302, 1062.3814969381085, 1073.6081330314457, 11756.1182911282, 11806.763864722658, 11938.462769175409, 12010.759784804342, 12041.397930103547, 4843.128236917319, 4865.9026819463415, 4865.95134048662, 4893.192623217129, 4922.0, 10086.0, 10201.591936322613, 10273.589056437659, 10297.794044119075, 10303.53634086453, 5420.810271640474, 5434.538064264581, 5479.726013699299, 5480.506754392124, 5536.474034966753, 4441.467023957146, 4880.365552478155, 4986.301917731521, 4990.2764099205415, 5011.373578302712, 4064.6748260139116, 4098.4896161107945, 4112.136451345191, 4126.42230087788, 4143.0, 1344.126317893377, 1354.1401210231575, 1360.0411709744606, 1365.9317034148253, 1370.7464119137992, 814.7800093974594, 817.3992115794886, 857.322715055112, 935.9953200233955, 945.7021038373028, 5918.496927761103, 5993.190919225936, 5996.580239383201, 6036.909446358308, 6146.661933593606, 891.527490430073, 985.4826216236534, 988.906053924876, 994.9303548751662, 1003.3578509753763, 3952.446657467957, 4031.657309128757, 4033.9596604034173, 4047.4738284022887, 4050.6151915567975, 10659.84010239847, 11071.944640276746, 11094.278871873303, 11095.001449869551, 11152.888471115184, 10675.786484270271, 10712.875148109337, 10771.730706732395, 10830.429587709737, 10869.510872010778, 7959.761207163679, 8346.457480263764, 8508.0, 8564.914350856536, 8621.525816080175, 5635.98552260597, 5670.177824215548, 5789.450002249948, 5845.18167456565, 5848.5028772553105, 9477.383970041925, 9507.619695212114, 9601.519924003633, 9696.70909872705, 9716.514174291116, 9379.296552758708, 9785.36395134314, 9954.303198776017, 10169.542370593193, 10183.032611901857, 6838.111045564045, 7354.191038985814, 7570.886436703564, 7571.772846814604, 7705.1524332324525, 8226.0540037894, 8332.416730828783, 8342.457740246864, 8401.95799021001, 8406.495610263404, 7206.315400036988, 7333.193348731528, 7397.26027397256, 7410.18487966334, 7477.401807855467, 7222.0611320528, 7222.566646001257, 7257.0565826442225, 7306.7077316906725, 7350.448716346188, 5856.443637854397, 6976.860462790614, 6999.720011199495, 7089.964550177217, 7107.0, 4588.875725447287, 4658.510856360033, 4900.558949694402, 4939.975300123477, 5021.5229553192385, 4971.105201063843, 5011.273365362075, 5014.473480284518, 5068.163752980717, 5225.372955245243], "value": 4649.310341238211, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.4678600488202, 326.61622593452694, 331.6269197153201, 332.307139613905, 337.9155211197201, 5472.398036216018, 5518.91721624176, 6769.39075483207, 6809.514527197581, 6914.619695916723, 5348.866278343044, 5387.787747756753, 6341.763356145553, 6738.494612904027, 6825.488088393366, 3863.517060367454, 4033.1530378620523, 4784.282357646355, 4865.099956508039, 5017.0, 4975.104481193383, 5050.444451110375, 5084.51697088776, 5087.364079490064, 5099.898002039957, 5023.271625614284, 5035.194368900977, 5038.470960549144, 5052.671576347544, 5054.898902021957, 5044.747762611873, 5051.343325367697, 5054.620903432257, 5062.721550314731, 5063.873403164914, 3675.889723308305, 4164.167166566668, 4308.870733877989, 4309.4397728295435, 4314.223439780838, 5274.55166310864, 5651.886962260732, 6403.167588213549, 6522.543421960465, 6672.366125218096, 5906.5570082244, 5977.940220597782, 5986.880262394771, 5993.790217342415, 5999.310079340888, 7330.120385553768, 7352.48532602718, 7367.5211111277595, 7383.852322953565, 7385.29839665231, 4757.833475828329, 4820.325154478376, 4825.131476334259, 4830.1064303103685, 4841.9031619367415, 7524.510906791096, 7791.844163116706, 7800.18098099697, 7816.296533311973, 7834.921650783476, 4856.39295088114, 4896.46138924718, 4918.434380046269, 4920.3603531541385, 4921.212605983097, 4118.670506359484, 4955.2567114933045, 5005.249212618063, 5027.648064635514, 5031.798728050837, 5934.406559344031, 5934.8813023739285, 5937.643741375531, 5943.881122377528, 5962.37395073511, 7385.483016188923, 7388.815279617945, 7390.667419966115, 7391.778246652501, 7398.519096258726, 1719.9914000429917, 1745.1448790092763, 1747.3622127923215, 1748.064785339837, 1757.2882982392152, 1026.5431882812077, 1068.7755571330108, 1070.7002039428971, 1071.9624813131502, 1120.3613940054254, 6624.768133115318, 6703.597784132968, 6706.865862682816, 6717.328267173243, 6739.93260067393, 7469.663865126083, 7552.697892084365, 7647.57938313387, 7666.54000759945, 7672.079350477867, 6681.331866813377, 6835.384815366641, 6888.207856096613, 6925.515213934977, 6943.479239057091, 3324.817135057595, 4072.226277007359, 4117.75293482392, 4127.669786417079, 4135.669146468275, 8137.186281371815, 8144.267015968591, 8153.796155096052, 8162.102168761431, 8164.918350816416, 7735.458517903805, 8002.239787220205, 8057.153998830156, 8058.274755272054, 8088.676452941934, 6734.4949128816515, 7914.683412663544, 8138.145494723206, 8151.30713889326, 8155.714549990722, 6434.839129021625, 7743.883841742266, 7805.726799561988, 7869.134395216636, 7870.425914816945, 6209.472194863487, 7331.926680733124, 7634.46558740905, 7650.655720492702, 7810.0237470316215, 6185.474234690102, 8141.470804397695, 8160.959195203986, 8172.141925097779, 8201.09787923316, 4735.100330937111, 4757.881052973565, 4782.258749893772, 4799.976000120117, 4802.639802014933, 7786.987691600282, 7798.103218129988, 7800.063992320957, 7813.726519571787, 7814.6483408247905, 7496.025516683015, 7499.62501874915, 7506.512076714996, 7516.3986881048295, 7520.699172033057, 7209.603471808891, 7467.253274672489, 7477.738279160203, 7482.625868706652, 7495.287947644965, 7079.787606371817, 7189.352958233784, 7268.146622611201, 7295.781126566211, 7323.963380183065, 6275.6548389839, 7459.328660420588, 7476.0, 7504.962475187589, 7524.021877156154, 7023.297670232936, 7282.708691652275, 7291.453141014551, 7312.451566132455, 7320.121585409572], "value": 5328.362724588634, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.5325405351388, 709.1206903439733, 717.7487879242265, 743.1824992508239, 749.145973590107, 14507.92746036271, 14626.268686565669, 14794.112353258812, 14801.0, 14819.110853348806, 13537.390817413216, 13839.169649821019, 13966.650833729136, 13994.300284985735, 14019.088759230643, 727.3599232675239, 882.5851849630664, 889.2752406788479, 908.1418059933366, 930.8696782450462, 899.7750562359411, 918.669279059538, 918.8162367526488, 920.1442658327752, 922.9584668689891, 933.747888070224, 951.0584521323909, 964.7057647417535, 972.863799068131, 974.2790335151972, 929.6699671616581, 931.5388882503204, 941.1106504353389, 958.988267377916, 965.7730433348157, 682.8975653651943, 687.1547995964941, 688.8071340024801, 691.498663468986, 693.9583624982517, 14423.0, 14577.708445831024, 14743.336549855283, 14748.557543273719, 14823.77764333536, 12562.30907300098, 12598.622041338684, 12705.872941270654, 12724.936375318064, 12815.423305951255, 9936.95031524845, 10059.748506287327, 10157.23820713442, 10168.0, 10212.693619191436, 3495.8252087395526, 3517.84169712361, 3544.805035723034, 3570.0182449826248, 3614.5662520497444, 82145.58927205326, 82463.0, 82831.5858420704, 84151.15848841495, 84217.99999999939, 9272.907270927204, 9347.345685801929, 9504.42973421597, 9560.0, 9592.184664303473, 20207.898960505103, 20584.588308233753, 20726.689099663516, 20911.79088209129, 21014.15943362279, 7383.593902335423, 7406.07424071991, 7430.0, 7473.140588832354, 7564.773056808304, 3850.942235866464, 3916.118873253495, 3964.940525892114, 3968.4245784361683, 3971.7418367806, 3632.9818350908076, 3695.90760230991, 3702.0004598758183, 3708.8331025103935, 3720.4233343831747, 663.8838203314401, 668.9799306020827, 679.3580066836792, 680.7447207297264, 681.1383599746325, 501.34574380433156, 551.1622334052219, 591.8402031451484, 593.3828818029201, 596.406575457414, 8144.185581441927, 8193.46742461738, 8223.21879421454, 8243.629036693363, 8460.619272132768, 523.3170712220589, 586.9325027621793, 587.8765459253605, 611.8837420890017, 612.4824523277823, 3738.102855314705, 3797.810109494514, 3806.352920003606, 3830.195658911604, 3873.4577159197734, 87572.0, 91775.5411222953, 92476.0, 92637.0, 93171.0, 72280.63859680668, 75018.62490687621, 75254.62372688102, 75398.86901696479, 75798.0, 66576.00135997964, 66581.66709166423, 67066.66466667733, 67477.0, 68456.31543684499, 8186.2632363087605, 8436.578171091543, 8501.447405918596, 8509.787255318666, 8638.82722345562, 30835.0, 35588.0, 46385.53614463812, 46570.06859862716, 48300.0, 50933.0, 68235.65882170557, 68431.0, 69743.25641858994, 71052.93420598592, 10895.346279223271, 10898.945505272422, 10999.283952581864, 11277.154213434189, 11354.205205635857, 9934.254930880068, 10098.747531311483, 12089.274643521418, 12136.089793265364, 12277.38613069325, 11523.366214857928, 11587.594434194763, 11644.546397946418, 11861.525538978343, 11904.464299106388, 11450.198486105892, 11493.138014649103, 11521.423928803693, 12050.397480126134, 12132.818007729715, 9314.72055838326, 10625.25623206368, 10798.730031748955, 11040.723981900517, 11606.94196529012, 9515.809683806423, 9634.421934683942, 9687.757806054906, 9974.301798874008, 10051.949740251252, 7531.510451820614, 8408.327333813158, 9235.953820230856, 9355.48544830041, 9417.0], "value": 6816.907745062922, "unit": "score"}], "name": "dromaeo_css", "value": 7547.610977757152, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 120.0, 109.0, 118.0, 119.0, 117.0, 107.0, 116.0, 116.0, 119.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 125.0, 158.0, 178.0, 120.0, 141.0, 123.0, 151.0, 156.0, 122.0], "value": 142.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 195.0, 194.0, 196.0, 208.0, 237.0, 255.0, 195.0, 198.0, 193.0], "value": 206.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 79.0, 140.0, 73.0, 56.0, 56.0, 60.0, 127.0, 75.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 87.0, 135.0, 107.0, 111.0, 86.0, 121.0, 79.0, 79.0, 118.0], "value": 100.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 87.0, 90.0, 86.0, 92.0, 87.0, 80.0, 130.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 96.0, 96.0, 116.0, 97.0, 95.0, 95.0, 96.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 125.0, 79.0, 139.0, 142.0, 135.0, 92.0, 118.0, 149.0], "value": 116.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 62.0, 96.0, 56.0, 62.0, 65.0, 112.0, 94.0, 88.0], "value": 76.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 97.0, 101.0, 93.0, 78.0, 72.0, 66.0, 71.0, 66.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 80.0, 81.0, 82.0, 83.0, 82.0, 84.0, 84.0, 85.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 129.0, 127.0, 130.0, 156.0, 149.0, 129.0, 132.0, 187.0, 148.0], "value": 141.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 221.0, 214.0, 189.0, 172.0, 163.0, 165.0, 167.0, 169.0, 161.0], "value": 178.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 67.0, 71.0, 70.0, 68.0, 76.0, 83.0, 79.0, 71.0, 61.0], "value": 71.7, "unit": "score"}], "name": "kraken", "value": 1578.3, "alertThreshold": 2.0}]} 03:25:08 INFO - Return code: 0 03:25:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:25:08 ERROR - # TBPL SUCCESS # 03:25:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:25:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:25:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:25:08 INFO - 2016-05-12 03:25:08.606 screenresolution[1686:10556] starting screenresolution argv=screenresolution get 03:25:08 INFO - 2016-05-12 03:25:08.609 screenresolution[1686:10556] Display 0: 1600x1200x32@60 03:25:08 INFO - 2016-05-12 03:25:08.615 screenresolution[1687:10559] starting screenresolution argv=screenresolution list 03:25:08 INFO - Available Modes on Display 0 03:25:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:25:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:25:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:25:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:25:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:25:08 INFO - Intel Iris: 03:25:08 INFO - Chipset Model: Intel Iris 03:25:08 INFO - Type: GPU 03:25:08 INFO - Bus: Built-In 03:25:08 INFO - VRAM (Dynamic, Max): 1536 MB 03:25:08 INFO - Vendor: Intel (0x8086) 03:25:08 INFO - Device ID: 0x0a2e 03:25:08 INFO - Revision ID: 0x0009 03:25:08 INFO - Displays: 03:25:08 INFO - Display: 03:25:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:25:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:25:08 INFO - Display Serial Number: 1600x1200 60 03:25:08 INFO - Main Display: Yes 03:25:08 INFO - Mirror: Off 03:25:08 INFO - Online: Yes 03:25:08 INFO - Rotation: Supported 03:25:08 INFO - Return code: 0 03:25:08 INFO - Running post-action listener: _resource_record_post_action 03:25:08 INFO - Running post-run listener: _resource_record_post_run 03:25:09 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 38348288; Write bytes: 659295232; Read time: 470; Write time: 1917 03:25:09 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 356446720; Write bytes: 348485632; Read time: 27936; Write time: 653 03:25:09 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 30969344; Write bytes: 298266112; Read time: 306; Write time: 1222 03:25:09 INFO - Running post-run listener: _upload_blobber_files 03:25:09 INFO - Blob upload gear active. 03:25:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:25:09 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 03:25:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:25:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:25:10 INFO - (blobuploader) - INFO - Open directory for files ... 03:25:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:25:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:25:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:11 INFO - (blobuploader) - INFO - Done attempting. 03:25:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:25:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:25:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:12 INFO - (blobuploader) - INFO - Done attempting. 03:25:12 INFO - (blobuploader) - INFO - Iteration through files over. 03:25:12 INFO - Return code: 0 03:25:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:25:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:25:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f50408ff6554bd71fcea73461479b449baf07b608ad0650392f637fdc172139334b2822fd305357b2bd8cf241bd369008032d51215c7dc3fa9b299f07ad0b4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d36a0bfd56641f78dbf395cdf4de647d6aa617621e47e2f4a80b5c57760aeb349aff2cb979589e1f8de6f442c7fb8b4a9c9c80834dec6251093590b4370a18d2"} 03:25:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:25:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:25:12 INFO - Contents: 03:25:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f50408ff6554bd71fcea73461479b449baf07b608ad0650392f637fdc172139334b2822fd305357b2bd8cf241bd369008032d51215c7dc3fa9b299f07ad0b4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d36a0bfd56641f78dbf395cdf4de647d6aa617621e47e2f4a80b5c57760aeb349aff2cb979589e1f8de6f442c7fb8b4a9c9c80834dec6251093590b4370a18d2"} 03:25:12 INFO - Running post-run listener: copy_logs_to_upload_dir 03:25:12 INFO - Copying logs to upload dir... 03:25:12 INFO - mkdir: /builds/slave/test/build/upload/logs 03:25:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.345200 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-12 03:25:12.638609) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 03:25:12.642629) ========= 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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f50408ff6554bd71fcea73461479b449baf07b608ad0650392f637fdc172139334b2822fd305357b2bd8cf241bd369008032d51215c7dc3fa9b299f07ad0b4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d36a0bfd56641f78dbf395cdf4de647d6aa617621e47e2f4a80b5c57760aeb349aff2cb979589e1f8de6f442c7fb8b4a9c9c80834dec6251093590b4370a18d2"} build_url:https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011037 build_url: 'https://queue.taskcluster.net/v1/task/TpSSpvnkQLqEq5ga0Fb6Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0f50408ff6554bd71fcea73461479b449baf07b608ad0650392f637fdc172139334b2822fd305357b2bd8cf241bd369008032d51215c7dc3fa9b299f07ad0b4c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d36a0bfd56641f78dbf395cdf4de647d6aa617621e47e2f4a80b5c57760aeb349aff2cb979589e1f8de6f442c7fb8b4a9c9c80834dec6251093590b4370a18d2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 03:25:12.670791) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 03:25:12.671135) ========= 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.zz0Vr7XjvT/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.VtlDhZROzF/Listeners TMPDIR=/var/folders/qb/dyrsvlj566s_vdhrnz02jvg800000w/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.005323 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 03:25:12.719951) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 03:25:12.720312) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 03:25:12.720645) ========= ========= Total master_lag: 0.16 =========