builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0338 starttime: 1477359586.69 results: success (0) buildid: 20161024163422 builduid: ffd332a51cc145c4b3042b07ea82fa72 revision: fcd7ad8a2488527ce094cbdb3582c29437c155a8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:46.691270) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:46.691737) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:46.692034) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:46.716279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:46.716633) ========= 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.PbYi9bKwCo/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.mi0e56uQS9/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-24 18:39:46-- 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% 17.7M=0.001s 2016-10-24 18:39:46 (17.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127846 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:46.867369) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:46.867704) ========= 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.PbYi9bKwCo/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.mi0e56uQS9/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.024907 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:46.916717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:46.917144) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fcd7ad8a2488527ce094cbdb3582c29437c155a8 --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 fcd7ad8a2488527ce094cbdb3582c29437c155a8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PbYi9bKwCo/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.mi0e56uQS9/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-24 18:39:47,001 truncating revision to first 12 chars 2016-10-24 18:39:47,002 Setting DEBUG logging. 2016-10-24 18:39:47,002 attempt 1/10 2016-10-24 18:39:47,002 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fcd7ad8a2488?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-24 18:39:47,538 unpacking tar archive at: mozilla-aurora-fcd7ad8a2488/testing/mozharness/ program finished with exit code 0 elapsedTime=0.814205 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:47.753753) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:47.754078) ========= script_repo_revision: fcd7ad8a2488527ce094cbdb3582c29437c155a8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:47.754472) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:47.754751) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 18:39:47.773435) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-10-24 18:39:47.773769) ========= /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.PbYi9bKwCo/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.mi0e56uQS9/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:39:47 INFO - MultiFileLogger online at 20161024 18:39:47 in /builds/slave/test 18:39:47 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 18:39:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:39:47 INFO - {'append_to_log': False, 18:39:47 INFO - 'base_work_dir': '/builds/slave/test', 18:39:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:39:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:39:47 INFO - 'branch': 'Mozilla-Aurora', 18:39:47 INFO - 'buildbot_json_path': 'buildprops.json', 18:39:47 INFO - 'config_files': ('talos/mac_config.py',), 18:39:47 INFO - 'default_actions': ('clobber', 18:39:47 INFO - 'read-buildbot-config', 18:39:47 INFO - 'download-and-extract', 18:39:47 INFO - 'populate-webroot', 18:39:47 INFO - 'create-virtualenv', 18:39:47 INFO - 'install', 18:39:47 INFO - 'run-tests'), 18:39:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:39:47 INFO - 'download_minidump_stackwalk': True, 18:39:47 INFO - 'download_symbols': 'ondemand', 18:39:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:39:47 INFO - 'tooltool.py': '/tools/tooltool.py', 18:39:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:39:47 INFO - '/tools/misc-python/virtualenv.py')}, 18:39:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:39:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:39:47 INFO - 'installer_path': 'installer.exe', 18:39:47 INFO - 'log_level': 'info', 18:39:47 INFO - 'log_name': 'talos', 18:39:47 INFO - 'log_to_console': True, 18:39:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:39:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:39:47 INFO - 'opt_config_files': (), 18:39:47 INFO - 'pip_index': False, 18:39:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:39:47 INFO - 'cmd': ('bash', 18:39:47 INFO - '-c', 18:39:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:39:47 INFO - 'enabled': True, 18:39:47 INFO - 'halt_on_failure': False, 18:39:47 INFO - 'name': 'check_screen_resolution'},), 18:39:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:39:47 INFO - 'cmd': ('bash', 18:39:47 INFO - '-c', 18:39:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:39:47 INFO - 'enabled': True, 18:39:47 INFO - 'halt_on_failure': False, 18:39:47 INFO - 'name': 'check_screen_resolution'},), 18:39:47 INFO - 'run_cmd_checks_enabled': True, 18:39:47 INFO - 'sps_profile': False, 18:39:47 INFO - 'sps_profile_interval': 0, 18:39:47 INFO - 'suite': 'dromaeojs', 18:39:47 INFO - 'system_bits': '32', 18:39:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:39:47 INFO - 'title': 't-yosemite-r7-0338', 18:39:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:39:47 INFO - 'use_talos_json': True, 18:39:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:39:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:39:47 INFO - 'work_dir': 'build'} 18:39:47 INFO - [mozharness: 2016-10-25 01:39:47.990596Z] Running clobber step. 18:39:47 INFO - Running pre-action listener: _resource_record_pre_action 18:39:47 INFO - Running main action method: clobber 18:39:47 INFO - rmtree: /builds/slave/test/build 18:39:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:39:50 INFO - Running post-action listener: _resource_record_post_action 18:39:50 INFO - [mozharness: 2016-10-25 01:39:50.210158Z] Finished clobber step (success) 18:39:50 INFO - [mozharness: 2016-10-25 01:39:50.210334Z] Running read-buildbot-config step. 18:39:50 INFO - Running pre-action listener: _resource_record_pre_action 18:39:50 INFO - Running main action method: read_buildbot_config 18:39:50 INFO - Using buildbot properties: 18:39:50 INFO - { 18:39:50 INFO - "project": "", 18:39:50 INFO - "product": "firefox", 18:39:50 INFO - "script_repo_revision": "production", 18:39:50 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:39:50 INFO - "repository": "", 18:39:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:39:50 INFO - "buildid": "20161024163422", 18:39:50 INFO - "pgo_build": "False", 18:39:50 INFO - "basedir": "/builds/slave/test", 18:39:50 INFO - "buildnumber": 5, 18:39:50 INFO - "slavename": "t-yosemite-r7-0338", 18:39:50 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:39:50 INFO - "platform": "yosemite_r7", 18:39:50 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:39:50 INFO - "branch": "mozilla-aurora", 18:39:50 INFO - "repo_path": "releases/mozilla-aurora", 18:39:50 INFO - "revision": "fcd7ad8a2488527ce094cbdb3582c29437c155a8", 18:39:50 INFO - "stage_platform": "macosx64", 18:39:50 INFO - "builduid": "ffd332a51cc145c4b3042b07ea82fa72", 18:39:50 INFO - "slavebuilddir": "test" 18:39:50 INFO - } 18:39:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg. 18:39:50 INFO - Running post-action listener: _resource_record_post_action 18:39:50 INFO - [mozharness: 2016-10-25 01:39:50.213528Z] Finished read-buildbot-config step (success) 18:39:50 INFO - [mozharness: 2016-10-25 01:39:50.213643Z] Running download-and-extract step. 18:39:50 INFO - Running pre-action listener: _resource_record_pre_action 18:39:50 INFO - Running main action method: download_and_extract 18:39:50 INFO - mkdir: /builds/slave/test/build/tests 18:39:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:39:50 INFO - trying https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.test_packages.json 18:39:50 INFO - Downloading https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.mac.test_packages.json 18:39:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.mac.test_packages.json'}, attempt #1 18:39:51 INFO - Downloaded 1199 bytes. 18:39:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.mac.test_packages.json 18:39:51 INFO - Using the following test package requirements: 18:39:51 INFO - {u'common': [u'firefox-51.0a2.en-US.mac.common.tests.zip'], 18:39:51 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 18:39:51 INFO - u'firefox-51.0a2.en-US.mac.cppunittest.tests.zip'], 18:39:51 INFO - u'gtest': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 18:39:51 INFO - u'firefox-51.0a2.en-US.mac.gtest.tests.zip'], 18:39:51 INFO - u'jittest': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 18:39:51 INFO - u'jsshell-mac.zip'], 18:39:51 INFO - u'mochitest': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 18:39:51 INFO - u'firefox-51.0a2.en-US.mac.mochitest.tests.zip'], 18:39:51 INFO - u'mozbase': [u'firefox-51.0a2.en-US.mac.common.tests.zip'], 18:39:51 INFO - u'reftest': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 18:39:51 INFO - u'firefox-51.0a2.en-US.mac.reftest.tests.zip'], 18:39:51 INFO - u'talos': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 18:39:51 INFO - u'firefox-51.0a2.en-US.mac.talos.tests.zip'], 18:39:51 INFO - u'web-platform': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 18:39:51 INFO - u'firefox-51.0a2.en-US.mac.web-platform.tests.zip'], 18:39:51 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.mac.common.tests.zip', 18:39:51 INFO - u'firefox-51.0a2.en-US.mac.xpcshell.tests.zip']} 18:39:51 INFO - Downloading packages: [u'firefox-51.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:39:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip 18:39:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:39:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip into memory 18:39:51 INFO - Expected file size: 16438564 18:39:58 INFO - Downloading packages: [u'firefox-51.0a2.en-US.mac.common.tests.zip', u'firefox-51.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:39:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip 18:39:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:39:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.common.tests.zip into memory 18:39:59 INFO - Expected file size: 16438564 18:40:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.talos.tests.zip 18:40:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:40:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.talos.tests.zip into memory 18:40:06 INFO - Expected file size: 14414081 18:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:07 INFO - trying https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg 18:40:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a2.en-US.mac.dmg 18:40:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.mac.dmg'}, attempt #1 18:40:10 INFO - Downloaded 115434671 bytes. 18:40:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg 18:40:10 INFO - mkdir: /builds/slave/test/properties 18:40:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:40:10 INFO - Writing to file /builds/slave/test/properties/build_url 18:40:10 INFO - Contents: 18:40:10 INFO - build_url:https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg 18:40:11 INFO - Running post-action listener: _resource_record_post_action 18:40:11 INFO - Running post-action listener: set_extra_try_arguments 18:40:11 INFO - [mozharness: 2016-10-25 01:40:11.148427Z] Finished download-and-extract step (success) 18:40:11 INFO - [mozharness: 2016-10-25 01:40:11.148552Z] Running populate-webroot step. 18:40:11 INFO - Running pre-action listener: _resource_record_pre_action 18:40:11 INFO - Running main action method: populate_webroot 18:40:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:40:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:40:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:40:11 INFO - '--tppagecycles', 18:40:11 INFO - '7'], 18:40:11 INFO - 'tests': ['tsvgm']}}, 18:40:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:40:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:40:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:40:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:40:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:11 INFO - 'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:40:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:40:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:11 INFO - 'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['damp', 'tps']}, 18:40:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:11 INFO - 'tests': ['damp', 'tps']}, 18:40:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['dromaeo_dom']}, 18:40:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:40:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['basic_compositor_video']}, 18:40:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:40:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['a11yr', 18:40:11 INFO - 'ts_paint', 18:40:11 INFO - 'tpaint', 18:40:11 INFO - 'sessionrestore', 18:40:11 INFO - 'sessionrestore_no_auto_restore', 18:40:11 INFO - 'tabpaint']}, 18:40:11 INFO - 'other-e10s': {'tests': ['a11yr', 18:40:11 INFO - 'ts_paint', 18:40:11 INFO - 'tpaint', 18:40:11 INFO - 'sessionrestore', 18:40:11 INFO - 'sessionrestore_no_auto_restore', 18:40:11 INFO - 'tabpaint']}, 18:40:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:40:11 INFO - 'ts_paint', 18:40:11 INFO - 'tpaint', 18:40:11 INFO - 'sessionrestore', 18:40:11 INFO - 'sessionrestore_no_auto_restore', 18:40:11 INFO - 'tabpaint']}, 18:40:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:40:11 INFO - 'ts_paint', 18:40:11 INFO - 'tpaint', 18:40:11 INFO - 'sessionrestore', 18:40:11 INFO - 'sessionrestore_no_auto_restore', 18:40:11 INFO - 'tabpaint']}, 18:40:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['a11yr', 18:40:11 INFO - 'ts_paint', 18:40:11 INFO - 'tpaint', 18:40:11 INFO - 'sessionrestore', 18:40:11 INFO - 'sessionrestore_no_auto_restore', 18:40:11 INFO - 'tabpaint']}, 18:40:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['a11yr', 18:40:11 INFO - 'ts_paint', 18:40:11 INFO - 'tpaint', 18:40:11 INFO - 'sessionrestore', 18:40:11 INFO - 'sessionrestore_no_auto_restore', 18:40:11 INFO - 'tabpaint']}, 18:40:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['tsvgx', 18:40:11 INFO - 'tsvgr_opacity', 18:40:11 INFO - 'tart', 18:40:11 INFO - 'tscrollx', 18:40:11 INFO - 'cart']}, 18:40:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:40:11 INFO - 'tsvgr_opacity', 18:40:11 INFO - 'tart', 18:40:11 INFO - 'tscrollx', 18:40:11 INFO - 'cart']}, 18:40:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:11 INFO - 'talos_options': ['--disable-e10s'], 18:40:11 INFO - 'tests': ['tp5o']}, 18:40:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:11 INFO - 'tests': ['tp5o']}, 18:40:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:11 INFO - 'talos_options': ['--disable-e10s', 18:40:11 INFO - '--xperf_path', 18:40:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:40:11 INFO - 'tests': ['tp5n']}, 18:40:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:40:11 INFO - 'talos_options': ['--xperf_path', 18:40:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:40:11 INFO - 'tests': ['tp5n']}}, 18:40:11 INFO - 'talos.zip': {'path': '', 18:40:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:40:11 INFO - Running post-action listener: _resource_record_post_action 18:40:11 INFO - [mozharness: 2016-10-25 01:40:11.167061Z] Finished populate-webroot step (success) 18:40:11 INFO - [mozharness: 2016-10-25 01:40:11.167190Z] Running create-virtualenv step. 18:40:11 INFO - Running pre-action listener: _resource_record_pre_action 18:40:11 INFO - Running main action method: create_virtualenv 18:40:11 INFO - Creating virtualenv /builds/slave/test/build/venv 18:40:11 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 18:40:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:40:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:40:11 INFO - Using real prefix '/tools/python27' 18:40:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:40:12 INFO - Installing distribute.............................................................................................................................................................................................done. 18:40:14 INFO - Installing pip.................done. 18:40:14 INFO - Return code: 0 18:40:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:40: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')]} 18:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40: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')]} 18:40:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1093ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9da5150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, '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.PbYi9bKwCo/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.mi0e56uQS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:40:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:40:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:40:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:40:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:14 INFO - 'HOME': '/Users/cltbld', 18:40:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:14 INFO - 'LOGNAME': 'cltbld', 18:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:14 INFO - 'MOZ_NO_REMOTE': '1', 18:40:14 INFO - 'NO_EM_RESTART': '1', 18:40:14 INFO - 'PAGER': '/bin/cat', 18:40:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:14 INFO - 'PWD': '/builds/slave/test', 18:40:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:14 INFO - 'SHELL': '/bin/bash', 18:40:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:40:14 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:40:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:14 INFO - 'USER': 'cltbld', 18:40:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:14 INFO - 'XPC_FLAGS': '0x0', 18:40:14 INFO - 'XPC_SERVICE_NAME': '0', 18:40:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:15 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 18:40:15 INFO - Cleaning up... 18:40:15 INFO - Return code: 0 18:40:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:15 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 0x1093ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9da5150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, '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.PbYi9bKwCo/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.mi0e56uQS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:40:15 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 18:40:15 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 18:40:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:40:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:15 INFO - 'HOME': '/Users/cltbld', 18:40:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:15 INFO - 'LOGNAME': 'cltbld', 18:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:15 INFO - 'MOZ_NO_REMOTE': '1', 18:40:15 INFO - 'NO_EM_RESTART': '1', 18:40:15 INFO - 'PAGER': '/bin/cat', 18:40:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:15 INFO - 'PWD': '/builds/slave/test', 18:40:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:15 INFO - 'SHELL': '/bin/bash', 18:40:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:40:15 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:40:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:15 INFO - 'USER': 'cltbld', 18:40:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:15 INFO - 'XPC_FLAGS': '0x0', 18:40:15 INFO - 'XPC_SERVICE_NAME': '0', 18:40:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:15 INFO - Downloading/unpacking psutil>=3.1.1 18:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:40:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:40:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:40:18 INFO - Installing collected packages: psutil 18:40:18 INFO - Running setup.py install for psutil 18:40:18 INFO - building 'psutil._psutil_osx' extension 18:40:18 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 18:40:18 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 18:40:18 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 18:40:18 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 18:40:18 INFO - building 'psutil._psutil_posix' extension 18:40:18 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 18:40:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:40:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:40:19 INFO - ^ 18:40:19 INFO - 1 warning generated. 18:40:19 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 18:40:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:40:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:40:19 INFO - Successfully installed psutil 18:40:19 INFO - Cleaning up... 18:40:19 INFO - Return code: 0 18:40:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:40:19 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')]} 18:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:19 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')]} 18:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:19 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 0x1093ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9da5150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, '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.PbYi9bKwCo/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.mi0e56uQS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:40:19 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 18:40:19 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 18:40:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:40:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:19 INFO - 'HOME': '/Users/cltbld', 18:40:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:19 INFO - 'LOGNAME': 'cltbld', 18:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:19 INFO - 'MOZ_NO_REMOTE': '1', 18:40:19 INFO - 'NO_EM_RESTART': '1', 18:40:19 INFO - 'PAGER': '/bin/cat', 18:40:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:19 INFO - 'PWD': '/builds/slave/test', 18:40:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:19 INFO - 'SHELL': '/bin/bash', 18:40:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:40:19 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:40:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:19 INFO - 'USER': 'cltbld', 18:40:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:19 INFO - 'XPC_FLAGS': '0x0', 18:40:19 INFO - 'XPC_SERVICE_NAME': '0', 18:40:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:40:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:40:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:40:22 INFO - Installing collected packages: mozsystemmonitor 18:40:22 INFO - Running setup.py install for mozsystemmonitor 18:40:22 INFO - Successfully installed mozsystemmonitor 18:40:22 INFO - Cleaning up... 18:40:22 INFO - Return code: 0 18:40:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:22 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 0x1093ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9da5150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, '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.PbYi9bKwCo/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.mi0e56uQS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:40:22 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 18:40:22 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 18:40:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:40:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:22 INFO - 'HOME': '/Users/cltbld', 18:40:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:22 INFO - 'LOGNAME': 'cltbld', 18:40:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:22 INFO - 'MOZ_NO_REMOTE': '1', 18:40:22 INFO - 'NO_EM_RESTART': '1', 18:40:22 INFO - 'PAGER': '/bin/cat', 18:40:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:22 INFO - 'PWD': '/builds/slave/test', 18:40:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:22 INFO - 'SHELL': '/bin/bash', 18:40:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:40:22 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:40:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:22 INFO - 'USER': 'cltbld', 18:40:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:22 INFO - 'XPC_FLAGS': '0x0', 18:40:22 INFO - 'XPC_SERVICE_NAME': '0', 18:40:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:22 INFO - Downloading/unpacking jsonschema==2.5.1 18:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:40:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:40:25 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 18:40:25 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 18:40:25 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 18:40:25 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 18:40:25 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 18:40:25 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 18:40:25 INFO - Downloading functools32-3.2.3-2.tar.gz 18:40:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:40:25 INFO - warning: no files found matching '*.txt' 18:40:25 INFO - no previously-included directories found matching 'build' 18:40:25 INFO - no previously-included directories found matching 'dist' 18:40:25 INFO - no previously-included directories found matching '.git*' 18:40:25 INFO - Installing collected packages: jsonschema, functools32 18:40:25 INFO - Running setup.py install for functools32 18:40:25 INFO - warning: no files found matching '*.txt' 18:40:25 INFO - no previously-included directories found matching 'build' 18:40:25 INFO - no previously-included directories found matching 'dist' 18:40:25 INFO - no previously-included directories found matching '.git*' 18:40:25 INFO - Successfully installed jsonschema functools32 18:40:25 INFO - Cleaning up... 18:40:26 INFO - Return code: 0 18:40:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:40: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')]} 18:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40: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')]} 18:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40: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', '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 0x1093ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9da5150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, '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.PbYi9bKwCo/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.mi0e56uQS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:40: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:40: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 functools32==3.2.3-2 18:40:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:40:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:26 INFO - 'HOME': '/Users/cltbld', 18:40:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:26 INFO - 'LOGNAME': 'cltbld', 18:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:26 INFO - 'MOZ_NO_REMOTE': '1', 18:40:26 INFO - 'NO_EM_RESTART': '1', 18:40:26 INFO - 'PAGER': '/bin/cat', 18:40:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:26 INFO - 'PWD': '/builds/slave/test', 18:40:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:26 INFO - 'SHELL': '/bin/bash', 18:40:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:40:26 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:40:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:26 INFO - 'USER': 'cltbld', 18:40:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:26 INFO - 'XPC_FLAGS': '0x0', 18:40:26 INFO - 'XPC_SERVICE_NAME': '0', 18:40:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:40:26 INFO - Cleaning up... 18:40:26 INFO - Return code: 0 18:40:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:40: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')]} 18:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40: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')]} 18:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40: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', '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 0x1093ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9da5150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, '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.PbYi9bKwCo/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.mi0e56uQS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:40: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:40: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 blobuploader==1.2.4 18:40:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:40:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:26 INFO - 'HOME': '/Users/cltbld', 18:40:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:26 INFO - 'LOGNAME': 'cltbld', 18:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:26 INFO - 'MOZ_NO_REMOTE': '1', 18:40:26 INFO - 'NO_EM_RESTART': '1', 18:40:26 INFO - 'PAGER': '/bin/cat', 18:40:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:26 INFO - 'PWD': '/builds/slave/test', 18:40:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:26 INFO - 'SHELL': '/bin/bash', 18:40:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:40:26 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:40:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:26 INFO - 'USER': 'cltbld', 18:40:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:26 INFO - 'XPC_FLAGS': '0x0', 18:40:26 INFO - 'XPC_SERVICE_NAME': '0', 18:40:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:26 INFO - Downloading/unpacking blobuploader==1.2.4 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40:29 INFO - Downloading blobuploader-1.2.4.tar.gz 18:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:40:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:40:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:30 INFO - Downloading docopt-0.6.1.tar.gz 18:40:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:40:30 INFO - Installing collected packages: blobuploader, requests, docopt 18:40:30 INFO - Running setup.py install for blobuploader 18:40:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:40:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:40:30 INFO - Running setup.py install for requests 18:40:31 INFO - Running setup.py install for docopt 18:40:31 INFO - Successfully installed blobuploader requests docopt 18:40:31 INFO - Cleaning up... 18:40:31 INFO - Return code: 0 18:40:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:31 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 0x1093ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9da5150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, '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.PbYi9bKwCo/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.mi0e56uQS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:40:31 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 18:40:31 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 18:40:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:40:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:31 INFO - 'HOME': '/Users/cltbld', 18:40:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:31 INFO - 'LOGNAME': 'cltbld', 18:40:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:31 INFO - 'MOZ_NO_REMOTE': '1', 18:40:31 INFO - 'NO_EM_RESTART': '1', 18:40:31 INFO - 'PAGER': '/bin/cat', 18:40:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:31 INFO - 'PWD': '/builds/slave/test', 18:40:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:31 INFO - 'SHELL': '/bin/bash', 18:40:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:40:31 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:40:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:31 INFO - 'USER': 'cltbld', 18:40:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:31 INFO - 'XPC_FLAGS': '0x0', 18:40:31 INFO - 'XPC_SERVICE_NAME': '0', 18:40:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:40:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-pogC6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:40:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ZvBgUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:40:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-pJU21v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:40:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-wuzzpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:40:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Ep5TUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:40:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-EgSCFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:40:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-nyTel7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:40:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-M6rt2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:40:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-7TlecT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:40:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-2qeOFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:40:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-jtUjCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:40:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-eMJeER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:40:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-99b_Wl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:40:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-VeubAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-NpW_NL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:40:33 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-HM4H4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:40:33 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ov7lsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:40:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:40:33 INFO - Running setup.py install for manifestparser 18:40:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:40:33 INFO - Running setup.py install for mozcrash 18:40:33 INFO - Running setup.py install for mozdebug 18:40:33 INFO - Running setup.py install for mozdevice 18:40:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:40:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:40:33 INFO - Running setup.py install for mozfile 18:40:34 INFO - Running setup.py install for mozhttpd 18:40:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Running setup.py install for mozinfo 18:40:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Running setup.py install for mozInstall 18:40:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Running setup.py install for mozleak 18:40:34 INFO - Running setup.py install for mozlog 18:40:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Running setup.py install for moznetwork 18:40:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:40:34 INFO - Running setup.py install for mozprocess 18:40:35 INFO - Running setup.py install for mozprofile 18:40:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:40:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:40:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:40:35 INFO - Running setup.py install for mozrunner 18:40:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:40:35 INFO - Running setup.py install for mozscreenshot 18:40:35 INFO - Running setup.py install for moztest 18:40:35 INFO - Running setup.py install for mozversion 18:40:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:40:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:40:35 INFO - Cleaning up... 18:40:35 INFO - Return code: 0 18:40:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:35 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 0x1093ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9da5150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, '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.PbYi9bKwCo/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.mi0e56uQS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:40:35 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 18:40:35 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 18:40:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:40:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:35 INFO - 'HOME': '/Users/cltbld', 18:40:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:35 INFO - 'LOGNAME': 'cltbld', 18:40:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:35 INFO - 'MOZ_NO_REMOTE': '1', 18:40:35 INFO - 'NO_EM_RESTART': '1', 18:40:35 INFO - 'PAGER': '/bin/cat', 18:40:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:35 INFO - 'PWD': '/builds/slave/test', 18:40:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:35 INFO - 'SHELL': '/bin/bash', 18:40:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:40:35 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:40:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:35 INFO - 'USER': 'cltbld', 18:40:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:35 INFO - 'XPC_FLAGS': '0x0', 18:40:35 INFO - 'XPC_SERVICE_NAME': '0', 18:40:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:40:36 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-c5el3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:40:36 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-C0wkii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:40:36 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-wwNxaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:40:36 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-hLyXl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:40:36 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-3LIqo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:40:36 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-tW10ec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:40:36 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-MYC7um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:40:36 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)) 18:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:40:36 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-KjgIvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:40:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-kFDLPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:40:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-29siHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:40:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ykxr_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:40:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-3wj6d6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:40:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-YjmbXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:40:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-fY74AG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-qMwpoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:37 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)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:40:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-kXp0ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:40:37 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-cUGpPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:40:37 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)) 18:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:40:37 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)) 18:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:40:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:40:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40:40 INFO - Downloading blessings-1.6.tar.gz 18:40:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:40:40 INFO - Installing collected packages: blessings 18:40:40 INFO - Running setup.py install for blessings 18:40:40 INFO - Successfully installed blessings 18:40:40 INFO - Cleaning up... 18:40:40 INFO - Return code: 0 18:40:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:40:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:40:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:40:41 INFO - Reading from file tmpfile_stdout 18:40:41 INFO - Current package versions: 18:40:41 INFO - blessings == 1.6 18:40:41 INFO - blobuploader == 1.2.4 18:40:41 INFO - docopt == 0.6.1 18:40:41 INFO - functools32 == 3.2.3-2 18:40:41 INFO - jsonschema == 2.5.1 18:40:41 INFO - manifestparser == 1.1 18:40:41 INFO - mozInstall == 1.12 18:40:41 INFO - mozcrash == 0.17 18:40:41 INFO - mozdebug == 0.1 18:40:41 INFO - mozdevice == 0.48 18:40:41 INFO - mozfile == 1.2 18:40:41 INFO - mozhttpd == 0.7 18:40:41 INFO - mozinfo == 0.9 18:40:41 INFO - mozleak == 0.1 18:40:41 INFO - mozlog == 3.4 18:40:41 INFO - moznetwork == 0.27 18:40:41 INFO - mozprocess == 0.23 18:40:41 INFO - mozprofile == 0.28 18:40:41 INFO - mozrunner == 6.12 18:40:41 INFO - mozscreenshot == 0.1 18:40:41 INFO - mozsystemmonitor == 0.3 18:40:41 INFO - moztest == 0.7 18:40:41 INFO - mozversion == 1.4 18:40:41 INFO - psutil == 3.1.1 18:40:41 INFO - requests == 1.2.3 18:40:41 INFO - wsgiref == 0.1.2 18:40:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:41 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 0x1093ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9da5150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, '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.PbYi9bKwCo/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.mi0e56uQS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:40:41 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 18:40:41 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 18:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:41 INFO - 'HOME': '/Users/cltbld', 18:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:41 INFO - 'LOGNAME': 'cltbld', 18:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:41 INFO - 'MOZ_NO_REMOTE': '1', 18:40:41 INFO - 'NO_EM_RESTART': '1', 18:40:41 INFO - 'PAGER': '/bin/cat', 18:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:41 INFO - 'PWD': '/builds/slave/test', 18:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:41 INFO - 'SHELL': '/bin/bash', 18:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:40:41 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:41 INFO - 'USER': 'cltbld', 18:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:41 INFO - 'XPC_FLAGS': '0x0', 18:40:41 INFO - 'XPC_SERVICE_NAME': '0', 18:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:41 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)) 18:40:41 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)) 18:40:41 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)) 18:40:41 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)) 18:40:41 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)) 18:40:41 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)) 18:40:41 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)) 18:40:41 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)) 18:40:41 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)) 18:40:41 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)) 18:40:41 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)) 18:40:41 INFO - Cleaning up... 18:40:41 INFO - Return code: 0 18:40:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:41 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 0x1093ce490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109344570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af9da5150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, '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.PbYi9bKwCo/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.mi0e56uQS9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 18:40:41 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 18:40:41 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 18:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:41 INFO - 'HOME': '/Users/cltbld', 18:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:41 INFO - 'LOGNAME': 'cltbld', 18:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:41 INFO - 'MOZ_NO_REMOTE': '1', 18:40:41 INFO - 'NO_EM_RESTART': '1', 18:40:41 INFO - 'PAGER': '/bin/cat', 18:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:41 INFO - 'PWD': '/builds/slave/test', 18:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:41 INFO - 'SHELL': '/bin/bash', 18:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:40:41 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:41 INFO - 'USER': 'cltbld', 18:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:41 INFO - 'XPC_FLAGS': '0x0', 18:40:41 INFO - 'XPC_SERVICE_NAME': '0', 18:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:40:41 INFO - Cleaning up... 18:40:41 INFO - Return code: 0 18:40:41 INFO - Running post-action listener: _resource_record_post_action 18:40:41 INFO - Running post-action listener: _start_resource_monitoring 18:40:41 INFO - Starting resource monitoring. 18:40:41 INFO - [mozharness: 2016-10-25 01:40:41.662035Z] Finished create-virtualenv step (success) 18:40:41 INFO - [mozharness: 2016-10-25 01:40:41.662978Z] Running install step. 18:40:41 INFO - Running pre-action listener: _resource_record_pre_action 18:40:41 INFO - Running main action method: install 18:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:40:41 INFO - Reading from file tmpfile_stdout 18:40:41 INFO - Detecting whether we're running mozinstall >=1.0... 18:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:40:42 INFO - Reading from file tmpfile_stdout 18:40:42 INFO - Output received: 18:40:42 INFO - Usage: mozinstall [options] installer 18:40:42 INFO - Options: 18:40:42 INFO - -h, --help show this help message and exit 18:40:42 INFO - -d DEST, --destination=DEST 18:40:42 INFO - Directory to install application into. [default: 18:40:42 INFO - "/builds/slave/test"] 18:40:42 INFO - --app=APP Application being installed. [default: firefox] 18:40:42 INFO - mkdir: /builds/slave/test/build/application 18:40:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:41:14 INFO - Reading from file tmpfile_stdout 18:41:14 INFO - Output received: 18:41:14 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:41:14 INFO - Running post-action listener: _resource_record_post_action 18:41:14 INFO - [mozharness: 2016-10-25 01:41:14.324602Z] Finished install step (success) 18:41:14 INFO - [mozharness: 2016-10-25 01:41:14.324736Z] Running run-tests step. 18:41:14 INFO - Running pre-action listener: _resource_record_pre_action 18:41:14 INFO - Running main action method: run_tests 18:41:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:41:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:41:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:41:14 INFO - 2016-10-24 18:41:14.339 screenresolution[1640:7480] starting screenresolution argv=screenresolution get 18:41:14 INFO - 2016-10-24 18:41:14.342 screenresolution[1640:7480] Display 0: 1600x1200x32@60 18:41:14 INFO - 2016-10-24 18:41:14.347 screenresolution[1641:7482] starting screenresolution argv=screenresolution list 18:41:14 INFO - Available Modes on Display 0 18:41:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:41:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:41:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:41:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:41:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:41:14 INFO - Intel Iris: 18:41:14 INFO - Chipset Model: Intel Iris 18:41:14 INFO - Type: GPU 18:41:14 INFO - Bus: Built-In 18:41:14 INFO - VRAM (Dynamic, Max): 1536 MB 18:41:14 INFO - Vendor: Intel (0x8086) 18:41:14 INFO - Device ID: 0x0a2e 18:41:14 INFO - Revision ID: 0x0009 18:41:14 INFO - Displays: 18:41:14 INFO - Display: 18:41:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:41:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:41:14 INFO - Display Serial Number: 1600x1200 60 18:41:14 INFO - Main Display: Yes 18:41:14 INFO - Mirror: Off 18:41:14 INFO - Online: Yes 18:41:14 INFO - Rotation: Supported 18:41:14 INFO - Return code: 0 18:41:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:41:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:41:14 INFO - Python 2.7.3 18:41:14 INFO - Return code: 0 18:41:14 INFO - grabbing minidump binary from tooltool 18:41: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')]} 18:41:14 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 0x1091e2d30>, '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 0x109048db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091dab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:41:14 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 18:41:14 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 18:41:14 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 18:41:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:41:14 INFO - Return code: 0 18:41:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:41:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:41:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:41:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:41:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:41:14 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/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--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 18:41:14 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/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0338 --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 18:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PbYi9bKwCo/Render', 18:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:41:14 INFO - 'HOME': '/Users/cltbld', 18:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:41:14 INFO - 'LOGNAME': 'cltbld', 18:41:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:41:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:14 INFO - 'MOZ_NO_REMOTE': '1', 18:41:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:41:14 INFO - 'NO_EM_RESTART': '1', 18:41:14 INFO - 'PAGER': '/bin/cat', 18:41:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:14 INFO - 'PWD': '/builds/slave/test', 18:41:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:41:14 INFO - 'SHELL': '/bin/bash', 18:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mi0e56uQS9/Listeners', 18:41:14 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 18:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:41:14 INFO - 'USER': 'cltbld', 18:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:14 INFO - 'XPC_FLAGS': '0x0', 18:41:14 INFO - 'XPC_SERVICE_NAME': '0', 18:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:41:14 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/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--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 18:41:14 INFO - mozversion application_buildid: 20161024163422 18:41:14 INFO - mozversion application_changeset: fcd7ad8a2488527ce094cbdb3582c29437c155a8 18:41:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:41:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:41:14 INFO - mozversion application_name: Firefox 18:41:14 INFO - mozversion application_remotingname: firefox-dev 18:41:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:41:14 INFO - mozversion application_vendor: Mozilla 18:41:14 INFO - mozversion application_version: 51.0a2 18:41:14 INFO - mozversion platform_buildid: 20161024163422 18:41:14 INFO - mozversion platform_changeset: fcd7ad8a2488527ce094cbdb3582c29437c155a8 18:41:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:41:14 INFO - mozversion platform_version: 51.0a2 18:41:14 INFO - using testdate: 1477359674 18:41:14 INFO - actual date: 1477359674 18:41:14 INFO - starting webserver on 'localhost:49278' 18:41:14 INFO - SUITE-START | Running 2 tests 18:41:14 INFO - TEST-START | dromaeo_css 18:41:14 INFO - operating with platform_type : mac_ 18:41:14 INFO - Initialising browser for dromaeo_css test... 18:41:14 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpx6LIzj/profile http://localhost:49278/getInfo.html) 18:41:17 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 18:41:17 INFO - PROCESS | 1651 | colorDepth:24 18:41:17 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 18:41:17 INFO - PROCESS | 1651 | __metrics 18:41:17 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:41:17 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:41:18 INFO - TEST-INFO | 1651: exit 0 18:41:18 INFO - Browser initialized. 18:41:18 INFO - Running cycle 1/1 for dromaeo_css test... 18:41:18 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpx6LIzj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:41:30 INFO - PROCESS | 1653 | RSS: Main: 220049408 18:41:30 INFO - PROCESS | 1653 | 18:42:21 INFO - PROCESS | 1653 | 1477359741320 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:44:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 18:44:35 INFO - PROCESS | 1653 | RSS: Main: 368517120 18:44:35 INFO - PROCESS | 1653 | 18:47:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 18:47:38 INFO - PROCESS | 1653 | RSS: Main: 352288768 18:47:38 INFO - PROCESS | 1653 | 18:50:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 18:50:41 INFO - PROCESS | 1653 | RSS: Main: 315809792 18:50:41 INFO - PROCESS | 1653 | 18:53:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 18:53:44 INFO - PROCESS | 1653 | RSS: Main: 321490944 18:53:44 INFO - PROCESS | 1653 | 18:56:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 18:56:48 INFO - PROCESS | 1653 | RSS: Main: 315772928 18:56:48 INFO - PROCESS | 1653 | 18:59:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 18:59:51 INFO - PROCESS | 1653 | RSS: Main: 365527040 18:59:51 INFO - PROCESS | 1653 | 18:59:51 INFO - PROCESS | 1653 | __start_tp_report 18:59:51 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 18:59:51 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 18:59:51 INFO - PROCESS | 1653 | |i|pagename|runs| 18:59:51 INFO - PROCESS | 1653 | |0;dojo.html;3295.6869097435742;3332.983335083324;3352.88264910728;3375.983120084399;3410.9658903410973;5701.800436984701;5759.740811663475;5766.250387449626;5784.450477204664;5847.298324201097;4871.537203965627;5556.749946252418;5762.942370576293;5764.769409223626;5771.394003629622;5217.060929032773;5222.869428264305;5239.161734122522;5243.239730239123;5285.392179899303;5140.074786538422;5151.974240128794;5334.813281535147;5335.093034184198;5364.356277246736;5196;5245.8950820983555;5248.422673505911;5256.027634887539;5259.658122222043;5249.947500525003;5266.368035835686;5269.815556455505;5270.947290527084;5274.129768588178;5104.081265372231;5199.298094757197;5200.063988482072;5203.271541984125;5240.109181439165;5764.567657425668;5765.077587585966;5770.855728606776;5776.364599894008;5782.971085144547;6084.128919687898;6128.3338155912115;6162;6190.3190649028575;6220.968895155495;6256.499480041632;6301.180846489973;6302.905456418157;6309.337519560426;6323.272823625296;4902.950970490285;5798.82603521895;5844.357120716718;5844.649321040752;5874.441928016831;6148.44663980244;6243.313235544087;6272.090546870678;6310.211223597051;6313.55805093639;5776.508996735324;5941.049432090931;5945.643261404243;5948.910766338508;5980.970095149496;6115.296740874856;6148.954677704801;6149.600275982047;6152.261728592508;6158.907616385758;6003.849903752353;6021.187139736167;6044.879102417927;6079.8480037998515;6104.053871649901;6041.063635136473;6133.907991380043;6171.7531298748445;6203.503719702413;6221.844453888599;4868.780904859219;4874.683145595525;4876.906676231222;4885.193942999436;4906.337644418029;4319.589638984291;4356.237658409766;4405.933910991338;4407.052483715952;4433.623142032963;5659.490645841812;5679.318481782213;5741.741621627037;5752.712364381764;5760.94239057604;6329.556931014877;6336.366363363719;6345.270293916166;6355.269144048401;6365.904511432333;5803.897259520678;5805.01314776489;5810.4189581041555;5820.912686309709;5858.707064646751;5260.473952604785;5263.868403289948;5304.867378315495;5389.7035663038905;5427.728613569306;6259.530535209787;6302.149209856794;6328;6369.076483909715;6370.55406121567;6305.96847015762;6316.431521163117;6327.841803954938;6331.860265152132;6337.588056776295;6186.505079593531;6254.655993920378;6288.842778930563;6301.369863013845;6323.652199128908;6133.854967164949;6163.630182189083;6201.534884883562;6275.246970363585;6277.5605707600025;5331.70675612845;6093.360197179232;6297.307296197506;6319.146915166578;6340.080688300079;5086.61850361217;5888.499477544286;6120.265568131675;6152.16945712341;6175.382461753788;5963.493103086286;6071.39286071407;6100.816975490742;6107.583301036431;6109.297430795337;6039.365866583946;6043.697815109315;6069.241344831896;6073.301570319293;6082.239720034996;6174.722137503733;6188.473979711594;6192.287886893065;6209.720562574605;6215.192025036898;5791.276090488689;5808.30300363959;5831.620944638585;5908.822735317947;5951.970240148771 18:59:51 INFO - PROCESS | 1653 | |1;ext.html;469.7956388970798;504.58371843229344;515.9664621799583;521.4967556308162;532.9866753331166;30485;41369.58630413695;42553.51062712806;42697.57302426975;44945.550544494545;43660.78169609155;43736;43923.56076439234;43946;43999.12001759963;8902.821943561124;9977.351472154323;10155.949220253888;10160.491975401237;10168.288219824615;8894;9098;9125.996140424548;9128.224100951427;10430;12402.13185077045;12492.500299987989;12516.749665006695;12521.373931303398;12529;12311.507539698401;12393.318367489785;12401.937990310082;12403.317817520032;12403.875961240363;10928.508217130247;11917.642470725892;12074.879251207463;12265.632031039084;12358.75282494354;41437.96405089897;41562.58437415618;41615;41709.58290417118;41746.373804392955;2867.5125228710963;3402.5406570112964;3407.2382623782933;3435.261418794971;3456.2050728332374;30688.53967190494;30878;31695.815972655328;32477.6752232477;32696.67303326984;3223.3714425687;3236.4498035333813;3250.8537115829845;3300.9834950825334;3382.3573521030926;523071;523383;523479.9999999962;523886;525089;34337.313253734785;61068.38931610716;61518;61528.07707884385;61790.69104654448;30197.849010755246;34629.82685086558;35954.10114747152;35969.280614387564;35998.1000474985;15539.766903496457;15683.921580392025;15696.293666785023;15730.977486463344;15739.763903541456;13142.934285328512;13193.670158245928;13218;13234.867651323557;13256.337183140804;6599.571027883172;7289.781306560812;7434.888476672854;7746.806329841799;7906.327962123284;2821.943561128766;3221.0497903118635;3247.269364392993;3268.7058164765285;3290.407726609233;900.9414388064755;985.930984831055;991.5785791038779;1000.884898236712;1015.3349556040805;30426.087217383512;30486;31027.844860776004;31228.843855780575;31444.685553144634;1009.3287963504334;1096.9122470202365;1131.7566723154562;1175.3359351966112;1223.6023292429995;15284.923575382274;15845.920770396075;15978.280977356048;16220.945638533458;16308.429204977769;699862;711275;711316;712040;712409;49435.0112997738;50187.49812501901;51713;57916.71041644849;59559;1357.5995081450997;1380.82049333586;1404.768213244803;1405.9859401406263;1413.886889048894;30296.852593026273;31670;32090.716371344886;32125.35749285048;32212.838935806107;50990;51402.48597513977;51439.97120057503;51682.48317516777;51792.741036294574;469827;470972;472610;472901;473377;26873;27049.729502704722;27101.915923362845;27336.863315683295;27358.726412736414;47532.52467475281;47701.76149119232;48531.02937941292;48542.786430339525;48674;39391.606083938794;40125.996850078984;40299;40971.590284096776;41569;39518.40722389109;39755.60244397519;39797.60202397939;40103.79948100241;41518;38283.425748613234;38980;39084.80457597694;39106.65360078404;39179.608203917596;33558.328833423686;35117;35138;35584.64415355813;35629.28741425209;25820.741792582587;25944.8702756485;26040.609390859532;26388.736112638628;26496 18:59:51 INFO - PROCESS | 1653 | |2;jquery.html;317.8712621388338;354.40283122937836;378.61570505936464;378.67244833219274;408.48326866513844;13310.668933106685;15124.848751512496;15409.229538523072;20308;20706.48233794152;19612.01939903003;20569.868657223848;21106.577868442622;21233.15067397302;21473.355799326007;5687.374388817227;5978.043513037926;6219.906701399483;6233.096201050845;6253.843653908666;5778.451047150535;6018.097285407181;6105.267367915856;6200.658963756991;6204.317525072261;5799.3620701722775;6074.149619053314;6152.169457123276;6157.3842615738295;6172.567920245562;6029.276486821587;6029.8191054268445;6079.240094988127;6080.543959203055;6087.391260873899;5079.974600126976;5235.952809438089;5277.076511610453;5277.102892508283;5329.920051199197;25175.748242517522;25286.36784080394;25610.48779024409;25906.093286735064;26125;1879.5207222158263;2247.820174386045;2579.4454192348735;3126.9687303126907;3156.447621666199;14759.409623615149;15729.292181851844;16081.678366432723;16103.677926441524;16164.595885102848;1857.674906891302;1934.9129289182022;1960.8725432846961;1970.556624759432;2010.0753653319491;65141;97114.5144274274;117688.8231117695;121365;123661;9468.526573671288;9917.65288214909;10136.645217417356;10196.28625996188;10366.222533309881;12528.874711252953;12549.686257843445;12551.3096779678;12627.5580354689;12645.557405490763;8129;8159.918400815915;8226.506409615442;8286.171382861665;8499.23506884371;6655.401013908675;8078.424707182043;8841.955790221007;8856.955715221382;8921.330900182513;3293.045016945059;3311.2549676322633;3933.940990885139;4001.699872509515;4005.6394924456354;1946.8734532255642;1947.6396866579728;1950.9804901951084;1986.3345779163853;2002.6895831146196;823.7693445835175;885.1325700813111;915.3455279475207;944.9480278584743;1044.5926088825365;20381;21495.67756483654;21599.35201943944;21684.783152168275;21711.674324885138;940.5308133205389;951.8429459139164;954.289054654287;1024.1601886453036;1045.7385653586603;9841.261905357127;11109.83335249972;11225.943870280596;11235.045021173291;11270.436478176058;563827;581339;583268;584801.1519884747;593099;115321;117043.41478292554;125979;138922;144759;975.878015248094;984.7636567223958;996.05872450535;1002.4286156890503;1004.1213937804421;17822.91088544549;18016.639667206844;18586.721199181753;18836.623267534847;18868.52828679294;42093;53333;56916;57724.422755771906;59084.70457647684;83407.16592834237;83814;84546.57726711327;84919.57540212259;86092;7799.45403821727;8096.028476582847;8250.54621995796;8339.416240863082;8438.957805211181;7496.962515187389;12287.815682764587;12380.195287306296;12410.689732756753;12799.936000319938;10880.455977201267;10958.780824383628;11096.833547496633;11113.499892504695;11272.041876440595;10547.367157970546;10963.835542467028;10971.067459265732;11350.602728904449;11523.96284334414;11030.28303160292;11419.942900285445;11635.883641163819;11669.474873630877;11861.703457413634;7978.960105199437;8160.857479952692;8185.836283274182;8511;8526.82946341082;9971.700848974542;10790.730231743955;11144.665660030212;11200.831987520356;11223.66329010131 18:59:51 INFO - PROCESS | 1653 | |3;mootools.html;1374.031307927911;1376.6764810269588;1536.554399224225;1747.5631092226943;1947;7862.488938219012;8390.874136887944;8655.999999999993;8894.822103557924;8912.197902188818;7615.733449329275;8791.560421978907;8890.688825891095;8896.555172241378;8908.287337013024;2402.0992127952018;2424.2848359733835;2429.53838770633;2431.8176136789725;2434.45224824414;2389.462370966536;2416.6254230594286;2421.237310247275;2421.8667973261463;2430.2587710748217;2383.92848214553;2405.434722840131;2417.0694282701197;2417.226487523993;2421.3462365161486;2148.8611036150787;2262.2195342606774;2356.634721618135;2384.8688322142275;2404.4349577849193;2352.929412117639;2390.115657206827;2390.725066617344;2394.174009966559;2399.184277345711;8876.979147398068;8926.419782714103;8937.240334571568;8955.731328060168;8963.731088067369;5252.789888404459;5443.700596467193;5445.591580631429;5459.153831156177;5481.287432633772;3653.872114476007;3681.987453450296;3751.381022131345;3758.4362345648096;3766.472693822867;1089.9237053406264;1094.3215206571977;1094.9069329107035;1105.4086063955745;1105.5025238642563;11635.301881887113;11689.357085360301;11776.05791536675;11780.587679431179;11781.469833857496;4479.888002799956;4512.864614061584;4708.246680531099;4712.528747125329;4726.834560790356;9215.95392023049;9224.584893679801;9232.353735238468;9235.584398702074;9242.907570924339;5293.708846013429;5295.920561191585;5302.469753024667;5311;5328.893422131536;4391.016699214861;4444.400005999213;4444.799984000728;4606.792694328697;4962.082014827196;3804.7907365094634;3830.8467661293794;3870.5161854768153;3913.3151698452657;3925.2345792570177;1178.6287319494331;1188.9524419023141;1190.815423609342;1202.3627477436971;1204.1931905623253;841.2975165736678;907.1699395053585;921.2860033473977;925.509479975614;944.5371767833713;5768.855778605568;5802.709864506842;6000.639961602318;6065.332813390523;6119.694015299217;395.95248570171765;418.9958100419018;452.50224752771686;454.538643277074;461.4600916927203;3555.8577656893954;3696;3961.603839616015;3963.564007959122;3966.484357033567;10529.789404211873;11050.226484146033;11051.226414151093;11060.115190784718;11121.22151449407;10957.616483423202;10968.287061341147;10971.451427428598;10978.451077446096;10983.61557345489;8168.591570421455;8200;8348.874766878502;8373.874391884126;8393;5658.5473162147755;5823.417658234142;5884.35272120075;6056.606320589148;6092.299385570539;9842.917279099436;10136.138428233682;10193.949030254802;10224.591016359262;10263.897361026593;9457.858132127885;10296.845547316947;10493;10523.526441310007;10541.631042913461;6672.766453174116;6874.65626718652;6989.47578931572;7089.6100714459135;7205.171405288459;8344.749657510285;8379.287760540423;8385.413021088465;8412.537310447984;8464.153584641486;7533.472656913963;7591.4685971983645;7612.58130802789;7620.542767433972;7639.656215470206;7241.891371629325;7383.889241661487;7441.479096463196;7538.283863033003;7583.696652133853;7019.613921234381;7045.894311585228;7112.502124851418;7132.322429369202;7135.892961605477;4931.9753401232765;4974.9751251243515;5096.0827051131155;5119.308893299357;5228.294180285613;5038.445770965264;5039.8488045357235;5124.7950081996305;5189.922151167811;5257.5005374489365 18:59:51 INFO - PROCESS | 1653 | |4;prototype.html;298.58049440536047;300.5926968957062;307.31775458482167;308.23711314496626;315.27014960366733;4364.708528022789;5232.529072383485;5277.788888444467;5279.313689220401;5329.493698098684;4685.789139488723;5070.619703522232;5166.250893620423;5201;5288.682679039261;3745.1386181178345;3848.422736589506;4027.7986100694993;4069.369247766601;4072.9185416291657;3376.746743994198;4010.879673609782;4030.7581545107387;4051.3315302974975;4055.2700513907484;3643;3839.533827909453;4057.8782636520805;4059.7361171523903;4070.5115386153557;3557.5019497270405;4009.7594144351433;4041.8884806678107;4046.9999999999854;4063.7764922929227;2923.4591600553754;3510.5436293281964;3523.5771707395274;3551.627079156703;3558.804265765382;4966.602671786249;5218.608604354688;5256.106461901486;5256.159014557691;5257.447967963385;4347.000189956296;4560.931586026212;4587.266037433995;4621.7752295641685;4622.329762184464;4965.776540055706;5508.228847961289;5543.833684989457;5568.55451563878;5575.553955683536;3848.65362117408;3858.2283543291455;3861.4980052593264;3874.380099184117;3941.6649584785328;5227.426002248033;5510.752016159283;5664.631798933097;5772.6536407815665;5781.479666829963;3888.0862997195636;3888.2223555289056;3898.590647981978;3909.980450097731;3937.940930886039;3964.583718709494;3973.920521589552;3976.622220889011;3988.7806170660892;4050.2911990401562;4374.387585737999;4584.6561507887045;4606.953930460652;4613.838515651936;4631.490536041033;5024.899502009939;5560.944390556043;5562.610617256834;5576.525995290364;5586.636868603528;1143.976141353484;1222.5109956017486;1294.695746499572;1364.324659293656;1369.3906211735882;741.7686640177387;893.6023469556116;914.533587870191;948.0188005414363;954.2699834626517;4982.90034199314;5182.689038657692;5246;5254.737263136904;5256.2904007959205;5237.947620523746;5659.886802263932;5680.630759000652;5716.42835716425;5753.3958934311295;4206.137741762931;5155.6391052626705;5199.972010915746;5244.842654720288;5264.5782939848095;2741.465414244243;2874.404705388488;2926.473234817711;2946.292889706498;2964.5256758918467;5055.823046193365;5608.327000759853;5850.9175802476675;5879.382664820132;5882.147088672119;4704.788284527204;5273.2617433559335;5532.806351777668;5738.684372359477;5786.768529258867;5706.629069110494;5822.505087067562;5826.76692932278;5849.941500585025;5857.531397488191;4488.0126372198065;4999.475160076212;5117.211119939568;5427.1859221115565;5461.726913654381;4533.161365147326;4745.715257084586;5460.91808622863;5553.194786755978;5567.802922371709;5144.639875208849;5505.724713764376;5584.944150558443;5810.5061069809535;5821.301443826768;3512.6838584527513;3869.477620521306;3879.224155169034;3926.764394136361;4008.8596899109557;4870.756462176948;4886.53577910098;5565.443455654402;5619.297587801525;5632.295963004624;4633.934195135071;4852.8544143675745;5489.505944465017;5534.667919924817;5552.250446189874;4568.406107206042;4827.744786355643;4859.586935110555;5249.343832020997;5504.201890725903;4706.905861882811;5044.571211447068;5170.612204084633;5383.973080134574;5425.565954723692;5157.813702848291;5474.2609747683555;5492.144085075658;5555.194496798023;5652.830415087554;5163.302954101148;5397.78549826286;5432.782688692408;5447.455254474521;5469.945300547103 18:59:51 INFO - PROCESS | 1653 | |5;yui.html;598.7575780255969;789.6170357376675;790.9011373578303;804.3886646148928;829.3945419843515;15139.470118545853;16460.83539164608;16472.670546589063;16484.258208380623;16517.339306427744;14818.555443336718;14893.77659335111;14971.625709357271;14983.175925324103;14983.175925324103;666.3835951744641;689.3175756001573;692.2074225012358;827.1521690267464;835.415209353455;842.6629348260714;865.5153114256005;866.0646501778065;867.8568036274007;868.448535180162;913.1690161952624;914.6432891172448;916.757059379265;916.7799728065283;917.0783362720457;700.6356694518872;728.6320408193881;730.3536370312258;912.63494602159;917.0783362720457;540.6026570470701;541.0261529247379;643.7167646235641;668.0413606474676;671.4460570029745;15256.160911149882;15296.617584560476;15321.310541025681;15348.923255383652;15366.692666146726;13943.511977080854;14014.859851401457;14210.786838197435;14244.07413518118;14320.283985800668;12390.132690711655;12637.368131593475;12760.553380631633;12772.744545109139;13263.204207747469;4473.485549161823;4497.775111244458;4518.774061296922;4541.772911354452;4576.313552967082;122521;125345;129423.05865412026;130543;132405.99999999904;10583.894161058292;10822.350658960488;11095.167862410352;11130.777384452427;11191.888081119085;22084.227052053102;22139.33581992543;22159.113635454723;22166.113355465925;22201.555968880853;7725.5364678119495;7780.533168009937;7920.287174154354;8179.713710020121;8188.590570471452;3159.3839201355513;4251.851185208503;4260.957390426118;4327.221100201994;4445.110977804452;3476.721862251014;3574.964250357515;3575.713942884563;3586.8385922633543;3590.856365745341;633.5406830048178;642.9292777794439;648.3840351665937;653.859420224654;661.2362721057186;412.88645622454067;531.9228711836762;541.8618252345626;597.5548216578677;608.5557542993639;8353.289970352467;8997.505137217517;8997.730068097968;9009.23421509179;9294.953525232331;601.7623038899698;602.8643555200133;614.1862032806549;614.8727926215286;642.1748053750907;3713.405855063231;4388.166248412791;4450.710703804242;4482.193205223091;4507.965370993008;100853.99146008446;101211;127471.36264318619;137597;171091.1445442765;117236;123996;139929;143730.2813485926;147797;113907.43046284716;114308;116614.41692791482;117935.41032294955;118366;9322.95338523303;9983.377676169097;10018.799624007333;10037.949810250902;10124.69625911224;61791.764164715554;61885;65037;66264;67140.32859671341;88633.55683221543;98409;98513;101343.49328253312;104350.47824760828;12777.169483983884;12830.154893007164;12895;13257.66855828612;13558.593242202749;14324.928375358057;14369.640758980691;14698.338574763948;15155.621109471911;15545.37818487293;13091.541796037092;13192.736145277233;13454.798178027142;13781.173129612256;14170.787438188228;12890.871091288967;13614.591562253148;13984.017132999048;14011.299435028412;14630;11259.268147570381;13076.934615327244;13796.793048104488;14305.284735762962;14479.276036198358;10874.130069594572;11193.99254067138;11272.436378181223;11459.19785614999;11858.940705296764;11460.713482163013;11589;11590.988560867041;11698.70753231176;11731.530738770354 18:59:51 INFO - PROCESS | 1653 | __end_tp_report 18:59:51 INFO - PROCESS | 1653 | __start_cc_report 18:59:51 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1303 18:59:51 INFO - PROCESS | 1653 | __end_cc_report 18:59:51 INFO - PROCESS | 1653 | __startTimestamp1477360791735__endTimestamp 18:59:51 INFO - PROCESS | 1653 | 18:59:51 INFO - PROCESS | 1653 | ------- Summary: start ------- 18:59:51 INFO - PROCESS | 1653 | Number of tests: 6 18:59:51 INFO - PROCESS | 1653 | 18:59:51 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5728.54 Median:5908.82 stddev:646.60 (10.9%) stddev-sans-first:619.78 18:59:51 INFO - PROCESS | 1653 | Values: 3295.7 3333.0 3352.9 3376.0 3411.0 5701.8 5759.7 5766.3 5784.5 5847.3 4871.5 5556.7 5762.9 5764.8 5771.4 5217.1 5222.9 5239.2 5243.2 5285.4 5140.1 5152.0 5334.8 5335.1 5364.4 5196.0 5245.9 5248.4 5256.0 5259.7 5249.9 5266.4 5269.8 5270.9 5274.1 5104.1 5199.3 5200.1 5203.3 5240.1 5764.6 5765.1 5770.9 5776.4 5783.0 6084.1 6128.3 6162.0 6190.3 6221.0 6256.5 6301.2 6302.9 6309.3 6323.3 4903.0 5798.8 5844.4 5844.6 5874.4 6148.4 6243.3 6272.1 6310.2 6313.6 5776.5 5941.0 5945.6 5948.9 5981.0 6115.3 6149.0 6149.6 6152.3 6158.9 6003.8 6021.2 6044.9 6079.8 6104.1 6041.1 6133.9 6171.8 6203.5 6221.8 4868.8 4874.7 4876.9 4885.2 4906.3 4319.6 4356.2 4405.9 4407.1 4433.6 5659.5 5679.3 5741.7 5752.7 5760.9 6329.6 6336.4 6345.3 6355.3 6365.9 5803.9 5805.0 5810.4 5820.9 5858.7 5260.5 5263.9 5304.9 5389.7 5427.7 6259.5 6302.1 6328.0 6369.1 6370.6 6306.0 6316.4 6327.8 6331.9 6337.6 6186.5 6254.7 6288.8 6301.4 6323.7 6133.9 6163.6 6201.5 6275.2 6277.6 5331.7 6093.4 6297.3 6319.1 6340.1 5086.6 5888.5 6120.3 6152.2 6175.4 5963.5 6071.4 6100.8 6107.6 6109.3 6039.4 6043.7 6069.2 6073.3 6082.2 6174.7 6188.5 6192.3 6209.7 6215.2 5791.3 5808.3 5831.6 5908.8 5952.0 18:59:51 INFO - PROCESS | 1653 | 18:59:51 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:69418.97 Median:34873.41 stddev:154654.41 (443.5%) stddev-sans-first:155001.62 18:59:51 INFO - PROCESS | 1653 | Values: 469.8 504.6 516.0 521.5 533.0 30485.0 41369.6 42553.5 42697.6 44945.6 43660.8 43736.0 43923.6 43946.0 43999.1 8902.8 9977.4 10155.9 10160.5 10168.3 8894.0 9098.0 9126.0 9128.2 10430.0 12402.1 12492.5 12516.7 12521.4 12529.0 12311.5 12393.3 12401.9 12403.3 12403.9 10928.5 11917.6 12074.9 12265.6 12358.8 41438.0 41562.6 41615.0 41709.6 41746.4 2867.5 3402.5 3407.2 3435.3 3456.2 30688.5 30878.0 31695.8 32477.7 32696.7 3223.4 3236.4 3250.9 3301.0 3382.4 523071.0 523383.0 523480.0 523886.0 525089.0 34337.3 61068.4 61518.0 61528.1 61790.7 30197.8 34629.8 35954.1 35969.3 35998.1 15539.8 15683.9 15696.3 15731.0 15739.8 13142.9 13193.7 13218.0 13234.9 13256.3 6599.6 7289.8 7434.9 7746.8 7906.3 2821.9 3221.0 3247.3 3268.7 3290.4 900.9 985.9 991.6 1000.9 1015.3 30426.1 30486.0 31027.8 31228.8 31444.7 1009.3 1096.9 1131.8 1175.3 1223.6 15284.9 15845.9 15978.3 16220.9 16308.4 699862.0 711275.0 711316.0 712040.0 712409.0 49435.0 50187.5 51713.0 57916.7 59559.0 1357.6 1380.8 1404.8 1406.0 1413.9 30296.9 31670.0 32090.7 32125.4 32212.8 50990.0 51402.5 51440.0 51682.5 51792.7 469827.0 470972.0 472610.0 472901.0 473377.0 26873.0 27049.7 27101.9 27336.9 27358.7 47532.5 47701.8 48531.0 48542.8 48674.0 39391.6 40126.0 40299.0 40971.6 41569.0 39518.4 39755.6 39797.6 40103.8 41518.0 38283.4 38980.0 39084.8 39106.7 39179.6 33558.3 35117.0 35138.0 35584.6 35629.3 25820.7 25944.9 26040.6 26388.7 26496.0 18:59:51 INFO - PROCESS | 1653 | 18:59:51 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:34375.85 Median:14625.98 stddev:97103.93 (663.9%) stddev-sans-first:97342.66 18:59:51 INFO - PROCESS | 1653 | Values: 317.9 354.4 378.6 378.7 408.5 13310.7 15124.8 15409.2 20308.0 20706.5 19612.0 20569.9 21106.6 21233.2 21473.4 5687.4 5978.0 6219.9 6233.1 6253.8 5778.5 6018.1 6105.3 6200.7 6204.3 5799.4 6074.1 6152.2 6157.4 6172.6 6029.3 6029.8 6079.2 6080.5 6087.4 5080.0 5236.0 5277.1 5277.1 5329.9 25175.7 25286.4 25610.5 25906.1 26125.0 1879.5 2247.8 2579.4 3127.0 3156.4 14759.4 15729.3 16081.7 16103.7 16164.6 1857.7 1934.9 1960.9 1970.6 2010.1 65141.0 97114.5 117688.8 121365.0 123661.0 9468.5 9917.7 10136.6 10196.3 10366.2 12528.9 12549.7 12551.3 12627.6 12645.6 8129.0 8159.9 8226.5 8286.2 8499.2 6655.4 8078.4 8842.0 8857.0 8921.3 3293.0 3311.3 3933.9 4001.7 4005.6 1946.9 1947.6 1951.0 1986.3 2002.7 823.8 885.1 915.3 944.9 1044.6 20381.0 21495.7 21599.4 21684.8 21711.7 940.5 951.8 954.3 1024.2 1045.7 9841.3 11109.8 11225.9 11235.0 11270.4 563827.0 581339.0 583268.0 584801.2 593099.0 115321.0 117043.4 125979.0 138922.0 144759.0 975.9 984.8 996.1 1002.4 1004.1 17822.9 18016.6 18586.7 18836.6 18868.5 42093.0 53333.0 56916.0 57724.4 59084.7 83407.2 83814.0 84546.6 84919.6 86092.0 7799.5 8096.0 8250.5 8339.4 8439.0 7497.0 12287.8 12380.2 12410.7 12799.9 10880.5 10958.8 11096.8 11113.5 11272.0 10547.4 10963.8 10971.1 11350.6 11524.0 11030.3 11419.9 11635.9 11669.5 11861.7 7979.0 8160.9 8185.8 8511.0 8526.8 9971.7 10790.7 11144.7 11200.8 11223.7 18:59:51 INFO - PROCESS | 1653 | 18:59:51 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5715.24 Median:4719.68 stddev:3236.63 (68.6%) stddev-sans-first:3229.27 18:59:51 INFO - PROCESS | 1653 | Values: 1374.0 1376.7 1536.6 1747.6 1947.0 7862.5 8390.9 8656.0 8894.8 8912.2 7615.7 8791.6 8890.7 8896.6 8908.3 2402.1 2424.3 2429.5 2431.8 2434.5 2389.5 2416.6 2421.2 2421.9 2430.3 2383.9 2405.4 2417.1 2417.2 2421.3 2148.9 2262.2 2356.6 2384.9 2404.4 2352.9 2390.1 2390.7 2394.2 2399.2 8877.0 8926.4 8937.2 8955.7 8963.7 5252.8 5443.7 5445.6 5459.2 5481.3 3653.9 3682.0 3751.4 3758.4 3766.5 1089.9 1094.3 1094.9 1105.4 1105.5 11635.3 11689.4 11776.1 11780.6 11781.5 4479.9 4512.9 4708.2 4712.5 4726.8 9216.0 9224.6 9232.4 9235.6 9242.9 5293.7 5295.9 5302.5 5311.0 5328.9 4391.0 4444.4 4444.8 4606.8 4962.1 3804.8 3830.8 3870.5 3913.3 3925.2 1178.6 1189.0 1190.8 1202.4 1204.2 841.3 907.2 921.3 925.5 944.5 5768.9 5802.7 6000.6 6065.3 6119.7 396.0 419.0 452.5 454.5 461.5 3555.9 3696.0 3961.6 3963.6 3966.5 10529.8 11050.2 11051.2 11060.1 11121.2 10957.6 10968.3 10971.5 10978.5 10983.6 8168.6 8200.0 8348.9 8373.9 8393.0 5658.5 5823.4 5884.4 6056.6 6092.3 9842.9 10136.1 10193.9 10224.6 10263.9 9457.9 10296.8 10493.0 10523.5 10541.6 6672.8 6874.7 6989.5 7089.6 7205.2 8344.7 8379.3 8385.4 8412.5 8464.2 7533.5 7591.5 7612.6 7620.5 7639.7 7241.9 7383.9 7441.5 7538.3 7583.7 7019.6 7045.9 7112.5 7132.3 7135.9 4932.0 4975.0 5096.1 5119.3 5228.3 5038.4 5039.8 5124.8 5189.9 5257.5 18:59:51 INFO - PROCESS | 1653 | 18:59:51 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4476.28 Median:5050.20 stddev:1347.09 (26.7%) stddev-sans-first:1313.87 18:59:51 INFO - PROCESS | 1653 | Values: 298.6 300.6 307.3 308.2 315.3 4364.7 5232.5 5277.8 5279.3 5329.5 4685.8 5070.6 5166.3 5201.0 5288.7 3745.1 3848.4 4027.8 4069.4 4072.9 3376.7 4010.9 4030.8 4051.3 4055.3 3643.0 3839.5 4057.9 4059.7 4070.5 3557.5 4009.8 4041.9 4047.0 4063.8 2923.5 3510.5 3523.6 3551.6 3558.8 4966.6 5218.6 5256.1 5256.2 5257.4 4347.0 4560.9 4587.3 4621.8 4622.3 4965.8 5508.2 5543.8 5568.6 5575.6 3848.7 3858.2 3861.5 3874.4 3941.7 5227.4 5510.8 5664.6 5772.7 5781.5 3888.1 3888.2 3898.6 3910.0 3937.9 3964.6 3973.9 3976.6 3988.8 4050.3 4374.4 4584.7 4607.0 4613.8 4631.5 5024.9 5560.9 5562.6 5576.5 5586.6 1144.0 1222.5 1294.7 1364.3 1369.4 741.8 893.6 914.5 948.0 954.3 4982.9 5182.7 5246.0 5254.7 5256.3 5237.9 5659.9 5680.6 5716.4 5753.4 4206.1 5155.6 5200.0 5244.8 5264.6 2741.5 2874.4 2926.5 2946.3 2964.5 5055.8 5608.3 5850.9 5879.4 5882.1 4704.8 5273.3 5532.8 5738.7 5786.8 5706.6 5822.5 5826.8 5849.9 5857.5 4488.0 4999.5 5117.2 5427.2 5461.7 4533.2 4745.7 5460.9 5553.2 5567.8 5144.6 5505.7 5584.9 5810.5 5821.3 3512.7 3869.5 3879.2 3926.8 4008.9 4870.8 4886.5 5565.4 5619.3 5632.3 4633.9 4852.9 5489.5 5534.7 5552.3 4568.4 4827.7 4859.6 5249.3 5504.2 4706.9 5044.6 5170.6 5384.0 5425.6 5157.8 5474.3 5492.1 5555.2 5652.8 5163.3 5397.8 5432.8 5447.5 5469.9 18:59:51 INFO - PROCESS | 1653 | 18:59:51 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25471.05 Median:22111.78 stddev:40539.21 (183.3%) stddev-sans-first:40609.91 18:59:51 INFO - PROCESS | 1653 | Values: 598.8 789.6 790.9 804.4 829.4 15139.5 16460.8 16472.7 16484.3 16517.3 14818.6 14893.8 14971.6 14983.2 14983.2 666.4 689.3 692.2 827.2 835.4 842.7 865.5 866.1 867.9 868.4 913.2 914.6 916.8 916.8 917.1 700.6 728.6 730.4 912.6 917.1 540.6 541.0 643.7 668.0 671.4 15256.2 15296.6 15321.3 15348.9 15366.7 13943.5 14014.9 14210.8 14244.1 14320.3 12390.1 12637.4 12760.6 12772.7 13263.2 4473.5 4497.8 4518.8 4541.8 4576.3 122521.0 125345.0 129423.1 130543.0 132406.0 10583.9 10822.4 11095.2 11130.8 11191.9 22084.2 22139.3 22159.1 22166.1 22201.6 7725.5 7780.5 7920.3 8179.7 8188.6 3159.4 4251.9 4261.0 4327.2 4445.1 3476.7 3575.0 3575.7 3586.8 3590.9 633.5 642.9 648.4 653.9 661.2 412.9 531.9 541.9 597.6 608.6 8353.3 8997.5 8997.7 9009.2 9295.0 601.8 602.9 614.2 614.9 642.2 3713.4 4388.2 4450.7 4482.2 4508.0 100854.0 101211.0 127471.4 137597.0 171091.1 117236.0 123996.0 139929.0 143730.3 147797.0 113907.4 114308.0 116614.4 117935.4 118366.0 9323.0 9983.4 10018.8 10037.9 10124.7 61791.8 61885.0 65037.0 66264.0 67140.3 88633.6 98409.0 98513.0 101343.5 104350.5 12777.2 12830.2 12895.0 13257.7 13558.6 14324.9 14369.6 14698.3 15155.6 15545.4 13091.5 13192.7 13454.8 13781.2 14170.8 12890.9 13614.6 13984.0 14011.3 14630.0 11259.3 13076.9 13796.8 14305.3 14479.3 10874.1 11194.0 11272.4 11459.2 11858.9 11460.7 11589.0 11591.0 11698.7 11731.5 18:59:51 INFO - PROCESS | 1653 | -------- Summary: end -------- 18:59:51 INFO - PROCESS | 1653 | 18:59:52 INFO - TEST-INFO | 1653: exit 0 18:59:52 INFO - TEST-OK | dromaeo_css | took 1117435ms 18:59:52 INFO - TEST-START | kraken 18:59:52 INFO - operating with platform_type : mac_ 18:59:52 INFO - Initialising browser for kraken test... 18:59:52 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmptq8guV/profile http://localhost:49278/getInfo.html) 18:59:54 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 18:59:54 INFO - PROCESS | 1664 | colorDepth:24 18:59:54 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 18:59:54 INFO - PROCESS | 1664 | __metrics 18:59:54 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:59:54 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:59:55 INFO - TEST-INFO | 1664: exit 0 18:59:55 INFO - Browser initialized. 18:59:55 INFO - Running cycle 1/1 for kraken test... 18:59:55 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmptq8guV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:00:07 INFO - PROCESS | 1665 | RSS: Main: 219701248 19:00:07 INFO - PROCESS | 1665 | 19:00:10 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 19:00:11 INFO - PROCESS | 1665 | RSS: Main: 269623296 19:00:11 INFO - PROCESS | 1665 | 19:00:13 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 19:00:14 INFO - PROCESS | 1665 | RSS: Main: 280506368 19:00:14 INFO - PROCESS | 1665 | 19:00:17 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 19:00:18 INFO - PROCESS | 1665 | RSS: Main: 376438784 19:00:18 INFO - PROCESS | 1665 | 19:00:20 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 19:00:20 INFO - PROCESS | 1665 | RSS: Main: 280109056 19:00:20 INFO - PROCESS | 1665 | 19:00:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:00:23 INFO - PROCESS | 1665 | RSS: Main: 280776704 19:00:23 INFO - PROCESS | 1665 | 19:00:26 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 19:00:26 INFO - PROCESS | 1665 | RSS: Main: 318435328 19:00:26 INFO - PROCESS | 1665 | 19:00:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 19:00:30 INFO - PROCESS | 1665 | RSS: Main: 318668800 19:00:30 INFO - PROCESS | 1665 | 19:00:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 19:00:33 INFO - PROCESS | 1665 | RSS: Main: 316243968 19:00:33 INFO - PROCESS | 1665 | 19:00:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:00:35 INFO - PROCESS | 1665 | RSS: Main: 289902592 19:00:35 INFO - PROCESS | 1665 | 19:00:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:00:38 INFO - PROCESS | 1665 | RSS: Main: 295923712 19:00:38 INFO - PROCESS | 1665 | 19:00:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:00:41 INFO - PROCESS | 1665 | RSS: Main: 298012672 19:00:41 INFO - PROCESS | 1665 | 19:00:43 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:00:44 INFO - PROCESS | 1665 | RSS: Main: 309268480 19:00:44 INFO - PROCESS | 1665 | 19:00:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:00:48 INFO - PROCESS | 1665 | RSS: Main: 309125120 19:00:48 INFO - PROCESS | 1665 | 19:00:49 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 19:00:50 INFO - PROCESS | 1665 | RSS: Main: 352669696 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | __start_tp_report 19:00:50 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 19:00:50 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 19:00:50 INFO - PROCESS | 1665 | |i|pagename|runs| 19:00:50 INFO - PROCESS | 1665 | |0;ai-astar;113;112;113;111;111;114;112;111;111;112 19:00:50 INFO - PROCESS | 1665 | |1;audio-beat-detection;142;110;109;144;110;110;108;109;139;108 19:00:50 INFO - PROCESS | 1665 | |2;audio-dft;179;182;176;179;183;181;180;182;195;260 19:00:50 INFO - PROCESS | 1665 | |3;audio-fft;80;59;59;88;61;57;58;57;81;57 19:00:50 INFO - PROCESS | 1665 | |4;audio-oscillator;89;71;94;69;68;68;92;68;69;99 19:00:50 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;89;85;89;86;101;130;88;87;89;85 19:00:50 INFO - PROCESS | 1665 | |6;imaging-darkroom;96;96;95;96;95;95;101;95;95;96 19:00:50 INFO - PROCESS | 1665 | |7;imaging-desaturate;88;85;84;86;95;132;124;86;87;87 19:00:50 INFO - PROCESS | 1665 | |8;json-parse-financial;56;51;52;52;55;57;58;57;56;56 19:00:50 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;71;69;69;75;72;69;70;70;69;71 19:00:50 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;86;80;77;82;81;75;79;85;83;84 19:00:50 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;138;134;139;139;137;140;138;141;169;138 19:00:50 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;211;162;162;162;163;162;164;165;164;161 19:00:50 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;69;65;62;68;83;63;58;82;83;83 19:00:50 INFO - PROCESS | 1665 | __end_tp_report 19:00:50 INFO - PROCESS | 1665 | __start_cc_report 19:00:50 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1951 19:00:50 INFO - PROCESS | 1665 | __end_cc_report 19:00:50 INFO - PROCESS | 1665 | __startTimestamp1477360850057__endTimestamp 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | ------- Summary: start ------- 19:00:50 INFO - PROCESS | 1665 | Number of tests: 14 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:1.05 (0.9%) stddev-sans-first:1.05 19:00:50 INFO - PROCESS | 1665 | Values: 113.0 112.0 113.0 111.0 111.0 114.0 112.0 111.0 111.0 112.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:118.90 Median:110.00 stddev:15.77 (14.3%) stddev-sans-first:14.34 19:00:50 INFO - PROCESS | 1665 | Values: 142.0 110.0 109.0 144.0 110.0 110.0 108.0 109.0 139.0 108.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:189.70 Median:182.00 stddev:25.21 (13.9%) stddev-sans-first:26.44 19:00:50 INFO - PROCESS | 1665 | Values: 179.0 182.0 176.0 179.0 183.0 181.0 180.0 182.0 195.0 260.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:65.70 Median:60.00 stddev:12.18 (20.3%) stddev-sans-first:11.76 19:00:50 INFO - PROCESS | 1665 | Values: 80.0 59.0 59.0 88.0 61.0 57.0 58.0 57.0 81.0 57.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:80.00 stddev:13.00 (16.2%) stddev-sans-first:13.24 19:00:50 INFO - PROCESS | 1665 | Values: 89.0 71.0 94.0 69.0 68.0 68.0 92.0 68.0 69.0 99.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:87.50 stddev:13.82 (15.8%) stddev-sans-first:14.59 19:00:50 INFO - PROCESS | 1665 | Values: 89.0 85.0 89.0 86.0 101.0 130.0 88.0 87.0 89.0 85.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:95.50 stddev:1.83 (1.9%) stddev-sans-first:1.94 19:00:50 INFO - PROCESS | 1665 | Values: 96.0 96.0 95.0 96.0 95.0 95.0 101.0 95.0 95.0 96.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:86.50 stddev:17.54 (20.3%) stddev-sans-first:18.40 19:00:50 INFO - PROCESS | 1665 | Values: 88.0 85.0 84.0 86.0 95.0 132.0 124.0 86.0 87.0 87.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:56.00 stddev:2.45 (4.4%) stddev-sans-first:2.57 19:00:50 INFO - PROCESS | 1665 | Values: 56.0 51.0 52.0 52.0 55.0 57.0 58.0 57.0 56.0 56.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.50 stddev:1.90 (2.7%) stddev-sans-first:2.01 19:00:50 INFO - PROCESS | 1665 | Values: 71.0 69.0 69.0 75.0 72.0 69.0 70.0 70.0 69.0 71.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:82.50 stddev:3.52 (4.3%) stddev-sans-first:3.28 19:00:50 INFO - PROCESS | 1665 | Values: 86.0 80.0 77.0 82.0 81.0 75.0 79.0 85.0 83.0 84.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:141.30 Median:139.00 stddev:9.91 (7.1%) stddev-sans-first:10.44 19:00:50 INFO - PROCESS | 1665 | Values: 138.0 134.0 139.0 139.0 137.0 140.0 138.0 141.0 169.0 138.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.60 Median:163.50 stddev:15.30 (9.4%) stddev-sans-first:1.30 19:00:50 INFO - PROCESS | 1665 | Values: 211.0 162.0 162.0 162.0 163.0 162.0 164.0 165.0 164.0 161.0 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:75.50 stddev:10.07 (13.3%) stddev-sans-first:10.64 19:00:50 INFO - PROCESS | 1665 | Values: 69.0 65.0 62.0 68.0 83.0 63.0 58.0 82.0 83.0 83.0 19:00:50 INFO - PROCESS | 1665 | -------- Summary: end -------- 19:00:50 INFO - PROCESS | 1665 | 19:00:50 INFO - TEST-INFO | 1665: exit 0 19:00:50 INFO - TEST-OK | kraken | took 58562ms 19:00:50 INFO - SUITE-END | took 1175s 19:00:51 INFO - Completed test suite (00:19:36) 19:00:51 INFO - javascript benchmark 19:00:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.6869097435742, 3332.983335083324, 3352.88264910728, 3375.983120084399, 3410.9658903410973, 5701.800436984701, 5759.740811663475, 5766.250387449626, 5784.450477204664, 5847.298324201097, 4871.537203965627, 5556.749946252418, 5762.942370576293, 5764.769409223626, 5771.394003629622, 5217.060929032773, 5222.869428264305, 5239.161734122522, 5243.239730239123, 5285.392179899303, 5140.074786538422, 5151.974240128794, 5334.813281535147, 5335.093034184198, 5364.356277246736, 5196.0, 5245.8950820983555, 5248.422673505911, 5256.027634887539, 5259.658122222043, 5249.947500525003, 5266.368035835686, 5269.815556455505, 5270.947290527084, 5274.129768588178, 5104.081265372231, 5199.298094757197, 5200.063988482072, 5203.271541984125, 5240.109181439165, 5764.567657425668, 5765.077587585966, 5770.855728606776, 5776.364599894008, 5782.971085144547, 6084.128919687898, 6128.3338155912115, 6162.0, 6190.3190649028575, 6220.968895155495, 6256.499480041632, 6301.180846489973, 6302.905456418157, 6309.337519560426, 6323.272823625296, 4902.950970490285, 5798.82603521895, 5844.357120716718, 5844.649321040752, 5874.441928016831, 6148.44663980244, 6243.313235544087, 6272.090546870678, 6310.211223597051, 6313.55805093639, 5776.508996735324, 5941.049432090931, 5945.643261404243, 5948.910766338508, 5980.970095149496, 6115.296740874856, 6148.954677704801, 6149.600275982047, 6152.261728592508, 6158.907616385758, 6003.849903752353, 6021.187139736167, 6044.879102417927, 6079.8480037998515, 6104.053871649901, 6041.063635136473, 6133.907991380043, 6171.7531298748445, 6203.503719702413, 6221.844453888599, 4868.780904859219, 4874.683145595525, 4876.906676231222, 4885.193942999436, 4906.337644418029, 4319.589638984291, 4356.237658409766, 4405.933910991338, 4407.052483715952, 4433.623142032963, 5659.490645841812, 5679.318481782213, 5741.741621627037, 5752.712364381764, 5760.94239057604, 6329.556931014877, 6336.366363363719, 6345.270293916166, 6355.269144048401, 6365.904511432333, 5803.897259520678, 5805.01314776489, 5810.4189581041555, 5820.912686309709, 5858.707064646751, 5260.473952604785, 5263.868403289948, 5304.867378315495, 5389.7035663038905, 5427.728613569306, 6259.530535209787, 6302.149209856794, 6328.0, 6369.076483909715, 6370.55406121567, 6305.96847015762, 6316.431521163117, 6327.841803954938, 6331.860265152132, 6337.588056776295, 6186.505079593531, 6254.655993920378, 6288.842778930563, 6301.369863013845, 6323.652199128908, 6133.854967164949, 6163.630182189083, 6201.534884883562, 6275.246970363585, 6277.5605707600025, 5331.70675612845, 6093.360197179232, 6297.307296197506, 6319.146915166578, 6340.080688300079, 5086.61850361217, 5888.499477544286, 6120.265568131675, 6152.16945712341, 6175.382461753788, 5963.493103086286, 6071.39286071407, 6100.816975490742, 6107.583301036431, 6109.297430795337, 6039.365866583946, 6043.697815109315, 6069.241344831896, 6073.301570319293, 6082.239720034996, 6174.722137503733, 6188.473979711594, 6192.287886893065, 6209.720562574605, 6215.192025036898, 5791.276090488689, 5808.30300363959, 5831.620944638585, 5908.822735317947, 5951.970240148771], "value": 5687.219834291564, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.7956388970798, 504.58371843229344, 515.9664621799583, 521.4967556308162, 532.9866753331166, 30485.0, 41369.58630413695, 42553.51062712806, 42697.57302426975, 44945.550544494545, 43660.78169609155, 43736.0, 43923.56076439234, 43946.0, 43999.12001759963, 8902.821943561124, 9977.351472154323, 10155.949220253888, 10160.491975401237, 10168.288219824615, 8894.0, 9098.0, 9125.996140424548, 9128.224100951427, 10430.0, 12402.13185077045, 12492.500299987989, 12516.749665006695, 12521.373931303398, 12529.0, 12311.507539698401, 12393.318367489785, 12401.937990310082, 12403.317817520032, 12403.875961240363, 10928.508217130247, 11917.642470725892, 12074.879251207463, 12265.632031039084, 12358.75282494354, 41437.96405089897, 41562.58437415618, 41615.0, 41709.58290417118, 41746.373804392955, 2867.5125228710963, 3402.5406570112964, 3407.2382623782933, 3435.261418794971, 3456.2050728332374, 30688.53967190494, 30878.0, 31695.815972655328, 32477.6752232477, 32696.67303326984, 3223.3714425687, 3236.4498035333813, 3250.8537115829845, 3300.9834950825334, 3382.3573521030926, 523071.0, 523383.0, 523479.9999999962, 523886.0, 525089.0, 34337.313253734785, 61068.38931610716, 61518.0, 61528.07707884385, 61790.69104654448, 30197.849010755246, 34629.82685086558, 35954.10114747152, 35969.280614387564, 35998.1000474985, 15539.766903496457, 15683.921580392025, 15696.293666785023, 15730.977486463344, 15739.763903541456, 13142.934285328512, 13193.670158245928, 13218.0, 13234.867651323557, 13256.337183140804, 6599.571027883172, 7289.781306560812, 7434.888476672854, 7746.806329841799, 7906.327962123284, 2821.943561128766, 3221.0497903118635, 3247.269364392993, 3268.7058164765285, 3290.407726609233, 900.9414388064755, 985.930984831055, 991.5785791038779, 1000.884898236712, 1015.3349556040805, 30426.087217383512, 30486.0, 31027.844860776004, 31228.843855780575, 31444.685553144634, 1009.3287963504334, 1096.9122470202365, 1131.7566723154562, 1175.3359351966112, 1223.6023292429995, 15284.923575382274, 15845.920770396075, 15978.280977356048, 16220.945638533458, 16308.429204977769, 699862.0, 711275.0, 711316.0, 712040.0, 712409.0, 49435.0112997738, 50187.49812501901, 51713.0, 57916.71041644849, 59559.0, 1357.5995081450997, 1380.82049333586, 1404.768213244803, 1405.9859401406263, 1413.886889048894, 30296.852593026273, 31670.0, 32090.716371344886, 32125.35749285048, 32212.838935806107, 50990.0, 51402.48597513977, 51439.97120057503, 51682.48317516777, 51792.741036294574, 469827.0, 470972.0, 472610.0, 472901.0, 473377.0, 26873.0, 27049.729502704722, 27101.915923362845, 27336.863315683295, 27358.726412736414, 47532.52467475281, 47701.76149119232, 48531.02937941292, 48542.786430339525, 48674.0, 39391.606083938794, 40125.996850078984, 40299.0, 40971.590284096776, 41569.0, 39518.40722389109, 39755.60244397519, 39797.60202397939, 40103.79948100241, 41518.0, 38283.425748613234, 38980.0, 39084.80457597694, 39106.65360078404, 39179.608203917596, 33558.328833423686, 35117.0, 35138.0, 35584.64415355813, 35629.28741425209, 25820.741792582587, 25944.8702756485, 26040.609390859532, 26388.736112638628, 26496.0], "value": 19276.935820148676, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.8712621388338, 354.40283122937836, 378.61570505936464, 378.67244833219274, 408.48326866513844, 13310.668933106685, 15124.848751512496, 15409.229538523072, 20308.0, 20706.48233794152, 19612.01939903003, 20569.868657223848, 21106.577868442622, 21233.15067397302, 21473.355799326007, 5687.374388817227, 5978.043513037926, 6219.906701399483, 6233.096201050845, 6253.843653908666, 5778.451047150535, 6018.097285407181, 6105.267367915856, 6200.658963756991, 6204.317525072261, 5799.3620701722775, 6074.149619053314, 6152.169457123276, 6157.3842615738295, 6172.567920245562, 6029.276486821587, 6029.8191054268445, 6079.240094988127, 6080.543959203055, 6087.391260873899, 5079.974600126976, 5235.952809438089, 5277.076511610453, 5277.102892508283, 5329.920051199197, 25175.748242517522, 25286.36784080394, 25610.48779024409, 25906.093286735064, 26125.0, 1879.5207222158263, 2247.820174386045, 2579.4454192348735, 3126.9687303126907, 3156.447621666199, 14759.409623615149, 15729.292181851844, 16081.678366432723, 16103.677926441524, 16164.595885102848, 1857.674906891302, 1934.9129289182022, 1960.8725432846961, 1970.556624759432, 2010.0753653319491, 65141.0, 97114.5144274274, 117688.8231117695, 121365.0, 123661.0, 9468.526573671288, 9917.65288214909, 10136.645217417356, 10196.28625996188, 10366.222533309881, 12528.874711252953, 12549.686257843445, 12551.3096779678, 12627.5580354689, 12645.557405490763, 8129.0, 8159.918400815915, 8226.506409615442, 8286.171382861665, 8499.23506884371, 6655.401013908675, 8078.424707182043, 8841.955790221007, 8856.955715221382, 8921.330900182513, 3293.045016945059, 3311.2549676322633, 3933.940990885139, 4001.699872509515, 4005.6394924456354, 1946.8734532255642, 1947.6396866579728, 1950.9804901951084, 1986.3345779163853, 2002.6895831146196, 823.7693445835175, 885.1325700813111, 915.3455279475207, 944.9480278584743, 1044.5926088825365, 20381.0, 21495.67756483654, 21599.35201943944, 21684.783152168275, 21711.674324885138, 940.5308133205389, 951.8429459139164, 954.289054654287, 1024.1601886453036, 1045.7385653586603, 9841.261905357127, 11109.83335249972, 11225.943870280596, 11235.045021173291, 11270.436478176058, 563827.0, 581339.0, 583268.0, 584801.1519884747, 593099.0, 115321.0, 117043.41478292554, 125979.0, 138922.0, 144759.0, 975.878015248094, 984.7636567223958, 996.05872450535, 1002.4286156890503, 1004.1213937804421, 17822.91088544549, 18016.639667206844, 18586.721199181753, 18836.623267534847, 18868.52828679294, 42093.0, 53333.0, 56916.0, 57724.422755771906, 59084.70457647684, 83407.16592834237, 83814.0, 84546.57726711327, 84919.57540212259, 86092.0, 7799.45403821727, 8096.028476582847, 8250.54621995796, 8339.416240863082, 8438.957805211181, 7496.962515187389, 12287.815682764587, 12380.195287306296, 12410.689732756753, 12799.936000319938, 10880.455977201267, 10958.780824383628, 11096.833547496633, 11113.499892504695, 11272.041876440595, 10547.367157970546, 10963.835542467028, 10971.067459265732, 11350.602728904449, 11523.96284334414, 11030.28303160292, 11419.942900285445, 11635.883641163819, 11669.474873630877, 11861.703457413634, 7978.960105199437, 8160.857479952692, 8185.836283274182, 8511.0, 8526.82946341082, 9971.700848974542, 10790.730231743955, 11144.665660030212, 11200.831987520356, 11223.66329010131], "value": 9557.698051584708, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.031307927911, 1376.6764810269588, 1536.554399224225, 1747.5631092226943, 1947.0, 7862.488938219012, 8390.874136887944, 8655.999999999993, 8894.822103557924, 8912.197902188818, 7615.733449329275, 8791.560421978907, 8890.688825891095, 8896.555172241378, 8908.287337013024, 2402.0992127952018, 2424.2848359733835, 2429.53838770633, 2431.8176136789725, 2434.45224824414, 2389.462370966536, 2416.6254230594286, 2421.237310247275, 2421.8667973261463, 2430.2587710748217, 2383.92848214553, 2405.434722840131, 2417.0694282701197, 2417.226487523993, 2421.3462365161486, 2148.8611036150787, 2262.2195342606774, 2356.634721618135, 2384.8688322142275, 2404.4349577849193, 2352.929412117639, 2390.115657206827, 2390.725066617344, 2394.174009966559, 2399.184277345711, 8876.979147398068, 8926.419782714103, 8937.240334571568, 8955.731328060168, 8963.731088067369, 5252.789888404459, 5443.700596467193, 5445.591580631429, 5459.153831156177, 5481.287432633772, 3653.872114476007, 3681.987453450296, 3751.381022131345, 3758.4362345648096, 3766.472693822867, 1089.9237053406264, 1094.3215206571977, 1094.9069329107035, 1105.4086063955745, 1105.5025238642563, 11635.301881887113, 11689.357085360301, 11776.05791536675, 11780.587679431179, 11781.469833857496, 4479.888002799956, 4512.864614061584, 4708.246680531099, 4712.528747125329, 4726.834560790356, 9215.95392023049, 9224.584893679801, 9232.353735238468, 9235.584398702074, 9242.907570924339, 5293.708846013429, 5295.920561191585, 5302.469753024667, 5311.0, 5328.893422131536, 4391.016699214861, 4444.400005999213, 4444.799984000728, 4606.792694328697, 4962.082014827196, 3804.7907365094634, 3830.8467661293794, 3870.5161854768153, 3913.3151698452657, 3925.2345792570177, 1178.6287319494331, 1188.9524419023141, 1190.815423609342, 1202.3627477436971, 1204.1931905623253, 841.2975165736678, 907.1699395053585, 921.2860033473977, 925.509479975614, 944.5371767833713, 5768.855778605568, 5802.709864506842, 6000.639961602318, 6065.332813390523, 6119.694015299217, 395.95248570171765, 418.9958100419018, 452.50224752771686, 454.538643277074, 461.4600916927203, 3555.8577656893954, 3696.0, 3961.603839616015, 3963.564007959122, 3966.484357033567, 10529.789404211873, 11050.226484146033, 11051.226414151093, 11060.115190784718, 11121.22151449407, 10957.616483423202, 10968.287061341147, 10971.451427428598, 10978.451077446096, 10983.61557345489, 8168.591570421455, 8200.0, 8348.874766878502, 8373.874391884126, 8393.0, 5658.5473162147755, 5823.417658234142, 5884.35272120075, 6056.606320589148, 6092.299385570539, 9842.917279099436, 10136.138428233682, 10193.949030254802, 10224.591016359262, 10263.897361026593, 9457.858132127885, 10296.845547316947, 10493.0, 10523.526441310007, 10541.631042913461, 6672.766453174116, 6874.65626718652, 6989.47578931572, 7089.6100714459135, 7205.171405288459, 8344.749657510285, 8379.287760540423, 8385.413021088465, 8412.537310447984, 8464.153584641486, 7533.472656913963, 7591.4685971983645, 7612.58130802789, 7620.542767433972, 7639.656215470206, 7241.891371629325, 7383.889241661487, 7441.479096463196, 7538.283863033003, 7583.696652133853, 7019.613921234381, 7045.894311585228, 7112.502124851418, 7132.322429369202, 7135.892961605477, 4931.9753401232765, 4974.9751251243515, 5096.0827051131155, 5119.308893299357, 5228.294180285613, 5038.445770965264, 5039.8488045357235, 5124.7950081996305, 5189.922151167811, 5257.5005374489365], "value": 4505.910175857495, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.58049440536047, 300.5926968957062, 307.31775458482167, 308.23711314496626, 315.27014960366733, 4364.708528022789, 5232.529072383485, 5277.788888444467, 5279.313689220401, 5329.493698098684, 4685.789139488723, 5070.619703522232, 5166.250893620423, 5201.0, 5288.682679039261, 3745.1386181178345, 3848.422736589506, 4027.7986100694993, 4069.369247766601, 4072.9185416291657, 3376.746743994198, 4010.879673609782, 4030.7581545107387, 4051.3315302974975, 4055.2700513907484, 3643.0, 3839.533827909453, 4057.8782636520805, 4059.7361171523903, 4070.5115386153557, 3557.5019497270405, 4009.7594144351433, 4041.8884806678107, 4046.9999999999854, 4063.7764922929227, 2923.4591600553754, 3510.5436293281964, 3523.5771707395274, 3551.627079156703, 3558.804265765382, 4966.602671786249, 5218.608604354688, 5256.106461901486, 5256.159014557691, 5257.447967963385, 4347.000189956296, 4560.931586026212, 4587.266037433995, 4621.7752295641685, 4622.329762184464, 4965.776540055706, 5508.228847961289, 5543.833684989457, 5568.55451563878, 5575.553955683536, 3848.65362117408, 3858.2283543291455, 3861.4980052593264, 3874.380099184117, 3941.6649584785328, 5227.426002248033, 5510.752016159283, 5664.631798933097, 5772.6536407815665, 5781.479666829963, 3888.0862997195636, 3888.2223555289056, 3898.590647981978, 3909.980450097731, 3937.940930886039, 3964.583718709494, 3973.920521589552, 3976.622220889011, 3988.7806170660892, 4050.2911990401562, 4374.387585737999, 4584.6561507887045, 4606.953930460652, 4613.838515651936, 4631.490536041033, 5024.899502009939, 5560.944390556043, 5562.610617256834, 5576.525995290364, 5586.636868603528, 1143.976141353484, 1222.5109956017486, 1294.695746499572, 1364.324659293656, 1369.3906211735882, 741.7686640177387, 893.6023469556116, 914.533587870191, 948.0188005414363, 954.2699834626517, 4982.90034199314, 5182.689038657692, 5246.0, 5254.737263136904, 5256.2904007959205, 5237.947620523746, 5659.886802263932, 5680.630759000652, 5716.42835716425, 5753.3958934311295, 4206.137741762931, 5155.6391052626705, 5199.972010915746, 5244.842654720288, 5264.5782939848095, 2741.465414244243, 2874.404705388488, 2926.473234817711, 2946.292889706498, 2964.5256758918467, 5055.823046193365, 5608.327000759853, 5850.9175802476675, 5879.382664820132, 5882.147088672119, 4704.788284527204, 5273.2617433559335, 5532.806351777668, 5738.684372359477, 5786.768529258867, 5706.629069110494, 5822.505087067562, 5826.76692932278, 5849.941500585025, 5857.531397488191, 4488.0126372198065, 4999.475160076212, 5117.211119939568, 5427.1859221115565, 5461.726913654381, 4533.161365147326, 4745.715257084586, 5460.91808622863, 5553.194786755978, 5567.802922371709, 5144.639875208849, 5505.724713764376, 5584.944150558443, 5810.5061069809535, 5821.301443826768, 3512.6838584527513, 3869.477620521306, 3879.224155169034, 3926.764394136361, 4008.8596899109557, 4870.756462176948, 4886.53577910098, 5565.443455654402, 5619.297587801525, 5632.295963004624, 4633.934195135071, 4852.8544143675745, 5489.505944465017, 5534.667919924817, 5552.250446189874, 4568.406107206042, 4827.744786355643, 4859.586935110555, 5249.343832020997, 5504.201890725903, 4706.905861882811, 5044.571211447068, 5170.612204084633, 5383.973080134574, 5425.565954723692, 5157.813702848291, 5474.2609747683555, 5492.144085075658, 5555.194496798023, 5652.830415087554, 5163.302954101148, 5397.78549826286, 5432.782688692408, 5447.455254474521, 5469.945300547103], "value": 4045.445084979577, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.7575780255969, 789.6170357376675, 790.9011373578303, 804.3886646148928, 829.3945419843515, 15139.470118545853, 16460.83539164608, 16472.670546589063, 16484.258208380623, 16517.339306427744, 14818.555443336718, 14893.77659335111, 14971.625709357271, 14983.175925324103, 14983.175925324103, 666.3835951744641, 689.3175756001573, 692.2074225012358, 827.1521690267464, 835.415209353455, 842.6629348260714, 865.5153114256005, 866.0646501778065, 867.8568036274007, 868.448535180162, 913.1690161952624, 914.6432891172448, 916.757059379265, 916.7799728065283, 917.0783362720457, 700.6356694518872, 728.6320408193881, 730.3536370312258, 912.63494602159, 917.0783362720457, 540.6026570470701, 541.0261529247379, 643.7167646235641, 668.0413606474676, 671.4460570029745, 15256.160911149882, 15296.617584560476, 15321.310541025681, 15348.923255383652, 15366.692666146726, 13943.511977080854, 14014.859851401457, 14210.786838197435, 14244.07413518118, 14320.283985800668, 12390.132690711655, 12637.368131593475, 12760.553380631633, 12772.744545109139, 13263.204207747469, 4473.485549161823, 4497.775111244458, 4518.774061296922, 4541.772911354452, 4576.313552967082, 122521.0, 125345.0, 129423.05865412026, 130543.0, 132405.99999999904, 10583.894161058292, 10822.350658960488, 11095.167862410352, 11130.777384452427, 11191.888081119085, 22084.227052053102, 22139.33581992543, 22159.113635454723, 22166.113355465925, 22201.555968880853, 7725.5364678119495, 7780.533168009937, 7920.287174154354, 8179.713710020121, 8188.590570471452, 3159.3839201355513, 4251.851185208503, 4260.957390426118, 4327.221100201994, 4445.110977804452, 3476.721862251014, 3574.964250357515, 3575.713942884563, 3586.8385922633543, 3590.856365745341, 633.5406830048178, 642.9292777794439, 648.3840351665937, 653.859420224654, 661.2362721057186, 412.88645622454067, 531.9228711836762, 541.8618252345626, 597.5548216578677, 608.5557542993639, 8353.289970352467, 8997.505137217517, 8997.730068097968, 9009.23421509179, 9294.953525232331, 601.7623038899698, 602.8643555200133, 614.1862032806549, 614.8727926215286, 642.1748053750907, 3713.405855063231, 4388.166248412791, 4450.710703804242, 4482.193205223091, 4507.965370993008, 100853.99146008446, 101211.0, 127471.36264318619, 137597.0, 171091.1445442765, 117236.0, 123996.0, 139929.0, 143730.2813485926, 147797.0, 113907.43046284716, 114308.0, 116614.41692791482, 117935.41032294955, 118366.0, 9322.95338523303, 9983.377676169097, 10018.799624007333, 10037.949810250902, 10124.69625911224, 61791.764164715554, 61885.0, 65037.0, 66264.0, 67140.32859671341, 88633.55683221543, 98409.0, 98513.0, 101343.49328253312, 104350.47824760828, 12777.169483983884, 12830.154893007164, 12895.0, 13257.66855828612, 13558.593242202749, 14324.928375358057, 14369.640758980691, 14698.338574763948, 15155.621109471911, 15545.37818487293, 13091.541796037092, 13192.736145277233, 13454.798178027142, 13781.173129612256, 14170.787438188228, 12890.871091288967, 13614.591562253148, 13984.017132999048, 14011.299435028412, 14630.0, 11259.268147570381, 13076.934615327244, 13796.793048104488, 14305.284735762962, 14479.276036198358, 10874.130069594572, 11193.99254067138, 11272.436378181223, 11459.19785614999, 11858.940705296764, 11460.713482163013, 11589.0, 11590.988560867041, 11698.70753231176, 11731.530738770354], "value": 7842.832922547208, "unit": "score"}], "name": "dromaeo_css", "value": 7287.744983697562, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 111.0, 111.0, 114.0, 112.0, 111.0, 111.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 109.0, 144.0, 110.0, 110.0, 108.0, 109.0, 139.0, 108.0], "value": 118.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 182.0, 176.0, 179.0, 183.0, 181.0, 180.0, 182.0, 195.0, 260.0], "value": 189.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 88.0, 61.0, 57.0, 58.0, 57.0, 81.0, 57.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 94.0, 69.0, 68.0, 68.0, 92.0, 68.0, 69.0, 99.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 86.0, 101.0, 130.0, 88.0, 87.0, 89.0, 85.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 101.0, 95.0, 95.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 84.0, 86.0, 95.0, 132.0, 124.0, 86.0, 87.0, 87.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 51.0, 52.0, 52.0, 55.0, 57.0, 58.0, 57.0, 56.0, 56.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 69.0, 75.0, 72.0, 69.0, 70.0, 70.0, 69.0, 71.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 77.0, 82.0, 81.0, 75.0, 79.0, 85.0, 83.0, 84.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 134.0, 139.0, 139.0, 137.0, 140.0, 138.0, 141.0, 169.0, 138.0], "value": 141.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 162.0, 162.0, 162.0, 163.0, 162.0, 164.0, 165.0, 164.0, 161.0], "value": 167.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 62.0, 68.0, 83.0, 63.0, 58.0, 82.0, 83.0, 83.0], "value": 71.6, "unit": "score"}], "name": "kraken", "value": 1436.4999999999998, "alertThreshold": 2.0}]} 19:00:51 INFO - Return code: 0 19:00:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:00:51 INFO - # TBPL SUCCESS # 19:00:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:00:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:00:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:00:51 INFO - 2016-10-24 19:00:51.330 screenresolution[1669:9921] starting screenresolution argv=screenresolution get 19:00:51 INFO - 2016-10-24 19:00:51.333 screenresolution[1669:9921] Display 0: 1600x1200x32@60 19:00:51 INFO - 2016-10-24 19:00:51.339 screenresolution[1670:9924] starting screenresolution argv=screenresolution list 19:00:51 INFO - Available Modes on Display 0 19:00:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:00:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:00:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:00:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:00:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:00:51 INFO - Intel Iris: 19:00:51 INFO - Chipset Model: Intel Iris 19:00:51 INFO - Type: GPU 19:00:51 INFO - Bus: Built-In 19:00:51 INFO - VRAM (Dynamic, Max): 1536 MB 19:00:51 INFO - Vendor: Intel (0x8086) 19:00:51 INFO - Device ID: 0x0a2e 19:00:51 INFO - Revision ID: 0x0009 19:00:51 INFO - Displays: 19:00:51 INFO - Display: 19:00:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:00:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:00:51 INFO - Display Serial Number: 1600x1200 60 19:00:51 INFO - Main Display: Yes 19:00:51 INFO - Mirror: Off 19:00:51 INFO - Online: Yes 19:00:51 INFO - Rotation: Supported 19:00:51 INFO - Return code: 0 19:00:51 INFO - Running post-action listener: _resource_record_post_action 19:00:51 INFO - [mozharness: 2016-10-25 02:00:51.579190Z] Finished run-tests step (success) 19:00:51 INFO - Running post-run listener: _resource_record_post_run 19:00:51 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 37528576; Write bytes: 663291392; Read time: 241; Write time: 1869 19:00:51 INFO - TinderboxPrint: CPU usage
53.4% 19:00:51 INFO - TinderboxPrint: I/O read bytes / time
37,528,576 / 241 19:00:51 INFO - TinderboxPrint: I/O write bytes / time
663,291,392 / 1,869 19:00:51 INFO - TinderboxPrint: CPU idle
2,255.2 (46.6%) 19:00:51 INFO - TinderboxPrint: CPU system
970.8 (20.1%) 19:00:51 INFO - TinderboxPrint: CPU user
1,611.6 (33.3%) 19:00:51 INFO - TinderboxPrint: Swap in / out
401,219,584 / 0 19:00:51 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 356610560; Write bytes: 356788224; Read time: 27512; Write time: 713 19:00:51 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 35738624; Write bytes: 295480832; Read time: 226; Write time: 1119 19:00:52 INFO - Running post-run listener: _upload_blobber_files 19:00:52 INFO - Blob upload gear active. 19:00:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:00:52 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 19:00:52 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'] 19:00:52 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 19:00:52 INFO - (blobuploader) - INFO - Open directory for files ... 19:00:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:00:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:00:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:00:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:00:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:00:54 INFO - (blobuploader) - INFO - Done attempting. 19:00:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:00:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:00:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:00:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:00:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:00:54 INFO - (blobuploader) - INFO - Done attempting. 19:00:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:00:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:00:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:00:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:00:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:00:57 INFO - (blobuploader) - INFO - Done attempting. 19:00:57 INFO - (blobuploader) - INFO - Iteration through files over. 19:00:57 INFO - Return code: 0 19:00:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:00:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:00:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d7ffd83361cfaaf66081a1c98d0ee074153dfb86f80f247e65e758d828c130991bc12cf63c213a7c2d0d5a61446740696db4e2086bd4624ff9ddf207fc6d7aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f82f22c197b3e80894fc7ca0df9fe9b3909a5c1e918df3bf33be6904104d2b4d26f9cf44969b3551228fea88d89bdee51b5bc343f11b8a77f8130088de5438e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2548b7244858055597779ebd4d7ab7c56eb33316f0ccafd9b2f19d4371c2dd14576babee00de120e4ff431cba9eb518e181b4420ccf2372a505132d98ff25963"} 19:00:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:00:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:00:57 INFO - Contents: 19:00:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d7ffd83361cfaaf66081a1c98d0ee074153dfb86f80f247e65e758d828c130991bc12cf63c213a7c2d0d5a61446740696db4e2086bd4624ff9ddf207fc6d7aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f82f22c197b3e80894fc7ca0df9fe9b3909a5c1e918df3bf33be6904104d2b4d26f9cf44969b3551228fea88d89bdee51b5bc343f11b8a77f8130088de5438e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2548b7244858055597779ebd4d7ab7c56eb33316f0ccafd9b2f19d4371c2dd14576babee00de120e4ff431cba9eb518e181b4420ccf2372a505132d98ff25963"} 19:00:57 INFO - Running post-run listener: copy_logs_to_upload_dir 19:00:57 INFO - Copying logs to upload dir... 19:00:57 INFO - mkdir: /builds/slave/test/build/upload/logs 19:00:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.886527 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-10-24 19:00:57.697529) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 19:00:57.701201) ========= 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.PbYi9bKwCo/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.mi0e56uQS9/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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/3d7ffd83361cfaaf66081a1c98d0ee074153dfb86f80f247e65e758d828c130991bc12cf63c213a7c2d0d5a61446740696db4e2086bd4624ff9ddf207fc6d7aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f82f22c197b3e80894fc7ca0df9fe9b3909a5c1e918df3bf33be6904104d2b4d26f9cf44969b3551228fea88d89bdee51b5bc343f11b8a77f8130088de5438e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2548b7244858055597779ebd4d7ab7c56eb33316f0ccafd9b2f19d4371c2dd14576babee00de120e4ff431cba9eb518e181b4420ccf2372a505132d98ff25963"} build_url:https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010949 build_url: 'https://queue.taskcluster.net/v1/task/Pmc3hO1RSbecRhMU9I7Jjw/artifacts/public/build/firefox-51.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d7ffd83361cfaaf66081a1c98d0ee074153dfb86f80f247e65e758d828c130991bc12cf63c213a7c2d0d5a61446740696db4e2086bd4624ff9ddf207fc6d7aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f82f22c197b3e80894fc7ca0df9fe9b3909a5c1e918df3bf33be6904104d2b4d26f9cf44969b3551228fea88d89bdee51b5bc343f11b8a77f8130088de5438e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2548b7244858055597779ebd4d7ab7c56eb33316f0ccafd9b2f19d4371c2dd14576babee00de120e4ff431cba9eb518e181b4420ccf2372a505132d98ff25963"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 19:00:57.733394) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:00:57.733728) ========= 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.PbYi9bKwCo/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.mi0e56uQS9/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.005087 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:00:57.783013) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 19:00:57.783325) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 19:00:57.783694) ========= ========= Total master_lag: 0.17 =========