builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0137 starttime: 1490155099.22 results: success (0) buildid: 20170321200735 builduid: 7b83031d305344068192ba6da06213e9 revision: aa03407e251b883e9034796feb2301b9fb68af6e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:19.219281) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:19.219755) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:19.220062) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:19.245825) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:19.246401) ========= 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.12sUssLPyz/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.Urib4CFTny/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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-03-21 20:58:19-- 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% 33.5M=0s 2017-03-21 20:58:19 (33.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090699 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:19.362138) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:19.362465) ========= 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.12sUssLPyz/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.Urib4CFTny/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.031520 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:19.416409) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 20:58:19.416767) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev aa03407e251b883e9034796feb2301b9fb68af6e --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 aa03407e251b883e9034796feb2301b9fb68af6e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.12sUssLPyz/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.Urib4CFTny/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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-03-21 20:58:19,517 truncating revision to first 12 chars 2017-03-21 20:58:19,517 Setting DEBUG logging. 2017-03-21 20:58:19,517 attempt 1/10 2017-03-21 20:58:19,517 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/aa03407e251b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 20:58:20,700 unpacking tar archive at: mozilla-aurora-aa03407e251b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.452068 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 20:58:20.892111) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:20.892481) ========= script_repo_revision: aa03407e251b883e9034796feb2301b9fb68af6e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:20.892957) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:20.893238) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 20:58:20.913471) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-21 20:58:20.913952) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.12sUssLPyz/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.Urib4CFTny/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:58:21 INFO - MultiFileLogger online at 20170321 20:58:21 in /builds/slave/test 20:58:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 20:58:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:58:21 INFO - {'append_to_log': False, 20:58:21 INFO - 'base_work_dir': '/builds/slave/test', 20:58:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:58:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:58:21 INFO - 'branch': 'Mozilla-Aurora', 20:58:21 INFO - 'buildbot_json_path': 'buildprops.json', 20:58:21 INFO - 'config_files': ('talos/mac_config.py',), 20:58:21 INFO - 'default_actions': ('clobber', 20:58:21 INFO - 'read-buildbot-config', 20:58:21 INFO - 'download-and-extract', 20:58:21 INFO - 'populate-webroot', 20:58:21 INFO - 'create-virtualenv', 20:58:21 INFO - 'install', 20:58:21 INFO - 'run-tests'), 20:58:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:58:21 INFO - 'download_minidump_stackwalk': True, 20:58:21 INFO - 'download_symbols': 'ondemand', 20:58:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:58:21 INFO - 'tooltool.py': '/tools/tooltool.py', 20:58:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:58:21 INFO - '/tools/misc-python/virtualenv.py')}, 20:58:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:58:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:58:21 INFO - 'gecko_profile': False, 20:58:21 INFO - 'gecko_profile_interval': 0, 20:58:21 INFO - 'installer_path': 'installer.exe', 20:58:21 INFO - 'log_level': 'info', 20:58:21 INFO - 'log_name': 'talos', 20:58:21 INFO - 'log_to_console': True, 20:58:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:58:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:58:21 INFO - 'opt_config_files': (), 20:58:21 INFO - 'pip_index': False, 20:58:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:58:21 INFO - 'cmd': ('bash', 20:58:21 INFO - '-c', 20:58:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:58:21 INFO - 'enabled': True, 20:58:21 INFO - 'halt_on_failure': False, 20:58:21 INFO - 'name': 'check_screen_resolution'},), 20:58:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:58:21 INFO - 'cmd': ('bash', 20:58:21 INFO - '-c', 20:58:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:58:21 INFO - 'enabled': True, 20:58:21 INFO - 'halt_on_failure': False, 20:58:21 INFO - 'name': 'check_screen_resolution'},), 20:58:21 INFO - 'run_cmd_checks_enabled': True, 20:58:21 INFO - 'suite': 'dromaeojs-e10s', 20:58:21 INFO - 'system_bits': '32', 20:58:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:58:21 INFO - 'title': 't-yosemite-r7-0137', 20:58:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:58:21 INFO - 'use_talos_json': True, 20:58:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:58:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:58:21 INFO - 'work_dir': 'build'} 20:58:21 INFO - [mozharness: 2017-03-22 03:58:21.124043Z] Running clobber step. 20:58:21 INFO - Running pre-action listener: _resource_record_pre_action 20:58:21 INFO - Running main action method: clobber 20:58:21 INFO - rmtree: /builds/slave/test/build 20:58:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:58:23 INFO - Running post-action listener: _resource_record_post_action 20:58:23 INFO - [mozharness: 2017-03-22 03:58:23.620995Z] Finished clobber step (success) 20:58:23 INFO - [mozharness: 2017-03-22 03:58:23.621145Z] Running read-buildbot-config step. 20:58:23 INFO - Running pre-action listener: _resource_record_pre_action 20:58:23 INFO - Running main action method: read_buildbot_config 20:58:23 INFO - Using buildbot properties: 20:58:23 INFO - { 20:58:23 INFO - "project": "", 20:58:23 INFO - "product": "firefox", 20:58:23 INFO - "script_repo_revision": "production", 20:58:23 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 20:58:23 INFO - "repository": "", 20:58:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 20:58:23 INFO - "buildid": "20170321200735", 20:58:23 INFO - "pgo_build": "False", 20:58:23 INFO - "basedir": "/builds/slave/test", 20:58:23 INFO - "buildnumber": 22, 20:58:23 INFO - "slavename": "t-yosemite-r7-0137", 20:58:23 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 20:58:23 INFO - "platform": "yosemite_r7", 20:58:23 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:58:23 INFO - "branch": "mozilla-aurora", 20:58:23 INFO - "repo_path": "releases/mozilla-aurora", 20:58:23 INFO - "revision": "aa03407e251b883e9034796feb2301b9fb68af6e", 20:58:23 INFO - "stage_platform": "macosx64", 20:58:23 INFO - "builduid": "7b83031d305344068192ba6da06213e9", 20:58:23 INFO - "slavebuilddir": "test" 20:58:23 INFO - } 20:58:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 20:58:23 INFO - Running post-action listener: _resource_record_post_action 20:58:23 INFO - [mozharness: 2017-03-22 03:58:23.624384Z] Finished read-buildbot-config step (success) 20:58:23 INFO - [mozharness: 2017-03-22 03:58:23.624513Z] Running download-and-extract step. 20:58:23 INFO - Running pre-action listener: _resource_record_pre_action 20:58:23 INFO - Running main action method: download_and_extract 20:58:23 INFO - mkdir: /builds/slave/test/build/tests 20:58:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:58:23 INFO - trying https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 20:58:23 INFO - Downloading https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/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 20:58:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/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 20:58:24 INFO - Downloaded 1199 bytes. 20:58:24 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 20:58:24 INFO - Using the following test package requirements: 20:58:24 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 20:58:24 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:58:24 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 20:58:24 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:58:24 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 20:58:24 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:58:24 INFO - u'jsshell-mac.zip'], 20:58:24 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:58:24 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 20:58:24 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 20:58:24 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:58:24 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 20:58:24 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:58:24 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 20:58:24 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:58:24 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 20:58:24 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 20:58:24 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 20:58:24 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:58:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 20:58:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:58:24 INFO - Fetch https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 20:58:26 INFO - Expected file size: 51782017 20:58:26 INFO - Obtained file size: 51782017 20:58:41 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 20:58:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 20:58:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:58:41 INFO - Fetch https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 20:58:43 INFO - Expected file size: 51782017 20:58:43 INFO - Obtained file size: 51782017 20:58:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 20:58:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 20:58:58 INFO - Fetch https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 20:58:59 INFO - Expected file size: 14404438 20:58:59 INFO - Obtained file size: 14404438 20:59:00 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')]} 20:59:00 INFO - trying https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 20:59:00 INFO - Downloading https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 20:59:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/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 20:59:02 INFO - Downloaded 74694700 bytes. 20:59:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 20:59:02 INFO - mkdir: /builds/slave/test/properties 20:59:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:59:02 INFO - Writing to file /builds/slave/test/properties/build_url 20:59:02 INFO - Contents: 20:59:02 INFO - build_url:https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 20:59:03 INFO - Running post-action listener: _resource_record_post_action 20:59:03 INFO - Running post-action listener: set_extra_try_arguments 20:59:03 INFO - [mozharness: 2017-03-22 03:59:03.034027Z] Finished download-and-extract step (success) 20:59:03 INFO - [mozharness: 2017-03-22 03:59:03.034162Z] Running populate-webroot step. 20:59:03 INFO - Running pre-action listener: _resource_record_pre_action 20:59:03 INFO - Running main action method: populate_webroot 20:59:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:59:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:59:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:59:03 INFO - '--tppagecycles', 20:59:03 INFO - '7'], 20:59:03 INFO - 'tests': ['tsvgm']}}, 20:59:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:59:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:59:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:59:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:59:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:59:03 INFO - 'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:59:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:59:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:59:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:59:03 INFO - 'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['damp', 'tps']}, 20:59:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:59:03 INFO - 'tests': ['damp', 'tps']}, 20:59:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['dromaeo_dom']}, 20:59:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:59:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['basic_compositor_video']}, 20:59:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:59:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['a11yr', 20:59:03 INFO - 'ts_paint', 20:59:03 INFO - 'tpaint', 20:59:03 INFO - 'sessionrestore', 20:59:03 INFO - 'sessionrestore_no_auto_restore', 20:59:03 INFO - 'tabpaint']}, 20:59:03 INFO - 'other-e10s': {'tests': ['a11yr', 20:59:03 INFO - 'ts_paint', 20:59:03 INFO - 'tpaint', 20:59:03 INFO - 'sessionrestore', 20:59:03 INFO - 'sessionrestore_no_auto_restore', 20:59:03 INFO - 'tabpaint']}, 20:59:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:59:03 INFO - 'ts_paint', 20:59:03 INFO - 'tpaint', 20:59:03 INFO - 'sessionrestore', 20:59:03 INFO - 'sessionrestore_no_auto_restore', 20:59:03 INFO - 'tabpaint']}, 20:59:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:59:03 INFO - 'ts_paint', 20:59:03 INFO - 'tpaint', 20:59:03 INFO - 'sessionrestore', 20:59:03 INFO - 'sessionrestore_no_auto_restore', 20:59:03 INFO - 'tabpaint']}, 20:59:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['a11yr', 20:59:03 INFO - 'ts_paint', 20:59:03 INFO - 'tpaint', 20:59:03 INFO - 'sessionrestore', 20:59:03 INFO - 'sessionrestore_no_auto_restore', 20:59:03 INFO - 'tabpaint']}, 20:59:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['a11yr', 20:59:03 INFO - 'ts_paint', 20:59:03 INFO - 'tpaint', 20:59:03 INFO - 'sessionrestore', 20:59:03 INFO - 'sessionrestore_no_auto_restore', 20:59:03 INFO - 'tabpaint']}, 20:59:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['tsvgx', 20:59:03 INFO - 'tsvgr_opacity', 20:59:03 INFO - 'tart', 20:59:03 INFO - 'tscrollx', 20:59:03 INFO - 'cart', 20:59:03 INFO - 'tsvg_static']}, 20:59:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:59:03 INFO - 'tsvgr_opacity', 20:59:03 INFO - 'tart', 20:59:03 INFO - 'tscrollx', 20:59:03 INFO - 'cart', 20:59:03 INFO - 'tsvg_static']}, 20:59:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:59:03 INFO - 'talos_options': ['--disable-e10s'], 20:59:03 INFO - 'tests': ['tp5o']}, 20:59:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:59:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:59:03 INFO - 'talos_options': ['--disable-e10s', 20:59:03 INFO - '--xperf_path', 20:59:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:59:03 INFO - 'tests': ['tp5n']}, 20:59:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:59:03 INFO - 'talos_options': ['--xperf_path', 20:59:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:59:03 INFO - 'tests': ['tp5n']}}, 20:59:03 INFO - 'talos.zip': {'path': '', 20:59:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:59:03 INFO - Running post-action listener: _resource_record_post_action 20:59:03 INFO - [mozharness: 2017-03-22 03:59:03.052449Z] Finished populate-webroot step (success) 20:59:03 INFO - [mozharness: 2017-03-22 03:59:03.052583Z] Running create-virtualenv step. 20:59:03 INFO - Running pre-action listener: _resource_record_pre_action 20:59:03 INFO - Running main action method: create_virtualenv 20:59:03 INFO - Creating virtualenv /builds/slave/test/build/venv 20:59:03 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 20:59:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:59:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:59:03 INFO - Using real prefix '/tools/python27' 20:59:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:59:03 INFO - Installing distribute.............................................................................................................................................................................................done. 20:59:06 INFO - Installing pip.................done. 20:59:06 INFO - Return code: 0 20:59:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:59:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:59:07 INFO - Reading from file tmpfile_stdout 20:59:07 INFO - Output received: 20:59:07 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) 20:59:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:59: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')]} 20:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59: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')]} 20:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:07 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 0x10da3eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da41768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff002d98d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, '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.12sUssLPyz/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.Urib4CFTny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:59:07 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 20:59:07 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 20:59:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:07 INFO - 'HOME': '/Users/cltbld', 20:59:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:07 INFO - 'LOGNAME': 'cltbld', 20:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:07 INFO - 'MOZ_NO_REMOTE': '1', 20:59:07 INFO - 'NO_EM_RESTART': '1', 20:59:07 INFO - 'PAGER': '/bin/cat', 20:59:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:07 INFO - 'PWD': '/builds/slave/test', 20:59:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:07 INFO - 'SHELL': '/bin/bash', 20:59:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:07 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:07 INFO - 'USER': 'cltbld', 20:59:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:07 INFO - 'XPC_FLAGS': '0x0', 20:59:07 INFO - 'XPC_SERVICE_NAME': '0', 20:59:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:07 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 20:59:07 INFO - Cleaning up... 20:59:07 INFO - Return code: 0 20:59:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:59: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')]} 20:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59: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')]} 20:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:07 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 0x10da3eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da41768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff002d98d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, '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.12sUssLPyz/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.Urib4CFTny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:59:07 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 20:59:07 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 20:59:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:07 INFO - 'HOME': '/Users/cltbld', 20:59:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:07 INFO - 'LOGNAME': 'cltbld', 20:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:07 INFO - 'MOZ_NO_REMOTE': '1', 20:59:07 INFO - 'NO_EM_RESTART': '1', 20:59:07 INFO - 'PAGER': '/bin/cat', 20:59:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:07 INFO - 'PWD': '/builds/slave/test', 20:59:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:07 INFO - 'SHELL': '/bin/bash', 20:59:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:07 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:07 INFO - 'USER': 'cltbld', 20:59:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:07 INFO - 'XPC_FLAGS': '0x0', 20:59:07 INFO - 'XPC_SERVICE_NAME': '0', 20:59:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:07 INFO - Downloading/unpacking psutil>=3.1.1 20:59:07 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 20:59:07 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 20:59:07 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 20:59:07 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 20:59:07 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 20:59:07 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 20:59:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:59:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:59:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:59:11 INFO - Installing collected packages: psutil 20:59:11 INFO - Running setup.py install for psutil 20:59:11 INFO - building 'psutil._psutil_osx' extension 20:59:11 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 20:59:11 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 20:59:11 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 20:59:11 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 20:59:11 INFO - building 'psutil._psutil_posix' extension 20:59:11 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 20:59:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:59:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:59:12 INFO - ^ 20:59:12 INFO - 1 warning generated. 20:59:12 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 20:59:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:59:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:59:12 INFO - Successfully installed psutil 20:59:12 INFO - Cleaning up... 20:59:12 INFO - Return code: 0 20:59:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:12 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 0x10da3eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da41768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff002d98d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, '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.12sUssLPyz/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.Urib4CFTny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:59:12 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 20:59:12 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 20:59:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:12 INFO - 'HOME': '/Users/cltbld', 20:59:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:12 INFO - 'LOGNAME': 'cltbld', 20:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:12 INFO - 'MOZ_NO_REMOTE': '1', 20:59:12 INFO - 'NO_EM_RESTART': '1', 20:59:12 INFO - 'PAGER': '/bin/cat', 20:59:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:12 INFO - 'PWD': '/builds/slave/test', 20:59:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:12 INFO - 'SHELL': '/bin/bash', 20:59:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:12 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:12 INFO - 'USER': 'cltbld', 20:59:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:12 INFO - 'XPC_FLAGS': '0x0', 20:59:12 INFO - 'XPC_SERVICE_NAME': '0', 20:59:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:59:12 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 20:59:12 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 20:59:12 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 20:59:12 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 20:59:12 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 20:59:12 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 20:59:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:59:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:59:15 INFO - Installing collected packages: mozsystemmonitor 20:59:15 INFO - Running setup.py install for mozsystemmonitor 20:59:15 INFO - Successfully installed mozsystemmonitor 20:59:15 INFO - Cleaning up... 20:59:15 INFO - Return code: 0 20:59:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10da3eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da41768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff002d98d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, '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.12sUssLPyz/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.Urib4CFTny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:59:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:59:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:59:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:16 INFO - 'HOME': '/Users/cltbld', 20:59:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:16 INFO - 'LOGNAME': 'cltbld', 20:59:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:16 INFO - 'MOZ_NO_REMOTE': '1', 20:59:16 INFO - 'NO_EM_RESTART': '1', 20:59:16 INFO - 'PAGER': '/bin/cat', 20:59:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:16 INFO - 'PWD': '/builds/slave/test', 20:59:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:16 INFO - 'SHELL': '/bin/bash', 20:59:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:16 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:16 INFO - 'USER': 'cltbld', 20:59:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:16 INFO - 'XPC_FLAGS': '0x0', 20:59:16 INFO - 'XPC_SERVICE_NAME': '0', 20:59:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:16 INFO - Downloading/unpacking jsonschema==2.5.1 20:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:59:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:20 INFO - Downloading functools32-3.2.3-2.tar.gz 20:59:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:59:20 INFO - warning: no files found matching '*.txt' 20:59:20 INFO - no previously-included directories found matching 'build' 20:59:20 INFO - no previously-included directories found matching 'dist' 20:59:20 INFO - no previously-included directories found matching '.git*' 20:59:20 INFO - Installing collected packages: jsonschema, functools32 20:59:20 INFO - Running setup.py install for functools32 20:59:20 INFO - warning: no files found matching '*.txt' 20:59:20 INFO - no previously-included directories found matching 'build' 20:59:20 INFO - no previously-included directories found matching 'dist' 20:59:20 INFO - no previously-included directories found matching '.git*' 20:59:20 INFO - Successfully installed jsonschema functools32 20:59:20 INFO - Cleaning up... 20:59:20 INFO - Return code: 0 20:59:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:20 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 0x10da3eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da41768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff002d98d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, '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.12sUssLPyz/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.Urib4CFTny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:59:20 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 20:59:20 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 20:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:20 INFO - 'HOME': '/Users/cltbld', 20:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:20 INFO - 'LOGNAME': 'cltbld', 20:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:20 INFO - 'MOZ_NO_REMOTE': '1', 20:59:20 INFO - 'NO_EM_RESTART': '1', 20:59:20 INFO - 'PAGER': '/bin/cat', 20:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:20 INFO - 'PWD': '/builds/slave/test', 20:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:20 INFO - 'SHELL': '/bin/bash', 20:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:20 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:20 INFO - 'USER': 'cltbld', 20:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:20 INFO - 'XPC_FLAGS': '0x0', 20:59:20 INFO - 'XPC_SERVICE_NAME': '0', 20:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:59:20 INFO - Cleaning up... 20:59:20 INFO - Return code: 0 20:59:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:20 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 0x10da3eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da41768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff002d98d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, '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.12sUssLPyz/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.Urib4CFTny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:59:20 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 20:59:20 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 20:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:20 INFO - 'HOME': '/Users/cltbld', 20:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:20 INFO - 'LOGNAME': 'cltbld', 20:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:20 INFO - 'MOZ_NO_REMOTE': '1', 20:59:20 INFO - 'NO_EM_RESTART': '1', 20:59:20 INFO - 'PAGER': '/bin/cat', 20:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:20 INFO - 'PWD': '/builds/slave/test', 20:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:20 INFO - 'SHELL': '/bin/bash', 20:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:20 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:20 INFO - 'USER': 'cltbld', 20:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:20 INFO - 'XPC_FLAGS': '0x0', 20:59:20 INFO - 'XPC_SERVICE_NAME': '0', 20:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:21 INFO - Downloading/unpacking blobuploader==1.2.4 20:59:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:23 INFO - Downloading blobuploader-1.2.4.tar.gz 20:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:59:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:59: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 20:59: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 20:59: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 20:59: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 20:59: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 20:59: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 20:59:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:59:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:59:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:59:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:59:25 INFO - Downloading docopt-0.6.1.tar.gz 20:59:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:59:25 INFO - Installing collected packages: blobuploader, requests, docopt 20:59:25 INFO - Running setup.py install for blobuploader 20:59:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:59:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:59:25 INFO - Running setup.py install for requests 20:59:25 INFO - Running setup.py install for docopt 20:59:25 INFO - Successfully installed blobuploader requests docopt 20:59:25 INFO - Cleaning up... 20:59:26 INFO - Return code: 0 20:59:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:59: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')]} 20:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59: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')]} 20:59:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:26 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 0x10da3eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da41768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff002d98d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, '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.12sUssLPyz/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.Urib4CFTny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:59:26 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 20:59:26 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 20:59:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:26 INFO - 'HOME': '/Users/cltbld', 20:59:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:26 INFO - 'LOGNAME': 'cltbld', 20:59:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:26 INFO - 'MOZ_NO_REMOTE': '1', 20:59:26 INFO - 'NO_EM_RESTART': '1', 20:59:26 INFO - 'PAGER': '/bin/cat', 20:59:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:26 INFO - 'PWD': '/builds/slave/test', 20:59:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:26 INFO - 'SHELL': '/bin/bash', 20:59:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:26 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:26 INFO - 'USER': 'cltbld', 20:59:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:26 INFO - 'XPC_FLAGS': '0x0', 20:59:26 INFO - 'XPC_SERVICE_NAME': '0', 20:59:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:59:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-iUBLMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:59:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-TKP5ic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:59:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-yoXYZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:59:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-HVFM0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:59:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-dy2Aao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:59:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-UztnHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:59:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:59:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-j4vFVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:59:27 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-w5NrGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:59:27 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-3PAi6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:59:27 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-2Kbbn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:59:27 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-fZbKHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:59:27 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-fu_dKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:59:27 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-H4ufzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:59:27 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-XDNIwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:59:27 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Waapbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:59:27 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-hmWNFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:59:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:59:27 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-HJ7R1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:59:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:59:28 INFO - Running setup.py install for manifestparser 20:59:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:59:28 INFO - Running setup.py install for mozcrash 20:59:28 INFO - Running setup.py install for mozdebug 20:59:28 INFO - Running setup.py install for mozdevice 20:59:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:59:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:59:28 INFO - Running setup.py install for mozfile 20:59:28 INFO - Running setup.py install for mozhttpd 20:59:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:59:28 INFO - Running setup.py install for mozinfo 20:59:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:59:29 INFO - Running setup.py install for mozInstall 20:59:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:59:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:59:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:59:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:59:29 INFO - Running setup.py install for mozleak 20:59:29 INFO - Running setup.py install for mozlog 20:59:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:59:29 INFO - Running setup.py install for moznetwork 20:59:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:59:29 INFO - Running setup.py install for mozprocess 20:59:29 INFO - Running setup.py install for mozprofile 20:59:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:59:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:59:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:59:30 INFO - Running setup.py install for mozrunner 20:59:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:59:30 INFO - Running setup.py install for mozscreenshot 20:59:30 INFO - Running setup.py install for moztest 20:59:30 INFO - Running setup.py install for mozversion 20:59:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:59:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:59:30 INFO - Cleaning up... 20:59:30 INFO - Return code: 0 20:59:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:59: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')]} 20:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59: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')]} 20:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:30 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 0x10da3eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da41768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff002d98d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, '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.12sUssLPyz/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.Urib4CFTny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:59:30 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 20:59:30 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 20:59:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:30 INFO - 'HOME': '/Users/cltbld', 20:59:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:30 INFO - 'LOGNAME': 'cltbld', 20:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:30 INFO - 'MOZ_NO_REMOTE': '1', 20:59:30 INFO - 'NO_EM_RESTART': '1', 20:59:30 INFO - 'PAGER': '/bin/cat', 20:59:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:30 INFO - 'PWD': '/builds/slave/test', 20:59:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:30 INFO - 'SHELL': '/bin/bash', 20:59:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:30 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:30 INFO - 'USER': 'cltbld', 20:59:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:30 INFO - 'XPC_FLAGS': '0x0', 20:59:30 INFO - 'XPC_SERVICE_NAME': '0', 20:59:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:59:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-kyVbeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:59:31 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)) 20:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:59:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-J_jbCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:59:31 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)) 20:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:59:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-AIyXpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:59:31 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)) 20:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:59:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-xRmOoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:59:31 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)) 20:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:59:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-OhIIDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:59:31 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)) 20:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:59:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-3rDUxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:59:31 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)) 20:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:59:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-rEzQna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:59:31 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)) 20:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:59:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-jEhm9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:59:31 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)) 20:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:59:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-kCQOl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:59:31 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)) 20:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:59:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-uN3ReN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:59:31 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)) 20:59:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:59:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-pvZzkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:59:32 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)) 20:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:59:32 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-fOEm82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:59:32 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)) 20:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:59:32 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-tOxORr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:59:32 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)) 20:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:59:32 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-wZFnyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:59:32 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)) 20:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:59:32 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-vti644-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:59:32 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)) 20:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:59:32 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-OhnndC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:59:32 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)) 20:59:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:59:32 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ANZnu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:59:32 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)) 20:59:32 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)) 20:59:32 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)) 20:59:32 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)) 20:59:32 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)) 20:59:32 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)) 20:59:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:59:32 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 20:59:32 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 20:59:32 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 20:59:32 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 20:59:32 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 20:59:32 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 20:59:35 INFO - Downloading blessings-1.6.tar.gz 20:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:59:35 INFO - Installing collected packages: blessings 20:59:35 INFO - Running setup.py install for blessings 20:59:35 INFO - Successfully installed blessings 20:59:35 INFO - Cleaning up... 20:59:35 INFO - Return code: 0 20:59:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:59:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:59:36 INFO - Reading from file tmpfile_stdout 20:59:36 INFO - Current package versions: 20:59:36 INFO - blessings == 1.6 20:59:36 INFO - blobuploader == 1.2.4 20:59:36 INFO - docopt == 0.6.1 20:59:36 INFO - functools32 == 3.2.3-2 20:59:36 INFO - jsonschema == 2.5.1 20:59:36 INFO - manifestparser == 1.1 20:59:36 INFO - mozInstall == 1.12 20:59:36 INFO - mozcrash == 1.0 20:59:36 INFO - mozdebug == 0.1 20:59:36 INFO - mozdevice == 0.49 20:59:36 INFO - mozfile == 1.2 20:59:36 INFO - mozhttpd == 0.7 20:59:36 INFO - mozinfo == 0.9 20:59:36 INFO - mozleak == 0.1 20:59:36 INFO - mozlog == 3.4 20:59:36 INFO - moznetwork == 0.27 20:59:36 INFO - mozprocess == 0.25 20:59:36 INFO - mozprofile == 0.28 20:59:36 INFO - mozrunner == 6.13 20:59:36 INFO - mozscreenshot == 0.1 20:59:36 INFO - mozsystemmonitor == 0.3 20:59:36 INFO - moztest == 0.8 20:59:36 INFO - mozversion == 1.4 20:59:36 INFO - psutil == 3.1.1 20:59:36 INFO - requests == 1.2.3 20:59:36 INFO - wsgiref == 0.1.2 20:59:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:59: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')]} 20:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59: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')]} 20:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:36 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 0x10da3eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da41768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff002d98d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, '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.12sUssLPyz/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.Urib4CFTny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:59:36 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 20:59:36 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 20:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:36 INFO - 'HOME': '/Users/cltbld', 20:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:36 INFO - 'LOGNAME': 'cltbld', 20:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:36 INFO - 'MOZ_NO_REMOTE': '1', 20:59:36 INFO - 'NO_EM_RESTART': '1', 20:59:36 INFO - 'PAGER': '/bin/cat', 20:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:36 INFO - 'PWD': '/builds/slave/test', 20:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:36 INFO - 'SHELL': '/bin/bash', 20:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:36 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:36 INFO - 'USER': 'cltbld', 20:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:36 INFO - 'XPC_FLAGS': '0x0', 20:59:36 INFO - 'XPC_SERVICE_NAME': '0', 20:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:36 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)) 20:59:36 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)) 20:59:36 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)) 20:59:36 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)) 20:59:36 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)) 20:59:36 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)) 20:59:36 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)) 20:59:36 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)) 20:59:36 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)) 20:59:36 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)) 20:59:36 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)) 20:59:36 INFO - Cleaning up... 20:59:36 INFO - Return code: 0 20:59:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:59: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')]} 20:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59: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')]} 20:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:59:36 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 0x10da3eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da41768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff002d98d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, '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.12sUssLPyz/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.Urib4CFTny/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 20:59:36 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 20:59:36 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 20:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:36 INFO - 'HOME': '/Users/cltbld', 20:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:36 INFO - 'LOGNAME': 'cltbld', 20:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:36 INFO - 'MOZ_NO_REMOTE': '1', 20:59:36 INFO - 'NO_EM_RESTART': '1', 20:59:36 INFO - 'PAGER': '/bin/cat', 20:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:36 INFO - 'PWD': '/builds/slave/test', 20:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:36 INFO - 'SHELL': '/bin/bash', 20:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:36 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:36 INFO - 'USER': 'cltbld', 20:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:36 INFO - 'XPC_FLAGS': '0x0', 20:59:36 INFO - 'XPC_SERVICE_NAME': '0', 20:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:59:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:59:36 INFO - Cleaning up... 20:59:36 INFO - Return code: 0 20:59:36 INFO - Running post-action listener: _resource_record_post_action 20:59:36 INFO - Running post-action listener: _start_resource_monitoring 20:59:36 INFO - Starting resource monitoring. 20:59:36 INFO - [mozharness: 2017-03-22 03:59:36.580281Z] Finished create-virtualenv step (success) 20:59:36 INFO - [mozharness: 2017-03-22 03:59:36.581269Z] Running install step. 20:59:36 INFO - Running pre-action listener: _resource_record_pre_action 20:59:36 INFO - Running main action method: install 20:59:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:59:36 INFO - Reading from file tmpfile_stdout 20:59:36 INFO - Detecting whether we're running mozinstall >=1.0... 20:59:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:59:36 INFO - Reading from file tmpfile_stdout 20:59:36 INFO - Output received: 20:59:36 INFO - Usage: mozinstall [options] installer 20:59:36 INFO - Options: 20:59:36 INFO - -h, --help show this help message and exit 20:59:36 INFO - -d DEST, --destination=DEST 20:59:36 INFO - Directory to install application into. [default: 20:59:36 INFO - "/builds/slave/test"] 20:59:36 INFO - --app=APP Application being installed. [default: firefox] 20:59:36 INFO - mkdir: /builds/slave/test/build/application 20:59:36 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'] 20:59:36 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 20:59:57 INFO - Reading from file tmpfile_stdout 20:59:57 INFO - Output received: 20:59:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:59:57 INFO - Running post-action listener: _resource_record_post_action 20:59:57 INFO - [mozharness: 2017-03-22 03:59:57.161599Z] Finished install step (success) 20:59:57 INFO - [mozharness: 2017-03-22 03:59:57.161746Z] Running run-tests step. 20:59:57 INFO - Running pre-action listener: _resource_record_pre_action 20:59:57 INFO - Running main action method: run_tests 20:59:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:59:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:59:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:59:57 INFO - 2017-03-21 20:59:57.176 screenresolution[1629:7503] starting screenresolution argv=screenresolution get 20:59:57 INFO - 2017-03-21 20:59:57.178 screenresolution[1629:7503] Display 0: 1600x1200x32@60 20:59:57 INFO - 2017-03-21 20:59:57.184 screenresolution[1630:7506] starting screenresolution argv=screenresolution list 20:59:57 INFO - Available Modes on Display 0 20:59:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:59:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:59:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:59:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:59:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:59:57 INFO - Intel Iris: 20:59:57 INFO - Chipset Model: Intel Iris 20:59:57 INFO - Type: GPU 20:59:57 INFO - Bus: Built-In 20:59:57 INFO - VRAM (Dynamic, Max): 1536 MB 20:59:57 INFO - Vendor: Intel (0x8086) 20:59:57 INFO - Device ID: 0x0a2e 20:59:57 INFO - Revision ID: 0x0009 20:59:57 INFO - Displays: 20:59:57 INFO - Display: 20:59:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:59:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:59:57 INFO - Display Serial Number: 1600x1200 60 20:59:57 INFO - Main Display: Yes 20:59:57 INFO - Mirror: Off 20:59:57 INFO - Online: Yes 20:59:57 INFO - Rotation: Supported 20:59:57 INFO - Return code: 0 20:59:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:59:57 INFO - Python 2.7.3 20:59:57 INFO - Return code: 0 20:59:57 INFO - grabbing minidump binary from tooltool 20:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:59:57 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 0x10d81cd30>, '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 0x10d743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d811be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:59:57 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 20:59:57 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 20:59:57 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 20:59:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:59:57 INFO - Return code: 0 20:59:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:59:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:59:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:59:57 INFO - ENV: RUST_BACKTRACE is now 1 20:59:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:59:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:59:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0137 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:59:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.12sUssLPyz/Render', 20:59:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:59:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:59:57 INFO - 'HOME': '/Users/cltbld', 20:59:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:59:57 INFO - 'LOGNAME': 'cltbld', 20:59:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:59:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:59:57 INFO - 'MOZ_NO_REMOTE': '1', 20:59:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:59:57 INFO - 'NO_EM_RESTART': '1', 20:59:57 INFO - 'PAGER': '/bin/cat', 20:59:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:59:57 INFO - 'PWD': '/builds/slave/test', 20:59:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:59:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:59:57 INFO - 'RUST_BACKTRACE': '1', 20:59:57 INFO - 'SHELL': '/bin/bash', 20:59:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Urib4CFTny/Listeners', 20:59:57 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 20:59:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:59:57 INFO - 'USER': 'cltbld', 20:59:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:59:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:59:57 INFO - 'XPC_FLAGS': '0x0', 20:59:57 INFO - 'XPC_SERVICE_NAME': '0', 20:59:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:59:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:59:57 INFO - mozversion application_buildid: 20170321200735 20:59:57 INFO - mozversion application_changeset: aa03407e251b883e9034796feb2301b9fb68af6e 20:59:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:59:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:59:57 INFO - mozversion application_name: Firefox 20:59:57 INFO - mozversion application_remotingname: firefox-dev 20:59:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:59:57 INFO - mozversion application_vendor: Mozilla 20:59:57 INFO - mozversion application_version: 54.0a2 20:59:57 INFO - mozversion platform_buildid: 20170321200735 20:59:57 INFO - mozversion platform_changeset: aa03407e251b883e9034796feb2301b9fb68af6e 20:59:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:59:57 INFO - mozversion platform_version: 54.0a2 20:59:57 INFO - using testdate: 1490155197 20:59:57 INFO - actual date: 1490155197 20:59:57 INFO - starting webserver on 'localhost:49242' 20:59:57 INFO - SUITE-START | Running 2 tests 20:59:57 INFO - TEST-START | dromaeo_css 20:59:57 INFO - operating with platform_type : mac_ 20:59:57 INFO - Initialising browser for dromaeo_css test... 20:59:57 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpK_C2AN/profile http://localhost:49242/getInfo.html) 21:00:00 INFO - PROCESS | 1640 | 2017-03-21 21:00:00.721 plugin-container[1642:7679] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 21:00:00 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:00:00 INFO - PROCESS | 1640 | 2017-03-21 21:00:00.723 plugin-container[1642:7679] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 21:00:00 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:00:00 INFO - PROCESS | 1640 | 2017-03-21 21:00:00.724 plugin-container[1642:7679] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:00:00 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 21:00:00 INFO - PROCESS | 1640 | colorDepth:24 21:00:00 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 21:00:00 INFO - PROCESS | 1640 | __metrics 21:00:00 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 21:00:01 INFO - TEST-INFO | 1640: exit 0 21:00:01 INFO - Browser initialized. 21:00:01 INFO - Running cycle 1/1 for dromaeo_css test... 21:00:01 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpK_C2AN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:00:03 INFO - PROCESS | 1643 | 2017-03-21 21:00:03.960 plugin-container[1644:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 21:00:03 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:00:03 INFO - PROCESS | 1643 | 2017-03-21 21:00:03.960 plugin-container[1644:7810] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 21:00:03 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:00:03 INFO - PROCESS | 1643 | 2017-03-21 21:00:03.960 plugin-container[1644:7810] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:00:13 INFO - PROCESS | 1643 | RSS: Main: 220393472 21:00:13 INFO - PROCESS | 1643 | 21:03:17 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 21:03:19 INFO - PROCESS | 1643 | RSS: Main: 243789824 21:03:19 INFO - PROCESS | 1643 | 21:05:02 INFO - PROCESS | 1643 | ************************* 21:05:02 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 21:05:02 INFO - PROCESS | 1643 | 21:05:02 ERROR - PROCESS | 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 21:05:02 INFO - PROCESS | 1643 | Full stack: 21:05:02 INFO - PROCESS | 1643 | ************************* 21:06:21 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 21:06:21 INFO - PROCESS | 1643 | RSS: Main: 252624896 21:06:21 INFO - PROCESS | 1643 | 21:09:23 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 21:09:24 INFO - PROCESS | 1643 | RSS: Main: 259059712 21:09:24 INFO - PROCESS | 1643 | 21:12:26 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 21:12:27 INFO - PROCESS | 1643 | RSS: Main: 255315968 21:12:27 INFO - PROCESS | 1643 | 21:15:30 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 21:15:30 INFO - PROCESS | 1643 | RSS: Main: 260460544 21:15:30 INFO - PROCESS | 1643 | 21:18:33 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 21:18:34 INFO - PROCESS | 1643 | RSS: Main: 256622592 21:18:34 INFO - PROCESS | 1643 | 21:18:34 INFO - PROCESS | 1643 | __start_tp_report 21:18:34 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 21:18:34 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 21:18:34 INFO - PROCESS | 1643 | |i|pagename|runs| 21:18:34 INFO - PROCESS | 1643 | |0;dojo.html;3501.912452188695;3503.0716860032094;3516.472529120633;3531.858725650974;3541.397962346401;6134.3252242253375;6141.570090093695;6160.229971253593;6197.845053873655;6206.131141640163;6138.907916381258;6175.969120154393;6180.938190618092;6197.907031394533;6199.473044791198;5486.753096110665;5551.611387202897;5568.610197286192;5580.469855363734;5614.859628509279;5600.887982240352;5611.579131565149;5612.466815652527;5616.0171969905305;5668.518175955049;5410.567154627621;5410.675359478424;5603.271574695284;5612.242347283126;5614.9719251403685;5526.032944234763;5533.280673512418;5542.972285138569;5573.665580065209;5608.6634801911605;5476.479734425223;5546.972265138648;5548.223248745179;5553.028220061473;5556.583256255797;4990.25146228065;5971.402859714038;6201.100840378165;6215.844603884894;6222.12890195373;6311.747530098791;6337.366263373672;6356.809295721136;6376.6174029557915;6382.234131904202;5456.863578410532;5500.312460942382;6559.836004099936;6584.407403333675;6603.93396066038;5413.6736499557865;6233.2208473940755;6271.341509141565;6272.3100458949475;6284.183056202664;6385.393387628168;6445.936420490567;6537.836554086186;6734.124563806674;6741.325867413219;6317.557770956081;6388.648624325707;6391;6400.9039864402075;6404.135441715309;6571.737130514822;6597.637129957899;6610.537262391586;6618.205815302194;6629.502787290972;6249.281332646713;6505.349465053457;6515.218173819172;6517.608943463408;6520.543561950618;6318.589291696025;6331.9366806331345;6350.714217860207;6388.744450222032;6424.74301027963;5247.842564723064;5249.895002099936;5260.237265596467;5275;5287.259783630295;3888.9611103889165;4642.3500709900645;4663.300504924288;4664.953350466452;4700.341952126705;6035.849103772441;6049.062395328731;6114.996965385453;6116.214037636738;6282.528810339243;5408.513233808976;6456.644884531302;6467.967660161763;6496;6508.576942498722;6225.533085018551;6226.377362263828;6245.937540624626;6253.749850005949;6313.337099604567;5418.593605479605;5591.692456914909;5751.079827227623;5785.884282314414;5841.4450627190345;5570.944290557042;6616.636085015371;6631.104800851823;6715.630640314829;6731.831704207434;5460.91808622863;6602.438792702674;6603.9339606605245;6616.503762217757;6622.139121914121;5744.770209191753;6746.5277430581355;6786.915011422959;6827.975803629495;6853.862922741418;6229.813105606839;6305.527085468517;6387.904181437375;6395.584287021329;6493.415592596689;4682.321063445713;6298.779542715983;6574.868502629825;6576.013597960345;6576.980568011966;5856.594417339893;6154.1999540059505;6246.312905580474;6298.307186209422;6369.585976911486;5604.327480702342;6037.939620603738;6260.843478912881;6427.357264273535;6438.420542151229;4694.976525117352;4775.925416781197;6190.41191086846;6229.190205273286;6286.214223222098;5301.893962120813;5386.8383948481605;6236.812895613139;6274.121622972879;6401.487880969418;6257.43683068526;6283.434490895841;6298.0867774171575;6308.968455157695;6334.018227174795 21:18:34 INFO - PROCESS | 1643 | |1;ext.html;666.819958611175;753.745014550773;771.9266669666381;953.1945506047388;962.8315044867146;37831.81084094579;38688.80655596722;40209.99475013126;40222.597774022324;40225.597744022554;38411;38624;38646.806765966205;38713;38843.611563884355;7889.329407000412;10526.894731052667;10865.293755905881;10936.070434013118;10952.945235273812;10640.733981650443;10664.200184986119;10697.946510267477;10738.194635402337;10750.247482676215;10727.517261723202;10730.248882578222;10779.838302425469;10803.621873234439;10885;10705.14358851294;10712.41081740504;10718.624848130317;10766.946165269203;10784.245102842764;10485.266031377807;10550;10555.894441055569;10569.048785609331;10579.788404231873;34150.82924585386;34182.6581734182;34304.65695343039;34392.484112738326;34445.311093777986;3721.925561488755;3734.850605975758;3751.5096993523725;3818.9045273868096;3819.312523745725;21694.89152554243;25578;26564.867175664192;26629.06798262071;26632;2985.149232468758;3024.0474250611205;3034.180771191788;3230.370077834823;3232.8060316381093;369031;371972;372005;373226;373928;43733.56266437359;44097.77951110288;44113.33829992553;44210.55789442129;44277.55722442734;46115.30827037597;46409.83975400574;46593;46620.3006954896;46659.766701166955;13872.514461993782;13940.930295348458;14019.789703154462;14031.508897188549;14032.789508157386;12108.970737487236;12121.151519393734;12136.5145394185;12166.695832604255;12179.817302740466;10455.738606534745;11996.400179990966;12007.339596322112;12028.338441385808;12098.81851772224;5573.498385145356;5584.692841893735;5602;5638.210650508932;5642.774289028475;1148.2593727046176;1157.9826302605468;1159.3565571107947;1168.4741866160261;1208.945597448117;22415.99128039242;24953.251402457954;25616.463012219327;25973.350666233495;26949.326266843487;1232.6302109367155;1249.8875101240933;1261.6152073617452;1269.6000759760645;1302.5961951794975;12863.742725145445;13741.684673849666;13832.723345533033;13928.233947133005;13993.370298336396;402367;465502;466499;470412;471069;56176.71911640497;56415;56444;56472.87054258892;56674;1396.0855639556107;1403.9929800350933;1436.3895344478556;1444.2056868722161;1447.8986470947143;21184.364469065953;25670.101546445785;26657.933682652478;27142.72857271402;27266;40483.19033619252;40560.59439405568;40565;40583;40706.38940415837;460575;461828;464539;464938;466325.67348653515;28433.715662843108;28723.856380717963;36848.44727329039;38539.61460385472;38630.80684596671;45331.7733411344;45962.77018614885;46035;47102;47605.76197119131;33290.500642490864;35898.4615230777;37903;38281;39587;38068.42897356597;38168.42747358736;38884.416733748454;39185.82442526729;39717.404238937015;38046.42930355992;38665.80667096646;38693.613063869;38814;39036.219275614894;31672.5249121268;34240.143996400286;34745.8262708695;35165.64834351624;35187.64812351844;23976.74210217183;24219.636705449786;24957.625635615845;26047.218583442525;27526.17421477359 21:18:34 INFO - PROCESS | 1643 | |2;jquery.html;385.33721998163156;405.68559366490985;406.7701748512092;422.5077784381195;453.98987253361275;16900.492985210432;19818.71178373405;20065;21212.89393553034;21342.786572134275;21270.36188914335;21406.357809265708;21723.13107475699;22492;22590.43523911903;6470.611763294206;6692.297308782557;6742.292059333773;6770.796876093725;6784.72861085556;6300.401461861115;6516.543841931067;6558.836029099263;6583.111279977214;6653.966730166342;6579.111819904299;6579.769708060219;6589.80230593083;6592.110065141216;6593.044008618747;7061.399781018619;7418.1098268207625;7473.626318684044;7484;7490.513116647455;6657.633830139341;7083.150021997341;7256.129264488295;7282.526635768658;7304.904264360335;26033.088841890632;26288.86855565729;26292.816823243;26438;27080.32299192536;2838.403935173616;2888.581155732407;2919.5474701421313;2962.0453869785;2963.273997870514;15965.361385544564;16027.679446411123;16051;16604.50186494395;16814.32742690291;1925.2683980087477;1964.65618516759;2028.4928767808049;2101.9054142563623;2116.7883211678864;145487.27256363755;146132;154352.2282388592;158969;162691;16394.754078688828;19434.319798807257;20152.29466968649;20355;21072.367828965154;30968;31752.682473174973;31854;31861;31930;7996.64015119321;9966.850497242547;10047.748806279755;10300;10337.328073675188;8343.582820858932;8389.496630202086;8401.95799021001;8541.743747687578;8546.914530854612;7982.640781164745;8306.920100386911;9076.273898088137;9591.760205994906;9641.517924103768;3390.4744764561533;3961.623645753649;4081.469408976814;4085.264652362603;4086.366613174963;910.8451563234266;917.3899356927702;919.990800092004;954.1317401164906;956.268454632207;17596.82403175952;21515.67726484104;22533.661995070088;22663.433414164447;22673.433164171027;753.8416932444138;801.8476489466983;812.3907069697726;918.6830543462495;922.7923717163585;9687.031296870256;10178;11555.653330400102;11742.70643233926;11790.997765190055;430506;494606;731541;731848;747713;129272;162528;165332.17333913254;166194;166600.33399666092;1239.7086684629105;1258.4399942025893;1281.1928485054357;1287.7810772168566;1292.9224246545236;17857.910710446886;19080.088581840966;19973;20955.685664715347;21158.365249042552;68627.31372686409;70727;71684.64157679178;71727;73020;96517.51741241249;97264;98512.52231216394;99750;99757;10129.949350253202;10353.63762268317;10671.199660025684;11527.481263343001;11803.996660283628;11653.6503904883;12059.457324420247;13284.667883302995;13790.655233618838;14138.22239776822;10538.631147909786;10951.412045253235;11012.5044373005;11515.193936424705;11727.648170554898;10773.676789696321;11644;11822;11884.405779711153;12095.455704493143;11568.884311157117;11809.350485723367;11928.284302941851;12103.75792484163;12136.878631213574;11708.531658733555;11734.237274577124;11917.94041029824;12043.638690839289;12111.091668124749;11426.885731142582;11646.650600481998;11807.704807379885;11854.94072529632;11950.103742219542 21:18:34 INFO - PROCESS | 1643 | |3;mootools.html;1522.0791421190186;1550.2868680407005;1570.9788637385698;1591.3714082937238;1599.2563457992035;10701.250912436122;11105;11336.433178341082;11377.601783937564;11394.974452299291;11540.192186546945;11699.649010529676;11730.472128754205;11732.53069877204;11753.882461175386;2377.9167729129526;2409.265174121892;2418.9274321770285;2432.3797431654903;2435.5873593315923;2042.9284975025803;2362.9409264768346;2400.7479214682467;2412.9155479558217;2414.0705828256073;1989.3037436897116;2360.775726305998;2369.3365857559825;2425.78167964884;2427.2111563741855;2344.7303560090636;2354.3407845803117;2363.633636786301;2371.8221133414977;2377.5244951009868;2353.3528279723214;2383.630537266709;2414.5170965806733;2414.879256037191;2416.5046165536023;11258.986691197832;11480.598179063692;11494.482748276265;11506;11512.654620361318;5795.9420405796245;5881.3530511643685;5964.761409543613;5967.8209653710455;5980.671063091529;3534.8232588370734;3662.487251784752;3705.7035437164964;3711.183539621306;3751.568569614502;1188.4592510407745;1198.6703656494446;1249.4002878618314;1249.450241893573;1266.0567876931652;13795.586132415943;13967.79048314276;14945.476908308267;15472.458463953708;15637.765433518507;3898.863539776151;4712.128256272601;4745.0242598276645;4853.466118726938;4858.101251268456;11650.242734222249;11771.23486973344;11789.292642441309;11791.174617776673;11794.88205117955;5872.823815285548;5874.794382196603;5881.5000724938;5892.204552385459;5899.616524925866;4758.452777930583;4876.975615121902;5238.476152384731;5300.946990530046;5320.12217984036;3889.163829776555;3955.20895820837;4003.8798836034966;4022.6379625833815;4045.8786236412957;887.2857349833317;1005.983956204239;1042.7497400623947;1046.4140081554353;1055.3193190392153;768.5734417398397;869.7999460124207;944.9196818270386;951.1534734086669;962.6005207838668;6323.241211054611;6450.6774661266745;6651.7716564950315;6702;6718.294579069225;973.78576713123;992.2062350120019;994.2493417469908;1000.5097502224004;1031.9371047820607;4461.5092339842595;4707.882302942454;4708.081924024782;4813.9037219255415;4845.5881250093435;13078.934605327102;13534.255615941218;14118;14490.565283041526;14499.275036248146;11333.829992550118;13697.178169309873;13714.794278085838;13742.450301988009;13765.518206862711;8656;8804.823903521894;10247.692569222936;10271.845922311171;10302.278840481094;6363.938673509464;6490.613428752178;6982.6508674567085;7019.157701075699;7149.678264478215;11862.169648124893;11929.761404772029;12134.089943254115;12153.149279550347;12172.817407738714;10824.458777060958;12451.190672606252;12681.30252836103;12860.421281042563;12975.545855894998;9270.907290927003;9283;9317.906820931705;9368.57841397125;9410.341276110606;10254.743631409274;10274.589016439557;10307.845382319421;10345.948270258901;10369.844452333373;9098.744949155129;9122;9151.03914089011;9223.123803238681;9323.627054917728;8731.25784308341;8886.977997530366;8964.551772411483;8987.19115279628;8996.50519221449;8022.398320126131;8032.678692852454;8099.230573095547;8109.351251899951;8175.264226219669;4971.328870602422;5927.911081333697;6033.818985430444;6108.236470441195;6235.438810507076;6121.265448146251;6122.663253521099;6151.723172457339;6238.6880655966115;6266.68666566679 21:18:34 INFO - PROCESS | 1643 | |4;prototype.html;291.6398248163519;354.35153668786126;376.5895174260056;384.9826757795898;390.3578613181319;4632;4648.907021859561;4915.82794602189;4916.58209052231;5328.671328671328;4456.220161471746;5261.316028916245;5346.4118946915905;5362.678239305645;5383.703896285702;4030.7380020298733;4306.901740056297;4381.255186618267;4383.364412160235;4386.758728269944;3601.7298702597277;3762.2663580601793;4377.912441751163;4410.911781764363;4417.602415782579;4203.810828512725;4379.802908869093;4394.934075988863;4406.955930440703;4456.153330867141;3563.879491702433;4099.3031184698675;4292.935605965913;4402.207602631525;4421.933670994921;4004.1190937993597;4004.939925901114;4012.277789997799;4014.8996275093064;4025.8993525162105;4692.0381321828945;5045.763787871995;5128.589712822965;5165.405978312505;5178.430372659004;4527.320901864714;4795.304680821297;5016.974915125437;5025.306399796147;5064.9746751266375;5019.874503137415;5589.413111623262;5626.831195064155;5653.378128405872;5669.902136209587;3768.963535027862;3828.004718773111;3969.563348031743;4300.763458009808;4528.977355113237;5138.948610513922;5924.9703751480965;5925.459380561042;5952.226210592638;5958.225430694026;3621.9818900905325;4296.0978194578865;4337.674674399433;4360.4331436913;4632.722036677743;4234.9364759528635;4301.526832048472;4338.414314067623;4697.436307643105;4784.593309568656;4490.86683825187;4550.020740977685;4559.9512112213715;5031.924521132186;5146.742662866916;4793.161196790547;4807.95192048082;5354.581036025456;5644.379118296984;5822.359540450547;1454.9160875148082;1498.980693128683;1593.5219434169703;1593.9043657380362;1627.5768300242023;1131.5926266544038;1221.5236057937411;1224.7305592769576;1226.2029680707433;1246.507629486348;4675.719456832601;4819.987802561432;4910.337104490848;5005.749712514433;5121;5242.368403968982;5594.601349662585;5919.260092488439;5921.61509501881;5944.762209511571;4793.148076894242;4828.927566086511;4865.489123642037;5027.974860125676;5430.945690543044;2738.9726102739114;2760.903368382097;2863.4130003349264;3055.388922215566;3079.214800225973;5445.537129343971;5627.5779316551425;5991.483576897626;6106.2902387331615;6157.261128664589;4852.441969173519;5878.559108066826;6064.151018857364;6122.877542449215;6170.197874276405;5398.9190162146815;5448.030823201365;5716.142578613159;5876.147958546072;6140.938590614215;4977.701337919736;5028.597712182944;5557.944420555905;5814.505767009851;5962.344142144274;4832.163777764427;5101.285819985131;5130.152765215192;5821.446962538722;5830.883382332415;5392.710446627632;5822.186467863557;5922.92697555852;6095.293317871004;6136.815895523141;4070.435625555881;4593.977030114828;4600.861974140781;4622.445306563234;4659.370984916993;5161;5292.888493416272;5671.801486947937;5823.970880145572;5933.643981361132;5056.6713163644245;5084.745762711924;5222.6912796941915;5536.141898005815;5707.914381284367;4620.699654522446;5313.521783039545;5363.383210930637;5370.3287089113865;5726.742296596746;4429.512753597166;5170.759017835768;5193.454687257784;5511.614187006871;5520.779168833202;5644.475991482359;5645.379008309164;5689.203511508475;5692.434580490398;5918.940810591839;4849.442314133919;5020.397552293748;5466.4260252674485;5615.606907516435;5679.403662615366 21:18:34 INFO - PROCESS | 1643 | |5;yui.html;699.9510034297595;713.8643657705032;765.5330248548388;824.991750082499;833.2792134803393;15378.154201518912;15791.921040394782;16207.918960405212;16356.754648680278;16407.917960410196;14799.630009249775;15099.62250943727;15351.155686437241;15364.385424583003;15431.691366172698;902.6163880350841;946.1059298962483;949.2548349545574;951.7002144324516;953.6805170267934;909.236241557092;936.8313703533362;948.1229862377273;948.672707915768;949.9145076943074;971.208465100944;981.1464026297132;981.5386768218962;981.906718861707;983.4197823284261;823.4194892600732;973.600823662295;973.8685277487555;974.4445665970364;975.926805489584;717.1107826295356;722.4942540221866;722.6531264992833;725.1877896755615;725.8802297620887;14546.418143274257;14691.779623305552;14853.925730371388;15167.848321516864;15850.841491585052;14006.159630422208;14337.28313584327;14447.49433769813;14481.855181448156;14543.127412355292;11706.356150411724;11827.94086029573;11954.880451195464;11966.22219555726;12423.192492487959;3177.9682203177904;3237.7895436796766;3707.5550933887835;3865.690744740414;3870.7290489665734;95609;111555.44222278836;120176.39911800473;123839;124732;10677.78644427107;10689.412082335391;10959.61641342565;10978.451077446256;11057.391843448526;20693.275735349493;21226.469338266357;23217.535649287256;23236;23291.767082329297;7812.179721129313;7816.413768967236;7865.370770338359;7978.840423191504;8176.6320515576945;3673.9081522962138;4204.327307630826;4259.574042595716;4311.525732169459;4584.312353147055;2831.476176907279;3534.858605655745;3575.3028159508644;3614.6566076222716;3640.7997560134445;644.6518879804946;650.632392698124;656.896414024434;670.9566623899785;674.2414783368711;650.7982525417136;668.5954997226705;679.9422049125781;685.1572565744162;704.968276427562;8732.432391894634;9809.21526277896;10349.95351519579;10365.74085647865;10516.631917882838;677.3700458573574;710.4174576846935;720.3228964773097;722.5014739829501;727.8624972803997;5052.444231134572;5080.364954380702;5080.59355251579;5089.338386009869;5099.9490005100215;93515.53242233745;111940;114396.42801785938;118137.22794158095;118596.40701796436;80377;92736.07263927409;94194;96600.0339996605;96720.03279967251;82592;83773;84816.72774908382;85185.57407212924;85272.57363713018;9746.171575416169;9781.119699227103;9848.261380396356;9943.403395796275;9954.054364835329;41890.1621967565;46820;49929.50070499394;51444.48555514397;52039.43881683556;64479;67026.66486667536;67581.66209168923;70593.94109088265;71334;12498.437570309175;12559.811602825783;12929.870701292866;13184.868151318364;13241.536546220836;14389.496367627084;14950.92524537414;15075.924620376827;15099.32053057637;15622.218889055275;13702.38339274755;13818.930905345811;14172;14263.714725705635;14982.550523484313;12034.578789742318;13499.190048597116;14145;14311.355988980313;14882.181480018702;13322.933385333012;13607.523736669566;13818.930905345409;13864.306784660928;14162;10971.23201375896;11275.830862537232;11335.489902954552;11473.82789258145;11563.959243667774;9525.333226674065;9547.901991270815;10465.9476702616;10478.266521343432;11571.768564628828 21:18:34 INFO - PROCESS | 1643 | __end_tp_report 21:18:34 INFO - PROCESS | 1643 | __start_cc_report 21:18:34 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,416 21:18:34 INFO - PROCESS | 1643 | __end_cc_report 21:18:34 INFO - PROCESS | 1643 | __startTimestamp1490156314040__endTimestamp 21:18:34 INFO - PROCESS | 1643 | 21:18:34 INFO - PROCESS | 1643 | ------- Summary: start ------- 21:18:34 INFO - PROCESS | 1643 | Number of tests: 6 21:18:34 INFO - PROCESS | 1643 | 21:18:34 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5962.89 Median:6226.38 stddev:691.03 (11.1%) stddev-sans-first:665.64 21:18:34 INFO - PROCESS | 1643 | Values: 3501.9 3503.1 3516.5 3531.9 3541.4 6134.3 6141.6 6160.2 6197.8 6206.1 6138.9 6176.0 6180.9 6197.9 6199.5 5486.8 5551.6 5568.6 5580.5 5614.9 5600.9 5611.6 5612.5 5616.0 5668.5 5410.6 5410.7 5603.3 5612.2 5615.0 5526.0 5533.3 5543.0 5573.7 5608.7 5476.5 5547.0 5548.2 5553.0 5556.6 4990.3 5971.4 6201.1 6215.8 6222.1 6311.7 6337.4 6356.8 6376.6 6382.2 5456.9 5500.3 6559.8 6584.4 6603.9 5413.7 6233.2 6271.3 6272.3 6284.2 6385.4 6445.9 6537.8 6734.1 6741.3 6317.6 6388.6 6391.0 6400.9 6404.1 6571.7 6597.6 6610.5 6618.2 6629.5 6249.3 6505.3 6515.2 6517.6 6520.5 6318.6 6331.9 6350.7 6388.7 6424.7 5247.8 5249.9 5260.2 5275.0 5287.3 3889.0 4642.4 4663.3 4665.0 4700.3 6035.8 6049.1 6115.0 6116.2 6282.5 5408.5 6456.6 6468.0 6496.0 6508.6 6225.5 6226.4 6245.9 6253.7 6313.3 5418.6 5591.7 5751.1 5785.9 5841.4 5570.9 6616.6 6631.1 6715.6 6731.8 5460.9 6602.4 6603.9 6616.5 6622.1 5744.8 6746.5 6786.9 6828.0 6853.9 6229.8 6305.5 6387.9 6395.6 6493.4 4682.3 6298.8 6574.9 6576.0 6577.0 5856.6 6154.2 6246.3 6298.3 6369.6 5604.3 6037.9 6260.8 6427.4 6438.4 4695.0 4775.9 6190.4 6229.2 6286.2 5301.9 5386.8 6236.8 6274.1 6401.5 6257.4 6283.4 6298.1 6309.0 6334.0 21:18:34 INFO - PROCESS | 1643 | 21:18:34 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:56758.37 Median:34272.40 stddev:114728.54 (334.8%) stddev-sans-first:114973.09 21:18:34 INFO - PROCESS | 1643 | Values: 666.8 753.7 771.9 953.2 962.8 37831.8 38688.8 40210.0 40222.6 40225.6 38411.0 38624.0 38646.8 38713.0 38843.6 7889.3 10526.9 10865.3 10936.1 10952.9 10640.7 10664.2 10697.9 10738.2 10750.2 10727.5 10730.2 10779.8 10803.6 10885.0 10705.1 10712.4 10718.6 10766.9 10784.2 10485.3 10550.0 10555.9 10569.0 10579.8 34150.8 34182.7 34304.7 34392.5 34445.3 3721.9 3734.9 3751.5 3818.9 3819.3 21694.9 25578.0 26564.9 26629.1 26632.0 2985.1 3024.0 3034.2 3230.4 3232.8 369031.0 371972.0 372005.0 373226.0 373928.0 43733.6 44097.8 44113.3 44210.6 44277.6 46115.3 46409.8 46593.0 46620.3 46659.8 13872.5 13940.9 14019.8 14031.5 14032.8 12109.0 12121.2 12136.5 12166.7 12179.8 10455.7 11996.4 12007.3 12028.3 12098.8 5573.5 5584.7 5602.0 5638.2 5642.8 1148.3 1158.0 1159.4 1168.5 1208.9 22416.0 24953.3 25616.5 25973.4 26949.3 1232.6 1249.9 1261.6 1269.6 1302.6 12863.7 13741.7 13832.7 13928.2 13993.4 402367.0 465502.0 466499.0 470412.0 471069.0 56176.7 56415.0 56444.0 56472.9 56674.0 1396.1 1404.0 1436.4 1444.2 1447.9 21184.4 25670.1 26657.9 27142.7 27266.0 40483.2 40560.6 40565.0 40583.0 40706.4 460575.0 461828.0 464539.0 464938.0 466325.7 28433.7 28723.9 36848.4 38539.6 38630.8 45331.8 45962.8 46035.0 47102.0 47605.8 33290.5 35898.5 37903.0 38281.0 39587.0 38068.4 38168.4 38884.4 39185.8 39717.4 38046.4 38665.8 38693.6 38814.0 39036.2 31672.5 34240.1 34745.8 35165.6 35187.6 23976.7 24219.6 24957.6 26047.2 27526.2 21:18:34 INFO - PROCESS | 1643 | 21:18:34 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:40334.71 Median:22668.43 stddev:108649.20 (479.3%) stddev-sans-first:108912.58 21:18:34 INFO - PROCESS | 1643 | Values: 385.3 405.7 406.8 422.5 454.0 16900.5 19818.7 20065.0 21212.9 21342.8 21270.4 21406.4 21723.1 22492.0 22590.4 6470.6 6692.3 6742.3 6770.8 6784.7 6300.4 6516.5 6558.8 6583.1 6654.0 6579.1 6579.8 6589.8 6592.1 6593.0 7061.4 7418.1 7473.6 7484.0 7490.5 6657.6 7083.2 7256.1 7282.5 7304.9 26033.1 26288.9 26292.8 26438.0 27080.3 2838.4 2888.6 2919.5 2962.0 2963.3 15965.4 16027.7 16051.0 16604.5 16814.3 1925.3 1964.7 2028.5 2101.9 2116.8 145487.3 146132.0 154352.2 158969.0 162691.0 16394.8 19434.3 20152.3 20355.0 21072.4 30968.0 31752.7 31854.0 31861.0 31930.0 7996.6 9966.9 10047.7 10300.0 10337.3 8343.6 8389.5 8402.0 8541.7 8546.9 7982.6 8306.9 9076.3 9591.8 9641.5 3390.5 3961.6 4081.5 4085.3 4086.4 910.8 917.4 920.0 954.1 956.3 17596.8 21515.7 22533.7 22663.4 22673.4 753.8 801.8 812.4 918.7 922.8 9687.0 10178.0 11555.7 11742.7 11791.0 430506.0 494606.0 731541.0 731848.0 747713.0 129272.0 162528.0 165332.2 166194.0 166600.3 1239.7 1258.4 1281.2 1287.8 1292.9 17857.9 19080.1 19973.0 20955.7 21158.4 68627.3 70727.0 71684.6 71727.0 73020.0 96517.5 97264.0 98512.5 99750.0 99757.0 10129.9 10353.6 10671.2 11527.5 11804.0 11653.7 12059.5 13284.7 13790.7 14138.2 10538.6 10951.4 11012.5 11515.2 11727.6 10773.7 11644.0 11822.0 11884.4 12095.5 11568.9 11809.4 11928.3 12103.8 12136.9 11708.5 11734.2 11917.9 12043.6 12111.1 11426.9 11646.7 11807.7 11854.9 11950.1 21:18:34 INFO - PROCESS | 1643 | 21:18:34 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:6798.24 Median:2426.50 stddev:4221.22 (174.0%) stddev-sans-first:4214.45 21:18:34 INFO - PROCESS | 1643 | Values: 1522.1 1550.3 1571.0 1591.4 1599.3 10701.3 11105.0 11336.4 11377.6 11395.0 11540.2 11699.6 11730.5 11732.5 11753.9 2377.9 2409.3 2418.9 2432.4 2435.6 2042.9 2362.9 2400.7 2412.9 2414.1 1989.3 2360.8 2369.3 2425.8 2427.2 2344.7 2354.3 2363.6 2371.8 2377.5 2353.4 2383.6 2414.5 2414.9 2416.5 11259.0 11480.6 11494.5 11506.0 11512.7 5795.9 5881.4 5964.8 5967.8 5980.7 3534.8 3662.5 3705.7 3711.2 3751.6 1188.5 1198.7 1249.4 1249.5 1266.1 13795.6 13967.8 14945.5 15472.5 15637.8 3898.9 4712.1 4745.0 4853.5 4858.1 11650.2 11771.2 11789.3 11791.2 11794.9 5872.8 5874.8 5881.5 5892.2 5899.6 4758.5 4877.0 5238.5 5300.9 5320.1 3889.2 3955.2 4003.9 4022.6 4045.9 887.3 1006.0 1042.7 1046.4 1055.3 768.6 869.8 944.9 951.2 962.6 6323.2 6450.7 6651.8 6702.0 6718.3 973.8 992.2 994.2 1000.5 1031.9 4461.5 4707.9 4708.1 4813.9 4845.6 13078.9 13534.3 14118.0 14490.6 14499.3 11333.8 13697.2 13714.8 13742.5 13765.5 8656.0 8804.8 10247.7 10271.8 10302.3 6363.9 6490.6 6982.7 7019.2 7149.7 11862.2 11929.8 12134.1 12153.1 12172.8 10824.5 12451.2 12681.3 12860.4 12975.5 9270.9 9283.0 9317.9 9368.6 9410.3 10254.7 10274.6 10307.8 10345.9 10369.8 9098.7 9122.0 9151.0 9223.1 9323.6 8731.3 8887.0 8964.6 8987.2 8996.5 8022.4 8032.7 8099.2 8109.4 8175.3 4971.3 5927.9 6033.8 6108.2 6235.4 6121.3 6122.7 6151.7 6238.7 6266.7 21:18:34 INFO - PROCESS | 1643 | 21:18:34 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4657.89 Median:5011.36 stddev:1314.23 (26.2%) stddev-sans-first:1276.40 21:18:34 INFO - PROCESS | 1643 | Values: 291.6 354.4 376.6 385.0 390.4 4632.0 4648.9 4915.8 4916.6 5328.7 4456.2 5261.3 5346.4 5362.7 5383.7 4030.7 4306.9 4381.3 4383.4 4386.8 3601.7 3762.3 4377.9 4410.9 4417.6 4203.8 4379.8 4394.9 4407.0 4456.2 3563.9 4099.3 4292.9 4402.2 4421.9 4004.1 4004.9 4012.3 4014.9 4025.9 4692.0 5045.8 5128.6 5165.4 5178.4 4527.3 4795.3 5017.0 5025.3 5065.0 5019.9 5589.4 5626.8 5653.4 5669.9 3769.0 3828.0 3969.6 4300.8 4529.0 5138.9 5925.0 5925.5 5952.2 5958.2 3622.0 4296.1 4337.7 4360.4 4632.7 4234.9 4301.5 4338.4 4697.4 4784.6 4490.9 4550.0 4560.0 5031.9 5146.7 4793.2 4808.0 5354.6 5644.4 5822.4 1454.9 1499.0 1593.5 1593.9 1627.6 1131.6 1221.5 1224.7 1226.2 1246.5 4675.7 4820.0 4910.3 5005.7 5121.0 5242.4 5594.6 5919.3 5921.6 5944.8 4793.1 4828.9 4865.5 5028.0 5430.9 2739.0 2760.9 2863.4 3055.4 3079.2 5445.5 5627.6 5991.5 6106.3 6157.3 4852.4 5878.6 6064.2 6122.9 6170.2 5398.9 5448.0 5716.1 5876.1 6140.9 4977.7 5028.6 5557.9 5814.5 5962.3 4832.2 5101.3 5130.2 5821.4 5830.9 5392.7 5822.2 5922.9 6095.3 6136.8 4070.4 4594.0 4600.9 4622.4 4659.4 5161.0 5292.9 5671.8 5824.0 5933.6 5056.7 5084.7 5222.7 5536.1 5707.9 4620.7 5313.5 5363.4 5370.3 5726.7 4429.5 5170.8 5193.5 5511.6 5520.8 5644.5 5645.4 5689.2 5692.4 5918.9 4849.4 5020.4 5466.4 5615.6 5679.4 21:18:34 INFO - PROCESS | 1643 | 21:18:34 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:21334.32 Median:23047.24 stddev:31425.12 (136.4%) stddev-sans-first:31475.09 21:18:34 INFO - PROCESS | 1643 | Values: 700.0 713.9 765.5 825.0 833.3 15378.2 15791.9 16207.9 16356.8 16407.9 14799.6 15099.6 15351.2 15364.4 15431.7 902.6 946.1 949.3 951.7 953.7 909.2 936.8 948.1 948.7 949.9 971.2 981.1 981.5 981.9 983.4 823.4 973.6 973.9 974.4 975.9 717.1 722.5 722.7 725.2 725.9 14546.4 14691.8 14853.9 15167.8 15850.8 14006.2 14337.3 14447.5 14481.9 14543.1 11706.4 11827.9 11954.9 11966.2 12423.2 3178.0 3237.8 3707.6 3865.7 3870.7 95609.0 111555.4 120176.4 123839.0 124732.0 10677.8 10689.4 10959.6 10978.5 11057.4 20693.3 21226.5 23217.5 23236.0 23291.8 7812.2 7816.4 7865.4 7978.8 8176.6 3673.9 4204.3 4259.6 4311.5 4584.3 2831.5 3534.9 3575.3 3614.7 3640.8 644.7 650.6 656.9 671.0 674.2 650.8 668.6 679.9 685.2 705.0 8732.4 9809.2 10350.0 10365.7 10516.6 677.4 710.4 720.3 722.5 727.9 5052.4 5080.4 5080.6 5089.3 5099.9 93515.5 111940.0 114396.4 118137.2 118596.4 80377.0 92736.1 94194.0 96600.0 96720.0 82592.0 83773.0 84816.7 85185.6 85272.6 9746.2 9781.1 9848.3 9943.4 9954.1 41890.2 46820.0 49929.5 51444.5 52039.4 64479.0 67026.7 67581.7 70593.9 71334.0 12498.4 12559.8 12929.9 13184.9 13241.5 14389.5 14950.9 15075.9 15099.3 15622.2 13702.4 13818.9 14172.0 14263.7 14982.6 12034.6 13499.2 14145.0 14311.4 14882.2 13322.9 13607.5 13818.9 13864.3 14162.0 10971.2 11275.8 11335.5 11473.8 11564.0 9525.3 9547.9 10465.9 10478.3 11571.8 21:18:34 INFO - PROCESS | 1643 | -------- Summary: end -------- 21:18:34 INFO - PROCESS | 1643 | 21:18:34 INFO - TEST-INFO | 1643: exit 0 21:18:34 INFO - TEST-OK | dromaeo_css | took 1116940ms 21:18:34 INFO - TEST-START | kraken 21:18:34 INFO - operating with platform_type : mac_ 21:18:34 INFO - Initialising browser for kraken test... 21:18:34 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmp2qICX7/profile http://localhost:49242/getInfo.html) 21:18:37 INFO - PROCESS | 1655 | 2017-03-21 21:18:37.741 plugin-container[1656:10267] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 21:18:37 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:18:37 INFO - PROCESS | 1655 | 2017-03-21 21:18:37.744 plugin-container[1656:10267] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 21:18:37 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:18:37 INFO - PROCESS | 1655 | 2017-03-21 21:18:37.744 plugin-container[1656:10267] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:18:37 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 21:18:37 INFO - PROCESS | 1655 | colorDepth:24 21:18:37 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 21:18:37 INFO - PROCESS | 1655 | __metrics 21:18:37 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 21:18:38 INFO - TEST-INFO | 1655: exit 0 21:18:38 INFO - Browser initialized. 21:18:38 INFO - Running cycle 1/1 for kraken test... 21:18:38 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmp2qICX7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:18:41 INFO - PROCESS | 1657 | 2017-03-21 21:18:41.158 plugin-container[1658:10391] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 21:18:41 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:18:41 INFO - PROCESS | 1657 | 2017-03-21 21:18:41.158 plugin-container[1658:10391] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.CFPasteboardClient' 21:18:41 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:18:41 INFO - PROCESS | 1657 | 2017-03-21 21:18:41.158 plugin-container[1658:10391] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:18:50 INFO - PROCESS | 1657 | RSS: Main: 220954624 21:18:50 INFO - PROCESS | 1657 | 21:18:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 21:18:55 INFO - PROCESS | 1657 | RSS: Main: 235331584 21:18:55 INFO - PROCESS | 1657 | 21:18:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 21:18:58 INFO - PROCESS | 1657 | RSS: Main: 243326976 21:18:58 INFO - PROCESS | 1657 | 21:19:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 21:19:02 INFO - PROCESS | 1657 | RSS: Main: 246964224 21:19:02 INFO - PROCESS | 1657 | 21:19:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 21:19:05 INFO - PROCESS | 1657 | RSS: Main: 248385536 21:19:05 INFO - PROCESS | 1657 | 21:19:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:19:07 INFO - PROCESS | 1657 | RSS: Main: 248381440 21:19:07 INFO - PROCESS | 1657 | 21:19:11 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 21:19:11 INFO - PROCESS | 1657 | RSS: Main: 252538880 21:19:11 INFO - PROCESS | 1657 | 21:19:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 21:19:15 INFO - PROCESS | 1657 | RSS: Main: 251830272 21:19:15 INFO - PROCESS | 1657 | 21:19:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 21:19:19 INFO - PROCESS | 1657 | RSS: Main: 251715584 21:19:19 INFO - PROCESS | 1657 | 21:19:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:19:21 INFO - PROCESS | 1657 | RSS: Main: 251506688 21:19:21 INFO - PROCESS | 1657 | 21:19:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:19:24 INFO - PROCESS | 1657 | RSS: Main: 251281408 21:19:24 INFO - PROCESS | 1657 | 21:19:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:19:27 INFO - PROCESS | 1657 | RSS: Main: 251510784 21:19:27 INFO - PROCESS | 1657 | 21:19:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:19:31 INFO - PROCESS | 1657 | RSS: Main: 251826176 21:19:31 INFO - PROCESS | 1657 | 21:19:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:19:34 INFO - PROCESS | 1657 | RSS: Main: 260505600 21:19:34 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 21:19:36 INFO - PROCESS | 1657 | RSS: Main: 263749632 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | __start_tp_report 21:19:36 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 21:19:36 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 21:19:36 INFO - PROCESS | 1657 | |i|pagename|runs| 21:19:36 INFO - PROCESS | 1657 | |0;ai-astar;108;121;143;181;101;186;180;173;120;121 21:19:36 INFO - PROCESS | 1657 | |1;audio-beat-detection;130;141;98;178;137;140;132;114;132;114 21:19:36 INFO - PROCESS | 1657 | |2;audio-dft;195;201;198;200;236;289;197;198;195;201 21:19:36 INFO - PROCESS | 1657 | |3;audio-fft;102;51;53;119;89;52;80;86;98;85 21:19:36 INFO - PROCESS | 1657 | |4;audio-oscillator;121;70;79;68;72;71;81;71;70;87 21:19:36 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;113;86;137;136;89;90;91;91;91 21:19:36 INFO - PROCESS | 1657 | |6;imaging-darkroom;97;107;96;98;97;98;98;110;107;153 21:19:36 INFO - PROCESS | 1657 | |7;imaging-desaturate;91;95;90;90;144;141;99;142;143;91 21:19:36 INFO - PROCESS | 1657 | |8;json-parse-financial;68;72;85;72;62;99;57;69;82;107 21:19:36 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;49;74;96;86;60;60;59;60;60;79 21:19:36 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;75;76;107;102;87;86;90;100;104;106 21:19:36 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;144;138;139;137;159;156;141;152;190;152 21:19:36 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;175;176;176;174;177;176;176;177;172;174 21:19:36 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;70;68;68;67;69;72;71;82;60;89 21:19:36 INFO - PROCESS | 1657 | __end_tp_report 21:19:36 INFO - PROCESS | 1657 | __start_cc_report 21:19:36 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1022 21:19:36 INFO - PROCESS | 1657 | __end_cc_report 21:19:36 INFO - PROCESS | 1657 | __startTimestamp1490156376634__endTimestamp 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | ------- Summary: start ------- 21:19:36 INFO - PROCESS | 1657 | Number of tests: 14 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:143.40 Median:158.00 stddev:33.41 (21.1%) stddev-sans-first:32.89 21:19:36 INFO - PROCESS | 1657 | Values: 108.0 121.0 143.0 181.0 101.0 186.0 180.0 173.0 120.0 121.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:131.60 Median:138.50 stddev:21.32 (15.4%) stddev-sans-first:22.61 21:19:36 INFO - PROCESS | 1657 | Values: 130.0 141.0 98.0 178.0 137.0 140.0 132.0 114.0 132.0 114.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:211.00 Median:200.50 stddev:29.96 (14.9%) stddev-sans-first:31.21 21:19:36 INFO - PROCESS | 1657 | Values: 195.0 201.0 198.0 200.0 236.0 289.0 197.0 198.0 195.0 201.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:81.50 Median:82.50 stddev:23.10 (28.0%) stddev-sans-first:23.28 21:19:36 INFO - PROCESS | 1657 | Values: 102.0 51.0 53.0 119.0 89.0 52.0 80.0 86.0 98.0 85.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:71.50 stddev:15.96 (22.3%) stddev-sans-first:6.44 21:19:36 INFO - PROCESS | 1657 | Values: 121.0 70.0 79.0 68.0 72.0 71.0 81.0 71.0 70.0 87.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:101.30 Median:89.50 stddev:19.99 (22.3%) stddev-sans-first:20.71 21:19:36 INFO - PROCESS | 1657 | Values: 89.0 113.0 86.0 137.0 136.0 89.0 90.0 91.0 91.0 91.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:106.10 Median:97.00 stddev:17.26 (17.8%) stddev-sans-first:17.99 21:19:36 INFO - PROCESS | 1657 | Values: 97.0 107.0 96.0 98.0 97.0 98.0 98.0 110.0 107.0 153.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:112.60 Median:90.50 stddev:25.89 (28.6%) stddev-sans-first:26.25 21:19:36 INFO - PROCESS | 1657 | Values: 91.0 95.0 90.0 90.0 144.0 141.0 99.0 142.0 143.0 91.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:77.30 Median:72.00 stddev:15.96 (22.2%) stddev-sans-first:16.57 21:19:36 INFO - PROCESS | 1657 | Values: 68.0 72.0 85.0 72.0 62.0 99.0 57.0 69.0 82.0 107.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:67.00 stddev:14.77 (22.0%) stddev-sans-first:13.91 21:19:36 INFO - PROCESS | 1657 | Values: 49.0 74.0 96.0 86.0 60.0 60.0 59.0 60.0 60.0 79.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:75.50 stddev:12.12 (16.1%) stddev-sans-first:10.90 21:19:36 INFO - PROCESS | 1657 | Values: 75.0 76.0 107.0 102.0 87.0 86.0 90.0 100.0 104.0 106.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:150.80 Median:152.00 stddev:15.88 (10.4%) stddev-sans-first:16.65 21:19:36 INFO - PROCESS | 1657 | Values: 144.0 138.0 139.0 137.0 159.0 156.0 141.0 152.0 190.0 152.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.30 Median:176.00 stddev:1.57 (0.9%) stddev-sans-first:1.66 21:19:36 INFO - PROCESS | 1657 | Values: 175.0 176.0 176.0 174.0 177.0 176.0 176.0 177.0 172.0 174.0 21:19:36 INFO - PROCESS | 1657 | 21:19:36 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:70.50 stddev:8.18 (11.6%) stddev-sans-first:8.66 21:19:36 INFO - PROCESS | 1657 | Values: 70.0 68.0 68.0 67.0 69.0 72.0 71.0 82.0 60.0 89.0 21:19:36 INFO - PROCESS | 1657 | -------- Summary: end -------- 21:19:36 INFO - PROCESS | 1657 | 21:19:37 INFO - TEST-INFO | 1657: exit 0 21:19:37 INFO - TEST-OK | kraken | took 62816ms 21:19:37 INFO - SUITE-END | took 1179s 21:19:37 INFO - Completed test suite (00:19:40) 21:19:37 INFO - javascript benchmark 21:19:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7781.170922826409, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3501.912452188695, 3503.0716860032094, 3516.472529120633, 3531.858725650974, 3541.397962346401, 6134.3252242253375, 6141.570090093695, 6160.229971253593, 6197.845053873655, 6206.131141640163, 6138.907916381258, 6175.969120154393, 6180.938190618092, 6197.907031394533, 6199.473044791198, 5486.753096110665, 5551.611387202897, 5568.610197286192, 5580.469855363734, 5614.859628509279, 5600.887982240352, 5611.579131565149, 5612.466815652527, 5616.0171969905305, 5668.518175955049, 5410.567154627621, 5410.675359478424, 5603.271574695284, 5612.242347283126, 5614.9719251403685, 5526.032944234763, 5533.280673512418, 5542.972285138569, 5573.665580065209, 5608.6634801911605, 5476.479734425223, 5546.972265138648, 5548.223248745179, 5553.028220061473, 5556.583256255797, 4990.25146228065, 5971.402859714038, 6201.100840378165, 6215.844603884894, 6222.12890195373, 6311.747530098791, 6337.366263373672, 6356.809295721136, 6376.6174029557915, 6382.234131904202, 5456.863578410532, 5500.312460942382, 6559.836004099936, 6584.407403333675, 6603.93396066038, 5413.6736499557865, 6233.2208473940755, 6271.341509141565, 6272.3100458949475, 6284.183056202664, 6385.393387628168, 6445.936420490567, 6537.836554086186, 6734.124563806674, 6741.325867413219, 6317.557770956081, 6388.648624325707, 6391.0, 6400.9039864402075, 6404.135441715309, 6571.737130514822, 6597.637129957899, 6610.537262391586, 6618.205815302194, 6629.502787290972, 6249.281332646713, 6505.349465053457, 6515.218173819172, 6517.608943463408, 6520.543561950618, 6318.589291696025, 6331.9366806331345, 6350.714217860207, 6388.744450222032, 6424.74301027963, 5247.842564723064, 5249.895002099936, 5260.237265596467, 5275.0, 5287.259783630295, 3888.9611103889165, 4642.3500709900645, 4663.300504924288, 4664.953350466452, 4700.341952126705, 6035.849103772441, 6049.062395328731, 6114.996965385453, 6116.214037636738, 6282.528810339243, 5408.513233808976, 6456.644884531302, 6467.967660161763, 6496.0, 6508.576942498722, 6225.533085018551, 6226.377362263828, 6245.937540624626, 6253.749850005949, 6313.337099604567, 5418.593605479605, 5591.692456914909, 5751.079827227623, 5785.884282314414, 5841.4450627190345, 5570.944290557042, 6616.636085015371, 6631.104800851823, 6715.630640314829, 6731.831704207434, 5460.91808622863, 6602.438792702674, 6603.9339606605245, 6616.503762217757, 6622.139121914121, 5744.770209191753, 6746.5277430581355, 6786.915011422959, 6827.975803629495, 6853.862922741418, 6229.813105606839, 6305.527085468517, 6387.904181437375, 6395.584287021329, 6493.415592596689, 4682.321063445713, 6298.779542715983, 6574.868502629825, 6576.013597960345, 6576.980568011966, 5856.594417339893, 6154.1999540059505, 6246.312905580474, 6298.307186209422, 6369.585976911486, 5604.327480702342, 6037.939620603738, 6260.843478912881, 6427.357264273535, 6438.420542151229, 4694.976525117352, 4775.925416781197, 6190.41191086846, 6229.190205273286, 6286.214223222098, 5301.893962120813, 5386.8383948481605, 6236.812895613139, 6274.121622972879, 6401.487880969418, 6257.43683068526, 6283.434490895841, 6298.0867774171575, 6308.968455157695, 6334.018227174795], "value": 5923.987962508072, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.819958611175, 753.745014550773, 771.9266669666381, 953.1945506047388, 962.8315044867146, 37831.81084094579, 38688.80655596722, 40209.99475013126, 40222.597774022324, 40225.597744022554, 38411.0, 38624.0, 38646.806765966205, 38713.0, 38843.611563884355, 7889.329407000412, 10526.894731052667, 10865.293755905881, 10936.070434013118, 10952.945235273812, 10640.733981650443, 10664.200184986119, 10697.946510267477, 10738.194635402337, 10750.247482676215, 10727.517261723202, 10730.248882578222, 10779.838302425469, 10803.621873234439, 10885.0, 10705.14358851294, 10712.41081740504, 10718.624848130317, 10766.946165269203, 10784.245102842764, 10485.266031377807, 10550.0, 10555.894441055569, 10569.048785609331, 10579.788404231873, 34150.82924585386, 34182.6581734182, 34304.65695343039, 34392.484112738326, 34445.311093777986, 3721.925561488755, 3734.850605975758, 3751.5096993523725, 3818.9045273868096, 3819.312523745725, 21694.89152554243, 25578.0, 26564.867175664192, 26629.06798262071, 26632.0, 2985.149232468758, 3024.0474250611205, 3034.180771191788, 3230.370077834823, 3232.8060316381093, 369031.0, 371972.0, 372005.0, 373226.0, 373928.0, 43733.56266437359, 44097.77951110288, 44113.33829992553, 44210.55789442129, 44277.55722442734, 46115.30827037597, 46409.83975400574, 46593.0, 46620.3006954896, 46659.766701166955, 13872.514461993782, 13940.930295348458, 14019.789703154462, 14031.508897188549, 14032.789508157386, 12108.970737487236, 12121.151519393734, 12136.5145394185, 12166.695832604255, 12179.817302740466, 10455.738606534745, 11996.400179990966, 12007.339596322112, 12028.338441385808, 12098.81851772224, 5573.498385145356, 5584.692841893735, 5602.0, 5638.210650508932, 5642.774289028475, 1148.2593727046176, 1157.9826302605468, 1159.3565571107947, 1168.4741866160261, 1208.945597448117, 22415.99128039242, 24953.251402457954, 25616.463012219327, 25973.350666233495, 26949.326266843487, 1232.6302109367155, 1249.8875101240933, 1261.6152073617452, 1269.6000759760645, 1302.5961951794975, 12863.742725145445, 13741.684673849666, 13832.723345533033, 13928.233947133005, 13993.370298336396, 402367.0, 465502.0, 466499.0, 470412.0, 471069.0, 56176.71911640497, 56415.0, 56444.0, 56472.87054258892, 56674.0, 1396.0855639556107, 1403.9929800350933, 1436.3895344478556, 1444.2056868722161, 1447.8986470947143, 21184.364469065953, 25670.101546445785, 26657.933682652478, 27142.72857271402, 27266.0, 40483.19033619252, 40560.59439405568, 40565.0, 40583.0, 40706.38940415837, 460575.0, 461828.0, 464539.0, 464938.0, 466325.67348653515, 28433.715662843108, 28723.856380717963, 36848.44727329039, 38539.61460385472, 38630.80684596671, 45331.7733411344, 45962.77018614885, 46035.0, 47102.0, 47605.76197119131, 33290.500642490864, 35898.4615230777, 37903.0, 38281.0, 39587.0, 38068.42897356597, 38168.42747358736, 38884.416733748454, 39185.82442526729, 39717.404238937015, 38046.42930355992, 38665.80667096646, 38693.613063869, 38814.0, 39036.219275614894, 31672.5249121268, 34240.143996400286, 34745.8262708695, 35165.64834351624, 35187.64812351844, 23976.74210217183, 24219.636705449786, 24957.625635615845, 26047.218583442525, 27526.17421477359], "value": 18956.028868921196, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.33721998163156, 405.68559366490985, 406.7701748512092, 422.5077784381195, 453.98987253361275, 16900.492985210432, 19818.71178373405, 20065.0, 21212.89393553034, 21342.786572134275, 21270.36188914335, 21406.357809265708, 21723.13107475699, 22492.0, 22590.43523911903, 6470.611763294206, 6692.297308782557, 6742.292059333773, 6770.796876093725, 6784.72861085556, 6300.401461861115, 6516.543841931067, 6558.836029099263, 6583.111279977214, 6653.966730166342, 6579.111819904299, 6579.769708060219, 6589.80230593083, 6592.110065141216, 6593.044008618747, 7061.399781018619, 7418.1098268207625, 7473.626318684044, 7484.0, 7490.513116647455, 6657.633830139341, 7083.150021997341, 7256.129264488295, 7282.526635768658, 7304.904264360335, 26033.088841890632, 26288.86855565729, 26292.816823243, 26438.0, 27080.32299192536, 2838.403935173616, 2888.581155732407, 2919.5474701421313, 2962.0453869785, 2963.273997870514, 15965.361385544564, 16027.679446411123, 16051.0, 16604.50186494395, 16814.32742690291, 1925.2683980087477, 1964.65618516759, 2028.4928767808049, 2101.9054142563623, 2116.7883211678864, 145487.27256363755, 146132.0, 154352.2282388592, 158969.0, 162691.0, 16394.754078688828, 19434.319798807257, 20152.29466968649, 20355.0, 21072.367828965154, 30968.0, 31752.682473174973, 31854.0, 31861.0, 31930.0, 7996.64015119321, 9966.850497242547, 10047.748806279755, 10300.0, 10337.328073675188, 8343.582820858932, 8389.496630202086, 8401.95799021001, 8541.743747687578, 8546.914530854612, 7982.640781164745, 8306.920100386911, 9076.273898088137, 9591.760205994906, 9641.517924103768, 3390.4744764561533, 3961.623645753649, 4081.469408976814, 4085.264652362603, 4086.366613174963, 910.8451563234266, 917.3899356927702, 919.990800092004, 954.1317401164906, 956.268454632207, 17596.82403175952, 21515.67726484104, 22533.661995070088, 22663.433414164447, 22673.433164171027, 753.8416932444138, 801.8476489466983, 812.3907069697726, 918.6830543462495, 922.7923717163585, 9687.031296870256, 10178.0, 11555.653330400102, 11742.70643233926, 11790.997765190055, 430506.0, 494606.0, 731541.0, 731848.0, 747713.0, 129272.0, 162528.0, 165332.17333913254, 166194.0, 166600.33399666092, 1239.7086684629105, 1258.4399942025893, 1281.1928485054357, 1287.7810772168566, 1292.9224246545236, 17857.910710446886, 19080.088581840966, 19973.0, 20955.685664715347, 21158.365249042552, 68627.31372686409, 70727.0, 71684.64157679178, 71727.0, 73020.0, 96517.51741241249, 97264.0, 98512.52231216394, 99750.0, 99757.0, 10129.949350253202, 10353.63762268317, 10671.199660025684, 11527.481263343001, 11803.996660283628, 11653.6503904883, 12059.457324420247, 13284.667883302995, 13790.655233618838, 14138.22239776822, 10538.631147909786, 10951.412045253235, 11012.5044373005, 11515.193936424705, 11727.648170554898, 10773.676789696321, 11644.0, 11822.0, 11884.405779711153, 12095.455704493143, 11568.884311157117, 11809.350485723367, 11928.284302941851, 12103.75792484163, 12136.878631213574, 11708.531658733555, 11734.237274577124, 11917.94041029824, 12043.638690839289, 12111.091668124749, 11426.885731142582, 11646.650600481998, 11807.704807379885, 11854.94072529632, 11950.103742219542], "value": 11564.201436672016, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.0791421190186, 1550.2868680407005, 1570.9788637385698, 1591.3714082937238, 1599.2563457992035, 10701.250912436122, 11105.0, 11336.433178341082, 11377.601783937564, 11394.974452299291, 11540.192186546945, 11699.649010529676, 11730.472128754205, 11732.53069877204, 11753.882461175386, 2377.9167729129526, 2409.265174121892, 2418.9274321770285, 2432.3797431654903, 2435.5873593315923, 2042.9284975025803, 2362.9409264768346, 2400.7479214682467, 2412.9155479558217, 2414.0705828256073, 1989.3037436897116, 2360.775726305998, 2369.3365857559825, 2425.78167964884, 2427.2111563741855, 2344.7303560090636, 2354.3407845803117, 2363.633636786301, 2371.8221133414977, 2377.5244951009868, 2353.3528279723214, 2383.630537266709, 2414.5170965806733, 2414.879256037191, 2416.5046165536023, 11258.986691197832, 11480.598179063692, 11494.482748276265, 11506.0, 11512.654620361318, 5795.9420405796245, 5881.3530511643685, 5964.761409543613, 5967.8209653710455, 5980.671063091529, 3534.8232588370734, 3662.487251784752, 3705.7035437164964, 3711.183539621306, 3751.568569614502, 1188.4592510407745, 1198.6703656494446, 1249.4002878618314, 1249.450241893573, 1266.0567876931652, 13795.586132415943, 13967.79048314276, 14945.476908308267, 15472.458463953708, 15637.765433518507, 3898.863539776151, 4712.128256272601, 4745.0242598276645, 4853.466118726938, 4858.101251268456, 11650.242734222249, 11771.23486973344, 11789.292642441309, 11791.174617776673, 11794.88205117955, 5872.823815285548, 5874.794382196603, 5881.5000724938, 5892.204552385459, 5899.616524925866, 4758.452777930583, 4876.975615121902, 5238.476152384731, 5300.946990530046, 5320.12217984036, 3889.163829776555, 3955.20895820837, 4003.8798836034966, 4022.6379625833815, 4045.8786236412957, 887.2857349833317, 1005.983956204239, 1042.7497400623947, 1046.4140081554353, 1055.3193190392153, 768.5734417398397, 869.7999460124207, 944.9196818270386, 951.1534734086669, 962.6005207838668, 6323.241211054611, 6450.6774661266745, 6651.7716564950315, 6702.0, 6718.294579069225, 973.78576713123, 992.2062350120019, 994.2493417469908, 1000.5097502224004, 1031.9371047820607, 4461.5092339842595, 4707.882302942454, 4708.081924024782, 4813.9037219255415, 4845.5881250093435, 13078.934605327102, 13534.255615941218, 14118.0, 14490.565283041526, 14499.275036248146, 11333.829992550118, 13697.178169309873, 13714.794278085838, 13742.450301988009, 13765.518206862711, 8656.0, 8804.823903521894, 10247.692569222936, 10271.845922311171, 10302.278840481094, 6363.938673509464, 6490.613428752178, 6982.6508674567085, 7019.157701075699, 7149.678264478215, 11862.169648124893, 11929.761404772029, 12134.089943254115, 12153.149279550347, 12172.817407738714, 10824.458777060958, 12451.190672606252, 12681.30252836103, 12860.421281042563, 12975.545855894998, 9270.907290927003, 9283.0, 9317.906820931705, 9368.57841397125, 9410.341276110606, 10254.743631409274, 10274.589016439557, 10307.845382319421, 10345.948270258901, 10369.844452333373, 9098.744949155129, 9122.0, 9151.03914089011, 9223.123803238681, 9323.627054917728, 8731.25784308341, 8886.977997530366, 8964.551772411483, 8987.19115279628, 8996.50519221449, 8022.398320126131, 8032.678692852454, 8099.230573095547, 8109.351251899951, 8175.264226219669, 4971.328870602422, 5927.911081333697, 6033.818985430444, 6108.236470441195, 6235.438810507076, 6121.265448146251, 6122.663253521099, 6151.723172457339, 6238.6880655966115, 6266.68666566679], "value": 5179.2148895185965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.6398248163519, 354.35153668786126, 376.5895174260056, 384.9826757795898, 390.3578613181319, 4632.0, 4648.907021859561, 4915.82794602189, 4916.58209052231, 5328.671328671328, 4456.220161471746, 5261.316028916245, 5346.4118946915905, 5362.678239305645, 5383.703896285702, 4030.7380020298733, 4306.901740056297, 4381.255186618267, 4383.364412160235, 4386.758728269944, 3601.7298702597277, 3762.2663580601793, 4377.912441751163, 4410.911781764363, 4417.602415782579, 4203.810828512725, 4379.802908869093, 4394.934075988863, 4406.955930440703, 4456.153330867141, 3563.879491702433, 4099.3031184698675, 4292.935605965913, 4402.207602631525, 4421.933670994921, 4004.1190937993597, 4004.939925901114, 4012.277789997799, 4014.8996275093064, 4025.8993525162105, 4692.0381321828945, 5045.763787871995, 5128.589712822965, 5165.405978312505, 5178.430372659004, 4527.320901864714, 4795.304680821297, 5016.974915125437, 5025.306399796147, 5064.9746751266375, 5019.874503137415, 5589.413111623262, 5626.831195064155, 5653.378128405872, 5669.902136209587, 3768.963535027862, 3828.004718773111, 3969.563348031743, 4300.763458009808, 4528.977355113237, 5138.948610513922, 5924.9703751480965, 5925.459380561042, 5952.226210592638, 5958.225430694026, 3621.9818900905325, 4296.0978194578865, 4337.674674399433, 4360.4331436913, 4632.722036677743, 4234.9364759528635, 4301.526832048472, 4338.414314067623, 4697.436307643105, 4784.593309568656, 4490.86683825187, 4550.020740977685, 4559.9512112213715, 5031.924521132186, 5146.742662866916, 4793.161196790547, 4807.95192048082, 5354.581036025456, 5644.379118296984, 5822.359540450547, 1454.9160875148082, 1498.980693128683, 1593.5219434169703, 1593.9043657380362, 1627.5768300242023, 1131.5926266544038, 1221.5236057937411, 1224.7305592769576, 1226.2029680707433, 1246.507629486348, 4675.719456832601, 4819.987802561432, 4910.337104490848, 5005.749712514433, 5121.0, 5242.368403968982, 5594.601349662585, 5919.260092488439, 5921.61509501881, 5944.762209511571, 4793.148076894242, 4828.927566086511, 4865.489123642037, 5027.974860125676, 5430.945690543044, 2738.9726102739114, 2760.903368382097, 2863.4130003349264, 3055.388922215566, 3079.214800225973, 5445.537129343971, 5627.5779316551425, 5991.483576897626, 6106.2902387331615, 6157.261128664589, 4852.441969173519, 5878.559108066826, 6064.151018857364, 6122.877542449215, 6170.197874276405, 5398.9190162146815, 5448.030823201365, 5716.142578613159, 5876.147958546072, 6140.938590614215, 4977.701337919736, 5028.597712182944, 5557.944420555905, 5814.505767009851, 5962.344142144274, 4832.163777764427, 5101.285819985131, 5130.152765215192, 5821.446962538722, 5830.883382332415, 5392.710446627632, 5822.186467863557, 5922.92697555852, 6095.293317871004, 6136.815895523141, 4070.435625555881, 4593.977030114828, 4600.861974140781, 4622.445306563234, 4659.370984916993, 5161.0, 5292.888493416272, 5671.801486947937, 5823.970880145572, 5933.643981361132, 5056.6713163644245, 5084.745762711924, 5222.6912796941915, 5536.141898005815, 5707.914381284367, 4620.699654522446, 5313.521783039545, 5363.383210930637, 5370.3287089113865, 5726.742296596746, 4429.512753597166, 5170.759017835768, 5193.454687257784, 5511.614187006871, 5520.779168833202, 5644.475991482359, 5645.379008309164, 5689.203511508475, 5692.434580490398, 5918.940810591839, 4849.442314133919, 5020.397552293748, 5466.4260252674485, 5615.606907516435, 5679.403662615366], "value": 4273.151135254441, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.9510034297595, 713.8643657705032, 765.5330248548388, 824.991750082499, 833.2792134803393, 15378.154201518912, 15791.921040394782, 16207.918960405212, 16356.754648680278, 16407.917960410196, 14799.630009249775, 15099.62250943727, 15351.155686437241, 15364.385424583003, 15431.691366172698, 902.6163880350841, 946.1059298962483, 949.2548349545574, 951.7002144324516, 953.6805170267934, 909.236241557092, 936.8313703533362, 948.1229862377273, 948.672707915768, 949.9145076943074, 971.208465100944, 981.1464026297132, 981.5386768218962, 981.906718861707, 983.4197823284261, 823.4194892600732, 973.600823662295, 973.8685277487555, 974.4445665970364, 975.926805489584, 717.1107826295356, 722.4942540221866, 722.6531264992833, 725.1877896755615, 725.8802297620887, 14546.418143274257, 14691.779623305552, 14853.925730371388, 15167.848321516864, 15850.841491585052, 14006.159630422208, 14337.28313584327, 14447.49433769813, 14481.855181448156, 14543.127412355292, 11706.356150411724, 11827.94086029573, 11954.880451195464, 11966.22219555726, 12423.192492487959, 3177.9682203177904, 3237.7895436796766, 3707.5550933887835, 3865.690744740414, 3870.7290489665734, 95609.0, 111555.44222278836, 120176.39911800473, 123839.0, 124732.0, 10677.78644427107, 10689.412082335391, 10959.61641342565, 10978.451077446256, 11057.391843448526, 20693.275735349493, 21226.469338266357, 23217.535649287256, 23236.0, 23291.767082329297, 7812.179721129313, 7816.413768967236, 7865.370770338359, 7978.840423191504, 8176.6320515576945, 3673.9081522962138, 4204.327307630826, 4259.574042595716, 4311.525732169459, 4584.312353147055, 2831.476176907279, 3534.858605655745, 3575.3028159508644, 3614.6566076222716, 3640.7997560134445, 644.6518879804946, 650.632392698124, 656.896414024434, 670.9566623899785, 674.2414783368711, 650.7982525417136, 668.5954997226705, 679.9422049125781, 685.1572565744162, 704.968276427562, 8732.432391894634, 9809.21526277896, 10349.95351519579, 10365.74085647865, 10516.631917882838, 677.3700458573574, 710.4174576846935, 720.3228964773097, 722.5014739829501, 727.8624972803997, 5052.444231134572, 5080.364954380702, 5080.59355251579, 5089.338386009869, 5099.9490005100215, 93515.53242233745, 111940.0, 114396.42801785938, 118137.22794158095, 118596.40701796436, 80377.0, 92736.07263927409, 94194.0, 96600.0339996605, 96720.03279967251, 82592.0, 83773.0, 84816.72774908382, 85185.57407212924, 85272.57363713018, 9746.171575416169, 9781.119699227103, 9848.261380396356, 9943.403395796275, 9954.054364835329, 41890.1621967565, 46820.0, 49929.50070499394, 51444.48555514397, 52039.43881683556, 64479.0, 67026.66486667536, 67581.66209168923, 70593.94109088265, 71334.0, 12498.437570309175, 12559.811602825783, 12929.870701292866, 13184.868151318364, 13241.536546220836, 14389.496367627084, 14950.92524537414, 15075.924620376827, 15099.32053057637, 15622.218889055275, 13702.38339274755, 13818.930905345811, 14172.0, 14263.714725705635, 14982.550523484313, 12034.578789742318, 13499.190048597116, 14145.0, 14311.355988980313, 14882.181480018702, 13322.933385333012, 13607.523736669566, 13818.930905345409, 13864.306784660928, 14162.0, 10971.23201375896, 11275.830862537232, 11335.489902954552, 11473.82789258145, 11563.959243667774, 9525.333226674065, 9547.901991270815, 10465.9476702616, 10478.266521343432, 11571.768564628828], "value": 7722.177120127857, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1603.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 143.0, 181.0, 101.0, 186.0, 180.0, 173.0, 120.0, 121.0], "value": 143.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 141.0, 98.0, 178.0, 137.0, 140.0, 132.0, 114.0, 132.0, 114.0], "value": 131.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 201.0, 198.0, 200.0, 236.0, 289.0, 197.0, 198.0, 195.0, 201.0], "value": 211.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 51.0, 53.0, 119.0, 89.0, 52.0, 80.0, 86.0, 98.0, 85.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 70.0, 79.0, 68.0, 72.0, 71.0, 81.0, 71.0, 70.0, 87.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 113.0, 86.0, 137.0, 136.0, 89.0, 90.0, 91.0, 91.0, 91.0], "value": 101.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 96.0, 98.0, 97.0, 98.0, 98.0, 110.0, 107.0, 153.0], "value": 106.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 90.0, 90.0, 144.0, 141.0, 99.0, 142.0, 143.0, 91.0], "value": 112.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 85.0, 72.0, 62.0, 99.0, 57.0, 69.0, 82.0, 107.0], "value": 77.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 74.0, 96.0, 86.0, 60.0, 60.0, 59.0, 60.0, 60.0, 79.0], "value": 68.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 107.0, 102.0, 87.0, 86.0, 90.0, 100.0, 104.0, 106.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 138.0, 139.0, 137.0, 159.0, 156.0, 141.0, 152.0, 190.0, 152.0], "value": 150.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 176.0, 174.0, 177.0, 176.0, 176.0, 177.0, 172.0, 174.0], "value": 175.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 67.0, 69.0, 72.0, 71.0, 82.0, 60.0, 89.0], "value": 71.6, "unit": "score"}]}]} 21:19:37 INFO - Return code: 0 21:19:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:19:37 ERROR - # TBPL SUCCESS # 21:19:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:19:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:19:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:19:37 INFO - 2017-03-21 21:19:37.944 screenresolution[1662:10789] starting screenresolution argv=screenresolution get 21:19:37 INFO - 2017-03-21 21:19:37.947 screenresolution[1662:10789] Display 0: 1600x1200x32@60 21:19:37 INFO - 2017-03-21 21:19:37.953 screenresolution[1663:10792] starting screenresolution argv=screenresolution list 21:19:37 INFO - Available Modes on Display 0 21:19:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:19:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:19:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:19:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:19:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:19:38 INFO - Intel Iris: 21:19:38 INFO - Chipset Model: Intel Iris 21:19:38 INFO - Type: GPU 21:19:38 INFO - Bus: Built-In 21:19:38 INFO - VRAM (Dynamic, Max): 1536 MB 21:19:38 INFO - Vendor: Intel (0x8086) 21:19:38 INFO - Device ID: 0x0a2e 21:19:38 INFO - Revision ID: 0x0009 21:19:38 INFO - Displays: 21:19:38 INFO - Display: 21:19:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:19:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:19:38 INFO - Display Serial Number: 1600x1200 60 21:19:38 INFO - Main Display: Yes 21:19:38 INFO - Mirror: Off 21:19:38 INFO - Online: Yes 21:19:38 INFO - Rotation: Supported 21:19:38 INFO - Return code: 0 21:19:38 INFO - Running post-action listener: _resource_record_post_action 21:19:38 INFO - [mozharness: 2017-03-22 04:19:38.191375Z] Finished run-tests step (success) 21:19:38 INFO - Running post-run listener: _resource_record_post_run 21:19:38 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 40229376; Write bytes: 512836608; Read time: 444; Write time: 2003 21:19:38 INFO - TinderboxPrint: CPU usage
54.5% 21:19:38 INFO - TinderboxPrint: I/O read bytes / time
40,229,376 / 444 21:19:38 INFO - TinderboxPrint: I/O write bytes / time
512,836,608 / 2,003 21:19:38 INFO - TinderboxPrint: CPU idle
2,187.5 (45.5%) 21:19:38 INFO - TinderboxPrint: CPU system
966.1 (20.1%) 21:19:38 INFO - TinderboxPrint: CPU user
1,652.1 (34.4%) 21:19:38 INFO - TinderboxPrint: Swap in / out
248,778,752 / 0 21:19:38 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202326528; Write bytes: 201385472; Read time: 15637; Write time: 413 21:19:38 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 38296064; Write bytes: 300690944; Read time: 425; Write time: 1556 21:19:38 INFO - Running post-run listener: _upload_blobber_files 21:19:38 INFO - Blob upload gear active. 21:19:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:19:38 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 21:19:38 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'] 21:19:38 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 21:19:39 INFO - (blobuploader) - INFO - Open directory for files ... 21:19:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:19:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:19:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:19:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:19:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:19:40 INFO - (blobuploader) - INFO - Done attempting. 21:19:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:19:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:19:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:19:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:19:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:19:41 INFO - (blobuploader) - INFO - Done attempting. 21:19:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:19:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:19:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:19:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:19:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:19:41 INFO - (blobuploader) - INFO - Done attempting. 21:19:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:19:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:19:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:19:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:19:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:19:43 INFO - (blobuploader) - INFO - Done attempting. 21:19:43 INFO - (blobuploader) - INFO - Iteration through files over. 21:19:43 INFO - Return code: 0 21:19:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:19:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:19:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bac1ad4f8b0e9c3af946b3a4d627d63a3cde8c39404526f995e2a53d53e65a0a2aede4fd964349f09322bac45108de2ddd041da80f01415aa4319b3ca44afda6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1946eaad77a07a3473ba04bc8cb500617ec89f29dc1869944db1bee24ec5e1013d78a44f74b3e91463c56d9b012e4e6ccc4b75ff5102b9503b9ca2e8070aa825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e37fb10d5dcb27a60b0fa7926282476caed0bd44d9188c9c066f36653d7716a569a0300d3156842ff25c1b0d0fb5684d8db7ba4a52318d1c1c5b32dc7a3fb784", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5bb2eb7abfc3e2002a237a822a5c6a38743af86be91d9907c200652eca1de7354d73f3868af21099f5d6c05d3561fd4508cd66baf3cd29b0ec80b1e238358518"} 21:19:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:19:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:19:43 INFO - Contents: 21:19:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bac1ad4f8b0e9c3af946b3a4d627d63a3cde8c39404526f995e2a53d53e65a0a2aede4fd964349f09322bac45108de2ddd041da80f01415aa4319b3ca44afda6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1946eaad77a07a3473ba04bc8cb500617ec89f29dc1869944db1bee24ec5e1013d78a44f74b3e91463c56d9b012e4e6ccc4b75ff5102b9503b9ca2e8070aa825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e37fb10d5dcb27a60b0fa7926282476caed0bd44d9188c9c066f36653d7716a569a0300d3156842ff25c1b0d0fb5684d8db7ba4a52318d1c1c5b32dc7a3fb784", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5bb2eb7abfc3e2002a237a822a5c6a38743af86be91d9907c200652eca1de7354d73f3868af21099f5d6c05d3561fd4508cd66baf3cd29b0ec80b1e238358518"} 21:19:43 INFO - Running post-run listener: copy_logs_to_upload_dir 21:19:43 INFO - Copying logs to upload dir... 21:19:43 INFO - mkdir: /builds/slave/test/build/upload/logs 21:19:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.005099 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-21 21:19:43.960527) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 21:19:43.964229) ========= 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.12sUssLPyz/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.Urib4CFTny/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bac1ad4f8b0e9c3af946b3a4d627d63a3cde8c39404526f995e2a53d53e65a0a2aede4fd964349f09322bac45108de2ddd041da80f01415aa4319b3ca44afda6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1946eaad77a07a3473ba04bc8cb500617ec89f29dc1869944db1bee24ec5e1013d78a44f74b3e91463c56d9b012e4e6ccc4b75ff5102b9503b9ca2e8070aa825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e37fb10d5dcb27a60b0fa7926282476caed0bd44d9188c9c066f36653d7716a569a0300d3156842ff25c1b0d0fb5684d8db7ba4a52318d1c1c5b32dc7a3fb784", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5bb2eb7abfc3e2002a237a822a5c6a38743af86be91d9907c200652eca1de7354d73f3868af21099f5d6c05d3561fd4508cd66baf3cd29b0ec80b1e238358518"} build_url:https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010634 build_url: 'https://queue.taskcluster.net/v1/task/OqEyNVKxSHqPR7pkq2SBRw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bac1ad4f8b0e9c3af946b3a4d627d63a3cde8c39404526f995e2a53d53e65a0a2aede4fd964349f09322bac45108de2ddd041da80f01415aa4319b3ca44afda6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1946eaad77a07a3473ba04bc8cb500617ec89f29dc1869944db1bee24ec5e1013d78a44f74b3e91463c56d9b012e4e6ccc4b75ff5102b9503b9ca2e8070aa825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e37fb10d5dcb27a60b0fa7926282476caed0bd44d9188c9c066f36653d7716a569a0300d3156842ff25c1b0d0fb5684d8db7ba4a52318d1c1c5b32dc7a3fb784", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5bb2eb7abfc3e2002a237a822a5c6a38743af86be91d9907c200652eca1de7354d73f3868af21099f5d6c05d3561fd4508cd66baf3cd29b0ec80b1e238358518"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 21:19:43.998147) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 21:19:43.998532) ========= 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.12sUssLPyz/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.Urib4CFTny/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.005033 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 21:19:44.048242) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 21:19:44.048632) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 21:19:44.049032) ========= ========= Total master_lag: 0.18 =========