builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0090 starttime: 1491931772.44 results: success (0) buildid: 20170411085621 builduid: 86378fcf94b94a6588ae6b5d77e3f17f revision: c876f8b62d63bc7ba2dd15870add8e461da05427 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:32.441020) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:32.441527) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:32.441818) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:32.469316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:32.469727) ========= 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.HMOvdlZR0P/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.BMFamAveP7/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 --2017-04-11 10:29:32-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.94M=0.001s 2017-04-11 10:29:32 (8.94 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133087 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:32.627479) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:32.627812) ========= 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.HMOvdlZR0P/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.BMFamAveP7/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.028440 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:32.679295) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 10:29:32.679678) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c876f8b62d63bc7ba2dd15870add8e461da05427 --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 c876f8b62d63bc7ba2dd15870add8e461da05427 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HMOvdlZR0P/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.BMFamAveP7/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 2017-04-11 10:29:32,782 truncating revision to first 12 chars 2017-04-11 10:29:32,782 Setting DEBUG logging. 2017-04-11 10:29:32,782 attempt 1/10 2017-04-11 10:29:32,782 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c876f8b62d63?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 10:29:33,762 unpacking tar archive at: mozilla-aurora-c876f8b62d63/testing/mozharness/ program finished with exit code 0 elapsedTime=1.241500 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 10:29:34.047723) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:34.048090) ========= script_repo_revision: c876f8b62d63bc7ba2dd15870add8e461da05427 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:34.048532) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:34.048822) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 10:29:34.070288) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-11 10:29:34.070753) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HMOvdlZR0P/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.BMFamAveP7/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:29:34 INFO - MultiFileLogger online at 20170411 10:29:34 in /builds/slave/test 10:29:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:29:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:29:34 INFO - {'append_to_log': False, 10:29:34 INFO - 'base_work_dir': '/builds/slave/test', 10:29:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:29:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:29:34 INFO - 'branch': 'Mozilla-Aurora', 10:29:34 INFO - 'buildbot_json_path': 'buildprops.json', 10:29:34 INFO - 'config_files': ('talos/mac_config.py',), 10:29:34 INFO - 'default_actions': ('clobber', 10:29:34 INFO - 'read-buildbot-config', 10:29:34 INFO - 'download-and-extract', 10:29:34 INFO - 'populate-webroot', 10:29:34 INFO - 'create-virtualenv', 10:29:34 INFO - 'install', 10:29:34 INFO - 'run-tests'), 10:29:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:29:34 INFO - 'download_minidump_stackwalk': True, 10:29:34 INFO - 'download_symbols': 'ondemand', 10:29:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:29:34 INFO - 'tooltool.py': '/tools/tooltool.py', 10:29:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:29:34 INFO - '/tools/misc-python/virtualenv.py')}, 10:29:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:29:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:29:34 INFO - 'gecko_profile': False, 10:29:34 INFO - 'gecko_profile_interval': 0, 10:29:34 INFO - 'installer_path': 'installer.exe', 10:29:34 INFO - 'log_level': 'info', 10:29:34 INFO - 'log_name': 'talos', 10:29:34 INFO - 'log_to_console': True, 10:29:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:29:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:29:34 INFO - 'opt_config_files': (), 10:29:34 INFO - 'pip_index': False, 10:29:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:29:34 INFO - 'cmd': ('bash', 10:29:34 INFO - '-c', 10:29:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:29:34 INFO - 'enabled': True, 10:29:34 INFO - 'halt_on_failure': False, 10:29:34 INFO - 'name': 'check_screen_resolution'},), 10:29:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:29:34 INFO - 'cmd': ('bash', 10:29:34 INFO - '-c', 10:29:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:29:34 INFO - 'enabled': True, 10:29:34 INFO - 'halt_on_failure': False, 10:29:34 INFO - 'name': 'check_screen_resolution'},), 10:29:34 INFO - 'run_cmd_checks_enabled': True, 10:29:34 INFO - 'suite': 'dromaeojs', 10:29:34 INFO - 'system_bits': '32', 10:29:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:29:34 INFO - 'title': 't-yosemite-r7-0090', 10:29:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:29:34 INFO - 'use_talos_json': True, 10:29:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:29:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:29:34 INFO - 'work_dir': 'build'} 10:29:34 INFO - [mozharness: 2017-04-11 17:29:34.290507Z] Running clobber step. 10:29:34 INFO - Running pre-action listener: _resource_record_pre_action 10:29:34 INFO - Running main action method: clobber 10:29:34 INFO - rmtree: /builds/slave/test/build 10:29:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:29:36 INFO - Running post-action listener: _resource_record_post_action 10:29:36 INFO - [mozharness: 2017-04-11 17:29:36.867662Z] Finished clobber step (success) 10:29:36 INFO - [mozharness: 2017-04-11 17:29:36.867944Z] Running read-buildbot-config step. 10:29:36 INFO - Running pre-action listener: _resource_record_pre_action 10:29:36 INFO - Running main action method: read_buildbot_config 10:29:36 INFO - Using buildbot properties: 10:29:36 INFO - { 10:29:36 INFO - "project": "", 10:29:36 INFO - "product": "firefox", 10:29:36 INFO - "script_repo_revision": "production", 10:29:36 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:29:36 INFO - "repository": "", 10:29:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:29:36 INFO - "buildid": "20170411085621", 10:29:36 INFO - "pgo_build": "False", 10:29:36 INFO - "basedir": "/builds/slave/test", 10:29:36 INFO - "buildnumber": 16, 10:29:36 INFO - "slavename": "t-yosemite-r7-0090", 10:29:36 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 10:29:36 INFO - "platform": "yosemite_r7", 10:29:36 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:29:36 INFO - "branch": "mozilla-aurora", 10:29:36 INFO - "repo_path": "releases/mozilla-aurora", 10:29:36 INFO - "revision": "c876f8b62d63bc7ba2dd15870add8e461da05427", 10:29:36 INFO - "stage_platform": "macosx64", 10:29:36 INFO - "builduid": "86378fcf94b94a6588ae6b5d77e3f17f", 10:29:36 INFO - "slavebuilddir": "test" 10:29:36 INFO - } 10:29:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 10:29:36 INFO - Running post-action listener: _resource_record_post_action 10:29:36 INFO - [mozharness: 2017-04-11 17:29:36.873743Z] Finished read-buildbot-config step (success) 10:29:36 INFO - [mozharness: 2017-04-11 17:29:36.873920Z] Running download-and-extract step. 10:29:36 INFO - Running pre-action listener: _resource_record_pre_action 10:29:36 INFO - Running main action method: download_and_extract 10:29:36 INFO - mkdir: /builds/slave/test/build/tests 10:29:36 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:29:36 INFO - trying https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 10:29:36 INFO - Downloading https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 10:29:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 10:29:38 INFO - Downloaded 1199 bytes. 10:29:38 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 10:29:38 INFO - Using the following test package requirements: 10:29:38 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 10:29:38 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:29:38 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 10:29:38 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:29:38 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 10:29:38 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:29:38 INFO - u'jsshell-mac.zip'], 10:29:38 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:29:38 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 10:29:38 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 10:29:38 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:29:38 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 10:29:38 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:29:38 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 10:29:38 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:29:38 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 10:29:38 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 10:29:38 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 10:29:38 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:29:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 10:29:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:29:38 INFO - Fetch https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 10:29:39 INFO - Expected file size: 51790623 10:29:39 INFO - Obtained file size: 51790623 10:29:55 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:29:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 10:29:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:29:55 INFO - Fetch https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 10:29:57 INFO - Expected file size: 51790623 10:29:57 INFO - Obtained file size: 51790623 10:30:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 10:30:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:30:13 INFO - Fetch https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 10:30:13 INFO - Expected file size: 14404424 10:30:13 INFO - Obtained file size: 14404424 10:30:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:14 INFO - trying https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 10:30:14 INFO - Downloading https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 10:30:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 10:30:16 INFO - Downloaded 74688692 bytes. 10:30:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 10:30:16 INFO - mkdir: /builds/slave/test/properties 10:30:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:30:16 INFO - Writing to file /builds/slave/test/properties/build_url 10:30:16 INFO - Contents: 10:30:16 INFO - build_url:https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 10:30:17 INFO - Running post-action listener: _resource_record_post_action 10:30:17 INFO - Running post-action listener: set_extra_try_arguments 10:30:17 INFO - [mozharness: 2017-04-11 17:30:17.071856Z] Finished download-and-extract step (success) 10:30:17 INFO - [mozharness: 2017-04-11 17:30:17.071951Z] Running populate-webroot step. 10:30:17 INFO - Running pre-action listener: _resource_record_pre_action 10:30:17 INFO - Running main action method: populate_webroot 10:30:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:30:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:30:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:30:17 INFO - '--tppagecycles', 10:30:17 INFO - '7'], 10:30:17 INFO - 'tests': ['tsvgm']}}, 10:30:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:30:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:30:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:30:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:30:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:30:17 INFO - 'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:30:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:30:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:30:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:30:17 INFO - 'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['damp', 'tps']}, 10:30:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:30:17 INFO - 'tests': ['damp', 'tps']}, 10:30:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['dromaeo_dom']}, 10:30:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:30:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['basic_compositor_video']}, 10:30:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:30:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['a11yr', 10:30:17 INFO - 'ts_paint', 10:30:17 INFO - 'tpaint', 10:30:17 INFO - 'sessionrestore', 10:30:17 INFO - 'sessionrestore_no_auto_restore', 10:30:17 INFO - 'tabpaint']}, 10:30:17 INFO - 'other-e10s': {'tests': ['a11yr', 10:30:17 INFO - 'ts_paint', 10:30:17 INFO - 'tpaint', 10:30:17 INFO - 'sessionrestore', 10:30:17 INFO - 'sessionrestore_no_auto_restore', 10:30:17 INFO - 'tabpaint']}, 10:30:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:30:17 INFO - 'ts_paint', 10:30:17 INFO - 'tpaint', 10:30:17 INFO - 'sessionrestore', 10:30:17 INFO - 'sessionrestore_no_auto_restore', 10:30:17 INFO - 'tabpaint']}, 10:30:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:30:17 INFO - 'ts_paint', 10:30:17 INFO - 'tpaint', 10:30:17 INFO - 'sessionrestore', 10:30:17 INFO - 'sessionrestore_no_auto_restore', 10:30:17 INFO - 'tabpaint']}, 10:30:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['a11yr', 10:30:17 INFO - 'ts_paint', 10:30:17 INFO - 'tpaint', 10:30:17 INFO - 'sessionrestore', 10:30:17 INFO - 'sessionrestore_no_auto_restore', 10:30:17 INFO - 'tabpaint']}, 10:30:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['a11yr', 10:30:17 INFO - 'ts_paint', 10:30:17 INFO - 'tpaint', 10:30:17 INFO - 'sessionrestore', 10:30:17 INFO - 'sessionrestore_no_auto_restore', 10:30:17 INFO - 'tabpaint']}, 10:30:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['tsvgx', 10:30:17 INFO - 'tsvgr_opacity', 10:30:17 INFO - 'tart', 10:30:17 INFO - 'tscrollx', 10:30:17 INFO - 'cart', 10:30:17 INFO - 'tsvg_static']}, 10:30:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:30:17 INFO - 'tsvgr_opacity', 10:30:17 INFO - 'tart', 10:30:17 INFO - 'tscrollx', 10:30:17 INFO - 'cart', 10:30:17 INFO - 'tsvg_static']}, 10:30:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:30:17 INFO - 'talos_options': ['--disable-e10s'], 10:30:17 INFO - 'tests': ['tp5o']}, 10:30:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:30:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:30:17 INFO - 'talos_options': ['--disable-e10s', 10:30:17 INFO - '--xperf_path', 10:30:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:30:17 INFO - 'tests': ['tp5n']}, 10:30:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:30:17 INFO - 'talos_options': ['--xperf_path', 10:30:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:30:17 INFO - 'tests': ['tp5n']}}, 10:30:17 INFO - 'talos.zip': {'path': '', 10:30:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:30:17 INFO - Running post-action listener: _resource_record_post_action 10:30:17 INFO - [mozharness: 2017-04-11 17:30:17.090609Z] Finished populate-webroot step (success) 10:30:17 INFO - [mozharness: 2017-04-11 17:30:17.090695Z] Running create-virtualenv step. 10:30:17 INFO - Running pre-action listener: _resource_record_pre_action 10:30:17 INFO - Running main action method: create_virtualenv 10:30:17 INFO - Creating virtualenv /builds/slave/test/build/venv 10:30:17 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 10:30:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:30:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:30:17 INFO - Using real prefix '/tools/python27' 10:30:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:30:17 INFO - Installing distribute.............................................................................................................................................................................................done. 10:30:20 INFO - Installing pip.................done. 10:30:20 INFO - Return code: 0 10:30:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:30:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:30:21 INFO - Reading from file tmpfile_stdout 10:30:21 INFO - Output received: 10:30:21 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:30:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd6db40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, '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.HMOvdlZR0P/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.BMFamAveP7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:21 INFO - 'HOME': '/Users/cltbld', 10:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:21 INFO - 'LOGNAME': 'cltbld', 10:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:21 INFO - 'MOZ_NO_REMOTE': '1', 10:30:21 INFO - 'NO_EM_RESTART': '1', 10:30:21 INFO - 'PAGER': '/bin/cat', 10:30:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:21 INFO - 'PWD': '/builds/slave/test', 10:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:21 INFO - 'SHELL': '/bin/bash', 10:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:30:21 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:21 INFO - 'USER': 'cltbld', 10:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:21 INFO - 'XPC_FLAGS': '0x0', 10:30:21 INFO - 'XPC_SERVICE_NAME': '0', 10:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:21 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 10:30:21 INFO - Cleaning up... 10:30:21 INFO - Return code: 0 10:30:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd6db40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, '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.HMOvdlZR0P/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.BMFamAveP7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:21 INFO - 'HOME': '/Users/cltbld', 10:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:21 INFO - 'LOGNAME': 'cltbld', 10:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:21 INFO - 'MOZ_NO_REMOTE': '1', 10:30:21 INFO - 'NO_EM_RESTART': '1', 10:30:21 INFO - 'PAGER': '/bin/cat', 10:30:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:21 INFO - 'PWD': '/builds/slave/test', 10:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:21 INFO - 'SHELL': '/bin/bash', 10:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:30:21 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:21 INFO - 'USER': 'cltbld', 10:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:21 INFO - 'XPC_FLAGS': '0x0', 10:30:21 INFO - 'XPC_SERVICE_NAME': '0', 10:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:21 INFO - Downloading/unpacking psutil>=3.1.1 10:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:30:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:30:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:30:25 INFO - Installing collected packages: psutil 10:30:25 INFO - Running setup.py install for psutil 10:30:25 INFO - building 'psutil._psutil_osx' extension 10:30:25 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 10:30:25 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 10:30:25 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 10:30:25 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 10:30:26 INFO - building 'psutil._psutil_posix' extension 10:30:26 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 10:30:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:30:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:30:26 INFO - ^ 10:30:26 INFO - 1 warning generated. 10:30:26 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 10:30:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:30:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:30:26 INFO - Successfully installed psutil 10:30:26 INFO - Cleaning up... 10:30:26 INFO - Return code: 0 10:30:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:30:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:26 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd6db40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, '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.HMOvdlZR0P/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.BMFamAveP7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:30:26 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.3'] in /builds/slave/test/build 10:30:26 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.3 10:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:26 INFO - 'HOME': '/Users/cltbld', 10:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:26 INFO - 'LOGNAME': 'cltbld', 10:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:26 INFO - 'MOZ_NO_REMOTE': '1', 10:30:26 INFO - 'NO_EM_RESTART': '1', 10:30:26 INFO - 'PAGER': '/bin/cat', 10:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:26 INFO - 'PWD': '/builds/slave/test', 10:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:26 INFO - 'SHELL': '/bin/bash', 10:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:30:26 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:26 INFO - 'USER': 'cltbld', 10:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:26 INFO - 'XPC_FLAGS': '0x0', 10:30:26 INFO - 'XPC_SERVICE_NAME': '0', 10:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:30:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:30:29 INFO - Installing collected packages: mozsystemmonitor 10:30:29 INFO - Running setup.py install for mozsystemmonitor 10:30:29 INFO - Successfully installed mozsystemmonitor 10:30:29 INFO - Cleaning up... 10:30:29 INFO - Return code: 0 10:30:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30: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', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd6db40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, '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.HMOvdlZR0P/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.BMFamAveP7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:30: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:30: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 jsonschema==2.5.1 10:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:30 INFO - 'HOME': '/Users/cltbld', 10:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:30 INFO - 'LOGNAME': 'cltbld', 10:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:30 INFO - 'MOZ_NO_REMOTE': '1', 10:30:30 INFO - 'NO_EM_RESTART': '1', 10:30:30 INFO - 'PAGER': '/bin/cat', 10:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:30 INFO - 'PWD': '/builds/slave/test', 10:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:30 INFO - 'SHELL': '/bin/bash', 10:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:30:30 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:30 INFO - 'USER': 'cltbld', 10:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:30 INFO - 'XPC_FLAGS': '0x0', 10:30:30 INFO - 'XPC_SERVICE_NAME': '0', 10:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:30 INFO - Downloading/unpacking jsonschema==2.5.1 10:30:30 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 10:30:30 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 10:30:30 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 10:30:30 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 10:30:30 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 10:30:30 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 10:30:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:30:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:30:33 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 10:30:33 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 10:30:33 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 10:30:33 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 10:30:33 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 10:30:33 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 10:30:33 INFO - Downloading functools32-3.2.3-2.tar.gz 10:30:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:30:34 INFO - warning: no files found matching '*.txt' 10:30:34 INFO - no previously-included directories found matching 'build' 10:30:34 INFO - no previously-included directories found matching 'dist' 10:30:34 INFO - no previously-included directories found matching '.git*' 10:30:34 INFO - Installing collected packages: jsonschema, functools32 10:30:34 INFO - Running setup.py install for functools32 10:30:34 INFO - warning: no files found matching '*.txt' 10:30:34 INFO - no previously-included directories found matching 'build' 10:30:34 INFO - no previously-included directories found matching 'dist' 10:30:34 INFO - no previously-included directories found matching '.git*' 10:30:34 INFO - Successfully installed jsonschema functools32 10:30:34 INFO - Cleaning up... 10:30:34 INFO - Return code: 0 10:30:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:34 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd6db40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, '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.HMOvdlZR0P/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.BMFamAveP7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:30:34 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:30:34 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 functools32==3.2.3-2 10:30:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:30:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:34 INFO - 'HOME': '/Users/cltbld', 10:30:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:34 INFO - 'LOGNAME': 'cltbld', 10:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:34 INFO - 'MOZ_NO_REMOTE': '1', 10:30:34 INFO - 'NO_EM_RESTART': '1', 10:30:34 INFO - 'PAGER': '/bin/cat', 10:30:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:34 INFO - 'PWD': '/builds/slave/test', 10:30:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:34 INFO - 'SHELL': '/bin/bash', 10:30:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:30:34 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:30:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:34 INFO - 'USER': 'cltbld', 10:30:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:34 INFO - 'XPC_FLAGS': '0x0', 10:30:34 INFO - 'XPC_SERVICE_NAME': '0', 10:30:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:30:34 INFO - Cleaning up... 10:30:34 INFO - Return code: 0 10:30:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:34 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 0x10d5f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd6db40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, '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.HMOvdlZR0P/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.BMFamAveP7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:30:34 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 10:30:34 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 10:30:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:30:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:34 INFO - 'HOME': '/Users/cltbld', 10:30:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:34 INFO - 'LOGNAME': 'cltbld', 10:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:34 INFO - 'MOZ_NO_REMOTE': '1', 10:30:34 INFO - 'NO_EM_RESTART': '1', 10:30:34 INFO - 'PAGER': '/bin/cat', 10:30:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:34 INFO - 'PWD': '/builds/slave/test', 10:30:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:34 INFO - 'SHELL': '/bin/bash', 10:30:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:30:34 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:30:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:34 INFO - 'USER': 'cltbld', 10:30:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:34 INFO - 'XPC_FLAGS': '0x0', 10:30:34 INFO - 'XPC_SERVICE_NAME': '0', 10:30:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:34 INFO - Downloading/unpacking blobuploader==1.2.4 10:30:34 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 10:30:34 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 10:30:34 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 10:30:34 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 10:30:34 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 10:30:34 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 10:30:37 INFO - Downloading blobuploader-1.2.4.tar.gz 10:30:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:30:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:30:37 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 10:30:37 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 10:30:37 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 10:30:37 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 10:30:37 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 10:30:37 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 10:30:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:30:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:30: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 10:30: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 10:30: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 10:30: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 10:30: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 10:30: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 10:30:38 INFO - Downloading docopt-0.6.1.tar.gz 10:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:30:38 INFO - Installing collected packages: blobuploader, requests, docopt 10:30:38 INFO - Running setup.py install for blobuploader 10:30:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:30:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:30:38 INFO - Running setup.py install for requests 10:30:39 INFO - Running setup.py install for docopt 10:30:39 INFO - Successfully installed blobuploader requests docopt 10:30:39 INFO - Cleaning up... 10:30:39 INFO - Return code: 0 10:30:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:39 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 0x10d5f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd6db40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, '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.HMOvdlZR0P/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.BMFamAveP7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:30:39 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 10:30:39 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 10:30:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:30:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:39 INFO - 'HOME': '/Users/cltbld', 10:30:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:39 INFO - 'LOGNAME': 'cltbld', 10:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:39 INFO - 'MOZ_NO_REMOTE': '1', 10:30:39 INFO - 'NO_EM_RESTART': '1', 10:30:39 INFO - 'PAGER': '/bin/cat', 10:30:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:39 INFO - 'PWD': '/builds/slave/test', 10:30:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:39 INFO - 'SHELL': '/bin/bash', 10:30:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:30:39 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:30:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:39 INFO - 'USER': 'cltbld', 10:30:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:39 INFO - 'XPC_FLAGS': '0x0', 10:30:39 INFO - 'XPC_SERVICE_NAME': '0', 10:30:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:30:39 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-bKUU6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:30:40 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-84VzZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:30:40 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-quUHNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:30:40 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-tLqq5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:30:40 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-jXMMHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:30:40 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-6zdoZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:30:40 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-wCOgRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:30:40 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-jSRQ_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:30:40 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-sDUANG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:30:40 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-J3EVQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:30:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:30:40 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-0aHEgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:30:41 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-O9UXhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:30:41 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-1b1SuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:30:41 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-xdHCjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:41 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ckNv9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:30:41 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-tR8ZPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:30:41 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-dqvzrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:30:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:30:41 INFO - Running setup.py install for manifestparser 10:30:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:30:41 INFO - Running setup.py install for mozcrash 10:30:42 INFO - Running setup.py install for mozdebug 10:30:42 INFO - Running setup.py install for mozdevice 10:30:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Running setup.py install for mozfile 10:30:42 INFO - Running setup.py install for mozhttpd 10:30:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Running setup.py install for mozinfo 10:30:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Running setup.py install for mozInstall 10:30:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:30:42 INFO - Running setup.py install for mozleak 10:30:43 INFO - Running setup.py install for mozlog 10:30:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:30:43 INFO - Running setup.py install for moznetwork 10:30:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:30:43 INFO - Running setup.py install for mozprocess 10:30:43 INFO - Running setup.py install for mozprofile 10:30:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:30:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:30:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:30:43 INFO - Running setup.py install for mozrunner 10:30:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:30:43 INFO - Running setup.py install for mozscreenshot 10:30:44 INFO - Running setup.py install for moztest 10:30:44 INFO - Running setup.py install for mozversion 10:30:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:30:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:30:44 INFO - Cleaning up... 10:30:44 INFO - Return code: 0 10:30:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:44 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 0x10d5f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd6db40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, '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.HMOvdlZR0P/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.BMFamAveP7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:30:44 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 10:30:44 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 10:30:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:30:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:44 INFO - 'HOME': '/Users/cltbld', 10:30:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:44 INFO - 'LOGNAME': 'cltbld', 10:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:44 INFO - 'MOZ_NO_REMOTE': '1', 10:30:44 INFO - 'NO_EM_RESTART': '1', 10:30:44 INFO - 'PAGER': '/bin/cat', 10:30:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:44 INFO - 'PWD': '/builds/slave/test', 10:30:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:44 INFO - 'SHELL': '/bin/bash', 10:30:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:30:44 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:30:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:44 INFO - 'USER': 'cltbld', 10:30:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:44 INFO - 'XPC_FLAGS': '0x0', 10:30:44 INFO - 'XPC_SERVICE_NAME': '0', 10:30:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:30:44 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-TQSZHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:30:44 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)) 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:30:44 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-3SVP0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:30:44 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-HD6CX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:30:44 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)) 10:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:30:44 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-kiNZEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 10:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:30:45 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-lEYY9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:30:45 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)) 10:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:30:45 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-YqPc_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:30:45 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)) 10:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:30:45 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-2VQQJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:30:45 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)) 10:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:30:45 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-XtEs2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:30:45 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)) 10:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:30:45 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-55Yx98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:30:45 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)) 10:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:30:45 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-pIBNGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 10:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:30:45 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-BpriFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:30:45 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)) 10:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:30:45 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-tCoMCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:30:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 10:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:30:45 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-4T6n5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:30:45 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)) 10:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:30:46 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-d1bd_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 10:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:46 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-6hJGff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:30:46 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)) 10:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:30:46 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-TVHrIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 10:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:30:46 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-1kzesL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:30:46 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)) 10:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:30:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:30:46 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)) 10:30:46 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:30:46 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:30:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:30:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:30:49 INFO - Downloading blessings-1.6.tar.gz 10:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:30:49 INFO - Installing collected packages: blessings 10:30:49 INFO - Running setup.py install for blessings 10:30:49 INFO - Successfully installed blessings 10:30:49 INFO - Cleaning up... 10:30:49 INFO - Return code: 0 10:30:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:30:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:30:49 INFO - Reading from file tmpfile_stdout 10:30:49 INFO - Current package versions: 10:30:49 INFO - blessings == 1.6 10:30:49 INFO - blobuploader == 1.2.4 10:30:49 INFO - docopt == 0.6.1 10:30:49 INFO - functools32 == 3.2.3-2 10:30:49 INFO - jsonschema == 2.5.1 10:30:49 INFO - manifestparser == 1.1 10:30:49 INFO - mozInstall == 1.12 10:30:49 INFO - mozcrash == 1.0 10:30:49 INFO - mozdebug == 0.1 10:30:49 INFO - mozdevice == 0.49 10:30:49 INFO - mozfile == 1.2 10:30:49 INFO - mozhttpd == 0.7 10:30:49 INFO - mozinfo == 0.9 10:30:49 INFO - mozleak == 0.1 10:30:49 INFO - mozlog == 3.4 10:30:49 INFO - moznetwork == 0.27 10:30:49 INFO - mozprocess == 0.25 10:30:49 INFO - mozprofile == 0.28 10:30:49 INFO - mozrunner == 6.13 10:30:49 INFO - mozscreenshot == 0.1 10:30:49 INFO - mozsystemmonitor == 0.3 10:30:49 INFO - moztest == 0.8 10:30:49 INFO - mozversion == 1.4 10:30:49 INFO - psutil == 3.1.1 10:30:49 INFO - requests == 1.2.3 10:30:49 INFO - wsgiref == 0.1.2 10:30:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:30:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:49 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 0x10d5f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd6db40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, '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.HMOvdlZR0P/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.BMFamAveP7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:30:49 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 10:30:49 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 10:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:49 INFO - 'HOME': '/Users/cltbld', 10:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:49 INFO - 'LOGNAME': 'cltbld', 10:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:49 INFO - 'MOZ_NO_REMOTE': '1', 10:30:49 INFO - 'NO_EM_RESTART': '1', 10:30:49 INFO - 'PAGER': '/bin/cat', 10:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:49 INFO - 'PWD': '/builds/slave/test', 10:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:49 INFO - 'SHELL': '/bin/bash', 10:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:30:49 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:49 INFO - 'USER': 'cltbld', 10:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:49 INFO - 'XPC_FLAGS': '0x0', 10:30:49 INFO - 'XPC_SERVICE_NAME': '0', 10:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:49 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)) 10:30:49 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)) 10:30:49 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)) 10:30:49 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)) 10:30:49 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)) 10:30:49 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)) 10:30:49 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)) 10:30:49 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)) 10:30:49 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)) 10:30:49 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)) 10:30:49 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)) 10:30:49 INFO - Cleaning up... 10:30:49 INFO - Return code: 0 10:30:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:30:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:30:49 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 0x10d5f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd6db40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, '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.HMOvdlZR0P/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.BMFamAveP7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 10:30:49 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 10:30:49 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 10:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:30:49 INFO - 'HOME': '/Users/cltbld', 10:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:30:49 INFO - 'LOGNAME': 'cltbld', 10:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:30:49 INFO - 'MOZ_NO_REMOTE': '1', 10:30:49 INFO - 'NO_EM_RESTART': '1', 10:30:49 INFO - 'PAGER': '/bin/cat', 10:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:30:49 INFO - 'PWD': '/builds/slave/test', 10:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:30:49 INFO - 'SHELL': '/bin/bash', 10:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:30:49 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:30:49 INFO - 'USER': 'cltbld', 10:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:30:49 INFO - 'XPC_FLAGS': '0x0', 10:30:49 INFO - 'XPC_SERVICE_NAME': '0', 10:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:30:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:30:50 INFO - Cleaning up... 10:30:50 INFO - Return code: 0 10:30:50 INFO - Running post-action listener: _resource_record_post_action 10:30:50 INFO - Running post-action listener: _start_resource_monitoring 10:30:50 INFO - Starting resource monitoring. 10:30:50 INFO - [mozharness: 2017-04-11 17:30:50.277102Z] Finished create-virtualenv step (success) 10:30:50 INFO - [mozharness: 2017-04-11 17:30:50.278014Z] Running install step. 10:30:50 INFO - Running pre-action listener: _resource_record_pre_action 10:30:50 INFO - Running main action method: install 10:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:30:50 INFO - Reading from file tmpfile_stdout 10:30:50 INFO - Detecting whether we're running mozinstall >=1.0... 10:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:30:50 INFO - Reading from file tmpfile_stdout 10:30:50 INFO - Output received: 10:30:50 INFO - Usage: mozinstall [options] installer 10:30:50 INFO - Options: 10:30:50 INFO - -h, --help show this help message and exit 10:30:50 INFO - -d DEST, --destination=DEST 10:30:50 INFO - Directory to install application into. [default: 10:30:50 INFO - "/builds/slave/test"] 10:30:50 INFO - --app=APP Application being installed. [default: firefox] 10:30:50 INFO - mkdir: /builds/slave/test/build/application 10:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:31:11 INFO - Reading from file tmpfile_stdout 10:31:11 INFO - Output received: 10:31:11 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:31:11 INFO - Running post-action listener: _resource_record_post_action 10:31:11 INFO - [mozharness: 2017-04-11 17:31:11.456134Z] Finished install step (success) 10:31:11 INFO - [mozharness: 2017-04-11 17:31:11.456312Z] Running run-tests step. 10:31:11 INFO - Running pre-action listener: _resource_record_pre_action 10:31:11 INFO - Running main action method: run_tests 10:31:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:31:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:31:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:31:11 INFO - 2017-04-11 10:31:11.470 screenresolution[1624:7533] starting screenresolution argv=screenresolution get 10:31:11 INFO - 2017-04-11 10:31:11.473 screenresolution[1624:7533] Display 0: 1600x1200x32@60 10:31:11 INFO - 2017-04-11 10:31:11.479 screenresolution[1625:7535] starting screenresolution argv=screenresolution list 10:31:11 INFO - Available Modes on Display 0 10:31:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:31:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:31:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:31:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:31:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:31:11 INFO - Intel Iris: 10:31:11 INFO - Chipset Model: Intel Iris 10:31:11 INFO - Type: GPU 10:31:11 INFO - Bus: Built-In 10:31:11 INFO - VRAM (Dynamic, Max): 1536 MB 10:31:11 INFO - Vendor: Intel (0x8086) 10:31:11 INFO - Device ID: 0x0a2e 10:31:11 INFO - Revision ID: 0x0009 10:31:11 INFO - Displays: 10:31:11 INFO - Display: 10:31:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:31:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:31:11 INFO - Display Serial Number: 1600x1200 60 10:31:11 INFO - Main Display: Yes 10:31:11 INFO - Mirror: Off 10:31:11 INFO - Online: Yes 10:31:11 INFO - Rotation: Supported 10:31:11 INFO - Return code: 0 10:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:31:11 INFO - Python 2.7.3 10:31:11 INFO - Return code: 0 10:31:11 INFO - grabbing minidump binary from tooltool 10:31: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:11 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d3cfd30>, '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 0x10d2f6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d3c4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:31:11 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 10:31:11 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 10:31:11 INFO - Calling ['/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'] with output_timeout 600 10:31:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:31:11 INFO - Return code: 0 10:31:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:31:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:31:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:31:11 INFO - ENV: RUST_BACKTRACE is now 1 10:31:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:31:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0090 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HMOvdlZR0P/Render', 10:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:11 INFO - 'HOME': '/Users/cltbld', 10:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:11 INFO - 'LOGNAME': 'cltbld', 10:31:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:31:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:11 INFO - 'MOZ_NO_REMOTE': '1', 10:31:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:31:11 INFO - 'NO_EM_RESTART': '1', 10:31:11 INFO - 'PAGER': '/bin/cat', 10:31:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:11 INFO - 'PWD': '/builds/slave/test', 10:31:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:11 INFO - 'RUST_BACKTRACE': '1', 10:31:11 INFO - 'SHELL': '/bin/bash', 10:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BMFamAveP7/Listeners', 10:31:11 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 10:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:11 INFO - 'USER': 'cltbld', 10:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:11 INFO - 'XPC_FLAGS': '0x0', 10:31:11 INFO - 'XPC_SERVICE_NAME': '0', 10:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:31:12 INFO - mozversion application_buildid: 20170411085621 10:31:12 INFO - mozversion application_changeset: c876f8b62d63bc7ba2dd15870add8e461da05427 10:31:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:31:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:31:12 INFO - mozversion application_name: Firefox 10:31:12 INFO - mozversion application_remotingname: firefox-dev 10:31:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:31:12 INFO - mozversion application_vendor: Mozilla 10:31:12 INFO - mozversion application_version: 54.0a2 10:31:12 INFO - mozversion platform_buildid: 20170411085621 10:31:12 INFO - mozversion platform_changeset: c876f8b62d63bc7ba2dd15870add8e461da05427 10:31:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:31:12 INFO - mozversion platform_version: 54.0a2 10:31:12 INFO - using testdate: 1491931872 10:31:12 INFO - actual date: 1491931872 10:31:12 INFO - starting webserver on 'localhost:49241' 10:31:12 INFO - SUITE-START | Running 2 tests 10:31:12 INFO - TEST-START | dromaeo_css 10:31:12 INFO - operating with platform_type : mac_ 10:31:12 INFO - Initialising browser for dromaeo_css test... 10:31:12 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpqtLkPn/profile http://localhost:49241/getInfo.html) 10:31:14 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 10:31:14 INFO - PROCESS | 1635 | colorDepth:24 10:31:14 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 10:31:14 INFO - PROCESS | 1635 | __metrics 10:31:14 INFO - PROCESS | 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:31:14 INFO - PROCESS | 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:31:15 INFO - TEST-INFO | 1635: exit 0 10:31:15 INFO - Browser initialized. 10:31:15 INFO - Running cycle 1/1 for dromaeo_css test... 10:31:15 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpqtLkPn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:31:27 INFO - PROCESS | 1637 | RSS: Main: 214679552 10:31:27 INFO - PROCESS | 1637 | 10:34:31 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 10:34:34 INFO - PROCESS | 1637 | RSS: Main: 435281920 10:34:34 INFO - PROCESS | 1637 | 10:36:17 INFO - PROCESS | 1637 | ************************* 10:36:17 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 10:36:17 INFO - PROCESS | 1637 | 10:36:17 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:36:17 INFO - PROCESS | 1637 | Full stack: 10:36:17 INFO - PROCESS | 1637 | ************************* 10:36:17 INFO - PROCESS | 1637 | ************************* 10:36:17 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 10:36:17 INFO - PROCESS | 1637 | 10:36:17 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:36:17 INFO - PROCESS | 1637 | Full stack: 10:36:17 INFO - PROCESS | 1637 | ************************* 10:36:17 INFO - PROCESS | 1637 | ************************* 10:36:17 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 10:36:17 INFO - PROCESS | 1637 | 10:36:17 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:36:17 INFO - PROCESS | 1637 | Full stack: 10:36:17 INFO - PROCESS | 1637 | ************************* 10:36:17 INFO - PROCESS | 1637 | ************************* 10:36:17 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 10:36:17 INFO - PROCESS | 1637 | 10:36:17 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:36:17 INFO - PROCESS | 1637 | Full stack: 10:36:17 INFO - PROCESS | 1637 | ************************* 10:37:37 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 10:37:38 INFO - PROCESS | 1637 | RSS: Main: 350699520 10:37:38 INFO - PROCESS | 1637 | 10:40:41 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 10:40:41 INFO - PROCESS | 1637 | RSS: Main: 322252800 10:40:41 INFO - PROCESS | 1637 | 10:43:44 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 10:43:44 INFO - PROCESS | 1637 | RSS: Main: 321540096 10:43:44 INFO - PROCESS | 1637 | 10:46:48 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 10:46:49 INFO - PROCESS | 1637 | RSS: Main: 321441792 10:46:49 INFO - PROCESS | 1637 | 10:49:52 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 10:49:52 INFO - PROCESS | 1637 | RSS: Main: 506208256 10:49:52 INFO - PROCESS | 1637 | 10:49:52 INFO - PROCESS | 1637 | __start_tp_report 10:49:52 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 10:49:52 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 10:49:52 INFO - PROCESS | 1637 | |i|pagename|runs| 10:49:52 INFO - PROCESS | 1637 | |0;dojo.html;3415.641357657444;3443.8622455101795;3446.155691855495;3449.741269404792;3499.1077275294797;6037.909431358533;6070.6661133637635;6077.635341879491;6080.908786368208;6096.085587161922;5958.553108516857;6048.818535443944;6055.182550355701;6058.666773327465;6082.118092876531;5378.784848606061;5404.6486978346475;5420.91868621971;5437.374701909272;5542.224088627595;4757.762981624768;5313.787448502056;5320.201969704556;5516.531094856942;5533.6956467394275;5118.539331460168;5412.729363531827;5416.295881535395;5424.728763561826;5424.891502169954;5394.946050539484;5406;5408.567314614821;5415.620906536544;5415.918761218604;5276;5363.249145119687;5369.275147855029;5382.838514844561;5385.084535628914;5987.580869339148;5996.610220335708;6005.819825405245;6009.369016253318;6025.849353766147;6366.9681651591445;6379.649119298437;6396.48828093756;6400.199975003125;6405.6797160141805;6348.714307856158;6370.267419246799;6397.456216221627;6432.903506447407;6434.163558737381;6088.817335479943;6138.508919286446;6154.876902461926;6158.907616385758;6180.752769889244;6291.685415729286;6345.016522439029;6625.966870165618;6629.801105966829;6636.30318816527;6294.055891616203;6326.43062124411;6361.2048493938255;6375.649339286384;6407.871842563216;6447.161868957006;6449.580777249464;6452.741890324428;6457.515686323451;6460.3862633049785;6385.680715964183;6391.872162556723;6393.488520918316;6398.840028999312;6399.872002559922;6134.099846298256;6168.136460895478;6333.366663333722;6333.651649159248;6339.144215530937;5133.922991155136;5143;5172.741362931838;5201.297824793604;5240.0567897778765;4608.631309495232;4612.446506419251;4615;4620.607248383858;4667.883302917454;6116.480099191618;6179.0422484514975;6188.783392581238;6190.721417536131;6217.6891155442045;6319.747210111636;6376.617402955838;6376.904346434807;6385.553011289165;6460.483161347081;5957.553183511167;5965.194698715704;5977.103434484862;5980.162777211194;5980.37206093354;5549.750261238254;5598.048331783607;5610.13042978339;5613.943860561424;5616.241807356037;6419.967900160469;6433.5496515243485;6448.935925572416;6553.2136143663065;6613.173353330833;5352.250684904195;6395.872082558416;6444.4844412445955;6444.645544495098;6445.291017988111;5583.581231407579;6684.097646817811;6691.799246022627;6707.128073350434;6715.731370745116;5987.0121429963565;5988.251468566429;5991.2510936132985;6402.263739669997;6415.262244841903;5578.414266501959;6356.237251529846;6390.4248617624635;6412.967935160481;6433.356664333529;6333.90499142504;6342.23893132827;6371.299157092623;6377.87244255103;6403.455706265019;5867.853303667443;6339.334369891095;6366.490680745622;6375.521835862236;6419.775307864203;6109.266887973472;6122.724477398617;6129.785457509144;6132.601380910227;6134.4172303631085;6142.6314421134875;6261.843453913689;6262.3111457738705;6270.498360131087;6317.210348706411;6238.407351301619;6258.155149054819;6269.780557680459;6289.622622642656;6325.905111423424 10:49:52 INFO - PROCESS | 1637 | |1;ext.html;796.7490240574218;815.5229190923308;872.6989188729885;884.5311984648139;888.2139306713557;27901.441971160566;40314.395284070764;41200.38199427007;43183.784081079626;43231.56768432315;42210;42410.78794606031;42464.57535424645;42464.78767606166;42509;7270.781876543712;8898.021217666068;9051.366404351698;9074.63701451941;9076.001639819648;7259.092613423322;10715.196360272972;10987.505562249718;11055.06031987278;11094;10883.727906802354;10915.945420272927;10916.672499824977;10925.235233533656;10946.233763636508;10852.294600850959;10860.728481787939;10889.564417423293;10892.836607450894;10900;7141.285871412869;7333;7397.371223446014;7400.777976660709;7402;38699.61300387017;39262.411063834064;40758;40812.38781418281;40831;3771.116518085004;3771.7359784815076;3809.085819403323;3848.903777405559;3952.3873799561115;27184.728152718417;27475.17574472769;28035.26860806098;28756.281092972633;29460.11619651414;3022.848857557113;3088.861001254949;3095.349976504937;3096.2878537936176;3153.9591934661507;321921;322748;323247.3837630797;324610;324914;37198;37843.864684059525;42615.93460163521;44589.55410445919;44937.77531112324;44927.10145797066;47455.762721186176;47524.52475475201;47596;47779.04441911212;13773;13847.653808654662;13854.515091971733;13870.65323366924;13928.58214253574;11885.465154068088;11923.642290731292;11942.522299108112;11953;11962.98314643265;11978.52085916571;12016.798320167913;12017.978471829818;12091.939540302417;12126.575569855013;5140.948590514122;5470.64440811346;5521.69630670317;5525.226468294442;5532.834014979557;725.26747984536;927.4574373991156;951.1772316945792;971.2715463402449;993.1508560180941;28089;28352;28365.865365385565;28932.710672893423;28993.275168120967;891.6923661336829;990.5542505872386;1065.8347956066823;1068.455087905174;1074.8119079161115;14181.078229915023;14575.41698332076;14579.489717859824;14900.776488352683;14948.252587370587;493322;493805;495725;496379;496574;57243;57753.422465774805;57836.4216357831;57874.7106264466;58118.70940645354;1398.3777219137387;1460.1750011243614;1462.020446300981;1463.5243545847643;1468.2585294426246;27419.725802741716;27722.029728960195;27743.44513109686;28352.432951340445;28446.57330140008;48505.75747121242;48573;48600.02799944052;48703.26945095755;48704.26943595778;450011;450447;451421;451427;454147;27108.780104895723;27636.098209687694;28304.85847570749;32040;33224.335513289116;43234.56765432305;44053.55946440495;44465.777671111435;44634.55365446434;45644;26874.462510749283;29906;30791.922282719996;30818.84590577033;31600.841995790794;30336.39327213399;30572.929947451732;30583.776648933792;31393.372132557677;31612.367752644357;29382.853085734434;35383.292334152655;36300.81849590735;36980.260394792494;37029;30961.845190773904;31100.844495777375;31280.68719312778;31733.5239971396;31911.521327180577;24547.263582092564;24553.28127031162;24663.136790212255;24927.501449971263;24965.750342496343 10:49:52 INFO - PROCESS | 1637 | |2;jquery.html;383.29091181314556;431.40595990288426;431.8186361728075;439.4046067578431;444.9977750111249;15834;17866.821331786694;20434.284800032;21213.151473941063;21342.786572134275;21332.786672133276;21715.239966601133;22091.889540552314;22732.204372846954;23000.309990700305;6459.224893012845;6624.37068478495;6727.0918426012595;6764.053032575419;6814.28450012749;6645.33546645334;6671.132752742136;6721.731130754764;6735.090762747015;6738.797836064926;6384.361563843625;6519.445847103002;6573.211214654249;6600.141981542392;6742.898856517131;6572.375624315682;6598.967005164992;6603.580230250471;6619.238787539422;6703.597784132968;5625.6905870177125;6476.579022363579;6503.642299673516;6504.77233296837;6514.93485065148;21103.788962110335;26721.73278267212;26935;27018.729812702015;27383.76773045218;2836.149155253436;2873.324768679359;2877.223149749556;2881.27968007998;2883.7692984561186;15967.441139560176;16674;16689.582760430963;16917.069561174132;17303.74044389335;1987.5031242189452;2021.191523390641;2035.4809523571394;2051.9487012824798;2060.3097962182687;136853;137496;138684;140035;142513;13759.862401375858;21192.576148476943;21292;21369.572608547744;21699.806510642065;29735.405291894473;29840.25399365033;30910.227244318623;31609;32126.83936580302;8765.649374024968;10369.429681367446;10687.35875847436;10927.945360273148;11037.558497660164;7437.962810185914;7755.7673269802;7862.095858976291;7923.445358824943;8193.180681931879;8076.757697269091;8159.714409995621;8265.504069755714;10340.741481462874;10414.791704166026;3593.4969104325414;4059.04612416082;4079.9592004080173;4156.210320039243;4170.791460426966;868.4181598329135;933.874681009382;1001.8998100189923;1055.984160237597;1121.1311233793874;22284.442888927908;22314.665280020814;22646.52797568153;23981.640275395883;24232.75767242305;1076.8330908709163;1079.7030816525362;1093.704699731068;1114.1147707169607;1114.3759494683004;11096.889031109586;11261.268017578832;11425.428728563538;11444.771104577861;11446.14153938458;712782;726186;754811;758051;782387;147431;149260.50739492683;152202;156232;165269;1333.9799903001463;1349.1770020287618;1384.0588399887972;1387.1399732166121;1391.0610338021795;18133.184006719992;19150.904245478683;19205.32781352646;19211.711824322367;19282.22871085141;45949.310760337954;59708.70145649244;64150.67924660534;65216.67391663011;66125;86373.56813215894;88242.55878720565;88781;89390.10609893818;90160.64759028751;11098.5560577576;11183.161262905152;11422.771544569228;11631;11662.94168529152;12810;12929.741405172032;13097;13163;13281.933590332374;11047.281926674741;11219.831702524632;11306.4912078955;11494;11508.654740357802;10251.077403033465;10352.585896564053;10397.58409663605;10623.36259824413;10704.732381690521;11159.72100697489;11263.324200547993;11346.829797553208;11457.713557160805;11486.597969071043;11353.03499202577;11381;11485.917772741788;11702.180847340604;11737.061035117342;10820.89179108199;11050.613228536962;11063.834042489209;11293.887061129284;11336.6032188873 10:49:52 INFO - PROCESS | 1637 | |3;mootools.html;1416.5891891351507;1537.7155226283146;1541.0522528644888;1564.992175039125;1568.388328551865;10436;11004.889951100487;11013.00882920537;11037.11703063755;11055.778884422307;10796.190285728582;10969.670909872697;10989;10989.505472253748;10998.230123891331;2374.9762502375015;2404.446977195237;2416.1301931304715;2418.3349578865777;2421.5156968606348;2327.7206735191717;2407.5064611754547;2413.4087148648605;2415.6618073469726;2416.794572461343;2328.860268383902;2386.069432921162;2387.4481586968436;2390.8924098415528;2397.6043952747777;2281.0647634469788;2350.5063936573342;2358.646203069536;2359.1742889988454;2394.5929192037397;2351.835371523994;2374.228375777879;2377.0491803278655;2377.1798729438315;2396.664466974625;9156.954215228881;10525.631602893938;10889;10896.945515272451;10897.455127243686;5570.6657600544095;5714.457126572969;5744.540436765049;5750.683712395817;5756.827295181151;3326.3846188455213;3358.0933148050135;3432.9485057724155;3433.8798142064907;3441.3633477806684;1054.9525271362731;1068.172166570906;1089.9455027248684;1098.7253186703324;1099.9340039597569;12705.364731763468;13369;13720.725585488235;14741.115533067943;15490.148041857694;4594.908101838011;4705.835295764632;4708.058388322281;4710.317004034396;4733.360996265529;11579.826302605468;11604.825927611093;11609.013233875216;11613.36126513033;11636.010939070104;4857.805687772449;5012.824551140692;5473.425290344536;5652.8869422611315;5703.857403564861;5043.193089105799;5195.922061169085;5286.127788914786;5288.338957630296;5309.0178317010705;3782.3569993101237;3799.6580307772433;3894.4937158169255;3905.160390515996;3908.5895980922164;917.513717729591;966.6906589891167;1007;1017.3234798858824;1020.7652239984697;823.6005537314397;941.5715849288557;949.7103383468109;951.7240000399806;997.4264797741183;6000.729967151488;6646.567973081735;6647.86704265912;6741.831454213684;6798.082258895085;945.8486642137225;961.4519723757392;974.2644303550774;980.2648013989508;986.8914419413859;4084.5302790179508;4113.300738874398;4158.708890377705;4204.222218889515;4292.334688123346;13010.479580816851;13640.863591364157;13657.590272291847;13686;13769.242691652054;13073.934630326787;13338;13631.863681363258;13658.248796316098;13664.863351366359;9967.352122112039;10069.748256293651;10071.395716257048;10224.233182511192;10228.53971571281;6407.327230640902;6462.353764623688;6526.97663724247;6546.149000629887;6574.441172500391;12092.637220883387;12192;12205.633830985085;12210.145289829627;12225.388730563614;8865.64537418496;12105.213161144497;12113.33376664292;12274;12349.876501235232;8270.090290068209;8356.6239519223;8363.95818020906;8458.74623761288;8471.915280847114;10030.849537256801;10244.02681745233;10261.897381026094;10267.589296428358;10271.845922311022;8431.620577073923;8591.269742071994;8672.609732561923;8689.522076285612;8754.25588824957;8282.378821588285;8293.087760346236;8364.958175209085;8378.664853405795;8435.915640843514;7683.154852966058;7863.488873223222;7866.331361834308;7866.606669666379;7871.370290376871;5751.309842818889;5781.103928891029;5930.733117009659;5967.671778052249;6014.398560144125;5752.223449834386;5939.703014849327;5975.40245975399;6027.2164618601055;6076.179715738319 10:49:52 INFO - PROCESS | 1637 | |4;prototype.html;367.06215619093217;378.4247943126448;379.5862509864251;381.46785234597746;389.20796179774146;4401.845935392262;4590.4163063743;4598.080383923212;4664.927066774644;4985.955758139164;4737.668363214576;4854.296127061574;4888.973315603718;4910.877228069291;4924.803007879689;3993.600639936012;4029.214303210875;4073.572274911136;4273.230818452677;4274.358846173068;3824.9617503825025;4102.892219100841;4211.284081706102;4227.957720422803;4242.257604919127;3664.6518580734787;3876.9612303876884;4122.917541649165;4151;4244.427002354673;3789.905252368685;4024.7182697211206;4235.3858690490015;4316.9639286571;4350.085442753363;3225.3226822367333;3312.4431517187127;3859.7227635695076;3860.0542866997484;3864.8647297344737;4433.13553856998;4500.797464114123;4804.471508134137;4821.831235906761;4884.38685303361;4604.930926036112;4678.3216433616935;4813.855584332476;4857;4938.950610493885;4623.583877451012;5010.799568017275;5340.759665815048;5342.67943923362;5418.91871621926;3658.798766067865;3798.392257238856;4266.957330426687;4297.162053399588;4303.246931786956;4855.150348688965;4861.513848615145;5151.5106064923775;5387.434319396485;5445.156000819879;3722.571904230994;4171.954473234556;4180.958190418118;4250.370584800291;4377.5403582624;3827.3110840049058;3849.634284742945;3933.5083114610675;4142.46148000758;4419.143959537002;3770.2836461072307;4185.895352616148;4448.221561226773;4587.7017993830295;4815.879603009953;4519.988605070712;4754.738489383048;5293.841184764463;5305.7266256098255;5310.787568497294;1465.2234315812636;1513.833478317384;1521.7184820808186;1522.3301747231403;1531.5526827148237;968.0754879090451;1016.588281745894;1134.9671798663178;1146.18620779246;1148.3511815824033;4305.806238719265;4374.606285434326;4457.710248833815;4644.375927600895;4962.975185124052;4673.789679464432;5052.72210028452;5150.403374953701;5182.352205974254;5326.414094449608;4129.421880936671;4156.875293741193;4963.65981185078;4987.62592805541;4993.775280112403;2584.3539115221197;2586;2729.959050614242;2734.5077885980318;2868.3402817352126;5106.463821298785;5210.635255532152;5400.756965936543;5756.194132821408;5769.509591684669;4486.394336764495;4861.805527778849;5636.703558181641;5813.88372232553;5844.503217226584;5246;5469.890602187934;5779.88440231193;5812.331581868139;5821.380378829459;5052.823151189691;5259.921101183409;5675.545956323567;5682.744276507485;5722.62802917809;4480.385203240668;4750.596199323097;5025.74871256443;5065.189569668983;5512.373849714264;5165.560927321219;5372.5970552207955;5608.691521966168;5627.8874422512135;5729.4843464088435;3621.619729928425;3840.3678436664864;3921.0786714510255;3953.367461206193;4417.293233082692;4995.350604421402;5128.743562821918;5202.4767987526775;5556.91664625039;5603.579731520071;4667.486576476517;4832.468428472936;4852.56326930578;4977.726225057657;5380.381256155436;5297.642549065628;5312.203169524602;5326.520613144836;5334.67991920486;5378.408375078817;4322.8703138905885;4387.714798538085;4787.8563643090765;5420.945790542044;5455.236266922555;4972.455711881299;4977.601791856715;5024.186455584738;5078.222622082241;5402.162664786964;4757.881052973703;5184.766685499086;5334.959073328321;5351.59863010268;5363.919541206963 10:49:52 INFO - PROCESS | 1637 | |5;yui.html;734.9118105827305;775.4610545670757;791.4697152907552;841.6086519768309;854.4488804720953;15132;15877.68244635108;15886.046837189762;15891.125988070651;15904.602384940383;14445.349959251833;14473.986820922539;14549.636259093528;14557.417703291856;15119.697606047872;921.0927236671873;926.2127191886909;944.2776276148752;948.0756262643902;952.4047470331043;931.0642803981989;950.8336041192798;951.3245595627113;953.6709835106877;953.7567920180344;1013.7161594753444;1014.5181039006486;1015.5430056474615;1016.0652199976003;1017.4048181813648;1009.5658866687321;1010;1011.7318910488726;1012.4886932099316;1014.2849291249688;746.9962650186715;747.7794050755041;748.0649188514357;748.2143749063462;748.3900620993927;15477.535673929688;15772.92113539425;15863.841361586467;16160.75758863618;16414.83585164157;13914.930425347808;13961.930190349085;14017.43930242789;14059.718805623932;14136.22250776207;11551.191416600841;11596.362200078993;11819;11877.287362758263;11989.64031079069;3611.6388361163936;3628.0050947301415;3727.6458736419995;3752.4934133891848;3842.903927401809;108436.4578177112;111581.44209278983;114835.79046813962;115837.84162158355;119096.40451797686;10788.730281742863;10975.231733778719;11048.668539943814;11067.225294229487;11279.436028198557;22197.556048879254;22971;22986.310410687707;22995.655065174036;22996.88501557515;7443.292887175709;7504.812379690442;7530.849383012309;7608.543487390774;7775.961120194363;4280.743155410685;4305.526392096866;4446.221911165598;4452.8218871245435;4565.155446242456;3418.6923176914197;3462.7402944778737;3480.582330120402;3535.6641119093647;3544.486049522805;594.7710131599365;597.1609888107216;602.3223873142714;613.6655522740117;617.5183356981562;653.506602515098;701.8631366883511;710.8874611233404;720.6288761287941;727.6361819090455;9385.671501497414;9459.763505912408;10253.436061016566;10406.739831504274;10422.583096676048;609.2384519350811;636.9713362898681;646.9288378278386;660.979324487099;667.552739664426;4998.525140111684;5002.499750024968;5003.3495645565845;5003.824866129668;5023.824166154167;88504;89208;92123.07876921279;93544;96055;74232;87750;91233.54383228041;91450.08549914548;93759;72546;72704.63647681728;73177.6341118291;73998.52002960019;75184;9627.518624068909;9646.758831029281;9648.179904708179;9657.46883921363;9682.854757178507;46508.767456163856;50061.99876002532;50196.49803501918;51362.74318628383;51850.22224666557;53087.73456132849;63979;67478.66260668666;68661.97007044799;69716.65141674259;12572.497100115894;12720.363981801058;13161.407736651681;13268.336583170996;13524.985626077887;14429.278536073365;14543.636409089435;14678.559643210721;14802.185879776716;14912.776308355167;13130.606081817561;13228.338583071001;13591.728165436438;13593.184408935496;13697.657558561115;13204.669883252996;13362;13768.518101866386;13789.51736689211;13915.02594818353;12904.677383065498;12922.935385323013;13583.728325433238;13627.38676759568;14097.436102556194;9701.563429645825;9757.60969561238;9816.852747208655;9993.300468967103;10098.242631802792;9979.311063371617;10264.58941642326;11062.557497700001;11694.181407301407;11755.941220293844 10:49:52 INFO - PROCESS | 1637 | __end_tp_report 10:49:52 INFO - PROCESS | 1637 | __start_cc_report 10:49:52 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,2577 10:49:52 INFO - PROCESS | 1637 | __end_cc_report 10:49:52 INFO - PROCESS | 1637 | __startTimestamp1491932992740__endTimestamp 10:49:52 INFO - PROCESS | 1637 | 10:49:52 INFO - PROCESS | 1637 | ------- Summary: start ------- 10:49:52 INFO - PROCESS | 1637 | Number of tests: 6 10:49:52 INFO - PROCESS | 1637 | 10:49:52 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5943.08 Median:6154.88 stddev:656.57 (10.7%) stddev-sans-first:627.94 10:49:52 INFO - PROCESS | 1637 | Values: 3415.6 3443.9 3446.2 3449.7 3499.1 6037.9 6070.7 6077.6 6080.9 6096.1 5958.6 6048.8 6055.2 6058.7 6082.1 5378.8 5404.6 5420.9 5437.4 5542.2 4757.8 5313.8 5320.2 5516.5 5533.7 5118.5 5412.7 5416.3 5424.7 5424.9 5394.9 5406.0 5408.6 5415.6 5415.9 5276.0 5363.2 5369.3 5382.8 5385.1 5987.6 5996.6 6005.8 6009.4 6025.8 6367.0 6379.6 6396.5 6400.2 6405.7 6348.7 6370.3 6397.5 6432.9 6434.2 6088.8 6138.5 6154.9 6158.9 6180.8 6291.7 6345.0 6626.0 6629.8 6636.3 6294.1 6326.4 6361.2 6375.6 6407.9 6447.2 6449.6 6452.7 6457.5 6460.4 6385.7 6391.9 6393.5 6398.8 6399.9 6134.1 6168.1 6333.4 6333.7 6339.1 5133.9 5143.0 5172.7 5201.3 5240.1 4608.6 4612.4 4615.0 4620.6 4667.9 6116.5 6179.0 6188.8 6190.7 6217.7 6319.7 6376.6 6376.9 6385.6 6460.5 5957.6 5965.2 5977.1 5980.2 5980.4 5549.8 5598.0 5610.1 5613.9 5616.2 6420.0 6433.5 6448.9 6553.2 6613.2 5352.3 6395.9 6444.5 6444.6 6445.3 5583.6 6684.1 6691.8 6707.1 6715.7 5987.0 5988.3 5991.3 6402.3 6415.3 5578.4 6356.2 6390.4 6413.0 6433.4 6333.9 6342.2 6371.3 6377.9 6403.5 5867.9 6339.3 6366.5 6375.5 6419.8 6109.3 6122.7 6129.8 6132.6 6134.4 6142.6 6261.8 6262.3 6270.5 6317.2 6238.4 6258.2 6269.8 6289.6 6325.9 10:49:52 INFO - PROCESS | 1637 | 10:49:52 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:55959.39 Median:323928.69 stddev:114179.96 (35.2%) stddev-sans-first:114425.15 10:49:52 INFO - PROCESS | 1637 | Values: 796.7 815.5 872.7 884.5 888.2 27901.4 40314.4 41200.4 43183.8 43231.6 42210.0 42410.8 42464.6 42464.8 42509.0 7270.8 8898.0 9051.4 9074.6 9076.0 7259.1 10715.2 10987.5 11055.1 11094.0 10883.7 10915.9 10916.7 10925.2 10946.2 10852.3 10860.7 10889.6 10892.8 10900.0 7141.3 7333.0 7397.4 7400.8 7402.0 38699.6 39262.4 40758.0 40812.4 40831.0 3771.1 3771.7 3809.1 3848.9 3952.4 27184.7 27475.2 28035.3 28756.3 29460.1 3022.8 3088.9 3095.3 3096.3 3154.0 321921.0 322748.0 323247.4 324610.0 324914.0 37198.0 37843.9 42615.9 44589.6 44937.8 44927.1 47455.8 47524.5 47596.0 47779.0 13773.0 13847.7 13854.5 13870.7 13928.6 11885.5 11923.6 11942.5 11953.0 11963.0 11978.5 12016.8 12018.0 12091.9 12126.6 5140.9 5470.6 5521.7 5525.2 5532.8 725.3 927.5 951.2 971.3 993.2 28089.0 28352.0 28365.9 28932.7 28993.3 891.7 990.6 1065.8 1068.5 1074.8 14181.1 14575.4 14579.5 14900.8 14948.3 493322.0 493805.0 495725.0 496379.0 496574.0 57243.0 57753.4 57836.4 57874.7 58118.7 1398.4 1460.2 1462.0 1463.5 1468.3 27419.7 27722.0 27743.4 28352.4 28446.6 48505.8 48573.0 48600.0 48703.3 48704.3 450011.0 450447.0 451421.0 451427.0 454147.0 27108.8 27636.1 28304.9 32040.0 33224.3 43234.6 44053.6 44465.8 44634.6 45644.0 26874.5 29906.0 30791.9 30818.8 31600.8 30336.4 30572.9 30583.8 31393.4 31612.4 29382.9 35383.3 36300.8 36980.3 37029.0 30961.8 31100.8 31280.7 31733.5 31911.5 24547.3 24553.3 24663.1 24927.5 24965.8 10:49:52 INFO - PROCESS | 1637 | 10:49:52 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:42505.63 Median:21252.58 stddev:124357.54 (585.1%) stddev-sans-first:124666.17 10:49:52 INFO - PROCESS | 1637 | Values: 383.3 431.4 431.8 439.4 445.0 15834.0 17866.8 20434.3 21213.2 21342.8 21332.8 21715.2 22091.9 22732.2 23000.3 6459.2 6624.4 6727.1 6764.1 6814.3 6645.3 6671.1 6721.7 6735.1 6738.8 6384.4 6519.4 6573.2 6600.1 6742.9 6572.4 6599.0 6603.6 6619.2 6703.6 5625.7 6476.6 6503.6 6504.8 6514.9 21103.8 26721.7 26935.0 27018.7 27383.8 2836.1 2873.3 2877.2 2881.3 2883.8 15967.4 16674.0 16689.6 16917.1 17303.7 1987.5 2021.2 2035.5 2051.9 2060.3 136853.0 137496.0 138684.0 140035.0 142513.0 13759.9 21192.6 21292.0 21369.6 21699.8 29735.4 29840.3 30910.2 31609.0 32126.8 8765.6 10369.4 10687.4 10927.9 11037.6 7438.0 7755.8 7862.1 7923.4 8193.2 8076.8 8159.7 8265.5 10340.7 10414.8 3593.5 4059.0 4080.0 4156.2 4170.8 868.4 933.9 1001.9 1056.0 1121.1 22284.4 22314.7 22646.5 23981.6 24232.8 1076.8 1079.7 1093.7 1114.1 1114.4 11096.9 11261.3 11425.4 11444.8 11446.1 712782.0 726186.0 754811.0 758051.0 782387.0 147431.0 149260.5 152202.0 156232.0 165269.0 1334.0 1349.2 1384.1 1387.1 1391.1 18133.2 19150.9 19205.3 19211.7 19282.2 45949.3 59708.7 64150.7 65216.7 66125.0 86373.6 88242.6 88781.0 89390.1 90160.6 11098.6 11183.2 11422.8 11631.0 11662.9 12810.0 12929.7 13097.0 13163.0 13281.9 11047.3 11219.8 11306.5 11494.0 11508.7 10251.1 10352.6 10397.6 10623.4 10704.7 11159.7 11263.3 11346.8 11457.7 11486.6 11353.0 11381.0 11485.9 11702.2 11737.1 10820.9 11050.6 11063.8 11293.9 11336.6 10:49:52 INFO - PROCESS | 1637 | 10:49:52 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6581.23 Median:3342.24 stddev:4085.90 (122.3%) stddev-sans-first:4078.93 10:49:52 INFO - PROCESS | 1637 | Values: 1416.6 1537.7 1541.1 1565.0 1568.4 10436.0 11004.9 11013.0 11037.1 11055.8 10796.2 10969.7 10989.0 10989.5 10998.2 2375.0 2404.4 2416.1 2418.3 2421.5 2327.7 2407.5 2413.4 2415.7 2416.8 2328.9 2386.1 2387.4 2390.9 2397.6 2281.1 2350.5 2358.6 2359.2 2394.6 2351.8 2374.2 2377.0 2377.2 2396.7 9157.0 10525.6 10889.0 10896.9 10897.5 5570.7 5714.5 5744.5 5750.7 5756.8 3326.4 3358.1 3432.9 3433.9 3441.4 1055.0 1068.2 1089.9 1098.7 1099.9 12705.4 13369.0 13720.7 14741.1 15490.1 4594.9 4705.8 4708.1 4710.3 4733.4 11579.8 11604.8 11609.0 11613.4 11636.0 4857.8 5012.8 5473.4 5652.9 5703.9 5043.2 5195.9 5286.1 5288.3 5309.0 3782.4 3799.7 3894.5 3905.2 3908.6 917.5 966.7 1007.0 1017.3 1020.8 823.6 941.6 949.7 951.7 997.4 6000.7 6646.6 6647.9 6741.8 6798.1 945.8 961.5 974.3 980.3 986.9 4084.5 4113.3 4158.7 4204.2 4292.3 13010.5 13640.9 13657.6 13686.0 13769.2 13073.9 13338.0 13631.9 13658.2 13664.9 9967.4 10069.7 10071.4 10224.2 10228.5 6407.3 6462.4 6527.0 6546.1 6574.4 12092.6 12192.0 12205.6 12210.1 12225.4 8865.6 12105.2 12113.3 12274.0 12349.9 8270.1 8356.6 8364.0 8458.7 8471.9 10030.8 10244.0 10261.9 10267.6 10271.8 8431.6 8591.3 8672.6 8689.5 8754.3 8282.4 8293.1 8365.0 8378.7 8435.9 7683.2 7863.5 7866.3 7866.6 7871.4 5751.3 5781.1 5930.7 5967.7 6014.4 5752.2 5939.7 5975.4 6027.2 6076.2 10:49:52 INFO - PROCESS | 1637 | 10:49:52 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4386.56 Median:4744.13 stddev:1230.83 (25.9%) stddev-sans-first:1196.74 10:49:52 INFO - PROCESS | 1637 | Values: 367.1 378.4 379.6 381.5 389.2 4401.8 4590.4 4598.1 4664.9 4986.0 4737.7 4854.3 4889.0 4910.9 4924.8 3993.6 4029.2 4073.6 4273.2 4274.4 3825.0 4102.9 4211.3 4228.0 4242.3 3664.7 3877.0 4122.9 4151.0 4244.4 3789.9 4024.7 4235.4 4317.0 4350.1 3225.3 3312.4 3859.7 3860.1 3864.9 4433.1 4500.8 4804.5 4821.8 4884.4 4604.9 4678.3 4813.9 4857.0 4939.0 4623.6 5010.8 5340.8 5342.7 5418.9 3658.8 3798.4 4267.0 4297.2 4303.2 4855.2 4861.5 5151.5 5387.4 5445.2 3722.6 4172.0 4181.0 4250.4 4377.5 3827.3 3849.6 3933.5 4142.5 4419.1 3770.3 4185.9 4448.2 4587.7 4815.9 4520.0 4754.7 5293.8 5305.7 5310.8 1465.2 1513.8 1521.7 1522.3 1531.6 968.1 1016.6 1135.0 1146.2 1148.4 4305.8 4374.6 4457.7 4644.4 4963.0 4673.8 5052.7 5150.4 5182.4 5326.4 4129.4 4156.9 4963.7 4987.6 4993.8 2584.4 2586.0 2730.0 2734.5 2868.3 5106.5 5210.6 5400.8 5756.2 5769.5 4486.4 4861.8 5636.7 5813.9 5844.5 5246.0 5469.9 5779.9 5812.3 5821.4 5052.8 5259.9 5675.5 5682.7 5722.6 4480.4 4750.6 5025.7 5065.2 5512.4 5165.6 5372.6 5608.7 5627.9 5729.5 3621.6 3840.4 3921.1 3953.4 4417.3 4995.4 5128.7 5202.5 5556.9 5603.6 4667.5 4832.5 4852.6 4977.7 5380.4 5297.6 5312.2 5326.5 5334.7 5378.4 4322.9 4387.7 4787.9 5420.9 5455.2 4972.5 4977.6 5024.2 5078.2 5402.2 4757.9 5184.8 5335.0 5351.6 5363.9 10:49:52 INFO - PROCESS | 1637 | 10:49:52 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20218.24 Median:3740.07 stddev:28832.69 (770.9%) stddev-sans-first:28876.45 10:49:52 INFO - PROCESS | 1637 | Values: 734.9 775.5 791.5 841.6 854.4 15132.0 15877.7 15886.0 15891.1 15904.6 14445.3 14474.0 14549.6 14557.4 15119.7 921.1 926.2 944.3 948.1 952.4 931.1 950.8 951.3 953.7 953.8 1013.7 1014.5 1015.5 1016.1 1017.4 1009.6 1010.0 1011.7 1012.5 1014.3 747.0 747.8 748.1 748.2 748.4 15477.5 15772.9 15863.8 16160.8 16414.8 13914.9 13961.9 14017.4 14059.7 14136.2 11551.2 11596.4 11819.0 11877.3 11989.6 3611.6 3628.0 3727.6 3752.5 3842.9 108436.5 111581.4 114835.8 115837.8 119096.4 10788.7 10975.2 11048.7 11067.2 11279.4 22197.6 22971.0 22986.3 22995.7 22996.9 7443.3 7504.8 7530.8 7608.5 7776.0 4280.7 4305.5 4446.2 4452.8 4565.2 3418.7 3462.7 3480.6 3535.7 3544.5 594.8 597.2 602.3 613.7 617.5 653.5 701.9 710.9 720.6 727.6 9385.7 9459.8 10253.4 10406.7 10422.6 609.2 637.0 646.9 661.0 667.6 4998.5 5002.5 5003.3 5003.8 5023.8 88504.0 89208.0 92123.1 93544.0 96055.0 74232.0 87750.0 91233.5 91450.1 93759.0 72546.0 72704.6 73177.6 73998.5 75184.0 9627.5 9646.8 9648.2 9657.5 9682.9 46508.8 50062.0 50196.5 51362.7 51850.2 53087.7 63979.0 67478.7 68662.0 69716.7 12572.5 12720.4 13161.4 13268.3 13525.0 14429.3 14543.6 14678.6 14802.2 14912.8 13130.6 13228.3 13591.7 13593.2 13697.7 13204.7 13362.0 13768.5 13789.5 13915.0 12904.7 12922.9 13583.7 13627.4 14097.4 9701.6 9757.6 9816.9 9993.3 10098.2 9979.3 10264.6 11062.6 11694.2 11755.9 10:49:52 INFO - PROCESS | 1637 | -------- Summary: end -------- 10:49:52 INFO - PROCESS | 1637 | 10:49:53 INFO - TEST-INFO | 1637: exit 0 10:49:53 INFO - TEST-OK | dromaeo_css | took 1121383ms 10:49:53 INFO - TEST-START | kraken 10:49:53 INFO - operating with platform_type : mac_ 10:49:53 INFO - Initialising browser for kraken test... 10:49:53 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpeNTCHq/profile http://localhost:49241/getInfo.html) 10:49:55 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 10:49:55 INFO - PROCESS | 1647 | colorDepth:24 10:49:55 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 10:49:55 INFO - PROCESS | 1647 | __metrics 10:49:55 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:49:55 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:49:56 INFO - TEST-INFO | 1647: exit 0 10:49:56 INFO - Browser initialized. 10:49:56 INFO - Running cycle 1/1 for kraken test... 10:49:56 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpeNTCHq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:50:08 INFO - PROCESS | 1648 | RSS: Main: 210685952 10:50:08 INFO - PROCESS | 1648 | 10:50:11 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 10:50:12 INFO - PROCESS | 1648 | RSS: Main: 264450048 10:50:12 INFO - PROCESS | 1648 | 10:50:15 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 10:50:15 INFO - PROCESS | 1648 | RSS: Main: 278097920 10:50:15 INFO - PROCESS | 1648 | 10:50:18 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 10:50:19 INFO - PROCESS | 1648 | RSS: Main: 375193600 10:50:19 INFO - PROCESS | 1648 | 10:50:21 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 10:50:22 INFO - PROCESS | 1648 | RSS: Main: 278302720 10:50:22 INFO - PROCESS | 1648 | 10:50:24 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:50:24 INFO - PROCESS | 1648 | RSS: Main: 279932928 10:50:24 INFO - PROCESS | 1648 | 10:50:27 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 10:50:28 INFO - PROCESS | 1648 | RSS: Main: 320012288 10:50:28 INFO - PROCESS | 1648 | 10:50:31 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 10:50:32 INFO - PROCESS | 1648 | RSS: Main: 322129920 10:50:32 INFO - PROCESS | 1648 | 10:50:35 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 10:50:35 INFO - PROCESS | 1648 | RSS: Main: 317919232 10:50:35 INFO - PROCESS | 1648 | 10:50:37 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:50:38 INFO - PROCESS | 1648 | RSS: Main: 296275968 10:50:38 INFO - PROCESS | 1648 | 10:50:39 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:50:40 INFO - PROCESS | 1648 | RSS: Main: 299921408 10:50:40 INFO - PROCESS | 1648 | 10:50:42 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:50:43 INFO - PROCESS | 1648 | RSS: Main: 302444544 10:50:43 INFO - PROCESS | 1648 | 10:50:46 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:50:46 INFO - PROCESS | 1648 | RSS: Main: 309432320 10:50:46 INFO - PROCESS | 1648 | 10:50:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:50:50 INFO - PROCESS | 1648 | RSS: Main: 310792192 10:50:50 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 10:50:52 INFO - PROCESS | 1648 | RSS: Main: 354168832 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | __start_tp_report 10:50:52 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 10:50:52 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 10:50:52 INFO - PROCESS | 1648 | |i|pagename|runs| 10:50:52 INFO - PROCESS | 1648 | |0;ai-astar;107;116;117;116;115;117;115;116;117;118 10:50:52 INFO - PROCESS | 1648 | |1;audio-beat-detection;164;139;146;145;111;110;110;109;123;111 10:50:52 INFO - PROCESS | 1648 | |2;audio-dft;186;186;184;184;185;185;184;184;186;185 10:50:52 INFO - PROCESS | 1648 | |3;audio-fft;82;61;60;90;62;60;59;61;74;50 10:50:52 INFO - PROCESS | 1648 | |4;audio-oscillator;89;71;88;69;69;68;105;101;100;92 10:50:52 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;88;88;89;88;89;88;89;88;122;88 10:50:52 INFO - PROCESS | 1648 | |6;imaging-darkroom;95;95;109;151;151;95;97;95;95;98 10:50:52 INFO - PROCESS | 1648 | |7;imaging-desaturate;83;86;87;87;85;86;87;84;86;86 10:50:52 INFO - PROCESS | 1648 | |8;json-parse-financial;64;62;61;62;62;62;66;65;65;66 10:50:52 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;54;67;54;65;61;57;58;59;58;57 10:50:52 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;83;82;86;86;82;85;83;88;83;111 10:50:52 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;142;128;135;140;144;127;140;147;166;134 10:50:52 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;163;163;164;162;166;164;162;164;165;176 10:50:52 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;65;66;85;79;84;83;82;79;79;63 10:50:52 INFO - PROCESS | 1648 | __end_tp_report 10:50:52 INFO - PROCESS | 1648 | __start_cc_report 10:50:52 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2076 10:50:52 INFO - PROCESS | 1648 | __end_cc_report 10:50:52 INFO - PROCESS | 1648 | __startTimestamp1491933052536__endTimestamp 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | ------- Summary: start ------- 10:50:52 INFO - PROCESS | 1648 | Number of tests: 14 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:115.40 Median:116.50 stddev:3.10 (2.7%) stddev-sans-first:1.00 10:50:52 INFO - PROCESS | 1648 | Values: 107.0 116.0 117.0 116.0 115.0 117.0 115.0 116.0 117.0 118.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:126.80 Median:131.00 stddev:20.08 (15.3%) stddev-sans-first:16.16 10:50:52 INFO - PROCESS | 1648 | Values: 164.0 139.0 146.0 145.0 111.0 110.0 110.0 109.0 123.0 111.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.00 stddev:0.88 (0.5%) stddev-sans-first:0.83 10:50:52 INFO - PROCESS | 1648 | Values: 186.0 186.0 184.0 184.0 185.0 185.0 184.0 184.0 186.0 185.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:65.90 Median:61.50 stddev:12.20 (19.8%) stddev-sans-first:11.46 10:50:52 INFO - PROCESS | 1648 | Values: 82.0 61.0 60.0 90.0 62.0 60.0 59.0 61.0 74.0 50.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:70.00 stddev:14.73 (21.0%) stddev-sans-first:15.55 10:50:52 INFO - PROCESS | 1648 | Values: 89.0 71.0 88.0 69.0 69.0 68.0 105.0 101.0 100.0 92.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:88.00 stddev:10.66 (12.1%) stddev-sans-first:11.22 10:50:52 INFO - PROCESS | 1648 | Values: 88.0 88.0 89.0 88.0 89.0 88.0 89.0 88.0 122.0 88.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:108.10 Median:95.00 stddev:23.01 (24.2%) stddev-sans-first:23.91 10:50:52 INFO - PROCESS | 1648 | Values: 95.0 95.0 109.0 151.0 151.0 95.0 97.0 95.0 95.0 98.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:1.34 (1.6%) stddev-sans-first:1.00 10:50:52 INFO - PROCESS | 1648 | Values: 83.0 86.0 87.0 87.0 85.0 86.0 87.0 84.0 86.0 86.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:64.50 stddev:1.90 (2.9%) stddev-sans-first:2.01 10:50:52 INFO - PROCESS | 1648 | Values: 64.0 62.0 61.0 62.0 62.0 62.0 66.0 65.0 65.0 66.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:58.50 stddev:4.27 (7.3%) stddev-sans-first:4.13 10:50:52 INFO - PROCESS | 1648 | Values: 54.0 67.0 54.0 65.0 61.0 57.0 58.0 59.0 58.0 57.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:84.00 stddev:8.70 (10.4%) stddev-sans-first:9.11 10:50:52 INFO - PROCESS | 1648 | Values: 83.0 82.0 86.0 86.0 82.0 85.0 83.0 88.0 83.0 111.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:140.30 Median:141.00 stddev:11.15 (7.9%) stddev-sans-first:11.81 10:50:52 INFO - PROCESS | 1648 | Values: 142.0 128.0 135.0 140.0 144.0 127.0 140.0 147.0 166.0 134.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:164.00 stddev:4.09 (2.5%) stddev-sans-first:4.28 10:50:52 INFO - PROCESS | 1648 | Values: 163.0 163.0 164.0 162.0 166.0 164.0 162.0 164.0 165.0 176.0 10:50:52 INFO - PROCESS | 1648 | 10:50:52 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.50 Median:80.50 stddev:8.46 (10.5%) stddev-sans-first:7.89 10:50:52 INFO - PROCESS | 1648 | Values: 65.0 66.0 85.0 79.0 84.0 83.0 82.0 79.0 79.0 63.0 10:50:52 INFO - PROCESS | 1648 | -------- Summary: end -------- 10:50:52 INFO - PROCESS | 1648 | 10:50:53 INFO - TEST-INFO | 1648: exit 0 10:50:53 INFO - TEST-OK | kraken | took 60015ms 10:50:53 INFO - SUITE-END | took 1181s 10:50:53 INFO - Completed test suite (00:19:41) 10:50:53 INFO - javascript benchmark 10:50:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7601.822831684767, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3415.641357657444, 3443.8622455101795, 3446.155691855495, 3449.741269404792, 3499.1077275294797, 6037.909431358533, 6070.6661133637635, 6077.635341879491, 6080.908786368208, 6096.085587161922, 5958.553108516857, 6048.818535443944, 6055.182550355701, 6058.666773327465, 6082.118092876531, 5378.784848606061, 5404.6486978346475, 5420.91868621971, 5437.374701909272, 5542.224088627595, 4757.762981624768, 5313.787448502056, 5320.201969704556, 5516.531094856942, 5533.6956467394275, 5118.539331460168, 5412.729363531827, 5416.295881535395, 5424.728763561826, 5424.891502169954, 5394.946050539484, 5406.0, 5408.567314614821, 5415.620906536544, 5415.918761218604, 5276.0, 5363.249145119687, 5369.275147855029, 5382.838514844561, 5385.084535628914, 5987.580869339148, 5996.610220335708, 6005.819825405245, 6009.369016253318, 6025.849353766147, 6366.9681651591445, 6379.649119298437, 6396.48828093756, 6400.199975003125, 6405.6797160141805, 6348.714307856158, 6370.267419246799, 6397.456216221627, 6432.903506447407, 6434.163558737381, 6088.817335479943, 6138.508919286446, 6154.876902461926, 6158.907616385758, 6180.752769889244, 6291.685415729286, 6345.016522439029, 6625.966870165618, 6629.801105966829, 6636.30318816527, 6294.055891616203, 6326.43062124411, 6361.2048493938255, 6375.649339286384, 6407.871842563216, 6447.161868957006, 6449.580777249464, 6452.741890324428, 6457.515686323451, 6460.3862633049785, 6385.680715964183, 6391.872162556723, 6393.488520918316, 6398.840028999312, 6399.872002559922, 6134.099846298256, 6168.136460895478, 6333.366663333722, 6333.651649159248, 6339.144215530937, 5133.922991155136, 5143.0, 5172.741362931838, 5201.297824793604, 5240.0567897778765, 4608.631309495232, 4612.446506419251, 4615.0, 4620.607248383858, 4667.883302917454, 6116.480099191618, 6179.0422484514975, 6188.783392581238, 6190.721417536131, 6217.6891155442045, 6319.747210111636, 6376.617402955838, 6376.904346434807, 6385.553011289165, 6460.483161347081, 5957.553183511167, 5965.194698715704, 5977.103434484862, 5980.162777211194, 5980.37206093354, 5549.750261238254, 5598.048331783607, 5610.13042978339, 5613.943860561424, 5616.241807356037, 6419.967900160469, 6433.5496515243485, 6448.935925572416, 6553.2136143663065, 6613.173353330833, 5352.250684904195, 6395.872082558416, 6444.4844412445955, 6444.645544495098, 6445.291017988111, 5583.581231407579, 6684.097646817811, 6691.799246022627, 6707.128073350434, 6715.731370745116, 5987.0121429963565, 5988.251468566429, 5991.2510936132985, 6402.263739669997, 6415.262244841903, 5578.414266501959, 6356.237251529846, 6390.4248617624635, 6412.967935160481, 6433.356664333529, 6333.90499142504, 6342.23893132827, 6371.299157092623, 6377.87244255103, 6403.455706265019, 5867.853303667443, 6339.334369891095, 6366.490680745622, 6375.521835862236, 6419.775307864203, 6109.266887973472, 6122.724477398617, 6129.785457509144, 6132.601380910227, 6134.4172303631085, 6142.6314421134875, 6261.843453913689, 6262.3111457738705, 6270.498360131087, 6317.210348706411, 6238.407351301619, 6258.155149054819, 6269.780557680459, 6289.622622642656, 6325.905111423424], "value": 5901.642316731106, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.7490240574218, 815.5229190923308, 872.6989188729885, 884.5311984648139, 888.2139306713557, 27901.441971160566, 40314.395284070764, 41200.38199427007, 43183.784081079626, 43231.56768432315, 42210.0, 42410.78794606031, 42464.57535424645, 42464.78767606166, 42509.0, 7270.781876543712, 8898.021217666068, 9051.366404351698, 9074.63701451941, 9076.001639819648, 7259.092613423322, 10715.196360272972, 10987.505562249718, 11055.06031987278, 11094.0, 10883.727906802354, 10915.945420272927, 10916.672499824977, 10925.235233533656, 10946.233763636508, 10852.294600850959, 10860.728481787939, 10889.564417423293, 10892.836607450894, 10900.0, 7141.285871412869, 7333.0, 7397.371223446014, 7400.777976660709, 7402.0, 38699.61300387017, 39262.411063834064, 40758.0, 40812.38781418281, 40831.0, 3771.116518085004, 3771.7359784815076, 3809.085819403323, 3848.903777405559, 3952.3873799561115, 27184.728152718417, 27475.17574472769, 28035.26860806098, 28756.281092972633, 29460.11619651414, 3022.848857557113, 3088.861001254949, 3095.349976504937, 3096.2878537936176, 3153.9591934661507, 321921.0, 322748.0, 323247.3837630797, 324610.0, 324914.0, 37198.0, 37843.864684059525, 42615.93460163521, 44589.55410445919, 44937.77531112324, 44927.10145797066, 47455.762721186176, 47524.52475475201, 47596.0, 47779.04441911212, 13773.0, 13847.653808654662, 13854.515091971733, 13870.65323366924, 13928.58214253574, 11885.465154068088, 11923.642290731292, 11942.522299108112, 11953.0, 11962.98314643265, 11978.52085916571, 12016.798320167913, 12017.978471829818, 12091.939540302417, 12126.575569855013, 5140.948590514122, 5470.64440811346, 5521.69630670317, 5525.226468294442, 5532.834014979557, 725.26747984536, 927.4574373991156, 951.1772316945792, 971.2715463402449, 993.1508560180941, 28089.0, 28352.0, 28365.865365385565, 28932.710672893423, 28993.275168120967, 891.6923661336829, 990.5542505872386, 1065.8347956066823, 1068.455087905174, 1074.8119079161115, 14181.078229915023, 14575.41698332076, 14579.489717859824, 14900.776488352683, 14948.252587370587, 493322.0, 493805.0, 495725.0, 496379.0, 496574.0, 57243.0, 57753.422465774805, 57836.4216357831, 57874.7106264466, 58118.70940645354, 1398.3777219137387, 1460.1750011243614, 1462.020446300981, 1463.5243545847643, 1468.2585294426246, 27419.725802741716, 27722.029728960195, 27743.44513109686, 28352.432951340445, 28446.57330140008, 48505.75747121242, 48573.0, 48600.02799944052, 48703.26945095755, 48704.26943595778, 450011.0, 450447.0, 451421.0, 451427.0, 454147.0, 27108.780104895723, 27636.098209687694, 28304.85847570749, 32040.0, 33224.335513289116, 43234.56765432305, 44053.55946440495, 44465.777671111435, 44634.55365446434, 45644.0, 26874.462510749283, 29906.0, 30791.922282719996, 30818.84590577033, 31600.841995790794, 30336.39327213399, 30572.929947451732, 30583.776648933792, 31393.372132557677, 31612.367752644357, 29382.853085734434, 35383.292334152655, 36300.81849590735, 36980.260394792494, 37029.0, 30961.845190773904, 31100.844495777375, 31280.68719312778, 31733.5239971396, 31911.521327180577, 24547.263582092564, 24553.28127031162, 24663.136790212255, 24927.501449971263, 24965.750342496343], "value": 18381.11535555812, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.29091181314556, 431.40595990288426, 431.8186361728075, 439.4046067578431, 444.9977750111249, 15834.0, 17866.821331786694, 20434.284800032, 21213.151473941063, 21342.786572134275, 21332.786672133276, 21715.239966601133, 22091.889540552314, 22732.204372846954, 23000.309990700305, 6459.224893012845, 6624.37068478495, 6727.0918426012595, 6764.053032575419, 6814.28450012749, 6645.33546645334, 6671.132752742136, 6721.731130754764, 6735.090762747015, 6738.797836064926, 6384.361563843625, 6519.445847103002, 6573.211214654249, 6600.141981542392, 6742.898856517131, 6572.375624315682, 6598.967005164992, 6603.580230250471, 6619.238787539422, 6703.597784132968, 5625.6905870177125, 6476.579022363579, 6503.642299673516, 6504.77233296837, 6514.93485065148, 21103.788962110335, 26721.73278267212, 26935.0, 27018.729812702015, 27383.76773045218, 2836.149155253436, 2873.324768679359, 2877.223149749556, 2881.27968007998, 2883.7692984561186, 15967.441139560176, 16674.0, 16689.582760430963, 16917.069561174132, 17303.74044389335, 1987.5031242189452, 2021.191523390641, 2035.4809523571394, 2051.9487012824798, 2060.3097962182687, 136853.0, 137496.0, 138684.0, 140035.0, 142513.0, 13759.862401375858, 21192.576148476943, 21292.0, 21369.572608547744, 21699.806510642065, 29735.405291894473, 29840.25399365033, 30910.227244318623, 31609.0, 32126.83936580302, 8765.649374024968, 10369.429681367446, 10687.35875847436, 10927.945360273148, 11037.558497660164, 7437.962810185914, 7755.7673269802, 7862.095858976291, 7923.445358824943, 8193.180681931879, 8076.757697269091, 8159.714409995621, 8265.504069755714, 10340.741481462874, 10414.791704166026, 3593.4969104325414, 4059.04612416082, 4079.9592004080173, 4156.210320039243, 4170.791460426966, 868.4181598329135, 933.874681009382, 1001.8998100189923, 1055.984160237597, 1121.1311233793874, 22284.442888927908, 22314.665280020814, 22646.52797568153, 23981.640275395883, 24232.75767242305, 1076.8330908709163, 1079.7030816525362, 1093.704699731068, 1114.1147707169607, 1114.3759494683004, 11096.889031109586, 11261.268017578832, 11425.428728563538, 11444.771104577861, 11446.14153938458, 712782.0, 726186.0, 754811.0, 758051.0, 782387.0, 147431.0, 149260.50739492683, 152202.0, 156232.0, 165269.0, 1333.9799903001463, 1349.1770020287618, 1384.0588399887972, 1387.1399732166121, 1391.0610338021795, 18133.184006719992, 19150.904245478683, 19205.32781352646, 19211.711824322367, 19282.22871085141, 45949.310760337954, 59708.70145649244, 64150.67924660534, 65216.67391663011, 66125.0, 86373.56813215894, 88242.55878720565, 88781.0, 89390.10609893818, 90160.64759028751, 11098.5560577576, 11183.161262905152, 11422.771544569228, 11631.0, 11662.94168529152, 12810.0, 12929.741405172032, 13097.0, 13163.0, 13281.933590332374, 11047.281926674741, 11219.831702524632, 11306.4912078955, 11494.0, 11508.654740357802, 10251.077403033465, 10352.585896564053, 10397.58409663605, 10623.36259824413, 10704.732381690521, 11159.72100697489, 11263.324200547993, 11346.829797553208, 11457.713557160805, 11486.597969071043, 11353.03499202577, 11381.0, 11485.917772741788, 11702.180847340604, 11737.061035117342, 10820.89179108199, 11050.613228536962, 11063.834042489209, 11293.887061129284, 11336.6032188873], "value": 11527.664469151905, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.5891891351507, 1537.7155226283146, 1541.0522528644888, 1564.992175039125, 1568.388328551865, 10436.0, 11004.889951100487, 11013.00882920537, 11037.11703063755, 11055.778884422307, 10796.190285728582, 10969.670909872697, 10989.0, 10989.505472253748, 10998.230123891331, 2374.9762502375015, 2404.446977195237, 2416.1301931304715, 2418.3349578865777, 2421.5156968606348, 2327.7206735191717, 2407.5064611754547, 2413.4087148648605, 2415.6618073469726, 2416.794572461343, 2328.860268383902, 2386.069432921162, 2387.4481586968436, 2390.8924098415528, 2397.6043952747777, 2281.0647634469788, 2350.5063936573342, 2358.646203069536, 2359.1742889988454, 2394.5929192037397, 2351.835371523994, 2374.228375777879, 2377.0491803278655, 2377.1798729438315, 2396.664466974625, 9156.954215228881, 10525.631602893938, 10889.0, 10896.945515272451, 10897.455127243686, 5570.6657600544095, 5714.457126572969, 5744.540436765049, 5750.683712395817, 5756.827295181151, 3326.3846188455213, 3358.0933148050135, 3432.9485057724155, 3433.8798142064907, 3441.3633477806684, 1054.9525271362731, 1068.172166570906, 1089.9455027248684, 1098.7253186703324, 1099.9340039597569, 12705.364731763468, 13369.0, 13720.725585488235, 14741.115533067943, 15490.148041857694, 4594.908101838011, 4705.835295764632, 4708.058388322281, 4710.317004034396, 4733.360996265529, 11579.826302605468, 11604.825927611093, 11609.013233875216, 11613.36126513033, 11636.010939070104, 4857.805687772449, 5012.824551140692, 5473.425290344536, 5652.8869422611315, 5703.857403564861, 5043.193089105799, 5195.922061169085, 5286.127788914786, 5288.338957630296, 5309.0178317010705, 3782.3569993101237, 3799.6580307772433, 3894.4937158169255, 3905.160390515996, 3908.5895980922164, 917.513717729591, 966.6906589891167, 1007.0, 1017.3234798858824, 1020.7652239984697, 823.6005537314397, 941.5715849288557, 949.7103383468109, 951.7240000399806, 997.4264797741183, 6000.729967151488, 6646.567973081735, 6647.86704265912, 6741.831454213684, 6798.082258895085, 945.8486642137225, 961.4519723757392, 974.2644303550774, 980.2648013989508, 986.8914419413859, 4084.5302790179508, 4113.300738874398, 4158.708890377705, 4204.222218889515, 4292.334688123346, 13010.479580816851, 13640.863591364157, 13657.590272291847, 13686.0, 13769.242691652054, 13073.934630326787, 13338.0, 13631.863681363258, 13658.248796316098, 13664.863351366359, 9967.352122112039, 10069.748256293651, 10071.395716257048, 10224.233182511192, 10228.53971571281, 6407.327230640902, 6462.353764623688, 6526.97663724247, 6546.149000629887, 6574.441172500391, 12092.637220883387, 12192.0, 12205.633830985085, 12210.145289829627, 12225.388730563614, 8865.64537418496, 12105.213161144497, 12113.33376664292, 12274.0, 12349.876501235232, 8270.090290068209, 8356.6239519223, 8363.95818020906, 8458.74623761288, 8471.915280847114, 10030.849537256801, 10244.02681745233, 10261.897381026094, 10267.589296428358, 10271.845922311022, 8431.620577073923, 8591.269742071994, 8672.609732561923, 8689.522076285612, 8754.25588824957, 8282.378821588285, 8293.087760346236, 8364.958175209085, 8378.664853405795, 8435.915640843514, 7683.154852966058, 7863.488873223222, 7866.331361834308, 7866.606669666379, 7871.370290376871, 5751.309842818889, 5781.103928891029, 5930.733117009659, 5967.671778052249, 6014.398560144125, 5752.223449834386, 5939.703014849327, 5975.40245975399, 6027.2164618601055, 6076.179715738319], "value": 5026.882933128856, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.06215619093217, 378.4247943126448, 379.5862509864251, 381.46785234597746, 389.20796179774146, 4401.845935392262, 4590.4163063743, 4598.080383923212, 4664.927066774644, 4985.955758139164, 4737.668363214576, 4854.296127061574, 4888.973315603718, 4910.877228069291, 4924.803007879689, 3993.600639936012, 4029.214303210875, 4073.572274911136, 4273.230818452677, 4274.358846173068, 3824.9617503825025, 4102.892219100841, 4211.284081706102, 4227.957720422803, 4242.257604919127, 3664.6518580734787, 3876.9612303876884, 4122.917541649165, 4151.0, 4244.427002354673, 3789.905252368685, 4024.7182697211206, 4235.3858690490015, 4316.9639286571, 4350.085442753363, 3225.3226822367333, 3312.4431517187127, 3859.7227635695076, 3860.0542866997484, 3864.8647297344737, 4433.13553856998, 4500.797464114123, 4804.471508134137, 4821.831235906761, 4884.38685303361, 4604.930926036112, 4678.3216433616935, 4813.855584332476, 4857.0, 4938.950610493885, 4623.583877451012, 5010.799568017275, 5340.759665815048, 5342.67943923362, 5418.91871621926, 3658.798766067865, 3798.392257238856, 4266.957330426687, 4297.162053399588, 4303.246931786956, 4855.150348688965, 4861.513848615145, 5151.5106064923775, 5387.434319396485, 5445.156000819879, 3722.571904230994, 4171.954473234556, 4180.958190418118, 4250.370584800291, 4377.5403582624, 3827.3110840049058, 3849.634284742945, 3933.5083114610675, 4142.46148000758, 4419.143959537002, 3770.2836461072307, 4185.895352616148, 4448.221561226773, 4587.7017993830295, 4815.879603009953, 4519.988605070712, 4754.738489383048, 5293.841184764463, 5305.7266256098255, 5310.787568497294, 1465.2234315812636, 1513.833478317384, 1521.7184820808186, 1522.3301747231403, 1531.5526827148237, 968.0754879090451, 1016.588281745894, 1134.9671798663178, 1146.18620779246, 1148.3511815824033, 4305.806238719265, 4374.606285434326, 4457.710248833815, 4644.375927600895, 4962.975185124052, 4673.789679464432, 5052.72210028452, 5150.403374953701, 5182.352205974254, 5326.414094449608, 4129.421880936671, 4156.875293741193, 4963.65981185078, 4987.62592805541, 4993.775280112403, 2584.3539115221197, 2586.0, 2729.959050614242, 2734.5077885980318, 2868.3402817352126, 5106.463821298785, 5210.635255532152, 5400.756965936543, 5756.194132821408, 5769.509591684669, 4486.394336764495, 4861.805527778849, 5636.703558181641, 5813.88372232553, 5844.503217226584, 5246.0, 5469.890602187934, 5779.88440231193, 5812.331581868139, 5821.380378829459, 5052.823151189691, 5259.921101183409, 5675.545956323567, 5682.744276507485, 5722.62802917809, 4480.385203240668, 4750.596199323097, 5025.74871256443, 5065.189569668983, 5512.373849714264, 5165.560927321219, 5372.5970552207955, 5608.691521966168, 5627.8874422512135, 5729.4843464088435, 3621.619729928425, 3840.3678436664864, 3921.0786714510255, 3953.367461206193, 4417.293233082692, 4995.350604421402, 5128.743562821918, 5202.4767987526775, 5556.91664625039, 5603.579731520071, 4667.486576476517, 4832.468428472936, 4852.56326930578, 4977.726225057657, 5380.381256155436, 5297.642549065628, 5312.203169524602, 5326.520613144836, 5334.67991920486, 5378.408375078817, 4322.8703138905885, 4387.714798538085, 4787.8563643090765, 5420.945790542044, 5455.236266922555, 4972.455711881299, 4977.601791856715, 5024.186455584738, 5078.222622082241, 5402.162664786964, 4757.881052973703, 5184.766685499086, 5334.959073328321, 5351.59863010268, 5363.919541206963], "value": 4034.3616025207016, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.9118105827305, 775.4610545670757, 791.4697152907552, 841.6086519768309, 854.4488804720953, 15132.0, 15877.68244635108, 15886.046837189762, 15891.125988070651, 15904.602384940383, 14445.349959251833, 14473.986820922539, 14549.636259093528, 14557.417703291856, 15119.697606047872, 921.0927236671873, 926.2127191886909, 944.2776276148752, 948.0756262643902, 952.4047470331043, 931.0642803981989, 950.8336041192798, 951.3245595627113, 953.6709835106877, 953.7567920180344, 1013.7161594753444, 1014.5181039006486, 1015.5430056474615, 1016.0652199976003, 1017.4048181813648, 1009.5658866687321, 1010.0, 1011.7318910488726, 1012.4886932099316, 1014.2849291249688, 746.9962650186715, 747.7794050755041, 748.0649188514357, 748.2143749063462, 748.3900620993927, 15477.535673929688, 15772.92113539425, 15863.841361586467, 16160.75758863618, 16414.83585164157, 13914.930425347808, 13961.930190349085, 14017.43930242789, 14059.718805623932, 14136.22250776207, 11551.191416600841, 11596.362200078993, 11819.0, 11877.287362758263, 11989.64031079069, 3611.6388361163936, 3628.0050947301415, 3727.6458736419995, 3752.4934133891848, 3842.903927401809, 108436.4578177112, 111581.44209278983, 114835.79046813962, 115837.84162158355, 119096.40451797686, 10788.730281742863, 10975.231733778719, 11048.668539943814, 11067.225294229487, 11279.436028198557, 22197.556048879254, 22971.0, 22986.310410687707, 22995.655065174036, 22996.88501557515, 7443.292887175709, 7504.812379690442, 7530.849383012309, 7608.543487390774, 7775.961120194363, 4280.743155410685, 4305.526392096866, 4446.221911165598, 4452.8218871245435, 4565.155446242456, 3418.6923176914197, 3462.7402944778737, 3480.582330120402, 3535.6641119093647, 3544.486049522805, 594.7710131599365, 597.1609888107216, 602.3223873142714, 613.6655522740117, 617.5183356981562, 653.506602515098, 701.8631366883511, 710.8874611233404, 720.6288761287941, 727.6361819090455, 9385.671501497414, 9459.763505912408, 10253.436061016566, 10406.739831504274, 10422.583096676048, 609.2384519350811, 636.9713362898681, 646.9288378278386, 660.979324487099, 667.552739664426, 4998.525140111684, 5002.499750024968, 5003.3495645565845, 5003.824866129668, 5023.824166154167, 88504.0, 89208.0, 92123.07876921279, 93544.0, 96055.0, 74232.0, 87750.0, 91233.54383228041, 91450.08549914548, 93759.0, 72546.0, 72704.63647681728, 73177.6341118291, 73998.52002960019, 75184.0, 9627.518624068909, 9646.758831029281, 9648.179904708179, 9657.46883921363, 9682.854757178507, 46508.767456163856, 50061.99876002532, 50196.49803501918, 51362.74318628383, 51850.22224666557, 53087.73456132849, 63979.0, 67478.66260668666, 68661.97007044799, 69716.65141674259, 12572.497100115894, 12720.363981801058, 13161.407736651681, 13268.336583170996, 13524.985626077887, 14429.278536073365, 14543.636409089435, 14678.559643210721, 14802.185879776716, 14912.776308355167, 13130.606081817561, 13228.338583071001, 13591.728165436438, 13593.184408935496, 13697.657558561115, 13204.669883252996, 13362.0, 13768.518101866386, 13789.51736689211, 13915.02594818353, 12904.677383065498, 12922.935385323013, 13583.728325433238, 13627.38676759568, 14097.436102556194, 9701.563429645825, 9757.60969561238, 9816.852747208655, 9993.300468967103, 10098.242631802792, 9979.311063371617, 10264.58941642326, 11062.557497700001, 11694.181407301407, 11755.941220293844], "value": 7608.579405636581, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1454.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 117.0, 116.0, 115.0, 117.0, 115.0, 116.0, 117.0, 118.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 139.0, 146.0, 145.0, 111.0, 110.0, 110.0, 109.0, 123.0, 111.0], "value": 126.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 184.0, 184.0, 185.0, 185.0, 184.0, 184.0, 186.0, 185.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 60.0, 90.0, 62.0, 60.0, 59.0, 61.0, 74.0, 50.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 88.0, 69.0, 69.0, 68.0, 105.0, 101.0, 100.0, 92.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 88.0, 89.0, 88.0, 89.0, 88.0, 122.0, 88.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 109.0, 151.0, 151.0, 95.0, 97.0, 95.0, 95.0, 98.0], "value": 108.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 87.0, 87.0, 85.0, 86.0, 87.0, 84.0, 86.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 61.0, 62.0, 62.0, 62.0, 66.0, 65.0, 65.0, 66.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 67.0, 54.0, 65.0, 61.0, 57.0, 58.0, 59.0, 58.0, 57.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 86.0, 86.0, 82.0, 85.0, 83.0, 88.0, 83.0, 111.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 128.0, 135.0, 140.0, 144.0, 127.0, 140.0, 147.0, 166.0, 134.0], "value": 140.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 162.0, 166.0, 164.0, 162.0, 164.0, 165.0, 176.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 85.0, 79.0, 84.0, 83.0, 82.0, 79.0, 79.0, 63.0], "value": 76.5, "unit": "score"}]}]} 10:50:53 INFO - Return code: 0 10:50:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:50:53 ERROR - # TBPL SUCCESS # 10:50:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:50:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:50:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:50:53 INFO - 2017-04-11 10:50:53.844 screenresolution[1652:9946] starting screenresolution argv=screenresolution get 10:50:53 INFO - 2017-04-11 10:50:53.846 screenresolution[1652:9946] Display 0: 1600x1200x32@60 10:50:53 INFO - 2017-04-11 10:50:53.853 screenresolution[1653:9949] starting screenresolution argv=screenresolution list 10:50:53 INFO - Available Modes on Display 0 10:50:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:50:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:50:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:50:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:50:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:50:54 INFO - Intel Iris: 10:50:54 INFO - Chipset Model: Intel Iris 10:50:54 INFO - Type: GPU 10:50:54 INFO - Bus: Built-In 10:50:54 INFO - VRAM (Dynamic, Max): 1536 MB 10:50:54 INFO - Vendor: Intel (0x8086) 10:50:54 INFO - Device ID: 0x0a2e 10:50:54 INFO - Revision ID: 0x0009 10:50:54 INFO - Displays: 10:50:54 INFO - Display: 10:50:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:50:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:50:54 INFO - Display Serial Number: 1600x1200 60 10:50:54 INFO - Main Display: Yes 10:50:54 INFO - Mirror: Off 10:50:54 INFO - Online: Yes 10:50:54 INFO - Rotation: Supported 10:50:54 INFO - Return code: 0 10:50:54 INFO - Running post-action listener: _resource_record_post_action 10:50:54 INFO - [mozharness: 2017-04-11 17:50:54.086854Z] Finished run-tests step (success) 10:50:54 INFO - Running post-run listener: _resource_record_post_run 10:50:54 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 40948736; Write bytes: 509182976; Read time: 408; Write time: 1565 10:50:54 INFO - TinderboxPrint: CPU usage
53.7% 10:50:54 INFO - TinderboxPrint: I/O read bytes / time
40,948,736 / 408 10:50:54 INFO - TinderboxPrint: I/O write bytes / time
509,182,976 / 1,565 10:50:54 INFO - TinderboxPrint: CPU idle
2,226.3 (46.2%) 10:50:54 INFO - TinderboxPrint: CPU system
959.7 (19.9%) 10:50:54 INFO - TinderboxPrint: CPU user
1,627.7 (33.8%) 10:50:54 INFO - TinderboxPrint: Swap in / out
247,386,112 / 0 10:50:54 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 208077312; Write bytes: 201635328; Read time: 16227; Write time: 363 10:50:54 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 35869696; Write bytes: 297381376; Read time: 372; Write time: 1172 10:50:54 INFO - Running post-run listener: _upload_blobber_files 10:50:54 INFO - Blob upload gear active. 10:50:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:50:54 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 10:50:54 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'] 10:50:54 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 10:50:54 INFO - (blobuploader) - INFO - Open directory for files ... 10:50:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:50:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:50:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:56 INFO - (blobuploader) - INFO - Done attempting. 10:50:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:50:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:50:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:57 INFO - (blobuploader) - INFO - Done attempting. 10:50:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:50:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:50:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:58 INFO - (blobuploader) - INFO - Done attempting. 10:50:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:50:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:50:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:50:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:50:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:50:59 INFO - (blobuploader) - INFO - Done attempting. 10:50:59 INFO - (blobuploader) - INFO - Iteration through files over. 10:50:59 INFO - Return code: 0 10:50:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:50:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:50:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/639a6f2e8812c7f622eccf2a166e6d15f2cdf4e9ded102f270f1edc54b930fe5dcf602658d0d5422c5a0cb14c160e0c44db1ec19e206117f38ba5685f6ca85ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5141fb5bcc048126082089e392ceed1f2673ff8aa5ea0d2652213ec64fcd39c2f8163044502305c0e34a041a152d9bb38572294d688df6882573ac6bcd2f9ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e3e3836133d6c50e94f533fc6b7b8315cd92822ec756c19bf9f1d8c3216ec62f624aabd283432df5e391bde25b9dd9aa2c2432c28121f14ae67af98405bf0e29", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc48a2e9eedbb10c04dec15957c7bdb86f6e207712d7c3cffa4018e3a72bed61f9ef77d2fcf355e696a73d8a21948c31b2f2f72e69d168db2ca85856d296a5bf"} 10:50:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:50:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:50:59 INFO - Contents: 10:50:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/639a6f2e8812c7f622eccf2a166e6d15f2cdf4e9ded102f270f1edc54b930fe5dcf602658d0d5422c5a0cb14c160e0c44db1ec19e206117f38ba5685f6ca85ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5141fb5bcc048126082089e392ceed1f2673ff8aa5ea0d2652213ec64fcd39c2f8163044502305c0e34a041a152d9bb38572294d688df6882573ac6bcd2f9ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e3e3836133d6c50e94f533fc6b7b8315cd92822ec756c19bf9f1d8c3216ec62f624aabd283432df5e391bde25b9dd9aa2c2432c28121f14ae67af98405bf0e29", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc48a2e9eedbb10c04dec15957c7bdb86f6e207712d7c3cffa4018e3a72bed61f9ef77d2fcf355e696a73d8a21948c31b2f2f72e69d168db2ca85856d296a5bf"} 10:50:59 INFO - Running post-run listener: copy_logs_to_upload_dir 10:50:59 INFO - Copying logs to upload dir... 10:50:59 INFO - mkdir: /builds/slave/test/build/upload/logs 10:50:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.909768 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-11 10:51:00.098908) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 10:51:00.102766) ========= 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.HMOvdlZR0P/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.BMFamAveP7/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/639a6f2e8812c7f622eccf2a166e6d15f2cdf4e9ded102f270f1edc54b930fe5dcf602658d0d5422c5a0cb14c160e0c44db1ec19e206117f38ba5685f6ca85ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5141fb5bcc048126082089e392ceed1f2673ff8aa5ea0d2652213ec64fcd39c2f8163044502305c0e34a041a152d9bb38572294d688df6882573ac6bcd2f9ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e3e3836133d6c50e94f533fc6b7b8315cd92822ec756c19bf9f1d8c3216ec62f624aabd283432df5e391bde25b9dd9aa2c2432c28121f14ae67af98405bf0e29", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc48a2e9eedbb10c04dec15957c7bdb86f6e207712d7c3cffa4018e3a72bed61f9ef77d2fcf355e696a73d8a21948c31b2f2f72e69d168db2ca85856d296a5bf"} build_url:https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011367 build_url: 'https://queue.taskcluster.net/v1/task/OzKqfOI9Rb6Lqf43TuZbYg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/639a6f2e8812c7f622eccf2a166e6d15f2cdf4e9ded102f270f1edc54b930fe5dcf602658d0d5422c5a0cb14c160e0c44db1ec19e206117f38ba5685f6ca85ad", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5141fb5bcc048126082089e392ceed1f2673ff8aa5ea0d2652213ec64fcd39c2f8163044502305c0e34a041a152d9bb38572294d688df6882573ac6bcd2f9ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e3e3836133d6c50e94f533fc6b7b8315cd92822ec756c19bf9f1d8c3216ec62f624aabd283432df5e391bde25b9dd9aa2c2432c28121f14ae67af98405bf0e29", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc48a2e9eedbb10c04dec15957c7bdb86f6e207712d7c3cffa4018e3a72bed61f9ef77d2fcf355e696a73d8a21948c31b2f2f72e69d168db2ca85856d296a5bf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 10:51:00.138386) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:51:00.138719) ========= 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.HMOvdlZR0P/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.BMFamAveP7/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.005048 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 10:51:00.187284) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 10:51:00.187682) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 10:51:00.188005) ========= ========= Total master_lag: 0.36 =========