builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0308 starttime: 1491949405.94 results: success (0) buildid: 20170411134408 builduid: 4af659f7a9ec4217a592f8c4d41ceea9 revision: 1c79bcfea67971696983deeadb0bc22f7158dd1e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:25.945023) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:25.945517) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:25.945818) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:25.972726) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:25.973264) ========= 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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-11 15:23:25-- 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% 22.6M=0.001s 2017-04-11 15:23:26 (22.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148112 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:26.145578) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:26.145927) ========= 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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.025162 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:26.196020) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-11 15:23:26.196645) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1c79bcfea67971696983deeadb0bc22f7158dd1e --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 1c79bcfea67971696983deeadb0bc22f7158dd1e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-11 15:23:26,304 truncating revision to first 12 chars 2017-04-11 15:23:26,304 Setting DEBUG logging. 2017-04-11 15:23:26,304 attempt 1/10 2017-04-11 15:23:26,304 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1c79bcfea679?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 15:23:28,375 unpacking tar archive at: mozilla-aurora-1c79bcfea679/testing/mozharness/ program finished with exit code 0 elapsedTime=2.343032 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-11 15:23:28.564166) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:28.564556) ========= script_repo_revision: 1c79bcfea67971696983deeadb0bc22f7158dd1e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:28.564950) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:28.565249) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 15:23:28.584831) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-11 15:23:28.585128) ========= /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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:23:28 INFO - MultiFileLogger online at 20170411 15:23:28 in /builds/slave/test 15:23:28 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 15:23:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:23:28 INFO - {'append_to_log': False, 15:23:28 INFO - 'base_work_dir': '/builds/slave/test', 15:23:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:23:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:23:28 INFO - 'branch': 'Mozilla-Aurora', 15:23:28 INFO - 'buildbot_json_path': 'buildprops.json', 15:23:28 INFO - 'config_files': ('talos/mac_config.py',), 15:23:28 INFO - 'default_actions': ('clobber', 15:23:28 INFO - 'read-buildbot-config', 15:23:28 INFO - 'download-and-extract', 15:23:28 INFO - 'populate-webroot', 15:23:28 INFO - 'create-virtualenv', 15:23:28 INFO - 'install', 15:23:28 INFO - 'run-tests'), 15:23:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:23:28 INFO - 'download_minidump_stackwalk': True, 15:23:28 INFO - 'download_symbols': 'ondemand', 15:23:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:23:28 INFO - 'tooltool.py': '/tools/tooltool.py', 15:23:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:23:28 INFO - '/tools/misc-python/virtualenv.py')}, 15:23:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:23:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:23:28 INFO - 'gecko_profile': False, 15:23:28 INFO - 'gecko_profile_interval': 0, 15:23:28 INFO - 'installer_path': 'installer.exe', 15:23:28 INFO - 'log_level': 'info', 15:23:28 INFO - 'log_name': 'talos', 15:23:28 INFO - 'log_to_console': True, 15:23:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:23:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:23:28 INFO - 'opt_config_files': (), 15:23:28 INFO - 'pip_index': False, 15:23:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:23:28 INFO - 'cmd': ('bash', 15:23:28 INFO - '-c', 15:23:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:23:28 INFO - 'enabled': True, 15:23:28 INFO - 'halt_on_failure': False, 15:23:28 INFO - 'name': 'check_screen_resolution'},), 15:23:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:23:28 INFO - 'cmd': ('bash', 15:23:28 INFO - '-c', 15:23:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:23:28 INFO - 'enabled': True, 15:23:28 INFO - 'halt_on_failure': False, 15:23:28 INFO - 'name': 'check_screen_resolution'},), 15:23:28 INFO - 'run_cmd_checks_enabled': True, 15:23:28 INFO - 'suite': 'dromaeojs', 15:23:28 INFO - 'system_bits': '32', 15:23:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:23:28 INFO - 'title': 't-yosemite-r7-0308', 15:23:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:23:28 INFO - 'use_talos_json': True, 15:23:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:23:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:23:28 INFO - 'work_dir': 'build'} 15:23:28 INFO - [mozharness: 2017-04-11 22:23:28.802116Z] Running clobber step. 15:23:28 INFO - Running pre-action listener: _resource_record_pre_action 15:23:28 INFO - Running main action method: clobber 15:23:28 INFO - rmtree: /builds/slave/test/build 15:23:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:23:31 INFO - Running post-action listener: _resource_record_post_action 15:23:31 INFO - [mozharness: 2017-04-11 22:23:31.441336Z] Finished clobber step (success) 15:23:31 INFO - [mozharness: 2017-04-11 22:23:31.441552Z] Running read-buildbot-config step. 15:23:31 INFO - Running pre-action listener: _resource_record_pre_action 15:23:31 INFO - Running main action method: read_buildbot_config 15:23:31 INFO - Using buildbot properties: 15:23:31 INFO - { 15:23:31 INFO - "project": "", 15:23:31 INFO - "product": "firefox", 15:23:31 INFO - "script_repo_revision": "production", 15:23:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:23:31 INFO - "repository": "", 15:23:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:23:31 INFO - "buildid": "20170411134408", 15:23:31 INFO - "pgo_build": "False", 15:23:31 INFO - "basedir": "/builds/slave/test", 15:23:31 INFO - "buildnumber": 110, 15:23:31 INFO - "slavename": "t-yosemite-r7-0308", 15:23:31 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:23:31 INFO - "platform": "yosemite_r7", 15:23:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:23:31 INFO - "branch": "mozilla-aurora", 15:23:31 INFO - "repo_path": "releases/mozilla-aurora", 15:23:31 INFO - "revision": "1c79bcfea67971696983deeadb0bc22f7158dd1e", 15:23:31 INFO - "stage_platform": "macosx64", 15:23:31 INFO - "builduid": "4af659f7a9ec4217a592f8c4d41ceea9", 15:23:31 INFO - "slavebuilddir": "test" 15:23:31 INFO - } 15:23:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 15:23:31 INFO - Running post-action listener: _resource_record_post_action 15:23:31 INFO - [mozharness: 2017-04-11 22:23:31.444996Z] Finished read-buildbot-config step (success) 15:23:31 INFO - [mozharness: 2017-04-11 22:23:31.445131Z] Running download-and-extract step. 15:23:31 INFO - Running pre-action listener: _resource_record_pre_action 15:23:31 INFO - Running main action method: download_and_extract 15:23:31 INFO - mkdir: /builds/slave/test/build/tests 15:23: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')]} 15:23:31 INFO - trying https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 15:23:31 INFO - Downloading https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 15:23:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 15:23:32 INFO - Downloaded 1199 bytes. 15:23:32 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 15:23:32 INFO - Using the following test package requirements: 15:23:32 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 15:23:32 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:23:32 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 15:23:32 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:23:32 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 15:23:32 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:23:32 INFO - u'jsshell-mac.zip'], 15:23:32 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:23:32 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 15:23:32 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 15:23:32 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:23:32 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 15:23:32 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:23:32 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 15:23:32 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:23:32 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 15:23:32 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 15:23:32 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 15:23:32 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:23:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 15:23:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:23:32 INFO - Fetch https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 15:23:34 INFO - Expected file size: 51790253 15:23:34 INFO - Obtained file size: 51790253 15:23:50 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:23:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 15:23:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:23:50 INFO - Fetch https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 15:23:52 INFO - Expected file size: 51790253 15:23:52 INFO - Obtained file size: 51790253 15:24:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 15:24:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:24:08 INFO - Fetch https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 15:24:10 INFO - Expected file size: 14404424 15:24:10 INFO - Obtained file size: 14404424 15:24:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:10 INFO - trying https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:24:10 INFO - Downloading https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 15:24:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 15:24:12 INFO - Downloaded 74725957 bytes. 15:24:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:24:12 INFO - mkdir: /builds/slave/test/properties 15:24:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:24:12 INFO - Writing to file /builds/slave/test/properties/build_url 15:24:12 INFO - Contents: 15:24:12 INFO - build_url:https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 15:24:13 INFO - Running post-action listener: _resource_record_post_action 15:24:13 INFO - Running post-action listener: set_extra_try_arguments 15:24:13 INFO - [mozharness: 2017-04-11 22:24:13.296334Z] Finished download-and-extract step (success) 15:24:13 INFO - [mozharness: 2017-04-11 22:24:13.296498Z] Running populate-webroot step. 15:24:13 INFO - Running pre-action listener: _resource_record_pre_action 15:24:13 INFO - Running main action method: populate_webroot 15:24:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:24:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:24:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:24:13 INFO - '--tppagecycles', 15:24:13 INFO - '7'], 15:24:13 INFO - 'tests': ['tsvgm']}}, 15:24:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:24:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:24:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:24:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:24:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:24:13 INFO - 'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:24:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:24:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:24:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:24:13 INFO - 'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['damp', 'tps']}, 15:24:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:24:13 INFO - 'tests': ['damp', 'tps']}, 15:24:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['dromaeo_dom']}, 15:24:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:24:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['basic_compositor_video']}, 15:24:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:24:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['a11yr', 15:24:13 INFO - 'ts_paint', 15:24:13 INFO - 'tpaint', 15:24:13 INFO - 'sessionrestore', 15:24:13 INFO - 'sessionrestore_no_auto_restore', 15:24:13 INFO - 'tabpaint']}, 15:24:13 INFO - 'other-e10s': {'tests': ['a11yr', 15:24:13 INFO - 'ts_paint', 15:24:13 INFO - 'tpaint', 15:24:13 INFO - 'sessionrestore', 15:24:13 INFO - 'sessionrestore_no_auto_restore', 15:24:13 INFO - 'tabpaint']}, 15:24:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:24:13 INFO - 'ts_paint', 15:24:13 INFO - 'tpaint', 15:24:13 INFO - 'sessionrestore', 15:24:13 INFO - 'sessionrestore_no_auto_restore', 15:24:13 INFO - 'tabpaint']}, 15:24:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:24:13 INFO - 'ts_paint', 15:24:13 INFO - 'tpaint', 15:24:13 INFO - 'sessionrestore', 15:24:13 INFO - 'sessionrestore_no_auto_restore', 15:24:13 INFO - 'tabpaint']}, 15:24:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['a11yr', 15:24:13 INFO - 'ts_paint', 15:24:13 INFO - 'tpaint', 15:24:13 INFO - 'sessionrestore', 15:24:13 INFO - 'sessionrestore_no_auto_restore', 15:24:13 INFO - 'tabpaint']}, 15:24:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['a11yr', 15:24:13 INFO - 'ts_paint', 15:24:13 INFO - 'tpaint', 15:24:13 INFO - 'sessionrestore', 15:24:13 INFO - 'sessionrestore_no_auto_restore', 15:24:13 INFO - 'tabpaint']}, 15:24:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['tsvgx', 15:24:13 INFO - 'tsvgr_opacity', 15:24:13 INFO - 'tart', 15:24:13 INFO - 'tscrollx', 15:24:13 INFO - 'cart', 15:24:13 INFO - 'tsvg_static']}, 15:24:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:24:13 INFO - 'tsvgr_opacity', 15:24:13 INFO - 'tart', 15:24:13 INFO - 'tscrollx', 15:24:13 INFO - 'cart', 15:24:13 INFO - 'tsvg_static']}, 15:24:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:24:13 INFO - 'talos_options': ['--disable-e10s'], 15:24:13 INFO - 'tests': ['tp5o']}, 15:24:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:24:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:24:13 INFO - 'talos_options': ['--disable-e10s', 15:24:13 INFO - '--xperf_path', 15:24:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:24:13 INFO - 'tests': ['tp5n']}, 15:24:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:24:13 INFO - 'talos_options': ['--xperf_path', 15:24:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:24:13 INFO - 'tests': ['tp5n']}}, 15:24:13 INFO - 'talos.zip': {'path': '', 15:24:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:24:13 INFO - Running post-action listener: _resource_record_post_action 15:24:13 INFO - [mozharness: 2017-04-11 22:24:13.315463Z] Finished populate-webroot step (success) 15:24:13 INFO - [mozharness: 2017-04-11 22:24:13.315598Z] Running create-virtualenv step. 15:24:13 INFO - Running pre-action listener: _resource_record_pre_action 15:24:13 INFO - Running main action method: create_virtualenv 15:24:13 INFO - Creating virtualenv /builds/slave/test/build/venv 15:24:13 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 15:24:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:24:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:24:13 INFO - Using real prefix '/tools/python27' 15:24:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:24:14 INFO - Installing distribute.............................................................................................................................................................................................done. 15:24:17 INFO - Installing pip.................done. 15:24:17 INFO - Return code: 0 15:24:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:24:17 INFO - Reading from file tmpfile_stdout 15:24:17 INFO - Output received: 15:24:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:24:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:17 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 0x10db14b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db17768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7204e2820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, '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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:24:17 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 15:24:17 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 15:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:17 INFO - 'HOME': '/Users/cltbld', 15:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:17 INFO - 'LOGNAME': 'cltbld', 15:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:17 INFO - 'MOZ_NO_REMOTE': '1', 15:24:17 INFO - 'NO_EM_RESTART': '1', 15:24:17 INFO - 'PAGER': '/bin/cat', 15:24:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:17 INFO - 'PWD': '/builds/slave/test', 15:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:17 INFO - 'SHELL': '/bin/bash', 15:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:24:17 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:17 INFO - 'USER': 'cltbld', 15:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:17 INFO - 'XPC_FLAGS': '0x0', 15:24:17 INFO - 'XPC_SERVICE_NAME': '0', 15:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:17 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 15:24:17 INFO - Cleaning up... 15:24:17 INFO - Return code: 0 15:24:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:17 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 0x10db14b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db17768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7204e2820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, '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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:24:17 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 15:24:17 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 15:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:17 INFO - 'HOME': '/Users/cltbld', 15:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:17 INFO - 'LOGNAME': 'cltbld', 15:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:17 INFO - 'MOZ_NO_REMOTE': '1', 15:24:17 INFO - 'NO_EM_RESTART': '1', 15:24:17 INFO - 'PAGER': '/bin/cat', 15:24:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:17 INFO - 'PWD': '/builds/slave/test', 15:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:17 INFO - 'SHELL': '/bin/bash', 15:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:24:17 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:17 INFO - 'USER': 'cltbld', 15:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:17 INFO - 'XPC_FLAGS': '0x0', 15:24:17 INFO - 'XPC_SERVICE_NAME': '0', 15:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:17 INFO - Downloading/unpacking psutil>=3.1.1 15:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:24:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:21 INFO - Installing collected packages: psutil 15:24:21 INFO - Running setup.py install for psutil 15:24:21 INFO - building 'psutil._psutil_osx' extension 15:24:21 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 15:24:21 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 15:24:22 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 15:24:22 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 15:24:22 INFO - building 'psutil._psutil_posix' extension 15:24:22 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 15:24:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:24:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:24:22 INFO - ^ 15:24:22 INFO - 1 warning generated. 15:24:22 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 15:24:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:22 INFO - Successfully installed psutil 15:24:22 INFO - Cleaning up... 15:24:22 INFO - Return code: 0 15:24:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:24: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')]} 15:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24: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')]} 15:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db14b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db17768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7204e2820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, '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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:24:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:24:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:24:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:24:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:23 INFO - 'HOME': '/Users/cltbld', 15:24:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:23 INFO - 'LOGNAME': 'cltbld', 15:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:23 INFO - 'MOZ_NO_REMOTE': '1', 15:24:23 INFO - 'NO_EM_RESTART': '1', 15:24:23 INFO - 'PAGER': '/bin/cat', 15:24:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:23 INFO - 'PWD': '/builds/slave/test', 15:24:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:23 INFO - 'SHELL': '/bin/bash', 15:24:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:24:23 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:24:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:23 INFO - 'USER': 'cltbld', 15:24:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:23 INFO - 'XPC_FLAGS': '0x0', 15:24:23 INFO - 'XPC_SERVICE_NAME': '0', 15:24:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:24:23 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 15:24:23 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 15:24:23 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 15:24:23 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 15:24:23 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 15:24:23 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 15:24:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:24:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:24:26 INFO - Installing collected packages: mozsystemmonitor 15:24:26 INFO - Running setup.py install for mozsystemmonitor 15:24:26 INFO - Successfully installed mozsystemmonitor 15:24:26 INFO - Cleaning up... 15:24:26 INFO - Return code: 0 15:24:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:24: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')]} 15:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24: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')]} 15:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24: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', '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 0x10db14b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db17768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7204e2820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, '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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:24: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:24: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 jsonschema==2.5.1 15:24:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:24:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:26 INFO - 'HOME': '/Users/cltbld', 15:24:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:26 INFO - 'LOGNAME': 'cltbld', 15:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:26 INFO - 'MOZ_NO_REMOTE': '1', 15:24:26 INFO - 'NO_EM_RESTART': '1', 15:24:26 INFO - 'PAGER': '/bin/cat', 15:24:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:26 INFO - 'PWD': '/builds/slave/test', 15:24:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:26 INFO - 'SHELL': '/bin/bash', 15:24:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:24:26 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:24:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:26 INFO - 'USER': 'cltbld', 15:24:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:26 INFO - 'XPC_FLAGS': '0x0', 15:24:26 INFO - 'XPC_SERVICE_NAME': '0', 15:24:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:27 INFO - Downloading/unpacking jsonschema==2.5.1 15:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:24:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24:30 INFO - Downloading functools32-3.2.3-2.tar.gz 15:24:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:24:30 INFO - warning: no files found matching '*.txt' 15:24:30 INFO - no previously-included directories found matching 'build' 15:24:30 INFO - no previously-included directories found matching 'dist' 15:24:30 INFO - no previously-included directories found matching '.git*' 15:24:30 INFO - Installing collected packages: jsonschema, functools32 15:24:30 INFO - Running setup.py install for functools32 15:24:30 INFO - warning: no files found matching '*.txt' 15:24:30 INFO - no previously-included directories found matching 'build' 15:24:30 INFO - no previously-included directories found matching 'dist' 15:24:30 INFO - no previously-included directories found matching '.git*' 15:24:30 INFO - Successfully installed jsonschema functools32 15:24:30 INFO - Cleaning up... 15:24:30 INFO - Return code: 0 15:24:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10db14b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db17768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7204e2820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, '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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:24:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:24:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:24:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:30 INFO - 'HOME': '/Users/cltbld', 15:24:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:30 INFO - 'LOGNAME': 'cltbld', 15:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:30 INFO - 'MOZ_NO_REMOTE': '1', 15:24:30 INFO - 'NO_EM_RESTART': '1', 15:24:30 INFO - 'PAGER': '/bin/cat', 15:24:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:30 INFO - 'PWD': '/builds/slave/test', 15:24:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:30 INFO - 'SHELL': '/bin/bash', 15:24:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:24:30 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:24:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:30 INFO - 'USER': 'cltbld', 15:24:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:30 INFO - 'XPC_FLAGS': '0x0', 15:24:30 INFO - 'XPC_SERVICE_NAME': '0', 15:24:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:24:31 INFO - Cleaning up... 15:24:31 INFO - Return code: 0 15:24:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:24: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')]} 15:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24: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')]} 15:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10db14b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db17768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7204e2820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, '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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:24:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:24:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:31 INFO - 'HOME': '/Users/cltbld', 15:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:31 INFO - 'LOGNAME': 'cltbld', 15:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:31 INFO - 'MOZ_NO_REMOTE': '1', 15:24:31 INFO - 'NO_EM_RESTART': '1', 15:24:31 INFO - 'PAGER': '/bin/cat', 15:24:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:31 INFO - 'PWD': '/builds/slave/test', 15:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:31 INFO - 'SHELL': '/bin/bash', 15:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:24:31 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:31 INFO - 'USER': 'cltbld', 15:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:31 INFO - 'XPC_FLAGS': '0x0', 15:24:31 INFO - 'XPC_SERVICE_NAME': '0', 15:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:31 INFO - Downloading/unpacking blobuploader==1.2.4 15:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:33 INFO - Downloading blobuploader-1.2.4.tar.gz 15:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:24:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:24:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:35 INFO - Downloading docopt-0.6.1.tar.gz 15:24:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:24:35 INFO - Installing collected packages: blobuploader, requests, docopt 15:24:35 INFO - Running setup.py install for blobuploader 15:24:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:24:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:24:35 INFO - Running setup.py install for requests 15:24:35 INFO - Running setup.py install for docopt 15:24:36 INFO - Successfully installed blobuploader requests docopt 15:24:36 INFO - Cleaning up... 15:24:36 INFO - Return code: 0 15:24:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:36 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 0x10db14b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db17768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7204e2820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, '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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:24:36 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 15:24:36 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 15:24:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:24:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:36 INFO - 'HOME': '/Users/cltbld', 15:24:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:36 INFO - 'LOGNAME': 'cltbld', 15:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:36 INFO - 'MOZ_NO_REMOTE': '1', 15:24:36 INFO - 'NO_EM_RESTART': '1', 15:24:36 INFO - 'PAGER': '/bin/cat', 15:24:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:36 INFO - 'PWD': '/builds/slave/test', 15:24:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:36 INFO - 'SHELL': '/bin/bash', 15:24:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:24:36 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:24:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:36 INFO - 'USER': 'cltbld', 15:24:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:36 INFO - 'XPC_FLAGS': '0x0', 15:24:36 INFO - 'XPC_SERVICE_NAME': '0', 15:24:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:24:36 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-JwxBP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:24:36 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ZfoRWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:24:36 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-nElpK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:36 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-NH17AS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:36 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-U0AnB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:36 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-Pa6372-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:36 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-UcBHqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-X_gLq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-LsXtCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-kaZMPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-YLLGXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-fEXc4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-QiTxQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-LHH7X0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-f8QzoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:37 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-YThdIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:38 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-OUg7wZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:24:38 INFO - Running setup.py install for manifestparser 15:24:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:24:38 INFO - Running setup.py install for mozcrash 15:24:38 INFO - Running setup.py install for mozdebug 15:24:38 INFO - Running setup.py install for mozdevice 15:24:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:24:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:24:38 INFO - Running setup.py install for mozfile 15:24:38 INFO - Running setup.py install for mozhttpd 15:24:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:24:39 INFO - Running setup.py install for mozinfo 15:24:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:24:39 INFO - Running setup.py install for mozInstall 15:24:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:24:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:24:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:24:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:24:39 INFO - Running setup.py install for mozleak 15:24:39 INFO - Running setup.py install for mozlog 15:24:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:24:39 INFO - Running setup.py install for moznetwork 15:24:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:24:39 INFO - Running setup.py install for mozprocess 15:24:39 INFO - Running setup.py install for mozprofile 15:24:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:24:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:24:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:24:40 INFO - Running setup.py install for mozrunner 15:24:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:24:40 INFO - Running setup.py install for mozscreenshot 15:24:40 INFO - Running setup.py install for moztest 15:24:40 INFO - Running setup.py install for mozversion 15:24:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:24:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:24:40 INFO - Cleaning up... 15:24:40 INFO - Return code: 0 15:24:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:40 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')]} 15:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:40 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')]} 15:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:40 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 0x10db14b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db17768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7204e2820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, '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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:24:40 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 15:24:40 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 15:24:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:24:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:40 INFO - 'HOME': '/Users/cltbld', 15:24:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:40 INFO - 'LOGNAME': 'cltbld', 15:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:40 INFO - 'MOZ_NO_REMOTE': '1', 15:24:40 INFO - 'NO_EM_RESTART': '1', 15:24:40 INFO - 'PAGER': '/bin/cat', 15:24:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:40 INFO - 'PWD': '/builds/slave/test', 15:24:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:40 INFO - 'SHELL': '/bin/bash', 15:24:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:24:40 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:24:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:40 INFO - 'USER': 'cltbld', 15:24:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:40 INFO - 'XPC_FLAGS': '0x0', 15:24:40 INFO - 'XPC_SERVICE_NAME': '0', 15:24:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:24:40 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-oFQYcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:24:41 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)) 15:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:24:41 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-unu9ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:24:41 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-NReiOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:24:41 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)) 15:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:41 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-_FJYyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:41 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-QaGABt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:41 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)) 15:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:41 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-GjJcE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:41 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)) 15:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:41 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-za6JNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:41 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)) 15:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:41 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-Zhv6wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:41 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)) 15:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:41 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-bOs_AZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:41 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)) 15:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:41 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-qXHZyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:42 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)) 15:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ZMqytp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:42 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)) 15:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-v5RsL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-KOAici-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:42 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)) 15:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-ZXjy3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-vL6q3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:42 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)) 15:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-wfWq0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:24:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:42 INFO - Running setup.py (path:/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/pip-_jlWZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:42 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)) 15:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:24:42 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)) 15:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:24:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:24:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:45 INFO - Downloading blessings-1.6.tar.gz 15:24:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:24:45 INFO - Installing collected packages: blessings 15:24:45 INFO - Running setup.py install for blessings 15:24:45 INFO - Successfully installed blessings 15:24:45 INFO - Cleaning up... 15:24:45 INFO - Return code: 0 15:24:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:24:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:24:46 INFO - Reading from file tmpfile_stdout 15:24:46 INFO - Current package versions: 15:24:46 INFO - blessings == 1.6 15:24:46 INFO - blobuploader == 1.2.4 15:24:46 INFO - docopt == 0.6.1 15:24:46 INFO - functools32 == 3.2.3-2 15:24:46 INFO - jsonschema == 2.5.1 15:24:46 INFO - manifestparser == 1.1 15:24:46 INFO - mozInstall == 1.12 15:24:46 INFO - mozcrash == 1.0 15:24:46 INFO - mozdebug == 0.1 15:24:46 INFO - mozdevice == 0.49 15:24:46 INFO - mozfile == 1.2 15:24:46 INFO - mozhttpd == 0.7 15:24:46 INFO - mozinfo == 0.9 15:24:46 INFO - mozleak == 0.1 15:24:46 INFO - mozlog == 3.4 15:24:46 INFO - moznetwork == 0.27 15:24:46 INFO - mozprocess == 0.25 15:24:46 INFO - mozprofile == 0.28 15:24:46 INFO - mozrunner == 6.13 15:24:46 INFO - mozscreenshot == 0.1 15:24:46 INFO - mozsystemmonitor == 0.3 15:24:46 INFO - moztest == 0.8 15:24:46 INFO - mozversion == 1.4 15:24:46 INFO - psutil == 3.1.1 15:24:46 INFO - requests == 1.2.3 15:24:46 INFO - wsgiref == 0.1.2 15:24:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:46 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 0x10db14b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db17768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7204e2820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, '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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:24:46 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 15:24:46 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 15:24:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:24:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:46 INFO - 'HOME': '/Users/cltbld', 15:24:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:46 INFO - 'LOGNAME': 'cltbld', 15:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:46 INFO - 'MOZ_NO_REMOTE': '1', 15:24:46 INFO - 'NO_EM_RESTART': '1', 15:24:46 INFO - 'PAGER': '/bin/cat', 15:24:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:46 INFO - 'PWD': '/builds/slave/test', 15:24:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:46 INFO - 'SHELL': '/bin/bash', 15:24:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:24:46 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:24:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:46 INFO - 'USER': 'cltbld', 15:24:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:46 INFO - 'XPC_FLAGS': '0x0', 15:24:46 INFO - 'XPC_SERVICE_NAME': '0', 15:24:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:46 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)) 15:24:46 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)) 15:24:46 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)) 15:24:46 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)) 15:24:46 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)) 15:24:46 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)) 15:24:46 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)) 15:24:46 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)) 15:24:46 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)) 15:24:46 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)) 15:24:46 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)) 15:24:46 INFO - Cleaning up... 15:24:46 INFO - Return code: 0 15:24:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:46 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 0x10db14b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db17768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7204e2820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, '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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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 15:24:46 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 15:24:46 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 15:24:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:24:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:46 INFO - 'HOME': '/Users/cltbld', 15:24:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:46 INFO - 'LOGNAME': 'cltbld', 15:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:46 INFO - 'MOZ_NO_REMOTE': '1', 15:24:46 INFO - 'NO_EM_RESTART': '1', 15:24:46 INFO - 'PAGER': '/bin/cat', 15:24:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:46 INFO - 'PWD': '/builds/slave/test', 15:24:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:46 INFO - 'SHELL': '/bin/bash', 15:24:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:24:46 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:24:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:46 INFO - 'USER': 'cltbld', 15:24:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:46 INFO - 'XPC_FLAGS': '0x0', 15:24:46 INFO - 'XPC_SERVICE_NAME': '0', 15:24:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:24:46 INFO - Cleaning up... 15:24:46 INFO - Return code: 0 15:24:46 INFO - Running post-action listener: _resource_record_post_action 15:24:46 INFO - Running post-action listener: _start_resource_monitoring 15:24:46 INFO - Starting resource monitoring. 15:24:46 INFO - [mozharness: 2017-04-11 22:24:46.570422Z] Finished create-virtualenv step (success) 15:24:46 INFO - [mozharness: 2017-04-11 22:24:46.571370Z] Running install step. 15:24:46 INFO - Running pre-action listener: _resource_record_pre_action 15:24:46 INFO - Running main action method: install 15:24:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:24:46 INFO - Reading from file tmpfile_stdout 15:24:46 INFO - Detecting whether we're running mozinstall >=1.0... 15:24:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:24:46 INFO - Reading from file tmpfile_stdout 15:24:46 INFO - Output received: 15:24:46 INFO - Usage: mozinstall [options] installer 15:24:46 INFO - Options: 15:24:46 INFO - -h, --help show this help message and exit 15:24:46 INFO - -d DEST, --destination=DEST 15:24:46 INFO - Directory to install application into. [default: 15:24:46 INFO - "/builds/slave/test"] 15:24:46 INFO - --app=APP Application being installed. [default: firefox] 15:24:46 INFO - mkdir: /builds/slave/test/build/application 15:24:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:25:07 INFO - Reading from file tmpfile_stdout 15:25:07 INFO - Output received: 15:25:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:25:07 INFO - Running post-action listener: _resource_record_post_action 15:25:07 INFO - [mozharness: 2017-04-11 22:25:07.079535Z] Finished install step (success) 15:25:07 INFO - [mozharness: 2017-04-11 22:25:07.079670Z] Running run-tests step. 15:25:07 INFO - Running pre-action listener: _resource_record_pre_action 15:25:07 INFO - Running main action method: run_tests 15:25:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:25:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:25:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:25:07 INFO - 2017-04-11 15:25:07.094 screenresolution[1622:7344] starting screenresolution argv=screenresolution get 15:25:07 INFO - 2017-04-11 15:25:07.096 screenresolution[1622:7344] Display 0: 1600x1200x32@60 15:25:07 INFO - 2017-04-11 15:25:07.102 screenresolution[1623:7347] starting screenresolution argv=screenresolution list 15:25:07 INFO - Available Modes on Display 0 15:25:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:25:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:25:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:25:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:25:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:25:07 INFO - Intel Iris: 15:25:07 INFO - Chipset Model: Intel Iris 15:25:07 INFO - Type: GPU 15:25:07 INFO - Bus: Built-In 15:25:07 INFO - VRAM (Dynamic, Max): 1536 MB 15:25:07 INFO - Vendor: Intel (0x8086) 15:25:07 INFO - Device ID: 0x0a2e 15:25:07 INFO - Revision ID: 0x0009 15:25:07 INFO - Displays: 15:25:07 INFO - Display: 15:25:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:25:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:25:07 INFO - Display Serial Number: 1600x1200 60 15:25:07 INFO - Main Display: Yes 15:25:07 INFO - Mirror: Off 15:25:07 INFO - Online: Yes 15:25:07 INFO - Rotation: Supported 15:25:07 INFO - Return code: 0 15:25:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:25:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:25:07 INFO - Python 2.7.3 15:25:07 INFO - Return code: 0 15:25:07 INFO - grabbing minidump binary from tooltool 15:25: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')]} 15:25:07 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 0x10d8f2d30>, '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 0x10d819db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d8e7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:25:07 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 15:25:07 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 15:25:07 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 15:25:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:25:07 INFO - Return code: 0 15:25:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:25:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:25:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:25:07 INFO - ENV: RUST_BACKTRACE is now 1 15:25:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:25:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:25:07 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/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--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 15:25:07 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/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0308 --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 15:25:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HDszcZ2Hjk/Render', 15:25:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:07 INFO - 'HOME': '/Users/cltbld', 15:25:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:07 INFO - 'LOGNAME': 'cltbld', 15:25:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:25:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:07 INFO - 'MOZ_NO_REMOTE': '1', 15:25:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:25:07 INFO - 'NO_EM_RESTART': '1', 15:25:07 INFO - 'PAGER': '/bin/cat', 15:25:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:07 INFO - 'PWD': '/builds/slave/test', 15:25:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:25:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:07 INFO - 'RUST_BACKTRACE': '1', 15:25:07 INFO - 'SHELL': '/bin/bash', 15:25:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cKNqW3CPP6/Listeners', 15:25:07 INFO - 'TMPDIR': '/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/', 15:25:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:07 INFO - 'USER': 'cltbld', 15:25:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:07 INFO - 'XPC_FLAGS': '0x0', 15:25:07 INFO - 'XPC_SERVICE_NAME': '0', 15:25:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:07 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/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0308', '--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 15:25:07 INFO - mozversion application_buildid: 20170411134408 15:25:07 INFO - mozversion application_changeset: 1c79bcfea67971696983deeadb0bc22f7158dd1e 15:25:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:25:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:25:07 INFO - mozversion application_name: Firefox 15:25:07 INFO - mozversion application_remotingname: firefox-dev 15:25:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:25:07 INFO - mozversion application_vendor: Mozilla 15:25:07 INFO - mozversion application_version: 54.0a2 15:25:07 INFO - mozversion platform_buildid: 20170411134408 15:25:07 INFO - mozversion platform_changeset: 1c79bcfea67971696983deeadb0bc22f7158dd1e 15:25:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:25:07 INFO - mozversion platform_version: 54.0a2 15:25:07 INFO - using testdate: 1491949507 15:25:07 INFO - actual date: 1491949507 15:25:07 INFO - starting webserver on 'localhost:49238' 15:25:07 INFO - SUITE-START | Running 2 tests 15:25:07 INFO - TEST-START | dromaeo_css 15:25:07 INFO - operating with platform_type : mac_ 15:25:07 INFO - Initialising browser for dromaeo_css test... 15:25:07 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmp95p1jX/profile http://localhost:49238/getInfo.html) 15:25:10 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 15:25:10 INFO - PROCESS | 1633 | colorDepth:24 15:25:10 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 15:25:10 INFO - PROCESS | 1633 | __metrics 15:25:10 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:25:10 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:25:11 INFO - TEST-INFO | 1633: exit 0 15:25:11 INFO - Browser initialized. 15:25:11 INFO - Running cycle 1/1 for dromaeo_css test... 15:25:11 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmp95p1jX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:25:23 INFO - PROCESS | 1638 | RSS: Main: 215105536 15:25:23 INFO - PROCESS | 1638 | 15:28:27 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 15:28:29 INFO - PROCESS | 1638 | RSS: Main: 338509824 15:28:29 INFO - PROCESS | 1638 | 15:30:13 INFO - PROCESS | 1638 | ************************* 15:30:13 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 15:30:13 INFO - PROCESS | 1638 | 15:30:13 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:30:13 INFO - PROCESS | 1638 | Full stack: 15:30:13 INFO - PROCESS | 1638 | ************************* 15:30:13 INFO - PROCESS | 1638 | ************************* 15:30:13 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 15:30:13 INFO - PROCESS | 1638 | 15:30:13 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:30:13 INFO - PROCESS | 1638 | Full stack: 15:30:13 INFO - PROCESS | 1638 | ************************* 15:30:13 INFO - PROCESS | 1638 | ************************* 15:30:13 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 15:30:13 INFO - PROCESS | 1638 | 15:30:13 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:30:13 INFO - PROCESS | 1638 | Full stack: 15:30:13 INFO - PROCESS | 1638 | ************************* 15:30:13 INFO - PROCESS | 1638 | ************************* 15:30:13 INFO - PROCESS | 1638 | A coding exception was thrown and uncaught in a Task. 15:30:13 INFO - PROCESS | 1638 | 15:30:13 ERROR - PROCESS | 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 15:30:13 INFO - PROCESS | 1638 | Full stack: 15:30:13 INFO - PROCESS | 1638 | ************************* 15:31:32 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 15:31:32 INFO - PROCESS | 1638 | RSS: Main: 351272960 15:31:32 INFO - PROCESS | 1638 | 15:34:35 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 15:34:35 INFO - PROCESS | 1638 | RSS: Main: 322736128 15:34:35 INFO - PROCESS | 1638 | 15:37:38 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 15:37:38 INFO - PROCESS | 1638 | RSS: Main: 345710592 15:37:38 INFO - PROCESS | 1638 | 15:40:42 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 15:40:43 INFO - PROCESS | 1638 | RSS: Main: 313077760 15:40:43 INFO - PROCESS | 1638 | 15:43:46 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 15:43:46 INFO - PROCESS | 1638 | RSS: Main: 360927232 15:43:46 INFO - PROCESS | 1638 | 15:43:46 INFO - PROCESS | 1638 | __start_tp_report 15:43:46 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 15:43:46 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 15:43:46 INFO - PROCESS | 1638 | |i|pagename|runs| 15:43:47 INFO - PROCESS | 1638 | |0;dojo.html;3378.983105084474;3386.4243078676623;3394.117529442344;3408.0798184490186;3438.845251963663;5847.064469684851;5851.561132915027;5886.676232807194;5896.911546326808;5927.73325200366;4909.680870743399;5885.941140588593;5968.074948382997;5969.5821292509545;5978.760849566012;5277.12927366985;5308.867278318035;5315.441878602749;5346.010987967238;5442.156465747816;5210.76551555181;5236.685798852062;5401.756920938547;5413.675179489223;5423.593230507708;4410.845620403286;5244.239585260135;5355.491228333302;5412.4587541245955;5462.890742185154;5408.972955135238;5412.485813847678;5416.9729151353995;5421.430749771296;5456.0997435423105;5256.2904007959205;5365;5368.436314187033;5396.649217800869;5427.864303392407;5661.179129026309;5947.286325640907;5952.940470595282;5990.9101363479585;6032.4570788629235;6192.411720886508;6201.658908760016;6208.50331973441;6210.223722034746;6234.283057448406;6291.591046581958;6341.397567231105;6347.936520634827;6356;6361.982082866719;6009.939900601025;6069.969650151721;6078.665673387962;6090.177825993523;6090.482309003695;6143.310589587899;6185.876282474325;6254.874902501924;6498.350164983463;6521.282658907517;5210.6613070150315;6164;6166.568340216142;6180.598261113013;6197.845053873689;6295.18162638854;6315.747370105144;6319.494440444845;6322.33615470369;6324.367563243731;6099.542534309944;6252.218472690914;6260.7495700171485;6262.749490020348;6263.060540918898;5736.541076713853;6148.292946311142;6182.969085154546;6214.720337584819;6232.0963460298;5121.359830021247;5127.64106512548;5130.589552835764;5140.357455318085;5155.458676838953;4546.681732278775;4551.453825540956;4552.339910712928;4563.247064234364;4576.931346029813;5725.227094342211;5769.336526299446;5914.733836977346;5976.103584462278;5993.670348130895;5597.272354593877;6137.1101190328045;6232.220972378453;6311.431971122529;6334.208223972004;5962.37395073511;5979.880402392015;5983.371745966698;5992.910106348409;6037.637741735422;5576.079946808731;5582.106862901916;5591.524720398812;5593.188987596775;5593.272874526286;6228.377162283736;6247.156633854554;6452.645104519296;6545.083688283549;6571.112899758471;6264.718087685974;6296.905546416899;6318.336574659595;6319.210098737658;6326.905096423466;6543.70553325092;6572.901406479003;6574.638394888327;6583.144191255106;6590.9670451647435;6131.560175786138;6166.845828854135;6188.783392581238;6224.62652240867;6233.657148856856;6286.717097730704;6304.148939893055;6306.684665766785;6309.968450157903;6315.747370105328;6097.207363042953;6140.3245642978345;6142.775043102407;6200.100985357189;6256.155419018374;6138.508919286535;6148.78479253224;6175.722092505758;6193.473554747717;6204.534659900616;5946.940530594638;5985.850353741192;6007.158997740233;6143.6928153593035;6185.659788711664;6030.939690603038;6249.093881387264;6267.435930766253;6275.995840665471;6284.9001424751405;6191.752329906753;6208.348123446973;6218.160548325918;6221.3156552780065;6225.62646241227 15:43:47 INFO - PROCESS | 1638 | |1;ext.html;786.6145588661556;791.7901756034651;874.3355050161875;876.1545108969843;967.7870868408947;37732.43401348979;38527.42208866869;40150.19699606006;40156.19687606246;40380.79809600955;38392;38595.22809543808;38625.61374386255;38666.226675466474;38950.610493895125;7425.517341372793;7601.6579253933705;7864.764057078282;7949.40379471539;7977.962864827593;11090.889091109066;11370.829437558443;11371.033462155685;11403.3728144952;11505;11353.772924541505;11388.829167562493;11398.886011139906;11404.942975285154;11436.142289328334;7308.707651693926;7478.700851965915;10963.50664220112;11307.26502777321;11321.716957076098;11042.668719938334;11117.110631149488;11127.833082503768;11131.332120072822;11184.161187910859;39311.21377572461;39485.802570987245;39553.40669889954;39605.40591891124;39615;3890.8638197662945;3892.4939757831303;3894.4937158169255;3905.941410878839;3919.510061242345;27204.59193112105;27237.727622723716;27296.590551141748;29358.119256422342;29432.55851162234;3014.442328169274;3151.6690747471644;3221.629512606057;3253.056613582058;3258.201740573551;352580;354387;354604;355121;355164;41969.37045944313;42605;50136.99726005532;51918;52112.47887521076;52406;52579.73710131425;54931;55042;55095.72452137794;14218.715625687428;14244.216568088646;14284.642883927985;14286.071405358618;14306.427742890375;12181.756364872654;12189.268643881396;12192.756144877052;12218.938905305595;12219.75560488803;11004.504797284142;13357.66605834862;13394.665133371744;13472.461101556024;13482.93258533701;5492.368377636543;5522.972385138049;5942.168096466498;5997.370276121032;6006.369331220247;879.8108406692592;913.5067063785614;930.385945276128;959.5346257065318;959.7312752429331;27690.723092768814;27998;28124.43751125007;28706.425871482454;28727.99452019211;904.8506996345661;980.8087422952456;1035.880873699519;1056.572088304238;1135.9545618175346;12557.497700091893;12712.237265764084;12715.364231788373;12734.172278802034;12744.553940612033;515210;518082;518321;518505;519676;46242.30636540455;46276;46347.30479042817;46365;46469.30296045562;1358.2733237717773;1403.4596680278166;1416.0937000319598;1418.858114188573;1419.2619837684347;18674.81325186785;21352.03915823823;22579;23016;23074.846257686713;40042.79978600088;40234.39648405218;40272.597274026884;40287.3956890641;40315.395269071574;481352.18647813075;482871;482953;483013;483192;24082.397940050938;24527;29368;29512.96704615414;29639.703602964557;29847;35067.82466087653;37926.810365948;38234.42648360221;39594;26907.461850762484;27066;27636.032738854847;28303.15090547287;28372.14883553497;26990.35630189899;27799.30501737473;27979.160625181277;28228.85885570559;28278.43443131085;31579.526307104952;31671.683283166873;32069;32295;32415.35169296648;27992;29077;29566.704332956397;29747.70252297536;30334.241643959078;22045.779542205015;23098.653520197546;23347.182848600216;23709.88145059264;23918.641220382055 15:43:47 INFO - PROCESS | 1638 | |2;jquery.html;387.14827379764523;405.0885507607884;422.7484646635253;436.54381171675595;447.16157205240177;10617.575296988121;14533.854661453395;20423.591528169447;20548.8972555137;21073;21199.470013249676;21361.35915922525;21835.781642183574;21846.344609661737;22164.224252151176;6297.370262973712;6351.079093531435;6390.424861762441;6535.0850880876715;6536.215654121512;4077.22532718784;4298.957010429887;6370.458511026545;6417.326180751024;6472.158619379474;7018.4385249180195;7274.236205198457;7432.888506672404;7496.250374962515;7504.5872477013745;6965.651717414109;7260.891086633705;7424.925750742477;7434.516756410816;7439.702411903518;6923.169219693669;7085.468589855782;7154.105736782903;7177.6770045348085;7236.49344545882;23794.929228184774;24583.016679332806;24723.887425065735;24805.62791558128;24999.87500062488;2844.9146525604265;2853.1583182960867;2881.538953767408;2897;2933;14447.927760361235;15304;15513.146776927264;15688.843111568853;15995.92002039994;1912.8852268863914;1964.1161477335113;1964.2143142742875;1967.498287936567;1972.619166583383;110681.44659276733;137368.31315843455;159612;168722.1563892185;170093;15877.603059923364;18587;18942.715859262124;19291.42125736202;19351.4194574163;29445.705542944725;30063.398732025675;30127.548086778716;30230.54654180189;30289.697103029128;9604.279679024101;9787.070228328297;9822.594960061491;9838.212942964547;9903.603855845686;8069.913002401328;8075.394345424117;8075.959620201979;8219.917800822035;8229.25936665691;8718.997315308694;8865.867011994826;8922.018577956549;8958.507282099547;8959.865602015974;3233.337165880989;3645.484615615217;3704.1665625234104;3737.383331750231;3752.5309336332957;768.9653965571563;873.9781505462287;888.8888888888889;903.8746760283489;926.749777560055;19132.61734765297;19940.60118797645;20526.589468210852;20551.691724624143;20595.691064634044;896.6368620708623;925.5279807298326;926.6941909169958;983.0857302708407;991.9057689519484;10083.848742268872;10328.122109620615;10368.118709909742;10528.842067368996;10533.789324213472;731589;790224;790238;804429;807930;144507;149758.25120874326;150812;151878;152413;1193.8209268609778;1285.3894400160036;1321.900857435677;1350.8108864759138;1355.4849157320155;14442.555744425474;17711.64576708433;19065.523361915508;19146.234150634376;19257.711134332716;61371.69314153401;62177.75644487175;62834;63041.684791575746;63709.68145159245;79081;89082;90090.54954725184;90988.54505727428;92235;7683.8529673599305;11436.485358159069;11658.18392712502;11713.297202167898;11813.11401644863;12779.80830287565;12806.67983300425;12810.935945320214;12828.973682105596;13181.472741090633;10829.620963266249;10852.620158294421;11134;11297.26567773092;11338.60314888975;9968.601255949681;10044.497775111362;10080.596776129167;10121.443320617436;10375.481225938824;11121.833172502256;11175.664730058112;11377.943110284727;11436.599719009795;11499.425028748361;9385.859212111687;11162.939520745516;11269.323840569592;11716.589919352782;11720.296782193096;10782.568697252022;10990.230683852373;11002.174836887105;11003.449827508432;11256.043236325004 15:43:47 INFO - PROCESS | 1638 | |3;mootools.html;1554.8833837462187;1555.3312075807412;1577.5503981365312;1583.4457939721099;1588.0630428047457;10729.463526823656;11314.264572802762;11324.660260192197;11329.263597866133;11330.376829274397;9424.764380890463;9733.610655573768;9853.704388868346;9866.851997220047;9868.407895526256;2297.2878407693624;2332.9533409331802;2334.6381310896754;2335.9065637374483;2336.88315584221;2264.071730590458;2322.1175953137868;2381.226101517005;2384.3085505203558;2385.866713311181;2315.8610483371053;2371.8221133415063;2394.0184524345023;2406.927792166238;2408.963865542018;2334.8949297281492;2337.906483740648;2363.9290821275304;2366.1363602285214;2370.940726481852;2331.9300420987397;2348.7064116985375;2358.3514533503426;2371.336025912747;2371.6442533619925;10834.837477437844;11146.442677866155;11149.386783726892;11159.051480624157;11159.107271418266;5575.888482230333;5740.54075673945;5743.224664670258;5745.224394706703;5758.942410575883;3220.8872689455757;3313.4035873542753;3385.492176173569;3480.9477857832153;3553.751237413382;1154.7863645225575;1156.6298784388998;1173.3312012153076;1199.460242890694;1203.7592481503734;13444.193348399127;13836.723265534734;13994.30028498571;15284.847151528453;15455.30451129702;4120.114175452232;4229.555896630809;4241.512226093977;4250.3199488081755;4524.163029839425;10310.948445257725;10502.894971050344;11509.76980460386;11532.365719885487;11590.304581725124;5622.690752008597;5630.577706672016;5632.155176723442;5632.3804381518;5633.295838020247;4224.134052519226;4494;5218.6868787872845;5258.868528286824;5259.605529585297;3706.870259540903;3829.425586162098;3851.422286657024;3896.3181443247886;3900.7269491135917;996.2329006664828;997.6258902911409;1010.9494525273707;1013.964511242103;1021.0351218098825;891.7815135291766;912.4616476279027;930.0745757970727;937.2595649436869;967.9419234845932;6245.687715614292;6543.803685889431;6567;6653.2681405045405;6661.800145995628;999.5402115027024;1001.8797744270735;1007.2898606482412;1009.1724785675675;1017.4404077757204;4097.057676734368;4110.670357010831;4183.707140500136;4210.621044105984;4258.638015768633;13144.342782860818;13721.794173087412;13748.312584370942;13780.035397522077;13800.378982945791;12958.416871240817;13426.597202083953;13660;13675.974301927396;13687.931560342135;9831.311808173503;10065.59737610502;10088.848667269996;10101.797964040677;10103.242256830767;5839.795607153729;6545.672716364258;6633.734650613921;6848.315168483112;6895.5173137879865;11768.293902365886;12353.135280530636;12366;12370.505179792708;12382.876171238173;9989.900100998897;10763.3541987481;11196.272242304223;12305;12342.81485777732;9241.676541321021;9485.33602647808;9488.90877549063;9500.42997420157;9506.762330941781;8743.956280218557;10162.745931351776;10365.429901355497;10367.94816025915;10372.222083343628;8773.517456539952;8808.383413161273;8905.821883562423;8911.73264802057;9063.954680226556;8585.82828343442;8602.096779838277;8625.525596092275;8699.521526316114;8757.430767000125;7869.803254918673;7908.485948413565;7913.089994650689;7916.564588947663;7928.524288542706;5732.254806875079;5767.163761254678;5825.533957283492;5940.524758019262;6004.249468816398;5278.313819203479;5674;5813.709314534341;5891.234139562001;5983.282006159288 15:43:47 INFO - PROCESS | 1638 | |4;prototype.html;362.8802495176592;373.58532029447315;380.4065657574186;382.82390100553744;386.0522417465123;4728.27417992432;4864.829730959417;5125.666831655944;5158.9742051289695;5190.455002224769;4308.2891322931755;4445.77771111444;4767.570918617333;5014.097462456765;5079.492050794928;3654.378755611539;4199;4201.096764195698;4223.535411104776;4234.74591524508;3519.454484554898;3630.4933452617133;4256.318988961767;4256.957430425702;4264.360345948117;3671.504346913159;3928.685705143582;4103.897402564945;4151.816732231315;4279.807408666617;3915.9818447203647;3988.6011398860173;4166.66666666669;4171.728837625545;4178.456800615931;3865.5747867734526;3894.123822140024;3894.883153505399;3900.395438707005;3904.3362628353248;4208.832417779798;4656.674032817704;4969.049605563586;5013.548780609763;5029.924551131736;4563.543645635443;4606.907861842745;4758.524147585248;4857.187108120144;4900.975495122537;4768.666193366465;5381.170402063317;5381.973090134564;5385.649932754359;5441.619086663935;4248.362745588156;4345.9348109778375;4355.651547876162;4397.428334316518;4423.60187583119;4960.305557221992;5125.948740512585;5328.801019770598;5338.679679219259;5498.725063746797;4040.5555388907196;4069;4173.332266837352;4328.653707703376;4484.573965473275;3836.7938435399756;4246.554111818276;4503.009337945647;4530.773461326987;4551.817927282872;4839.491853355417;4859.829905953275;4872.853814385574;4963.553280204799;5015.899682006339;4517.909641807146;5380.354357477127;5389.353277606712;5415.83752487426;5426.941746359422;1497.707946950356;1542.550002997179;1632.2899538700703;1682.3102527963722;1711.5207741832057;1306.9019823513274;1383.280694039094;1385.6535866033491;1403.3370456009661;1404.03121845926;4419;4523.886902827456;4701.976490117528;4881.145799485076;4991.929963072685;4854.892868564122;4904.828331008398;5223.973880130651;5583.609147359727;5608.914327954215;4370.453693288339;4447.851997152226;4517.6160026397465;5031.295618613397;5149.098907691139;2416.885225427751;2466.8519888806727;2710.793696804942;2829.717028297154;2865.821975298788;5485.592209360965;5578.581606379537;5810.709464526757;5845.298564172326;5850.414958504116;4757.191277482836;5280.709560974106;5502.30387110321;5746.655200687972;5749.798757043483;4845.2732090186755;5494.648264558696;5547.278853749068;5561.6663000219305;5857.974854400548;5227.529522343008;5246.842594722164;5358.705271210121;5592.608517403905;5615.052097648767;4973.950260497349;5118.97440512795;5200.323957885451;5232.476752324737;5536.667799932017;4883.096627123993;4931.457539670705;5380.408155103014;5555.72221388937;5782.653040817564;3578.481120237603;3893.7274390792372;4406.82372705101;4438.511763705925;4442.222611043119;5158.45836187195;5313.946860531345;5394.730263486731;5595.328560572757;5633.507120612959;4465.727267728682;4469.396631454712;4949.777260023236;4963.808685915282;5318.867028324323;5042.4705405931845;5051.898962020812;5124.7950081996305;5203.92194117081;5393.865153371197;4705.64707646921;4721.669483136285;5269.947300526946;5310.946890531045;5332.78709819254;4986.002799440054;5007.24891266313;5117.462666419972;5315.441878602691;5439.456054394529;4741.905161896674;4865.56209941107;5331.573474121983;5369.033573956725;5379.273798037215 15:43:47 INFO - PROCESS | 1638 | |5;yui.html;719.6114098386871;728.11170372146;771.4676872957659;820.3765138494745;823.4441751817524;15163.772543411844;15975.600609984727;15992.600184995352;16003.679926401479;16010.279537420814;14721.484748033794;14727.26363681817;14744.96785225032;14762.261886905666;14888.032277901955;884.7832281091141;916.0747644878654;932.3147486597345;937.0629370629371;946.7585765629755;937.6249500199906;941.4539567051094;941.5857022909931;942.1850099663776;942.9434233945951;978.6721448314787;978.7944531648362;979.4857699707675;979.8432250839867;980.7989362180772;861.3927181337142;974.5614473486959;975.492743773241;975.9414435133915;976.998576459135;700.8843540815759;709.6203531110831;725.7750097469801;726.0706295941204;726.6076318787848;15544.989575677544;15947.601309967227;15972.920135399365;16124.516264512084;16363.672726545403;11903.761924761542;12282;14057.297135143304;14313.777435656402;14336.498222562264;11506.712332191679;11709.414529273587;11741.589044383405;12028.218165819282;12057.276563406136;2959.644842618878;3583.5878873929573;3598.514200582914;3717.739758216926;3726.366517691999;116827.41586292099;118883.2167517488;119114.40442797817;120500;122326.3883680576;10816.513256903458;10883.346999179916;11038.944805276084;11056.51228473587;11064.446777661085;22564.774352256594;23153.958071886806;23166.53666926652;23189.188378406674;23430.414239644146;7763.417743669247;7789.415793815487;7789.766307010798;7848.411369147223;8006.71976480832;4223.472065991751;4315.697901146889;4426.778661066934;4516.209663308908;4536.591706746342;3472.409690352646;3542.734294927839;3554.5201397810965;3569.268299998546;3575.856965721342;658.8517583543664;678.1895634716565;683.3030309084805;696.8571442854203;704.2288693880193;668.762589280808;684.9041134241111;698.5738699393366;712.7042277454901;733.6478490324674;9434.198093162158;9479.289053321028;9695.884973228029;10427.113695335982;10466.895331046744;682.1132527713952;683.9931600684029;727.2364017781414;731.8536292741474;749.2919191364109;4319.546447623017;4939.0121975605025;5101.413337466165;5102.234664800236;5110.97444512775;89335;106762.86474270471;110194.44902775435;111377.88622113837;113706;93633.5318323404;95944.52027739956;96206.51896740471;97816.53275200877;100173;81482;81897.18102818895;84034.15965840386;84043;85433.57283213669;8225.712100076707;9833.557489913113;9898.455584942898;9938.354006989523;9946.204303655546;43296.91757705957;46139.53860461487;53675;53685.731571341894;54328.72835635796;56155.43844561665;69078;70265;71223.64388178026;72445;10826.837597435888;11039.779204416027;11465.598704045318;11594.898484643945;11687.88312116902;12113.42644459396;12630.494780209056;13114.868851311365;13322.40049197769;13672.93163534176;11767.705807354885;12391.690207744517;12418.316992565495;12480.687982800502;12610.243385396905;11602.6519204424;11968;12027.158098933342;12249.326287054297;12424.316662583284;11849.822252666389;11952.76094478123;11994.040476762013;12339.568115115928;12365.258084514959;8171.223733745284;8740.868886966828;8856.822863542564;9160.175584197188;9212.815743685223;8621.91378086211;9351.85972210431;10161.949190254001;10221.898509368732;10275.383476991405 15:43:47 INFO - PROCESS | 1638 | __end_tp_report 15:43:47 INFO - PROCESS | 1638 | __start_cc_report 15:43:47 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,1390 15:43:47 INFO - PROCESS | 1638 | __end_cc_report 15:43:47 INFO - PROCESS | 1638 | __startTimestamp1491950626994__endTimestamp 15:43:47 INFO - PROCESS | 1638 | 15:43:47 INFO - PROCESS | 1638 | ------- Summary: start ------- 15:43:47 INFO - PROCESS | 1638 | Number of tests: 6 15:43:47 INFO - PROCESS | 1638 | 15:43:47 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5849.33 Median:6097.21 stddev:639.68 (10.5%) stddev-sans-first:611.58 15:43:47 INFO - PROCESS | 1638 | Values: 3379.0 3386.4 3394.1 3408.1 3438.8 5847.1 5851.6 5886.7 5896.9 5927.7 4909.7 5885.9 5968.1 5969.6 5978.8 5277.1 5308.9 5315.4 5346.0 5442.2 5210.8 5236.7 5401.8 5413.7 5423.6 4410.8 5244.2 5355.5 5412.5 5462.9 5409.0 5412.5 5417.0 5421.4 5456.1 5256.3 5365.0 5368.4 5396.6 5427.9 5661.2 5947.3 5952.9 5990.9 6032.5 6192.4 6201.7 6208.5 6210.2 6234.3 6291.6 6341.4 6347.9 6356.0 6362.0 6009.9 6070.0 6078.7 6090.2 6090.5 6143.3 6185.9 6254.9 6498.4 6521.3 5210.7 6164.0 6166.6 6180.6 6197.8 6295.2 6315.7 6319.5 6322.3 6324.4 6099.5 6252.2 6260.7 6262.7 6263.1 5736.5 6148.3 6183.0 6214.7 6232.1 5121.4 5127.6 5130.6 5140.4 5155.5 4546.7 4551.5 4552.3 4563.2 4576.9 5725.2 5769.3 5914.7 5976.1 5993.7 5597.3 6137.1 6232.2 6311.4 6334.2 5962.4 5979.9 5983.4 5992.9 6037.6 5576.1 5582.1 5591.5 5593.2 5593.3 6228.4 6247.2 6452.6 6545.1 6571.1 6264.7 6296.9 6318.3 6319.2 6326.9 6543.7 6572.9 6574.6 6583.1 6591.0 6131.6 6166.8 6188.8 6224.6 6233.7 6286.7 6304.1 6306.7 6310.0 6315.7 6097.2 6140.3 6142.8 6200.1 6256.2 6138.5 6148.8 6175.7 6193.5 6204.5 5946.9 5985.9 6007.2 6143.7 6185.7 6030.9 6249.1 6267.4 6276.0 6284.9 6191.8 6208.3 6218.2 6221.3 6225.6 15:43:47 INFO - PROCESS | 1638 | 15:43:47 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:57308.94 Median:31870.34 stddev:121856.11 (382.3%) stddev-sans-first:122124.05 15:43:47 INFO - PROCESS | 1638 | Values: 786.6 791.8 874.3 876.2 967.8 37732.4 38527.4 40150.2 40156.2 40380.8 38392.0 38595.2 38625.6 38666.2 38950.6 7425.5 7601.7 7864.8 7949.4 7978.0 11090.9 11370.8 11371.0 11403.4 11505.0 11353.8 11388.8 11398.9 11404.9 11436.1 7308.7 7478.7 10963.5 11307.3 11321.7 11042.7 11117.1 11127.8 11131.3 11184.2 39311.2 39485.8 39553.4 39605.4 39615.0 3890.9 3892.5 3894.5 3905.9 3919.5 27204.6 27237.7 27296.6 29358.1 29432.6 3014.4 3151.7 3221.6 3253.1 3258.2 352580.0 354387.0 354604.0 355121.0 355164.0 41969.4 42605.0 50137.0 51918.0 52112.5 52406.0 52579.7 54931.0 55042.0 55095.7 14218.7 14244.2 14284.6 14286.1 14306.4 12181.8 12189.3 12192.8 12218.9 12219.8 11004.5 13357.7 13394.7 13472.5 13482.9 5492.4 5523.0 5942.2 5997.4 6006.4 879.8 913.5 930.4 959.5 959.7 27690.7 27998.0 28124.4 28706.4 28728.0 904.9 980.8 1035.9 1056.6 1136.0 12557.5 12712.2 12715.4 12734.2 12744.6 515210.0 518082.0 518321.0 518505.0 519676.0 46242.3 46276.0 46347.3 46365.0 46469.3 1358.3 1403.5 1416.1 1418.9 1419.3 18674.8 21352.0 22579.0 23016.0 23074.8 40042.8 40234.4 40272.6 40287.4 40315.4 481352.2 482871.0 482953.0 483013.0 483192.0 24082.4 24527.0 29368.0 29513.0 29639.7 29847.0 35067.8 37926.8 38234.4 39594.0 26907.5 27066.0 27636.0 28303.2 28372.1 26990.4 27799.3 27979.2 28228.9 28278.4 31579.5 31671.7 32069.0 32295.0 32415.4 27992.0 29077.0 29566.7 29747.7 30334.2 22045.8 23098.7 23347.2 23709.9 23918.6 15:43:47 INFO - PROCESS | 1638 | 15:43:47 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:43383.27 Median:24902.75 stddev:130657.51 (524.7%) stddev-sans-first:130984.15 15:43:47 INFO - PROCESS | 1638 | Values: 387.1 405.1 422.7 436.5 447.2 10617.6 14533.9 20423.6 20548.9 21073.0 21199.5 21361.4 21835.8 21846.3 22164.2 6297.4 6351.1 6390.4 6535.1 6536.2 4077.2 4299.0 6370.5 6417.3 6472.2 7018.4 7274.2 7432.9 7496.3 7504.6 6965.7 7260.9 7424.9 7434.5 7439.7 6923.2 7085.5 7154.1 7177.7 7236.5 23794.9 24583.0 24723.9 24805.6 24999.9 2844.9 2853.2 2881.5 2897.0 2933.0 14447.9 15304.0 15513.1 15688.8 15995.9 1912.9 1964.1 1964.2 1967.5 1972.6 110681.4 137368.3 159612.0 168722.2 170093.0 15877.6 18587.0 18942.7 19291.4 19351.4 29445.7 30063.4 30127.5 30230.5 30289.7 9604.3 9787.1 9822.6 9838.2 9903.6 8069.9 8075.4 8076.0 8219.9 8229.3 8719.0 8865.9 8922.0 8958.5 8959.9 3233.3 3645.5 3704.2 3737.4 3752.5 769.0 874.0 888.9 903.9 926.7 19132.6 19940.6 20526.6 20551.7 20595.7 896.6 925.5 926.7 983.1 991.9 10083.8 10328.1 10368.1 10528.8 10533.8 731589.0 790224.0 790238.0 804429.0 807930.0 144507.0 149758.3 150812.0 151878.0 152413.0 1193.8 1285.4 1321.9 1350.8 1355.5 14442.6 17711.6 19065.5 19146.2 19257.7 61371.7 62177.8 62834.0 63041.7 63709.7 79081.0 89082.0 90090.5 90988.5 92235.0 7683.9 11436.5 11658.2 11713.3 11813.1 12779.8 12806.7 12810.9 12829.0 13181.5 10829.6 10852.6 11134.0 11297.3 11338.6 9968.6 10044.5 10080.6 10121.4 10375.5 11121.8 11175.7 11377.9 11436.6 11499.4 9385.9 11162.9 11269.3 11716.6 11720.3 10782.6 10990.2 11002.2 11003.4 11256.0 15:43:47 INFO - PROCESS | 1638 | 15:43:47 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6588.86 Median:3349.45 stddev:4107.16 (122.6%) stddev-sans-first:4101.27 15:43:47 INFO - PROCESS | 1638 | Values: 1554.9 1555.3 1577.6 1583.4 1588.1 10729.5 11314.3 11324.7 11329.3 11330.4 9424.8 9733.6 9853.7 9866.9 9868.4 2297.3 2333.0 2334.6 2335.9 2336.9 2264.1 2322.1 2381.2 2384.3 2385.9 2315.9 2371.8 2394.0 2406.9 2409.0 2334.9 2337.9 2363.9 2366.1 2370.9 2331.9 2348.7 2358.4 2371.3 2371.6 10834.8 11146.4 11149.4 11159.1 11159.1 5575.9 5740.5 5743.2 5745.2 5758.9 3220.9 3313.4 3385.5 3480.9 3553.8 1154.8 1156.6 1173.3 1199.5 1203.8 13444.2 13836.7 13994.3 15284.8 15455.3 4120.1 4229.6 4241.5 4250.3 4524.2 10310.9 10502.9 11509.8 11532.4 11590.3 5622.7 5630.6 5632.2 5632.4 5633.3 4224.1 4494.0 5218.7 5258.9 5259.6 3706.9 3829.4 3851.4 3896.3 3900.7 996.2 997.6 1010.9 1014.0 1021.0 891.8 912.5 930.1 937.3 967.9 6245.7 6543.8 6567.0 6653.3 6661.8 999.5 1001.9 1007.3 1009.2 1017.4 4097.1 4110.7 4183.7 4210.6 4258.6 13144.3 13721.8 13748.3 13780.0 13800.4 12958.4 13426.6 13660.0 13676.0 13687.9 9831.3 10065.6 10088.8 10101.8 10103.2 5839.8 6545.7 6633.7 6848.3 6895.5 11768.3 12353.1 12366.0 12370.5 12382.9 9989.9 10763.4 11196.3 12305.0 12342.8 9241.7 9485.3 9488.9 9500.4 9506.8 8744.0 10162.7 10365.4 10367.9 10372.2 8773.5 8808.4 8905.8 8911.7 9064.0 8585.8 8602.1 8625.5 8699.5 8757.4 7869.8 7908.5 7913.1 7916.6 7928.5 5732.3 5767.2 5825.5 5940.5 6004.2 5278.3 5674.0 5813.7 5891.2 5983.3 15:43:47 INFO - PROCESS | 1638 | 15:43:47 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4473.09 Median:4856.04 stddev:1212.33 (25.0%) stddev-sans-first:1175.82 15:43:47 INFO - PROCESS | 1638 | Values: 362.9 373.6 380.4 382.8 386.1 4728.3 4864.8 5125.7 5159.0 5190.5 4308.3 4445.8 4767.6 5014.1 5079.5 3654.4 4199.0 4201.1 4223.5 4234.7 3519.5 3630.5 4256.3 4257.0 4264.4 3671.5 3928.7 4103.9 4151.8 4279.8 3916.0 3988.6 4166.7 4171.7 4178.5 3865.6 3894.1 3894.9 3900.4 3904.3 4208.8 4656.7 4969.0 5013.5 5029.9 4563.5 4606.9 4758.5 4857.2 4901.0 4768.7 5381.2 5382.0 5385.6 5441.6 4248.4 4345.9 4355.7 4397.4 4423.6 4960.3 5125.9 5328.8 5338.7 5498.7 4040.6 4069.0 4173.3 4328.7 4484.6 3836.8 4246.6 4503.0 4530.8 4551.8 4839.5 4859.8 4872.9 4963.6 5015.9 4517.9 5380.4 5389.4 5415.8 5426.9 1497.7 1542.6 1632.3 1682.3 1711.5 1306.9 1383.3 1385.7 1403.3 1404.0 4419.0 4523.9 4702.0 4881.1 4991.9 4854.9 4904.8 5224.0 5583.6 5608.9 4370.5 4447.9 4517.6 5031.3 5149.1 2416.9 2466.9 2710.8 2829.7 2865.8 5485.6 5578.6 5810.7 5845.3 5850.4 4757.2 5280.7 5502.3 5746.7 5749.8 4845.3 5494.6 5547.3 5561.7 5858.0 5227.5 5246.8 5358.7 5592.6 5615.1 4974.0 5119.0 5200.3 5232.5 5536.7 4883.1 4931.5 5380.4 5555.7 5782.7 3578.5 3893.7 4406.8 4438.5 4442.2 5158.5 5313.9 5394.7 5595.3 5633.5 4465.7 4469.4 4949.8 4963.8 5318.9 5042.5 5051.9 5124.8 5203.9 5393.9 4705.6 4721.7 5269.9 5310.9 5332.8 4986.0 5007.2 5117.5 5315.4 5439.5 4741.9 4865.6 5331.6 5369.0 5379.3 15:43:47 INFO - PROCESS | 1638 | 15:43:47 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21118.23 Median:4526.40 stddev:31731.34 (701.0%) stddev-sans-first:31783.39 15:43:47 INFO - PROCESS | 1638 | Values: 719.6 728.1 771.5 820.4 823.4 15163.8 15975.6 15992.6 16003.7 16010.3 14721.5 14727.3 14745.0 14762.3 14888.0 884.8 916.1 932.3 937.1 946.8 937.6 941.5 941.6 942.2 942.9 978.7 978.8 979.5 979.8 980.8 861.4 974.6 975.5 975.9 977.0 700.9 709.6 725.8 726.1 726.6 15545.0 15947.6 15972.9 16124.5 16363.7 11903.8 12282.0 14057.3 14313.8 14336.5 11506.7 11709.4 11741.6 12028.2 12057.3 2959.6 3583.6 3598.5 3717.7 3726.4 116827.4 118883.2 119114.4 120500.0 122326.4 10816.5 10883.3 11038.9 11056.5 11064.4 22564.8 23154.0 23166.5 23189.2 23430.4 7763.4 7789.4 7789.8 7848.4 8006.7 4223.5 4315.7 4426.8 4516.2 4536.6 3472.4 3542.7 3554.5 3569.3 3575.9 658.9 678.2 683.3 696.9 704.2 668.8 684.9 698.6 712.7 733.6 9434.2 9479.3 9695.9 10427.1 10466.9 682.1 684.0 727.2 731.9 749.3 4319.5 4939.0 5101.4 5102.2 5111.0 89335.0 106762.9 110194.4 111377.9 113706.0 93633.5 95944.5 96206.5 97816.5 100173.0 81482.0 81897.2 84034.2 84043.0 85433.6 8225.7 9833.6 9898.5 9938.4 9946.2 43296.9 46139.5 53675.0 53685.7 54328.7 56155.4 69078.0 70265.0 71223.6 72445.0 10826.8 11039.8 11465.6 11594.9 11687.9 12113.4 12630.5 13114.9 13322.4 13672.9 11767.7 12391.7 12418.3 12480.7 12610.2 11602.7 11968.0 12027.2 12249.3 12424.3 11849.8 11952.8 11994.0 12339.6 12365.3 8171.2 8740.9 8856.8 9160.2 9212.8 8621.9 9351.9 10161.9 10221.9 10275.4 15:43:47 INFO - PROCESS | 1638 | -------- Summary: end -------- 15:43:47 INFO - PROCESS | 1638 | 15:43:47 INFO - TEST-INFO | 1638: exit 0 15:43:47 INFO - TEST-OK | dromaeo_css | took 1119956ms 15:43:47 INFO - TEST-START | kraken 15:43:47 INFO - operating with platform_type : mac_ 15:43:47 INFO - Initialising browser for kraken test... 15:43:47 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmprgPzdK/profile http://localhost:49238/getInfo.html) 15:43:50 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 15:43:50 INFO - PROCESS | 1646 | colorDepth:24 15:43:50 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 15:43:50 INFO - PROCESS | 1646 | __metrics 15:43:50 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:43:50 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:43:50 INFO - TEST-INFO | 1646: exit 0 15:43:50 INFO - Browser initialized. 15:43:50 INFO - Running cycle 1/1 for kraken test... 15:43:50 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/T/tmprgPzdK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:44:03 INFO - PROCESS | 1647 | RSS: Main: 214925312 15:44:03 INFO - PROCESS | 1647 | 15:44:05 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 15:44:06 INFO - PROCESS | 1647 | RSS: Main: 267808768 15:44:06 INFO - PROCESS | 1647 | 15:44:09 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 15:44:09 INFO - PROCESS | 1647 | RSS: Main: 279203840 15:44:09 INFO - PROCESS | 1647 | 15:44:13 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 15:44:13 INFO - PROCESS | 1647 | RSS: Main: 375513088 15:44:13 INFO - PROCESS | 1647 | 15:44:15 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 15:44:16 INFO - PROCESS | 1647 | RSS: Main: 279191552 15:44:16 INFO - PROCESS | 1647 | 15:44:18 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:44:19 INFO - PROCESS | 1647 | RSS: Main: 280797184 15:44:19 INFO - PROCESS | 1647 | 15:44:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 15:44:22 INFO - PROCESS | 1647 | RSS: Main: 321064960 15:44:22 INFO - PROCESS | 1647 | 15:44:25 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 15:44:26 INFO - PROCESS | 1647 | RSS: Main: 320999424 15:44:26 INFO - PROCESS | 1647 | 15:44:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 15:44:30 INFO - PROCESS | 1647 | RSS: Main: 320327680 15:44:30 INFO - PROCESS | 1647 | 15:44:31 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:44:32 INFO - PROCESS | 1647 | RSS: Main: 294715392 15:44:32 INFO - PROCESS | 1647 | 15:44:34 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:44:34 INFO - PROCESS | 1647 | RSS: Main: 298283008 15:44:34 INFO - PROCESS | 1647 | 15:44:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:44:37 INFO - PROCESS | 1647 | RSS: Main: 305246208 15:44:37 INFO - PROCESS | 1647 | 15:44:40 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:44:41 INFO - PROCESS | 1647 | RSS: Main: 321335296 15:44:41 INFO - PROCESS | 1647 | 15:44:44 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:44:44 INFO - PROCESS | 1647 | RSS: Main: 314204160 15:44:44 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 15:44:46 INFO - PROCESS | 1647 | RSS: Main: 358408192 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | __start_tp_report 15:44:46 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 15:44:46 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 15:44:46 INFO - PROCESS | 1647 | |i|pagename|runs| 15:44:46 INFO - PROCESS | 1647 | |0;ai-astar;115;117;116;116;115;116;116;116;116;117 15:44:46 INFO - PROCESS | 1647 | |1;audio-beat-detection;133;107;107;140;107;107;108;102;130;94 15:44:46 INFO - PROCESS | 1647 | |2;audio-dft;185;185;180;183;186;183;183;184;187;183 15:44:46 INFO - PROCESS | 1647 | |3;audio-fft;82;62;60;93;53;78;75;84;66;74 15:44:46 INFO - PROCESS | 1647 | |4;audio-oscillator;83;68;89;69;69;68;82;69;69;84 15:44:46 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;88;88;87;87;88;87;88;87;88;88 15:44:46 INFO - PROCESS | 1647 | |6;imaging-darkroom;94;95;106;155;103;97;95;96;106;148 15:44:46 INFO - PROCESS | 1647 | |7;imaging-desaturate;86;84;86;84;80;83;71;79;130;136 15:44:46 INFO - PROCESS | 1647 | |8;json-parse-financial;64;61;63;60;64;64;64;63;63;63 15:44:46 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;60;58;55;65;59;57;59;60;57;57 15:44:46 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;87;83;83;85;88;98;96;98;81;89 15:44:46 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;139;136;135;137;136;134;139;141;178;137 15:44:46 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;160;164;149;204;203;164;167;162;163;161 15:44:46 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;66;65;64;64;65;63;66;67;75;79 15:44:46 INFO - PROCESS | 1647 | __end_tp_report 15:44:46 INFO - PROCESS | 1647 | __start_cc_report 15:44:46 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2207 15:44:46 INFO - PROCESS | 1647 | __end_cc_report 15:44:46 INFO - PROCESS | 1647 | __startTimestamp1491950686790__endTimestamp 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | ------- Summary: start ------- 15:44:46 INFO - PROCESS | 1647 | Number of tests: 14 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:116.00 Median:116.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 15:44:46 INFO - PROCESS | 1647 | Values: 115.0 117.0 116.0 116.0 115.0 116.0 116.0 116.0 116.0 117.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:113.50 Median:119.00 stddev:15.15 (12.7%) stddev-sans-first:14.34 15:44:46 INFO - PROCESS | 1647 | Values: 133.0 107.0 107.0 140.0 107.0 107.0 108.0 102.0 130.0 94.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.97 (1.1%) stddev-sans-first:2.05 15:44:46 INFO - PROCESS | 1647 | Values: 185.0 185.0 180.0 183.0 186.0 183.0 183.0 184.0 187.0 183.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:72.70 Median:76.50 stddev:12.34 (16.1%) stddev-sans-first:12.62 15:44:46 INFO - PROCESS | 1647 | Values: 82.0 62.0 60.0 93.0 53.0 78.0 75.0 84.0 66.0 74.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:75.50 stddev:8.38 (11.1%) stddev-sans-first:8.37 15:44:46 INFO - PROCESS | 1647 | Values: 83.0 68.0 89.0 69.0 69.0 68.0 82.0 69.0 69.0 84.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.00 stddev:0.52 (0.6%) stddev-sans-first:0.53 15:44:46 INFO - PROCESS | 1647 | Values: 88.0 88.0 87.0 87.0 88.0 87.0 88.0 87.0 88.0 88.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:109.50 Median:94.50 stddev:22.65 (24.0%) stddev-sans-first:23.32 15:44:46 INFO - PROCESS | 1647 | Values: 94.0 95.0 106.0 155.0 103.0 97.0 95.0 96.0 106.0 148.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:83.50 stddev:22.15 (26.5%) stddev-sans-first:23.39 15:44:46 INFO - PROCESS | 1647 | Values: 86.0 84.0 86.0 84.0 80.0 83.0 71.0 79.0 130.0 136.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:63.50 stddev:1.37 (2.2%) stddev-sans-first:1.39 15:44:46 INFO - PROCESS | 1647 | Values: 64.0 61.0 63.0 60.0 64.0 64.0 64.0 63.0 63.0 63.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:2.71 (4.6%) stddev-sans-first:2.83 15:44:46 INFO - PROCESS | 1647 | Values: 60.0 58.0 55.0 65.0 59.0 57.0 59.0 60.0 57.0 57.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:88.50 stddev:6.39 (7.2%) stddev-sans-first:6.75 15:44:46 INFO - PROCESS | 1647 | Values: 87.0 83.0 83.0 85.0 88.0 98.0 96.0 98.0 81.0 89.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:138.00 stddev:13.10 (9.5%) stddev-sans-first:13.87 15:44:46 INFO - PROCESS | 1647 | Values: 139.0 136.0 135.0 137.0 136.0 134.0 139.0 141.0 178.0 137.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:164.00 stddev:18.44 (11.2%) stddev-sans-first:19.22 15:44:46 INFO - PROCESS | 1647 | Values: 160.0 164.0 149.0 204.0 203.0 164.0 167.0 162.0 163.0 161.0 15:44:46 INFO - PROCESS | 1647 | 15:44:46 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:66.00 stddev:5.27 (8.0%) stddev-sans-first:5.57 15:44:46 INFO - PROCESS | 1647 | Values: 66.0 65.0 64.0 64.0 65.0 63.0 66.0 67.0 75.0 79.0 15:44:46 INFO - PROCESS | 1647 | -------- Summary: end -------- 15:44:46 INFO - PROCESS | 1647 | 15:44:47 INFO - TEST-INFO | 1647: exit 0 15:44:47 INFO - TEST-OK | kraken | took 60101ms 15:44:47 INFO - SUITE-END | took 1180s 15:44:47 INFO - Completed test suite (00:19:40) 15:44:47 INFO - javascript benchmark 15:44:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7543.919953309256, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.983105084474, 3386.4243078676623, 3394.117529442344, 3408.0798184490186, 3438.845251963663, 5847.064469684851, 5851.561132915027, 5886.676232807194, 5896.911546326808, 5927.73325200366, 4909.680870743399, 5885.941140588593, 5968.074948382997, 5969.5821292509545, 5978.760849566012, 5277.12927366985, 5308.867278318035, 5315.441878602749, 5346.010987967238, 5442.156465747816, 5210.76551555181, 5236.685798852062, 5401.756920938547, 5413.675179489223, 5423.593230507708, 4410.845620403286, 5244.239585260135, 5355.491228333302, 5412.4587541245955, 5462.890742185154, 5408.972955135238, 5412.485813847678, 5416.9729151353995, 5421.430749771296, 5456.0997435423105, 5256.2904007959205, 5365.0, 5368.436314187033, 5396.649217800869, 5427.864303392407, 5661.179129026309, 5947.286325640907, 5952.940470595282, 5990.9101363479585, 6032.4570788629235, 6192.411720886508, 6201.658908760016, 6208.50331973441, 6210.223722034746, 6234.283057448406, 6291.591046581958, 6341.397567231105, 6347.936520634827, 6356.0, 6361.982082866719, 6009.939900601025, 6069.969650151721, 6078.665673387962, 6090.177825993523, 6090.482309003695, 6143.310589587899, 6185.876282474325, 6254.874902501924, 6498.350164983463, 6521.282658907517, 5210.6613070150315, 6164.0, 6166.568340216142, 6180.598261113013, 6197.845053873689, 6295.18162638854, 6315.747370105144, 6319.494440444845, 6322.33615470369, 6324.367563243731, 6099.542534309944, 6252.218472690914, 6260.7495700171485, 6262.749490020348, 6263.060540918898, 5736.541076713853, 6148.292946311142, 6182.969085154546, 6214.720337584819, 6232.0963460298, 5121.359830021247, 5127.64106512548, 5130.589552835764, 5140.357455318085, 5155.458676838953, 4546.681732278775, 4551.453825540956, 4552.339910712928, 4563.247064234364, 4576.931346029813, 5725.227094342211, 5769.336526299446, 5914.733836977346, 5976.103584462278, 5993.670348130895, 5597.272354593877, 6137.1101190328045, 6232.220972378453, 6311.431971122529, 6334.208223972004, 5962.37395073511, 5979.880402392015, 5983.371745966698, 5992.910106348409, 6037.637741735422, 5576.079946808731, 5582.106862901916, 5591.524720398812, 5593.188987596775, 5593.272874526286, 6228.377162283736, 6247.156633854554, 6452.645104519296, 6545.083688283549, 6571.112899758471, 6264.718087685974, 6296.905546416899, 6318.336574659595, 6319.210098737658, 6326.905096423466, 6543.70553325092, 6572.901406479003, 6574.638394888327, 6583.144191255106, 6590.9670451647435, 6131.560175786138, 6166.845828854135, 6188.783392581238, 6224.62652240867, 6233.657148856856, 6286.717097730704, 6304.148939893055, 6306.684665766785, 6309.968450157903, 6315.747370105328, 6097.207363042953, 6140.3245642978345, 6142.775043102407, 6200.100985357189, 6256.155419018374, 6138.508919286535, 6148.78479253224, 6175.722092505758, 6193.473554747717, 6204.534659900616, 5946.940530594638, 5985.850353741192, 6007.158997740233, 6143.6928153593035, 6185.659788711664, 6030.939690603038, 6249.093881387264, 6267.435930766253, 6275.995840665471, 6284.9001424751405, 6191.752329906753, 6208.348123446973, 6218.160548325918, 6221.3156552780065, 6225.62646241227], "value": 5809.410463403926, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.6145588661556, 791.7901756034651, 874.3355050161875, 876.1545108969843, 967.7870868408947, 37732.43401348979, 38527.42208866869, 40150.19699606006, 40156.19687606246, 40380.79809600955, 38392.0, 38595.22809543808, 38625.61374386255, 38666.226675466474, 38950.610493895125, 7425.517341372793, 7601.6579253933705, 7864.764057078282, 7949.40379471539, 7977.962864827593, 11090.889091109066, 11370.829437558443, 11371.033462155685, 11403.3728144952, 11505.0, 11353.772924541505, 11388.829167562493, 11398.886011139906, 11404.942975285154, 11436.142289328334, 7308.707651693926, 7478.700851965915, 10963.50664220112, 11307.26502777321, 11321.716957076098, 11042.668719938334, 11117.110631149488, 11127.833082503768, 11131.332120072822, 11184.161187910859, 39311.21377572461, 39485.802570987245, 39553.40669889954, 39605.40591891124, 39615.0, 3890.8638197662945, 3892.4939757831303, 3894.4937158169255, 3905.941410878839, 3919.510061242345, 27204.59193112105, 27237.727622723716, 27296.590551141748, 29358.119256422342, 29432.55851162234, 3014.442328169274, 3151.6690747471644, 3221.629512606057, 3253.056613582058, 3258.201740573551, 352580.0, 354387.0, 354604.0, 355121.0, 355164.0, 41969.37045944313, 42605.0, 50136.99726005532, 51918.0, 52112.47887521076, 52406.0, 52579.73710131425, 54931.0, 55042.0, 55095.72452137794, 14218.715625687428, 14244.216568088646, 14284.642883927985, 14286.071405358618, 14306.427742890375, 12181.756364872654, 12189.268643881396, 12192.756144877052, 12218.938905305595, 12219.75560488803, 11004.504797284142, 13357.66605834862, 13394.665133371744, 13472.461101556024, 13482.93258533701, 5492.368377636543, 5522.972385138049, 5942.168096466498, 5997.370276121032, 6006.369331220247, 879.8108406692592, 913.5067063785614, 930.385945276128, 959.5346257065318, 959.7312752429331, 27690.723092768814, 27998.0, 28124.43751125007, 28706.425871482454, 28727.99452019211, 904.8506996345661, 980.8087422952456, 1035.880873699519, 1056.572088304238, 1135.9545618175346, 12557.497700091893, 12712.237265764084, 12715.364231788373, 12734.172278802034, 12744.553940612033, 515210.0, 518082.0, 518321.0, 518505.0, 519676.0, 46242.30636540455, 46276.0, 46347.30479042817, 46365.0, 46469.30296045562, 1358.2733237717773, 1403.4596680278166, 1416.0937000319598, 1418.858114188573, 1419.2619837684347, 18674.81325186785, 21352.03915823823, 22579.0, 23016.0, 23074.846257686713, 40042.79978600088, 40234.39648405218, 40272.597274026884, 40287.3956890641, 40315.395269071574, 481352.18647813075, 482871.0, 482953.0, 483013.0, 483192.0, 24082.397940050938, 24527.0, 29368.0, 29512.96704615414, 29639.703602964557, 29847.0, 35067.82466087653, 37926.810365948, 38234.42648360221, 39594.0, 26907.461850762484, 27066.0, 27636.032738854847, 28303.15090547287, 28372.14883553497, 26990.35630189899, 27799.30501737473, 27979.160625181277, 28228.85885570559, 28278.43443131085, 31579.526307104952, 31671.683283166873, 32069.0, 32295.0, 32415.35169296648, 27992.0, 29077.0, 29566.704332956397, 29747.70252297536, 30334.241643959078, 22045.779542205015, 23098.653520197546, 23347.182848600216, 23709.88145059264, 23918.641220382055], "value": 18097.27312377865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.14827379764523, 405.0885507607884, 422.7484646635253, 436.54381171675595, 447.16157205240177, 10617.575296988121, 14533.854661453395, 20423.591528169447, 20548.8972555137, 21073.0, 21199.470013249676, 21361.35915922525, 21835.781642183574, 21846.344609661737, 22164.224252151176, 6297.370262973712, 6351.079093531435, 6390.424861762441, 6535.0850880876715, 6536.215654121512, 4077.22532718784, 4298.957010429887, 6370.458511026545, 6417.326180751024, 6472.158619379474, 7018.4385249180195, 7274.236205198457, 7432.888506672404, 7496.250374962515, 7504.5872477013745, 6965.651717414109, 7260.891086633705, 7424.925750742477, 7434.516756410816, 7439.702411903518, 6923.169219693669, 7085.468589855782, 7154.105736782903, 7177.6770045348085, 7236.49344545882, 23794.929228184774, 24583.016679332806, 24723.887425065735, 24805.62791558128, 24999.87500062488, 2844.9146525604265, 2853.1583182960867, 2881.538953767408, 2897.0, 2933.0, 14447.927760361235, 15304.0, 15513.146776927264, 15688.843111568853, 15995.92002039994, 1912.8852268863914, 1964.1161477335113, 1964.2143142742875, 1967.498287936567, 1972.619166583383, 110681.44659276733, 137368.31315843455, 159612.0, 168722.1563892185, 170093.0, 15877.603059923364, 18587.0, 18942.715859262124, 19291.42125736202, 19351.4194574163, 29445.705542944725, 30063.398732025675, 30127.548086778716, 30230.54654180189, 30289.697103029128, 9604.279679024101, 9787.070228328297, 9822.594960061491, 9838.212942964547, 9903.603855845686, 8069.913002401328, 8075.394345424117, 8075.959620201979, 8219.917800822035, 8229.25936665691, 8718.997315308694, 8865.867011994826, 8922.018577956549, 8958.507282099547, 8959.865602015974, 3233.337165880989, 3645.484615615217, 3704.1665625234104, 3737.383331750231, 3752.5309336332957, 768.9653965571563, 873.9781505462287, 888.8888888888889, 903.8746760283489, 926.749777560055, 19132.61734765297, 19940.60118797645, 20526.589468210852, 20551.691724624143, 20595.691064634044, 896.6368620708623, 925.5279807298326, 926.6941909169958, 983.0857302708407, 991.9057689519484, 10083.848742268872, 10328.122109620615, 10368.118709909742, 10528.842067368996, 10533.789324213472, 731589.0, 790224.0, 790238.0, 804429.0, 807930.0, 144507.0, 149758.25120874326, 150812.0, 151878.0, 152413.0, 1193.8209268609778, 1285.3894400160036, 1321.900857435677, 1350.8108864759138, 1355.4849157320155, 14442.555744425474, 17711.64576708433, 19065.523361915508, 19146.234150634376, 19257.711134332716, 61371.69314153401, 62177.75644487175, 62834.0, 63041.684791575746, 63709.68145159245, 79081.0, 89082.0, 90090.54954725184, 90988.54505727428, 92235.0, 7683.8529673599305, 11436.485358159069, 11658.18392712502, 11713.297202167898, 11813.11401644863, 12779.80830287565, 12806.67983300425, 12810.935945320214, 12828.973682105596, 13181.472741090633, 10829.620963266249, 10852.620158294421, 11134.0, 11297.26567773092, 11338.60314888975, 9968.601255949681, 10044.497775111362, 10080.596776129167, 10121.443320617436, 10375.481225938824, 11121.833172502256, 11175.664730058112, 11377.943110284727, 11436.599719009795, 11499.425028748361, 9385.859212111687, 11162.939520745516, 11269.323840569592, 11716.589919352782, 11720.296782193096, 10782.568697252022, 10990.230683852373, 11002.174836887105, 11003.449827508432, 11256.043236325004], "value": 11177.621273278954, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1554.8833837462187, 1555.3312075807412, 1577.5503981365312, 1583.4457939721099, 1588.0630428047457, 10729.463526823656, 11314.264572802762, 11324.660260192197, 11329.263597866133, 11330.376829274397, 9424.764380890463, 9733.610655573768, 9853.704388868346, 9866.851997220047, 9868.407895526256, 2297.2878407693624, 2332.9533409331802, 2334.6381310896754, 2335.9065637374483, 2336.88315584221, 2264.071730590458, 2322.1175953137868, 2381.226101517005, 2384.3085505203558, 2385.866713311181, 2315.8610483371053, 2371.8221133415063, 2394.0184524345023, 2406.927792166238, 2408.963865542018, 2334.8949297281492, 2337.906483740648, 2363.9290821275304, 2366.1363602285214, 2370.940726481852, 2331.9300420987397, 2348.7064116985375, 2358.3514533503426, 2371.336025912747, 2371.6442533619925, 10834.837477437844, 11146.442677866155, 11149.386783726892, 11159.051480624157, 11159.107271418266, 5575.888482230333, 5740.54075673945, 5743.224664670258, 5745.224394706703, 5758.942410575883, 3220.8872689455757, 3313.4035873542753, 3385.492176173569, 3480.9477857832153, 3553.751237413382, 1154.7863645225575, 1156.6298784388998, 1173.3312012153076, 1199.460242890694, 1203.7592481503734, 13444.193348399127, 13836.723265534734, 13994.30028498571, 15284.847151528453, 15455.30451129702, 4120.114175452232, 4229.555896630809, 4241.512226093977, 4250.3199488081755, 4524.163029839425, 10310.948445257725, 10502.894971050344, 11509.76980460386, 11532.365719885487, 11590.304581725124, 5622.690752008597, 5630.577706672016, 5632.155176723442, 5632.3804381518, 5633.295838020247, 4224.134052519226, 4494.0, 5218.6868787872845, 5258.868528286824, 5259.605529585297, 3706.870259540903, 3829.425586162098, 3851.422286657024, 3896.3181443247886, 3900.7269491135917, 996.2329006664828, 997.6258902911409, 1010.9494525273707, 1013.964511242103, 1021.0351218098825, 891.7815135291766, 912.4616476279027, 930.0745757970727, 937.2595649436869, 967.9419234845932, 6245.687715614292, 6543.803685889431, 6567.0, 6653.2681405045405, 6661.800145995628, 999.5402115027024, 1001.8797744270735, 1007.2898606482412, 1009.1724785675675, 1017.4404077757204, 4097.057676734368, 4110.670357010831, 4183.707140500136, 4210.621044105984, 4258.638015768633, 13144.342782860818, 13721.794173087412, 13748.312584370942, 13780.035397522077, 13800.378982945791, 12958.416871240817, 13426.597202083953, 13660.0, 13675.974301927396, 13687.931560342135, 9831.311808173503, 10065.59737610502, 10088.848667269996, 10101.797964040677, 10103.242256830767, 5839.795607153729, 6545.672716364258, 6633.734650613921, 6848.315168483112, 6895.5173137879865, 11768.293902365886, 12353.135280530636, 12366.0, 12370.505179792708, 12382.876171238173, 9989.900100998897, 10763.3541987481, 11196.272242304223, 12305.0, 12342.81485777732, 9241.676541321021, 9485.33602647808, 9488.90877549063, 9500.42997420157, 9506.762330941781, 8743.956280218557, 10162.745931351776, 10365.429901355497, 10367.94816025915, 10372.222083343628, 8773.517456539952, 8808.383413161273, 8905.821883562423, 8911.73264802057, 9063.954680226556, 8585.82828343442, 8602.096779838277, 8625.525596092275, 8699.521526316114, 8757.430767000125, 7869.803254918673, 7908.485948413565, 7913.089994650689, 7916.564588947663, 7928.524288542706, 5732.254806875079, 5767.163761254678, 5825.533957283492, 5940.524758019262, 6004.249468816398, 5278.313819203479, 5674.0, 5813.709314534341, 5891.234139562001, 5983.282006159288], "value": 5035.838687337883, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.8802495176592, 373.58532029447315, 380.4065657574186, 382.82390100553744, 386.0522417465123, 4728.27417992432, 4864.829730959417, 5125.666831655944, 5158.9742051289695, 5190.455002224769, 4308.2891322931755, 4445.77771111444, 4767.570918617333, 5014.097462456765, 5079.492050794928, 3654.378755611539, 4199.0, 4201.096764195698, 4223.535411104776, 4234.74591524508, 3519.454484554898, 3630.4933452617133, 4256.318988961767, 4256.957430425702, 4264.360345948117, 3671.504346913159, 3928.685705143582, 4103.897402564945, 4151.816732231315, 4279.807408666617, 3915.9818447203647, 3988.6011398860173, 4166.66666666669, 4171.728837625545, 4178.456800615931, 3865.5747867734526, 3894.123822140024, 3894.883153505399, 3900.395438707005, 3904.3362628353248, 4208.832417779798, 4656.674032817704, 4969.049605563586, 5013.548780609763, 5029.924551131736, 4563.543645635443, 4606.907861842745, 4758.524147585248, 4857.187108120144, 4900.975495122537, 4768.666193366465, 5381.170402063317, 5381.973090134564, 5385.649932754359, 5441.619086663935, 4248.362745588156, 4345.9348109778375, 4355.651547876162, 4397.428334316518, 4423.60187583119, 4960.305557221992, 5125.948740512585, 5328.801019770598, 5338.679679219259, 5498.725063746797, 4040.5555388907196, 4069.0, 4173.332266837352, 4328.653707703376, 4484.573965473275, 3836.7938435399756, 4246.554111818276, 4503.009337945647, 4530.773461326987, 4551.817927282872, 4839.491853355417, 4859.829905953275, 4872.853814385574, 4963.553280204799, 5015.899682006339, 4517.909641807146, 5380.354357477127, 5389.353277606712, 5415.83752487426, 5426.941746359422, 1497.707946950356, 1542.550002997179, 1632.2899538700703, 1682.3102527963722, 1711.5207741832057, 1306.9019823513274, 1383.280694039094, 1385.6535866033491, 1403.3370456009661, 1404.03121845926, 4419.0, 4523.886902827456, 4701.976490117528, 4881.145799485076, 4991.929963072685, 4854.892868564122, 4904.828331008398, 5223.973880130651, 5583.609147359727, 5608.914327954215, 4370.453693288339, 4447.851997152226, 4517.6160026397465, 5031.295618613397, 5149.098907691139, 2416.885225427751, 2466.8519888806727, 2710.793696804942, 2829.717028297154, 2865.821975298788, 5485.592209360965, 5578.581606379537, 5810.709464526757, 5845.298564172326, 5850.414958504116, 4757.191277482836, 5280.709560974106, 5502.30387110321, 5746.655200687972, 5749.798757043483, 4845.2732090186755, 5494.648264558696, 5547.278853749068, 5561.6663000219305, 5857.974854400548, 5227.529522343008, 5246.842594722164, 5358.705271210121, 5592.608517403905, 5615.052097648767, 4973.950260497349, 5118.97440512795, 5200.323957885451, 5232.476752324737, 5536.667799932017, 4883.096627123993, 4931.457539670705, 5380.408155103014, 5555.72221388937, 5782.653040817564, 3578.481120237603, 3893.7274390792372, 4406.82372705101, 4438.511763705925, 4442.222611043119, 5158.45836187195, 5313.946860531345, 5394.730263486731, 5595.328560572757, 5633.507120612959, 4465.727267728682, 4469.396631454712, 4949.777260023236, 4963.808685915282, 5318.867028324323, 5042.4705405931845, 5051.898962020812, 5124.7950081996305, 5203.92194117081, 5393.865153371197, 4705.64707646921, 4721.669483136285, 5269.947300526946, 5310.946890531045, 5332.78709819254, 4986.002799440054, 5007.24891266313, 5117.462666419972, 5315.441878602691, 5439.456054394529, 4741.905161896674, 4865.56209941107, 5331.573474121983, 5369.033573956725, 5379.273798037215], "value": 4135.361725946755, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.6114098386871, 728.11170372146, 771.4676872957659, 820.3765138494745, 823.4441751817524, 15163.772543411844, 15975.600609984727, 15992.600184995352, 16003.679926401479, 16010.279537420814, 14721.484748033794, 14727.26363681817, 14744.96785225032, 14762.261886905666, 14888.032277901955, 884.7832281091141, 916.0747644878654, 932.3147486597345, 937.0629370629371, 946.7585765629755, 937.6249500199906, 941.4539567051094, 941.5857022909931, 942.1850099663776, 942.9434233945951, 978.6721448314787, 978.7944531648362, 979.4857699707675, 979.8432250839867, 980.7989362180772, 861.3927181337142, 974.5614473486959, 975.492743773241, 975.9414435133915, 976.998576459135, 700.8843540815759, 709.6203531110831, 725.7750097469801, 726.0706295941204, 726.6076318787848, 15544.989575677544, 15947.601309967227, 15972.920135399365, 16124.516264512084, 16363.672726545403, 11903.761924761542, 12282.0, 14057.297135143304, 14313.777435656402, 14336.498222562264, 11506.712332191679, 11709.414529273587, 11741.589044383405, 12028.218165819282, 12057.276563406136, 2959.644842618878, 3583.5878873929573, 3598.514200582914, 3717.739758216926, 3726.366517691999, 116827.41586292099, 118883.2167517488, 119114.40442797817, 120500.0, 122326.3883680576, 10816.513256903458, 10883.346999179916, 11038.944805276084, 11056.51228473587, 11064.446777661085, 22564.774352256594, 23153.958071886806, 23166.53666926652, 23189.188378406674, 23430.414239644146, 7763.417743669247, 7789.415793815487, 7789.766307010798, 7848.411369147223, 8006.71976480832, 4223.472065991751, 4315.697901146889, 4426.778661066934, 4516.209663308908, 4536.591706746342, 3472.409690352646, 3542.734294927839, 3554.5201397810965, 3569.268299998546, 3575.856965721342, 658.8517583543664, 678.1895634716565, 683.3030309084805, 696.8571442854203, 704.2288693880193, 668.762589280808, 684.9041134241111, 698.5738699393366, 712.7042277454901, 733.6478490324674, 9434.198093162158, 9479.289053321028, 9695.884973228029, 10427.113695335982, 10466.895331046744, 682.1132527713952, 683.9931600684029, 727.2364017781414, 731.8536292741474, 749.2919191364109, 4319.546447623017, 4939.0121975605025, 5101.413337466165, 5102.234664800236, 5110.97444512775, 89335.0, 106762.86474270471, 110194.44902775435, 111377.88622113837, 113706.0, 93633.5318323404, 95944.52027739956, 96206.51896740471, 97816.53275200877, 100173.0, 81482.0, 81897.18102818895, 84034.15965840386, 84043.0, 85433.57283213669, 8225.712100076707, 9833.557489913113, 9898.455584942898, 9938.354006989523, 9946.204303655546, 43296.91757705957, 46139.53860461487, 53675.0, 53685.731571341894, 54328.72835635796, 56155.43844561665, 69078.0, 70265.0, 71223.64388178026, 72445.0, 10826.837597435888, 11039.779204416027, 11465.598704045318, 11594.898484643945, 11687.88312116902, 12113.42644459396, 12630.494780209056, 13114.868851311365, 13322.40049197769, 13672.93163534176, 11767.705807354885, 12391.690207744517, 12418.316992565495, 12480.687982800502, 12610.243385396905, 11602.6519204424, 11968.0, 12027.158098933342, 12249.326287054297, 12424.316662583284, 11849.822252666389, 11952.76094478123, 11994.040476762013, 12339.568115115928, 12365.258084514959, 8171.223733745284, 8740.868886966828, 8856.822863542564, 9160.175584197188, 9212.815743685223, 8621.91378086211, 9351.85972210431, 10161.949190254001, 10221.898509368732, 10275.383476991405], "value": 7531.151945657542, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1438.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 116.0, 116.0, 115.0, 116.0, 116.0, 116.0, 116.0, 117.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 107.0, 107.0, 140.0, 107.0, 107.0, 108.0, 102.0, 130.0, 94.0], "value": 113.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 180.0, 183.0, 186.0, 183.0, 183.0, 184.0, 187.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 60.0, 93.0, 53.0, 78.0, 75.0, 84.0, 66.0, 74.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 68.0, 89.0, 69.0, 69.0, 68.0, 82.0, 69.0, 69.0, 84.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 87.0, 88.0, 87.0, 88.0, 87.0, 88.0, 88.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 106.0, 155.0, 103.0, 97.0, 95.0, 96.0, 106.0, 148.0], "value": 109.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 84.0, 80.0, 83.0, 71.0, 79.0, 130.0, 136.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 63.0, 60.0, 64.0, 64.0, 64.0, 63.0, 63.0, 63.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 55.0, 65.0, 59.0, 57.0, 59.0, 60.0, 57.0, 57.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 83.0, 85.0, 88.0, 98.0, 96.0, 98.0, 81.0, 89.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 135.0, 137.0, 136.0, 134.0, 139.0, 141.0, 178.0, 137.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 164.0, 149.0, 204.0, 203.0, 164.0, 167.0, 162.0, 163.0, 161.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 64.0, 65.0, 63.0, 66.0, 67.0, 75.0, 79.0], "value": 67.4, "unit": "score"}]}]} 15:44:48 INFO - Return code: 0 15:44:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:44:48 ERROR - # TBPL SUCCESS # 15:44:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:44:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:44:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:44:48 INFO - 2017-04-11 15:44:48.096 screenresolution[1651:9701] starting screenresolution argv=screenresolution get 15:44:48 INFO - 2017-04-11 15:44:48.100 screenresolution[1651:9701] Display 0: 1600x1200x32@60 15:44:48 INFO - 2017-04-11 15:44:48.106 screenresolution[1652:9704] starting screenresolution argv=screenresolution list 15:44:48 INFO - Available Modes on Display 0 15:44:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:44:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:44:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:44:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:44:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:44:48 INFO - Intel Iris: 15:44:48 INFO - Chipset Model: Intel Iris 15:44:48 INFO - Type: GPU 15:44:48 INFO - Bus: Built-In 15:44:48 INFO - VRAM (Dynamic, Max): 1536 MB 15:44:48 INFO - Vendor: Intel (0x8086) 15:44:48 INFO - Device ID: 0x0a2e 15:44:48 INFO - Revision ID: 0x0009 15:44:48 INFO - Displays: 15:44:48 INFO - Display: 15:44:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:44:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:44:48 INFO - Display Serial Number: 1600x1200 60 15:44:48 INFO - Main Display: Yes 15:44:48 INFO - Mirror: Off 15:44:48 INFO - Online: Yes 15:44:48 INFO - Rotation: Supported 15:44:48 INFO - Return code: 0 15:44:48 INFO - Running post-action listener: _resource_record_post_action 15:44:48 INFO - [mozharness: 2017-04-11 22:44:48.346531Z] Finished run-tests step (success) 15:44:48 INFO - Running post-run listener: _resource_record_post_run 15:44:48 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 38306816; Write bytes: 513010176; Read time: 243; Write time: 1615 15:44:48 INFO - TinderboxPrint: CPU usage
53.9% 15:44:48 INFO - TinderboxPrint: I/O read bytes / time
38,306,816 / 243 15:44:48 INFO - TinderboxPrint: I/O write bytes / time
513,010,176 / 1,615 15:44:48 INFO - TinderboxPrint: CPU idle
2,216.4 (46.1%) 15:44:48 INFO - TinderboxPrint: CPU system
961.3 (20.0%) 15:44:48 INFO - TinderboxPrint: CPU user
1,628.1 (33.9%) 15:44:48 INFO - TinderboxPrint: Swap in / out
246,558,720 / 0 15:44:48 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203911680; Write bytes: 199352320; Read time: 15541; Write time: 442 15:44:48 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36295680; Write bytes: 298406400; Read time: 224; Write time: 1117 15:44:48 INFO - Running post-run listener: _upload_blobber_files 15:44:48 INFO - Blob upload gear active. 15:44:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:44:48 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 15:44:48 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'] 15:44:48 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 15:44:49 INFO - (blobuploader) - INFO - Open directory for files ... 15:44:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:44:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:44:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:50 INFO - (blobuploader) - INFO - Done attempting. 15:44:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:44:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:44:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:51 INFO - (blobuploader) - INFO - Done attempting. 15:44:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:44:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:44:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:52 INFO - (blobuploader) - INFO - Done attempting. 15:44:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:44:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:44:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:55 INFO - (blobuploader) - INFO - Done attempting. 15:44:55 INFO - (blobuploader) - INFO - Iteration through files over. 15:44:55 INFO - Return code: 0 15:44:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:44:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:44:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e3ef085ecd2c8e0ea86c4c7fd4b213e74859822d8cbf477dc22a0500c1ac58b5089d509b1d9f5e2c56c64caa46f7d569a886ff6f05861ffb42acaab2efe0bd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4601686198f1cf8c9cf61d52d65d55615e87e6ca4e44a7c41fded7af72d115db69524df7215e630322a5edc3c21cd524785274b01a4fa3bc3d90496d86c272b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/319455aade792e3cf2a3472bbea583ef423971208008e09be73e8ff6247fc51799039bcfccdea8c94d27bad24657eb2889b0fb9a2f527b161264ce4bddfb023f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e64baf60781de672cb9e121e3df6137b5e73fb0001e3b7f9d151b774d5a93e2fef713c4b8fadde559f3b8acf13b473d9761dfd10abea0fa51750311060d1c3a3"} 15:44:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:44:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:44:55 INFO - Contents: 15:44:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e3ef085ecd2c8e0ea86c4c7fd4b213e74859822d8cbf477dc22a0500c1ac58b5089d509b1d9f5e2c56c64caa46f7d569a886ff6f05861ffb42acaab2efe0bd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4601686198f1cf8c9cf61d52d65d55615e87e6ca4e44a7c41fded7af72d115db69524df7215e630322a5edc3c21cd524785274b01a4fa3bc3d90496d86c272b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/319455aade792e3cf2a3472bbea583ef423971208008e09be73e8ff6247fc51799039bcfccdea8c94d27bad24657eb2889b0fb9a2f527b161264ce4bddfb023f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e64baf60781de672cb9e121e3df6137b5e73fb0001e3b7f9d151b774d5a93e2fef713c4b8fadde559f3b8acf13b473d9761dfd10abea0fa51750311060d1c3a3"} 15:44:55 INFO - Running post-run listener: copy_logs_to_upload_dir 15:44:55 INFO - Copying logs to upload dir... 15:44:55 INFO - mkdir: /builds/slave/test/build/upload/logs 15:44:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.471888 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-11 15:44:55.099194) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 15:44:55.104825) ========= 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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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/2e3ef085ecd2c8e0ea86c4c7fd4b213e74859822d8cbf477dc22a0500c1ac58b5089d509b1d9f5e2c56c64caa46f7d569a886ff6f05861ffb42acaab2efe0bd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4601686198f1cf8c9cf61d52d65d55615e87e6ca4e44a7c41fded7af72d115db69524df7215e630322a5edc3c21cd524785274b01a4fa3bc3d90496d86c272b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/319455aade792e3cf2a3472bbea583ef423971208008e09be73e8ff6247fc51799039bcfccdea8c94d27bad24657eb2889b0fb9a2f527b161264ce4bddfb023f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e64baf60781de672cb9e121e3df6137b5e73fb0001e3b7f9d151b774d5a93e2fef713c4b8fadde559f3b8acf13b473d9761dfd10abea0fa51750311060d1c3a3"} build_url:https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010772 build_url: 'https://queue.taskcluster.net/v1/task/FZJI_XdfRoaLwpHc76rNPg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e3ef085ecd2c8e0ea86c4c7fd4b213e74859822d8cbf477dc22a0500c1ac58b5089d509b1d9f5e2c56c64caa46f7d569a886ff6f05861ffb42acaab2efe0bd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4601686198f1cf8c9cf61d52d65d55615e87e6ca4e44a7c41fded7af72d115db69524df7215e630322a5edc3c21cd524785274b01a4fa3bc3d90496d86c272b9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/319455aade792e3cf2a3472bbea583ef423971208008e09be73e8ff6247fc51799039bcfccdea8c94d27bad24657eb2889b0fb9a2f527b161264ce4bddfb023f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e64baf60781de672cb9e121e3df6137b5e73fb0001e3b7f9d151b774d5a93e2fef713c4b8fadde559f3b8acf13b473d9761dfd10abea0fa51750311060d1c3a3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 15:44:55.138851) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 15:44:55.139162) ========= 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.HDszcZ2Hjk/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.cKNqW3CPP6/Listeners TMPDIR=/var/folders/d6/1wq_728n79bd9134fc4zhpd400000w/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.005046 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 15:44:55.187391) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 15:44:55.187819) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 15:44:55.188202) ========= ========= Total master_lag: 0.18 =========