builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0138 starttime: 1491847688.8 results: success (0) buildid: 20170410090449 builduid: ecfa5c23f9894a0e875526d98b40ba0e revision: e774d2cb0fc5b487607d452dc38715acb4040cc7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:08.801941) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:08.802392) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:08.802673) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:08.828845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:08.829244) ========= 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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-10 11:08:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 42.5M=0s 2017-04-10 11:08:09 (42.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.177737 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:09.221961) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:09.222454) ========= 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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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.027946 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:09.273748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 11:08:09.274150) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e774d2cb0fc5b487607d452dc38715acb4040cc7 --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 e774d2cb0fc5b487607d452dc38715acb4040cc7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uS3fpznO4o/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.FDDwA1lWPQ/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-10 11:08:09,387 truncating revision to first 12 chars 2017-04-10 11:08:09,387 Setting DEBUG logging. 2017-04-10 11:08:09,387 attempt 1/10 2017-04-10 11:08:09,387 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e774d2cb0fc5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-10 11:08:10,547 unpacking tar archive at: mozilla-aurora-e774d2cb0fc5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.440970 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 11:08:10.740691) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:10.741036) ========= script_repo_revision: e774d2cb0fc5b487607d452dc38715acb4040cc7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:10.741599) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:10.741900) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 11:08:10.762091) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-10 11:08:10.762542) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uS3fpznO4o/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.FDDwA1lWPQ/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:08:10 INFO - MultiFileLogger online at 20170410 11:08:10 in /builds/slave/test 11:08:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 11:08:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:08:10 INFO - {'append_to_log': False, 11:08:10 INFO - 'base_work_dir': '/builds/slave/test', 11:08:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:08:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:08:10 INFO - 'branch': 'Mozilla-Aurora', 11:08:10 INFO - 'buildbot_json_path': 'buildprops.json', 11:08:10 INFO - 'config_files': ('talos/mac_config.py',), 11:08:10 INFO - 'default_actions': ('clobber', 11:08:10 INFO - 'read-buildbot-config', 11:08:10 INFO - 'download-and-extract', 11:08:10 INFO - 'populate-webroot', 11:08:10 INFO - 'create-virtualenv', 11:08:10 INFO - 'install', 11:08:10 INFO - 'run-tests'), 11:08:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:08:10 INFO - 'download_minidump_stackwalk': True, 11:08:10 INFO - 'download_symbols': 'ondemand', 11:08:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:08:10 INFO - 'tooltool.py': '/tools/tooltool.py', 11:08:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:08:10 INFO - '/tools/misc-python/virtualenv.py')}, 11:08:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:08:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:08:10 INFO - 'gecko_profile': False, 11:08:10 INFO - 'gecko_profile_interval': 0, 11:08:10 INFO - 'installer_path': 'installer.exe', 11:08:10 INFO - 'log_level': 'info', 11:08:10 INFO - 'log_name': 'talos', 11:08:10 INFO - 'log_to_console': True, 11:08:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:08:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:08:10 INFO - 'opt_config_files': (), 11:08:10 INFO - 'pip_index': False, 11:08:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:08:10 INFO - 'cmd': ('bash', 11:08:10 INFO - '-c', 11:08:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:08:10 INFO - 'enabled': True, 11:08:10 INFO - 'halt_on_failure': False, 11:08:10 INFO - 'name': 'check_screen_resolution'},), 11:08:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:08:10 INFO - 'cmd': ('bash', 11:08:10 INFO - '-c', 11:08:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:08:10 INFO - 'enabled': True, 11:08:10 INFO - 'halt_on_failure': False, 11:08:10 INFO - 'name': 'check_screen_resolution'},), 11:08:10 INFO - 'run_cmd_checks_enabled': True, 11:08:10 INFO - 'suite': 'dromaeojs', 11:08:10 INFO - 'system_bits': '32', 11:08:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:08:10 INFO - 'title': 't-yosemite-r7-0138', 11:08:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:08:10 INFO - 'use_talos_json': True, 11:08:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:08:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:08:10 INFO - 'work_dir': 'build'} 11:08:10 INFO - [mozharness: 2017-04-10 18:08:10.979052Z] Running clobber step. 11:08:10 INFO - Running pre-action listener: _resource_record_pre_action 11:08:10 INFO - Running main action method: clobber 11:08:10 INFO - rmtree: /builds/slave/test/build 11:08:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:08:13 INFO - Running post-action listener: _resource_record_post_action 11:08:13 INFO - [mozharness: 2017-04-10 18:08:13.621025Z] Finished clobber step (success) 11:08:13 INFO - [mozharness: 2017-04-10 18:08:13.621133Z] Running read-buildbot-config step. 11:08:13 INFO - Running pre-action listener: _resource_record_pre_action 11:08:13 INFO - Running main action method: read_buildbot_config 11:08:13 INFO - Using buildbot properties: 11:08:13 INFO - { 11:08:13 INFO - "project": "", 11:08:13 INFO - "product": "firefox", 11:08:13 INFO - "script_repo_revision": "production", 11:08:13 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 11:08:13 INFO - "repository": "", 11:08:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 11:08:13 INFO - "buildid": "20170410090449", 11:08:13 INFO - "pgo_build": "False", 11:08:13 INFO - "basedir": "/builds/slave/test", 11:08:13 INFO - "buildnumber": 45, 11:08:13 INFO - "slavename": "t-yosemite-r7-0138", 11:08:13 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:08:13 INFO - "platform": "yosemite_r7", 11:08:13 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:08:13 INFO - "branch": "mozilla-aurora", 11:08:13 INFO - "repo_path": "releases/mozilla-aurora", 11:08:13 INFO - "revision": "e774d2cb0fc5b487607d452dc38715acb4040cc7", 11:08:13 INFO - "stage_platform": "macosx64", 11:08:13 INFO - "builduid": "ecfa5c23f9894a0e875526d98b40ba0e", 11:08:13 INFO - "slavebuilddir": "test" 11:08:13 INFO - } 11:08:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 11:08:13 INFO - Running post-action listener: _resource_record_post_action 11:08:13 INFO - [mozharness: 2017-04-10 18:08:13.624499Z] Finished read-buildbot-config step (success) 11:08:13 INFO - [mozharness: 2017-04-10 18:08:13.624637Z] Running download-and-extract step. 11:08:13 INFO - Running pre-action listener: _resource_record_pre_action 11:08:13 INFO - Running main action method: download_and_extract 11:08:13 INFO - mkdir: /builds/slave/test/build/tests 11:08:13 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')]} 11:08:13 INFO - trying https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 11:08:13 INFO - Downloading https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/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 11:08:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/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 11:08:14 INFO - Downloaded 1199 bytes. 11:08:14 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 11:08:14 INFO - Using the following test package requirements: 11:08:14 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 11:08:14 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:08:14 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 11:08:14 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:08:14 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 11:08:14 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:08:14 INFO - u'jsshell-mac.zip'], 11:08:14 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:08:14 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 11:08:14 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 11:08:14 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:08:14 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 11:08:14 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:08:14 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 11:08:14 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:08:14 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 11:08:14 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 11:08:14 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 11:08:14 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:08:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 11:08:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:08:14 INFO - Fetch https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 11:08:16 INFO - Expected file size: 51790751 11:08:16 INFO - Obtained file size: 51790751 11:08:32 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 11:08:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 11:08:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:08:32 INFO - Fetch https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 11:08:33 INFO - Expected file size: 51790751 11:08:33 INFO - Obtained file size: 51790751 11:08:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 11:08:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:08:49 INFO - Fetch https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 11:08:50 INFO - Expected file size: 14404424 11:08:50 INFO - Obtained file size: 14404424 11:08:51 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')]} 11:08:51 INFO - trying https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:08:51 INFO - Downloading https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 11:08:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/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 11:08:53 INFO - Downloaded 74689750 bytes. 11:08:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:08:53 INFO - mkdir: /builds/slave/test/properties 11:08:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:08:53 INFO - Writing to file /builds/slave/test/properties/build_url 11:08:53 INFO - Contents: 11:08:53 INFO - build_url:https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 11:08:53 INFO - Running post-action listener: _resource_record_post_action 11:08:53 INFO - Running post-action listener: set_extra_try_arguments 11:08:53 INFO - [mozharness: 2017-04-10 18:08:53.887817Z] Finished download-and-extract step (success) 11:08:53 INFO - [mozharness: 2017-04-10 18:08:53.887976Z] Running populate-webroot step. 11:08:53 INFO - Running pre-action listener: _resource_record_pre_action 11:08:53 INFO - Running main action method: populate_webroot 11:08:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:08:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:08:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:08:53 INFO - '--tppagecycles', 11:08:53 INFO - '7'], 11:08:53 INFO - 'tests': ['tsvgm']}}, 11:08:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:08:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:08:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:08:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:08:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:08:53 INFO - 'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:08:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:08:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:08:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:08:53 INFO - 'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['damp', 'tps']}, 11:08:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:08:53 INFO - 'tests': ['damp', 'tps']}, 11:08:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['dromaeo_dom']}, 11:08:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:08:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['basic_compositor_video']}, 11:08:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:08:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['a11yr', 11:08:53 INFO - 'ts_paint', 11:08:53 INFO - 'tpaint', 11:08:53 INFO - 'sessionrestore', 11:08:53 INFO - 'sessionrestore_no_auto_restore', 11:08:53 INFO - 'tabpaint']}, 11:08:53 INFO - 'other-e10s': {'tests': ['a11yr', 11:08:53 INFO - 'ts_paint', 11:08:53 INFO - 'tpaint', 11:08:53 INFO - 'sessionrestore', 11:08:53 INFO - 'sessionrestore_no_auto_restore', 11:08:53 INFO - 'tabpaint']}, 11:08:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:08:53 INFO - 'ts_paint', 11:08:53 INFO - 'tpaint', 11:08:53 INFO - 'sessionrestore', 11:08:53 INFO - 'sessionrestore_no_auto_restore', 11:08:53 INFO - 'tabpaint']}, 11:08:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:08:53 INFO - 'ts_paint', 11:08:53 INFO - 'tpaint', 11:08:53 INFO - 'sessionrestore', 11:08:53 INFO - 'sessionrestore_no_auto_restore', 11:08:53 INFO - 'tabpaint']}, 11:08:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['a11yr', 11:08:53 INFO - 'ts_paint', 11:08:53 INFO - 'tpaint', 11:08:53 INFO - 'sessionrestore', 11:08:53 INFO - 'sessionrestore_no_auto_restore', 11:08:53 INFO - 'tabpaint']}, 11:08:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['a11yr', 11:08:53 INFO - 'ts_paint', 11:08:53 INFO - 'tpaint', 11:08:53 INFO - 'sessionrestore', 11:08:53 INFO - 'sessionrestore_no_auto_restore', 11:08:53 INFO - 'tabpaint']}, 11:08:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['tsvgx', 11:08:53 INFO - 'tsvgr_opacity', 11:08:53 INFO - 'tart', 11:08:53 INFO - 'tscrollx', 11:08:53 INFO - 'cart', 11:08:53 INFO - 'tsvg_static']}, 11:08:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:08:53 INFO - 'tsvgr_opacity', 11:08:53 INFO - 'tart', 11:08:53 INFO - 'tscrollx', 11:08:53 INFO - 'cart', 11:08:53 INFO - 'tsvg_static']}, 11:08:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:08:53 INFO - 'talos_options': ['--disable-e10s'], 11:08:53 INFO - 'tests': ['tp5o']}, 11:08:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:08:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:08:53 INFO - 'talos_options': ['--disable-e10s', 11:08:53 INFO - '--xperf_path', 11:08:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:08:53 INFO - 'tests': ['tp5n']}, 11:08:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:08:53 INFO - 'talos_options': ['--xperf_path', 11:08:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:08:53 INFO - 'tests': ['tp5n']}}, 11:08:53 INFO - 'talos.zip': {'path': '', 11:08:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:08:53 INFO - Running post-action listener: _resource_record_post_action 11:08:53 INFO - [mozharness: 2017-04-10 18:08:53.906524Z] Finished populate-webroot step (success) 11:08:53 INFO - [mozharness: 2017-04-10 18:08:53.906636Z] Running create-virtualenv step. 11:08:53 INFO - Running pre-action listener: _resource_record_pre_action 11:08:53 INFO - Running main action method: create_virtualenv 11:08:53 INFO - Creating virtualenv /builds/slave/test/build/venv 11:08:53 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 11:08:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:08:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:08:54 INFO - Using real prefix '/tools/python27' 11:08:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:08:54 INFO - Installing distribute.............................................................................................................................................................................................done. 11:08:57 INFO - Installing pip.................done. 11:08:57 INFO - Return code: 0 11:08:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:08:57 INFO - Reading from file tmpfile_stdout 11:08:57 INFO - Output received: 11:08:57 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) 11:08:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:08: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')]} 11:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08: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')]} 11:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc0fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2dacb8900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, '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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:57 INFO - 'HOME': '/Users/cltbld', 11:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:57 INFO - 'LOGNAME': 'cltbld', 11:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:57 INFO - 'MOZ_NO_REMOTE': '1', 11:08:57 INFO - 'NO_EM_RESTART': '1', 11:08:57 INFO - 'PAGER': '/bin/cat', 11:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:57 INFO - 'PWD': '/builds/slave/test', 11:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:57 INFO - 'SHELL': '/bin/bash', 11:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:08:57 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:57 INFO - 'USER': 'cltbld', 11:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:57 INFO - 'XPC_FLAGS': '0x0', 11:08:57 INFO - 'XPC_SERVICE_NAME': '0', 11:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:58 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 11:08:58 INFO - Cleaning up... 11:08:58 INFO - Return code: 0 11:08:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:08:58 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')]} 11:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:08:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:58 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')]} 11:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:08:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:08:58 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 0x10fc0fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2dacb8900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, '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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:08:58 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 11:08:58 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 11:08:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:08:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:08:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:08:58 INFO - 'HOME': '/Users/cltbld', 11:08:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:08:58 INFO - 'LOGNAME': 'cltbld', 11:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:58 INFO - 'MOZ_NO_REMOTE': '1', 11:08:58 INFO - 'NO_EM_RESTART': '1', 11:08:58 INFO - 'PAGER': '/bin/cat', 11:08:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:58 INFO - 'PWD': '/builds/slave/test', 11:08:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:08:58 INFO - 'SHELL': '/bin/bash', 11:08:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:08:58 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:08:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:08:58 INFO - 'USER': 'cltbld', 11:08:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:08:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:58 INFO - 'XPC_FLAGS': '0x0', 11:08:58 INFO - 'XPC_SERVICE_NAME': '0', 11:08:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:08:58 INFO - Downloading/unpacking psutil>=3.1.1 11:08:58 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 11:08:58 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 11:08:58 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 11:08:58 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 11:08:58 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 11:08:58 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 11:09:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:09:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:09:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:09:02 INFO - Installing collected packages: psutil 11:09:02 INFO - Running setup.py install for psutil 11:09:02 INFO - building 'psutil._psutil_osx' extension 11:09:02 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 11:09:02 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 11:09:02 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 11:09:02 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 11:09:02 INFO - building 'psutil._psutil_posix' extension 11:09:02 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 11:09:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:09:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:09:03 INFO - ^ 11:09:03 INFO - 1 warning generated. 11:09:03 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 11:09:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:09:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:09:03 INFO - Successfully installed psutil 11:09:03 INFO - Cleaning up... 11:09:03 INFO - Return code: 0 11:09:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:09:03 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')]} 11:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:04 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')]} 11:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:04 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 0x10fc0fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2dacb8900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, '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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:09:04 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 11:09:04 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 11:09:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:09:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:04 INFO - 'HOME': '/Users/cltbld', 11:09:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:04 INFO - 'LOGNAME': 'cltbld', 11:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:04 INFO - 'MOZ_NO_REMOTE': '1', 11:09:04 INFO - 'NO_EM_RESTART': '1', 11:09:04 INFO - 'PAGER': '/bin/cat', 11:09:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:04 INFO - 'PWD': '/builds/slave/test', 11:09:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:04 INFO - 'SHELL': '/bin/bash', 11:09:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:09:04 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:09:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:04 INFO - 'USER': 'cltbld', 11:09:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:04 INFO - 'XPC_FLAGS': '0x0', 11:09:04 INFO - 'XPC_SERVICE_NAME': '0', 11:09:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:09:04 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 11:09:04 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 11:09:04 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 11:09:04 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 11:09:04 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 11:09:04 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 11:09:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:09:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:09:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:09:07 INFO - Installing collected packages: mozsystemmonitor 11:09:07 INFO - Running setup.py install for mozsystemmonitor 11:09:08 INFO - Successfully installed mozsystemmonitor 11:09:08 INFO - Cleaning up... 11:09:08 INFO - Return code: 0 11:09:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:09:08 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')]} 11:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:08 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')]} 11:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:08 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 0x10fc0fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2dacb8900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, '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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:09:08 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 11:09:08 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 11:09:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:09:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:08 INFO - 'HOME': '/Users/cltbld', 11:09:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:08 INFO - 'LOGNAME': 'cltbld', 11:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:08 INFO - 'MOZ_NO_REMOTE': '1', 11:09:08 INFO - 'NO_EM_RESTART': '1', 11:09:08 INFO - 'PAGER': '/bin/cat', 11:09:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:08 INFO - 'PWD': '/builds/slave/test', 11:09:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:08 INFO - 'SHELL': '/bin/bash', 11:09:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:09:08 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:09:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:08 INFO - 'USER': 'cltbld', 11:09:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:08 INFO - 'XPC_FLAGS': '0x0', 11:09:08 INFO - 'XPC_SERVICE_NAME': '0', 11:09:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:08 INFO - Downloading/unpacking jsonschema==2.5.1 11:09:08 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 11:09:08 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 11:09:08 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 11:09:08 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 11:09:08 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 11:09:08 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 11:09:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:09:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:09:11 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 11:09:11 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 11:09:11 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 11:09:11 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 11:09:11 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 11:09:11 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 11:09:11 INFO - Downloading functools32-3.2.3-2.tar.gz 11:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:09:11 INFO - warning: no files found matching '*.txt' 11:09:11 INFO - no previously-included directories found matching 'build' 11:09:11 INFO - no previously-included directories found matching 'dist' 11:09:11 INFO - no previously-included directories found matching '.git*' 11:09:11 INFO - Installing collected packages: jsonschema, functools32 11:09:11 INFO - Running setup.py install for functools32 11:09:11 INFO - warning: no files found matching '*.txt' 11:09:11 INFO - no previously-included directories found matching 'build' 11:09:11 INFO - no previously-included directories found matching 'dist' 11:09:11 INFO - no previously-included directories found matching '.git*' 11:09:11 INFO - Successfully installed jsonschema functools32 11:09:11 INFO - Cleaning up... 11:09:11 INFO - Return code: 0 11:09:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:11 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 0x10fc0fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2dacb8900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, '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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:09:11 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 11:09:11 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 11:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:11 INFO - 'HOME': '/Users/cltbld', 11:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:11 INFO - 'LOGNAME': 'cltbld', 11:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:11 INFO - 'MOZ_NO_REMOTE': '1', 11:09:11 INFO - 'NO_EM_RESTART': '1', 11:09:11 INFO - 'PAGER': '/bin/cat', 11:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:11 INFO - 'PWD': '/builds/slave/test', 11:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:11 INFO - 'SHELL': '/bin/bash', 11:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:09:11 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:11 INFO - 'USER': 'cltbld', 11:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:11 INFO - 'XPC_FLAGS': '0x0', 11:09:11 INFO - 'XPC_SERVICE_NAME': '0', 11:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:09:12 INFO - Cleaning up... 11:09:12 INFO - Return code: 0 11:09:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:09: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')]} 11:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09: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')]} 11:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09: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', '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 0x10fc0fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2dacb8900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, '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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:09: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:09: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 blobuploader==1.2.4 11:09:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:09:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:12 INFO - 'HOME': '/Users/cltbld', 11:09:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:12 INFO - 'LOGNAME': 'cltbld', 11:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:12 INFO - 'MOZ_NO_REMOTE': '1', 11:09:12 INFO - 'NO_EM_RESTART': '1', 11:09:12 INFO - 'PAGER': '/bin/cat', 11:09:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:12 INFO - 'PWD': '/builds/slave/test', 11:09:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:12 INFO - 'SHELL': '/bin/bash', 11:09:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:09:12 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:09:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:12 INFO - 'USER': 'cltbld', 11:09:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:12 INFO - 'XPC_FLAGS': '0x0', 11:09:12 INFO - 'XPC_SERVICE_NAME': '0', 11:09:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:12 INFO - Downloading/unpacking blobuploader==1.2.4 11:09: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 11:09: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 11:09: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 11:09: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 11:09: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 11:09: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 11:09:15 INFO - Downloading blobuploader-1.2.4.tar.gz 11:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:09:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:09:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:16 INFO - Downloading docopt-0.6.1.tar.gz 11:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:09:16 INFO - Installing collected packages: blobuploader, requests, docopt 11:09:16 INFO - Running setup.py install for blobuploader 11:09:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:09:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:09:16 INFO - Running setup.py install for requests 11:09:17 INFO - Running setup.py install for docopt 11:09:17 INFO - Successfully installed blobuploader requests docopt 11:09:17 INFO - Cleaning up... 11:09:17 INFO - Return code: 0 11:09:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:17 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 0x10fc0fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2dacb8900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, '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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:09:17 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 11:09:17 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 11:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:17 INFO - 'HOME': '/Users/cltbld', 11:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:17 INFO - 'LOGNAME': 'cltbld', 11:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:17 INFO - 'MOZ_NO_REMOTE': '1', 11:09:17 INFO - 'NO_EM_RESTART': '1', 11:09:17 INFO - 'PAGER': '/bin/cat', 11:09:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:17 INFO - 'PWD': '/builds/slave/test', 11:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:17 INFO - 'SHELL': '/bin/bash', 11:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:09:17 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:17 INFO - 'USER': 'cltbld', 11:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:17 INFO - 'XPC_FLAGS': '0x0', 11:09:17 INFO - 'XPC_SERVICE_NAME': '0', 11:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:09:17 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-wk7yqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:09:17 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-mRsvuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:09:17 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-2mlGjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:09:17 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-zsI4i7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:09:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:09:17 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-7_E6p8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:09:18 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-Ud3iB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:09:18 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-gv0Hvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:09:18 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-Icsyxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:09:18 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-GX2r5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:09:18 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-l2qzJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:09:18 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-FDjdXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:09:18 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-LQtKOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:09:18 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-PuaktJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:09:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:09:18 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-1RduZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:19 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-BIKrFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:09:19 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-zhaNLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:09:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:09:19 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-4_j0RF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:09:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:09:19 INFO - Running setup.py install for manifestparser 11:09:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:09:19 INFO - Running setup.py install for mozcrash 11:09:19 INFO - Running setup.py install for mozdebug 11:09:19 INFO - Running setup.py install for mozdevice 11:09:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:09:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:09:20 INFO - Running setup.py install for mozfile 11:09:20 INFO - Running setup.py install for mozhttpd 11:09:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:09:20 INFO - Running setup.py install for mozinfo 11:09:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:09:20 INFO - Running setup.py install for mozInstall 11:09:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:09:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:09:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:09:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:09:20 INFO - Running setup.py install for mozleak 11:09:20 INFO - Running setup.py install for mozlog 11:09:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:09:20 INFO - Running setup.py install for moznetwork 11:09:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:09:21 INFO - Running setup.py install for mozprocess 11:09:21 INFO - Running setup.py install for mozprofile 11:09:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:09:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:09:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:09:21 INFO - Running setup.py install for mozrunner 11:09:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:09:21 INFO - Running setup.py install for mozscreenshot 11:09:21 INFO - Running setup.py install for moztest 11:09:21 INFO - Running setup.py install for mozversion 11:09:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:09:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:09:22 INFO - Cleaning up... 11:09:22 INFO - Return code: 0 11:09:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:22 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 0x10fc0fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2dacb8900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, '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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:09:22 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 11:09:22 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 11:09:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:09:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:22 INFO - 'HOME': '/Users/cltbld', 11:09:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:22 INFO - 'LOGNAME': 'cltbld', 11:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:22 INFO - 'MOZ_NO_REMOTE': '1', 11:09:22 INFO - 'NO_EM_RESTART': '1', 11:09:22 INFO - 'PAGER': '/bin/cat', 11:09:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:22 INFO - 'PWD': '/builds/slave/test', 11:09:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:22 INFO - 'SHELL': '/bin/bash', 11:09:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:09:22 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:09:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:22 INFO - 'USER': 'cltbld', 11:09:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:22 INFO - 'XPC_FLAGS': '0x0', 11:09:22 INFO - 'XPC_SERVICE_NAME': '0', 11:09:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:09:22 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-uHteZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:09:22 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)) 11:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:09:22 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-t9D0P1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:09:22 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)) 11:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:09:22 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-vXYvz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:09:22 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)) 11:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:09:22 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-Y0KMCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:09:22 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)) 11:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:09:22 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-r_uoap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:09:22 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)) 11:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:09:22 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-lVJHVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:09:22 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)) 11:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:09:22 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-5MxOhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:09:23 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)) 11:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:09:23 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-fB330w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:09:23 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)) 11:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:09:23 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-IoZxd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:09:23 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)) 11:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:09:23 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-T5Swjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:09:23 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)) 11:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:09:23 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-jmOLTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:09:23 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)) 11:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:09:23 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-4OtgEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:09:23 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)) 11:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:09:23 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-Uz2nKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:09:23 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)) 11:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:09:23 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-Y863dT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:09:23 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)) 11:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:23 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-m3swGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:23 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)) 11:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:09:23 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-__xPRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:09:24 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)) 11:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:09:24 INFO - Running setup.py (path:/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/pip-syCdMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:09:24 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)) 11:09:24 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)) 11:09:24 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)) 11:09:24 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)) 11:09:24 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)) 11:09:24 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)) 11:09:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:09: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 11:09: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 11:09: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 11:09: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 11:09: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 11:09: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 11:09:26 INFO - Downloading blessings-1.6.tar.gz 11:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:09:26 INFO - Installing collected packages: blessings 11:09:26 INFO - Running setup.py install for blessings 11:09:27 INFO - Successfully installed blessings 11:09:27 INFO - Cleaning up... 11:09:27 INFO - Return code: 0 11:09:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:09:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:09:27 INFO - Reading from file tmpfile_stdout 11:09:27 INFO - Current package versions: 11:09:27 INFO - blessings == 1.6 11:09:27 INFO - blobuploader == 1.2.4 11:09:27 INFO - docopt == 0.6.1 11:09:27 INFO - functools32 == 3.2.3-2 11:09:27 INFO - jsonschema == 2.5.1 11:09:27 INFO - manifestparser == 1.1 11:09:27 INFO - mozInstall == 1.12 11:09:27 INFO - mozcrash == 1.0 11:09:27 INFO - mozdebug == 0.1 11:09:27 INFO - mozdevice == 0.49 11:09:27 INFO - mozfile == 1.2 11:09:27 INFO - mozhttpd == 0.7 11:09:27 INFO - mozinfo == 0.9 11:09:27 INFO - mozleak == 0.1 11:09:27 INFO - mozlog == 3.4 11:09:27 INFO - moznetwork == 0.27 11:09:27 INFO - mozprocess == 0.25 11:09:27 INFO - mozprofile == 0.28 11:09:27 INFO - mozrunner == 6.13 11:09:27 INFO - mozscreenshot == 0.1 11:09:27 INFO - mozsystemmonitor == 0.3 11:09:27 INFO - moztest == 0.8 11:09:27 INFO - mozversion == 1.4 11:09:27 INFO - psutil == 3.1.1 11:09:27 INFO - requests == 1.2.3 11:09:27 INFO - wsgiref == 0.1.2 11:09:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09:27 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')]} 11:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:27 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')]} 11:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:27 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 0x10fc0fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2dacb8900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, '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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:09:27 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 11:09:27 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 11:09:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:09:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:27 INFO - 'HOME': '/Users/cltbld', 11:09:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:27 INFO - 'LOGNAME': 'cltbld', 11:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:27 INFO - 'MOZ_NO_REMOTE': '1', 11:09:27 INFO - 'NO_EM_RESTART': '1', 11:09:27 INFO - 'PAGER': '/bin/cat', 11:09:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:27 INFO - 'PWD': '/builds/slave/test', 11:09:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:27 INFO - 'SHELL': '/bin/bash', 11:09:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:09:27 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:09:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:27 INFO - 'USER': 'cltbld', 11:09:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:27 INFO - 'XPC_FLAGS': '0x0', 11:09:27 INFO - 'XPC_SERVICE_NAME': '0', 11:09:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:27 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)) 11:09:27 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)) 11:09:27 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)) 11:09:27 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)) 11:09:27 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)) 11:09:27 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)) 11:09:27 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)) 11:09:27 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)) 11:09:27 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)) 11:09:27 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)) 11:09:27 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)) 11:09:27 INFO - Cleaning up... 11:09:27 INFO - Return code: 0 11:09:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:09:27 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')]} 11:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:27 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')]} 11:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:27 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 0x10fc0fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc12768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb2dacb8900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, '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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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 11:09:27 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 11:09:27 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 11:09:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:09:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:27 INFO - 'HOME': '/Users/cltbld', 11:09:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:27 INFO - 'LOGNAME': 'cltbld', 11:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:27 INFO - 'MOZ_NO_REMOTE': '1', 11:09:27 INFO - 'NO_EM_RESTART': '1', 11:09:27 INFO - 'PAGER': '/bin/cat', 11:09:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:27 INFO - 'PWD': '/builds/slave/test', 11:09:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:27 INFO - 'SHELL': '/bin/bash', 11:09:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:09:27 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:09:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:27 INFO - 'USER': 'cltbld', 11:09:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:27 INFO - 'XPC_FLAGS': '0x0', 11:09:27 INFO - 'XPC_SERVICE_NAME': '0', 11:09:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:09:27 INFO - Cleaning up... 11:09:27 INFO - Return code: 0 11:09:27 INFO - Running post-action listener: _resource_record_post_action 11:09:27 INFO - Running post-action listener: _start_resource_monitoring 11:09:28 INFO - Starting resource monitoring. 11:09:28 INFO - [mozharness: 2017-04-10 18:09:28.010022Z] Finished create-virtualenv step (success) 11:09:28 INFO - [mozharness: 2017-04-10 18:09:28.010965Z] Running install step. 11:09:28 INFO - Running pre-action listener: _resource_record_pre_action 11:09:28 INFO - Running main action method: install 11:09:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:09:28 INFO - Reading from file tmpfile_stdout 11:09:28 INFO - Detecting whether we're running mozinstall >=1.0... 11:09:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:09:28 INFO - Reading from file tmpfile_stdout 11:09:28 INFO - Output received: 11:09:28 INFO - Usage: mozinstall [options] installer 11:09:28 INFO - Options: 11:09:28 INFO - -h, --help show this help message and exit 11:09:28 INFO - -d DEST, --destination=DEST 11:09:28 INFO - Directory to install application into. [default: 11:09:28 INFO - "/builds/slave/test"] 11:09:28 INFO - --app=APP Application being installed. [default: firefox] 11:09:28 INFO - mkdir: /builds/slave/test/build/application 11:09:28 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'] 11:09:28 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 11:09:48 INFO - Reading from file tmpfile_stdout 11:09:48 INFO - Output received: 11:09:48 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:09:48 INFO - Running post-action listener: _resource_record_post_action 11:09:48 INFO - [mozharness: 2017-04-10 18:09:48.789052Z] Finished install step (success) 11:09:48 INFO - [mozharness: 2017-04-10 18:09:48.789208Z] Running run-tests step. 11:09:48 INFO - Running pre-action listener: _resource_record_pre_action 11:09:48 INFO - Running main action method: run_tests 11:09:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:09:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:09:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:09:48 INFO - 2017-04-10 11:09:48.804 screenresolution[1622:7323] starting screenresolution argv=screenresolution get 11:09:48 INFO - 2017-04-10 11:09:48.806 screenresolution[1622:7323] Display 0: 1600x1200x32@60 11:09:48 INFO - 2017-04-10 11:09:48.812 screenresolution[1623:7325] starting screenresolution argv=screenresolution list 11:09:48 INFO - Available Modes on Display 0 11:09:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:09:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:09:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:09:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:09:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:09:49 INFO - Intel Iris: 11:09:49 INFO - Chipset Model: Intel Iris 11:09:49 INFO - Type: GPU 11:09:49 INFO - Bus: Built-In 11:09:49 INFO - VRAM (Dynamic, Max): 1536 MB 11:09:49 INFO - Vendor: Intel (0x8086) 11:09:49 INFO - Device ID: 0x0a2e 11:09:49 INFO - Revision ID: 0x0009 11:09:49 INFO - Displays: 11:09:49 INFO - Display: 11:09:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:09:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:09:49 INFO - Display Serial Number: 1600x1200 60 11:09:49 INFO - Main Display: Yes 11:09:49 INFO - Mirror: Off 11:09:49 INFO - Online: Yes 11:09:49 INFO - Rotation: Supported 11:09:49 INFO - Return code: 0 11:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:09:49 INFO - Python 2.7.3 11:09:49 INFO - Return code: 0 11:09:49 INFO - grabbing minidump binary from tooltool 11:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:49 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 0x10f9edd30>, '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 0x10f914db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9e2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:09:49 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 11:09:49 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 11:09:49 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 11:09:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:09:49 INFO - Return code: 0 11:09:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:09:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:09:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:09:49 INFO - ENV: RUST_BACKTRACE is now 1 11:09:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:09:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0138', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0138 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:09:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uS3fpznO4o/Render', 11:09:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:49 INFO - 'HOME': '/Users/cltbld', 11:09:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:49 INFO - 'LOGNAME': 'cltbld', 11:09:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:09:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:49 INFO - 'MOZ_NO_REMOTE': '1', 11:09:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:09:49 INFO - 'NO_EM_RESTART': '1', 11:09:49 INFO - 'PAGER': '/bin/cat', 11:09:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:49 INFO - 'PWD': '/builds/slave/test', 11:09:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:09:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:49 INFO - 'RUST_BACKTRACE': '1', 11:09:49 INFO - 'SHELL': '/bin/bash', 11:09:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FDDwA1lWPQ/Listeners', 11:09:49 INFO - 'TMPDIR': '/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/', 11:09:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:49 INFO - 'USER': 'cltbld', 11:09:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:49 INFO - 'XPC_FLAGS': '0x0', 11:09:49 INFO - 'XPC_SERVICE_NAME': '0', 11:09:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0138', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:09:49 INFO - mozversion application_buildid: 20170410090449 11:09:49 INFO - mozversion application_changeset: e774d2cb0fc5b487607d452dc38715acb4040cc7 11:09:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:09:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:09:49 INFO - mozversion application_name: Firefox 11:09:49 INFO - mozversion application_remotingname: firefox-dev 11:09:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:09:49 INFO - mozversion application_vendor: Mozilla 11:09:49 INFO - mozversion application_version: 54.0a2 11:09:49 INFO - mozversion platform_buildid: 20170410090449 11:09:49 INFO - mozversion platform_changeset: e774d2cb0fc5b487607d452dc38715acb4040cc7 11:09:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:09:49 INFO - mozversion platform_version: 54.0a2 11:09:49 INFO - using testdate: 1491847789 11:09:49 INFO - actual date: 1491847789 11:09:49 INFO - starting webserver on 'localhost:49237' 11:09:49 INFO - SUITE-START | Running 2 tests 11:09:49 INFO - TEST-START | dromaeo_css 11:09:49 INFO - operating with platform_type : mac_ 11:09:49 INFO - Initialising browser for dromaeo_css test... 11:09:49 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/tmpsNLvar/profile http://localhost:49237/getInfo.html) 11:09:52 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 11:09:52 INFO - PROCESS | 1633 | colorDepth:24 11:09:52 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 11:09:52 INFO - PROCESS | 1633 | __metrics 11:09:52 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:09:52 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:09:52 INFO - TEST-INFO | 1633: exit 0 11:09:52 INFO - Browser initialized. 11:09:52 INFO - Running cycle 1/1 for dromaeo_css test... 11:09:52 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/tmpsNLvar/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:10:04 INFO - PROCESS | 1635 | RSS: Main: 214560768 11:10:04 INFO - PROCESS | 1635 | 11:13:09 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 11:13:11 INFO - PROCESS | 1635 | RSS: Main: 335073280 11:13:11 INFO - PROCESS | 1635 | 11:14:54 INFO - PROCESS | 1635 | ************************* 11:14:54 INFO - PROCESS | 1635 | A coding exception was thrown and uncaught in a Task. 11:14:54 INFO - PROCESS | 1635 | 11:14:54 ERROR - PROCESS | 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:14:54 INFO - PROCESS | 1635 | Full stack: 11:14:54 INFO - PROCESS | 1635 | ************************* 11:14:54 INFO - PROCESS | 1635 | ************************* 11:14:54 INFO - PROCESS | 1635 | A coding exception was thrown and uncaught in a Task. 11:14:54 INFO - PROCESS | 1635 | 11:14:54 ERROR - PROCESS | 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:14:54 INFO - PROCESS | 1635 | Full stack: 11:14:54 INFO - PROCESS | 1635 | ************************* 11:14:54 INFO - PROCESS | 1635 | ************************* 11:14:54 INFO - PROCESS | 1635 | A coding exception was thrown and uncaught in a Task. 11:14:54 INFO - PROCESS | 1635 | 11:14:54 ERROR - PROCESS | 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:14:54 INFO - PROCESS | 1635 | Full stack: 11:14:54 INFO - PROCESS | 1635 | ************************* 11:14:54 INFO - PROCESS | 1635 | ************************* 11:14:54 INFO - PROCESS | 1635 | A coding exception was thrown and uncaught in a Task. 11:14:54 INFO - PROCESS | 1635 | 11:14:54 ERROR - PROCESS | 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:14:54 INFO - PROCESS | 1635 | Full stack: 11:14:54 INFO - PROCESS | 1635 | ************************* 11:16:14 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 11:16:15 INFO - PROCESS | 1635 | RSS: Main: 348975104 11:16:15 INFO - PROCESS | 1635 | 11:19:17 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 11:19:18 INFO - PROCESS | 1635 | RSS: Main: 327249920 11:19:18 INFO - PROCESS | 1635 | 11:22:20 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 11:22:21 INFO - PROCESS | 1635 | RSS: Main: 318144512 11:22:21 INFO - PROCESS | 1635 | 11:25:25 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 11:25:25 INFO - PROCESS | 1635 | RSS: Main: 323559424 11:25:25 INFO - PROCESS | 1635 | 11:28:29 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 11:28:29 INFO - PROCESS | 1635 | RSS: Main: 373084160 11:28:29 INFO - PROCESS | 1635 | 11:28:29 INFO - PROCESS | 1635 | __start_tp_report 11:28:29 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 11:28:29 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 11:28:29 INFO - PROCESS | 1635 | |i|pagename|runs| 11:28:29 INFO - PROCESS | 1635 | |0;dojo.html;3395.270016946356;3400.2179498715295;3402.4726167444055;3410.13041674373;3447.758656894017;6014.037753959367;6042.637441753498;6044.848878778027;6050.213472248602;6084.361142080084;5977.551683623735;6041.003234466308;6048.153258543807;6048.727807248673;6065.48443382312;5347.064263753838;5364.5708343332635;5390.21841832934;5394.433584473632;5502.174673798942;4711.246200607903;5298.867528311785;5313.548348390372;5323.733813309338;5516.531094856942;4472.575105364985;5083.517065878754;5432;5433.103537916259;5441.129419292914;5447.12845944647;5447.700376479292;5454.7272636368225;5458.9454105459035;5461.18082287656;5214.765335559909;5326.334208223972;5380.569554435636;5415;5422.6475279106735;5834.183214349994;5837.562182836262;5879.412058794129;5967.194428752106;6059.515238780887;6253.3433989430905;6313.526485513559;6322.304546499881;6326.5255105867245;6330.841728956767;6347.238331400215;6350.460210882081;6352.07894855248;6375.203099612549;6377.298497165308;5767.336756273083;5863.618864773776;5915.645061296336;5932.021216499315;6101.786437474667;5505.669659820343;6311.558190926591;6327.462165715873;6342.175517182736;6407.327230640809;6281.59169653971;6342.873142537123;6346.174997250328;6351.714172862232;6352.888244557184;6046.244219472566;6351.841203969938;6352.015437607179;6357.141785858942;6361.363863613602;6197.659128747872;6206.286277078194;6262.2172228471445;6275.435210830956;6310.242770867525;5102.336696229467;6296.716647750863;6301.7164227609865;6303.086052522359;6344.270408903035;5127.538521533005;5180.145276029489;5182.300389447451;5191.662541934762;5197;4484.349769283435;4503.279475283939;4623.329617205486;4625.190591646448;4625.514320996314;5857.794977175864;5956.374580668968;6011.458968692838;6049.879002419927;6052.727627256784;6294.464970477469;6334.5565810393755;6347.5239357047485;6349.936500635027;6356.014817703263;5987.072003839412;6001.489873360813;6006.219191505076;6008.609440386361;6030.48740857032;5477.616566840363;5492.148716948879;5566.526845218121;5572.024895643246;5578.330600327987;5534.944650553604;6307.653079080603;6430.517711171775;6519.543631945908;6540.50946179029;6333.746650133943;6336.556441049082;6342.112104305309;6343.524235682435;6348.523860710373;6530.7387704493185;6547.1161393211305;6551.181102362205;6557.377049180321;6567.704453299518;6205.875882482234;6228.34602366745;6255.968720156552;6262;6321;6256.749730010931;6317.431431171217;6337.7781777637565;6349.714262858275;6357.650329231752;6267.2165979252595;6296.905546416716;6322.430981211529;6325.936740632535;6340.904886426615;6311.274203466476;6331.2085989251345;6346;6357.3006969234275;6363.745450182126;5804.477597016289;5980.0431930890845;6029.638221706711;6242.719077641426;6252.218472690914;6258.843528911813;6274.027525733519;6343.619382837044;6346.048092786119;6346.111544383883;6212.565120441525;6219.875602488016;6224.875502490016;6230.626162430269;6237.970734828702 11:28:29 INFO - PROCESS | 1635 | |1;ext.html;796.7171654062809;820.4707963363633;865.238590040764;881.2509367038023;883.6509578716407;39824.402633960475;40770.388444173324;42485.93785155369;42505.7874710626;42550.72347829562;40750;41002.17995640093;41008.17983640325;41063.58936410635;41146.17707645845;7420.666070026835;9274.907250927472;9412.52937353133;9425.811483770354;9458.101480359355;7476.327130558248;7564.319211270985;7566.092068951733;7572.167061623217;10938.343699378023;11183.776324473505;11203.775924481546;11206.271592346471;11225.887741122608;11274.379909104997;9659.565319560566;11143.108551315916;11179.385133817637;11187.944060279728;11189.49647265871;7293.8905916411295;7481.738139165103;10976.341419514854;11032.889671103267;11045.944770276177;28018.85990570034;28212.576811347848;28236;38708.806455967824;39386.0153496162;3755;3863.420486926955;3885.7668539887695;3909.569947305794;4085.9795701021303;27417.588736168975;27433.90264389442;27465.45069098627;27491;28245;3047.344820863525;3227.01576019314;3281.835908204604;3299.059767966118;3302.554155189067;352296;353615;353767;354818;358411.9999999974;42162.99999999939;42826.57173428288;52065.47934520606;52178.21732674013;52294;53799;54907.45092549103;54960.450395495536;55026;57489.85020299571;14679.19264440466;14710.970232083651;14754;14760.630984225265;14761.409543618134;12781.61655150347;12784.360781960864;12789.680257993625;12789.97680185583;12822;12080.939595301967;12283;12292.938535307445;12354.505819767288;12383.442745076492;5653.152027195872;5726.9713651431475;5736.393809733281;5740.253767010262;5746.2529871116485;1104.4588151805717;1123.690984979121;1124.983125253122;1126.414264582429;1172.372780562395;26094.478110437685;26763.86618066936;27301.590476142876;27320.863395682893;27478;1237.900967922564;1245.6138597034949;1270.8729127087217;1275.2464668549649;1284.1203775413871;13910.07888343439;14071.507497237548;14191.71616567663;14191.787123192953;14255;447425;448110;448437.5156248461;449432;449929;56071;56101.438985609624;56109;56117;56197;1412.7880817877194;1475.0780762023521;1476.180719700555;1477.7561702318997;1478.379080786051;25116;25740.32687875282;26045.348866277738;26560.601590976537;26566.335841604116;47347;47443.52556474391;47453.57639270827;47476.7626161867;47548.7622561885;431253;431292;431427;431841;432180;33551.83224083864;34112.48831267583;34151.82924085463;34155.31689366149;34298.65701342955;31635.84182079167;44387.55612443964;44779.552204477535;46391.134922087906;46749.597512074695;29051.709482905746;29159.271018224714;30386.578049933683;31051.91318303939;31070.533941990438;30721.38557228798;31005.449727513984;31038.844805775825;31380.215494612818;31464.37071258516;34594.135146621535;34723.4791478123;35116.64883351236;35611.28777424489;36840;27982.44035119327;28258.2935426616;29307.974220902168;29481;29510.55734163946;23285.88357058204;23441.296761097194;23655.344125911022;23706.629337066155;23827.64258536158 11:28:29 INFO - PROCESS | 1635 | |2;jquery.html;417.27810887165225;426.4739707122594;427.1435771278587;436.16474451425523;438.815697407089;18938.62122757544;19506.31727889524;20888.851113188768;20971.47571310718;21010;21222.787772122312;22466.775332246674;23155.652665210037;23261.651075233884;23416.882915585396;6756.425703815169;7014.859702805941;7148.070750802389;7158.141023077238;7226.241244669287;5429.4027656957705;7148.249433809453;7152.678129484186;7152.749653762119;7162.713491460361;6805.319468053204;7050.224475307712;7127.073480447534;7139.60732159731;7150.213476517579;7088.468364872603;7113;7130.144382674061;7175.3900918422;7177.138743350805;6719.092922455455;6995.475339349518;7000.789976300719;7008.19405768338;7014.298570142996;29526;30348.393032139233;30463.543046854316;30490;30907.69092309093;3005.879764809405;3017.637883453978;3027.9091627250996;3030.712082352173;3032.8786848526033;15322.923385383114;16964.66070678592;17664.467106578737;18528.62942741151;18565.35021274249;2032.2379107834563;2059.104289634014;2090.4669309326177;2128.7871212878745;2138.6364318065966;134247.98628020586;137861;140217;143539;145993.27003364914;22939.311820645406;24691.753082469306;24708.505829883303;24864;24987.87554560049;42411.78794106071;42533.36199957003;43812.780936095114;43950.12099757987;44026.679199624064;10036.598536058476;10323.48382580868;10500.737481563023;10523.21075919309;10686.305390149615;8692.739217823475;8693.087225841322;8705.651773928972;8868.8669669955;8896.510691911877;9291.953540232256;9591.856122158173;9649.469279189572;9659.985701501382;9691.030896910393;4022.2156679447226;4077.3068578341754;4116.303820997252;4118.382242663624;4120.681381957805;884.5046773806689;906.2387594420718;909.2544113826599;942.7548837302213;949.838527450335;21064.89467552683;22098.558028839332;22128.12334396518;22134;22230.888455577155;916.4821875640242;928.9442633442015;930.4556834251903;942.5664194470484;950.334765664038;10806.189535784848;11201.551937922555;11309.830352544719;11467.54129834814;11495.873263446289;715549;718920;762248;765522.1723891456;772900;84924;94462.31400603577;97125.51437242769;149105.50894491133;160013;1220.3898050974512;1278.7889998150388;1301.4533895763802;1303.4395210059765;1304.9217046977212;19823.60352792965;20092.899535502813;20505.384838454273;20551.691724623844;20563.4859128523;61965;62277;62618.06072908819;63766;64231.67884160549;82388;87041.1295887033;88604.5569772147;90421.64367534623;90521;11490.393024011393;11831;12160.574379896661;12244.510219591117;12266.632001039983;12861.614151575468;13131.409086590935;13178;13253.734925301247;13411.798823017467;10218.795624087328;10774.515146818256;10806.729831753955;10859.674209773719;11105.777884442105;8835.867461988204;10475.528601213116;10532.999365060306;10566.577336906745;10597.311174773615;10882.836757448804;10972.122230221723;11119.166062545502;11219.326840389604;11367.658970230907;11727.179097463437;11757.706057348634;11772.646820595395;11812.940935295268;12057.15599907998;10851.891481085404;11142.721431964266;11198.88801112011;11241.49413276388;11255.043321317453 11:28:29 INFO - PROCESS | 1635 | |3;mootools.html;1548.3961255110517;1559.5321403578923;1582.8575428211461;1585.4847174668228;1595.816481104673;10582.417967011812;11032.724181895437;11040.392778397185;11041.779164416717;11044.392558409285;10698.946505267482;10752.139828813695;11004.944975275132;11025.503852326645;11030.393328366936;2387.9851063298115;2417.5044115956275;2423.6970378702663;2424.9393765155837;2428.8421252618614;1963.4109767069892;2393.090625562284;2396.7363590005084;2397.4485868250313;2398.100712232913;2267.7958983691465;2280.4070941555233;2285.8857057147075;2388.486475407787;2394.7605239476;2186.5845489357052;2191.254973309067;2192.342297310808;2195.1877805212016;2249.5950728868797;2069.9793002069937;2360.244721689043;2367.7632236776185;2369.5616310982523;2372.205311220743;9468.479233642147;10797.136229101732;10875.02124808771;10892;10943.616973405893;5732.340780810218;5905.793297234576;5923.970380148072;5927.762889484416;5951.761929522814;3160.146760374686;3391.3386889556546;3395.8811441599673;3398.303347813692;3422.418188907867;1205.722683782726;1219.5975328141692;1241.1622155045309;1246.4391024039219;1264.8671889451566;13461.192328460225;13795.724085518234;14540;15131.167785771779;15132.546023619308;4673.556012178838;4790.0180463005;4792.305115758196;4795.184818580849;4801.879953001203;10945.01494865466;11501.367424791717;11528.76942461146;11530.192886498033;11546.653600392001;4876.244182151772;5050.696958182521;5262.736863156827;5426.620136590401;5701.287339082614;4652.325412815123;4989.525995030466;5166.095933211748;5177.301064356339;5195.792168313225;3722.8883133506038;3870.0905287257474;3909;3958.524977002778;3958.940615890764;961.927855410847;993.1955116355775;1030.253066526762;1043.9217058720626;1047.6019112737263;864.9783755406165;916.4592890194817;930.5719369090159;942.5240253671852;951.7810903492235;6492.441813964614;6551.148350714472;6558.114654521674;6582.144321238304;6636.634985075771;949.8005418862116;960.3373672166183;971.4656938683696;973.7614284500343;995.9203263738883;3647.398179300386;4014.6751571981176;4081.7346872453195;4227.8097485613225;4239.915201696011;12381.876181238073;13003;13077.607671769862;13090.607281781562;13131.474741010252;12788.552400665933;12853.357332133355;13029.218246905217;13083.345832708326;13293.13594616347;9928;10173.898261017295;10177.33847299938;10179.745506362253;10189.133923616428;5465.672059676433;6457.806265812033;6605.240397354366;6724.899126513008;6836.350546698056;10140.087392134448;11821.172517924007;12073.517059317528;12139.150259481752;12226.938865305616;12189.207701499729;12543.24740515572;12567.811482827949;12596.811047834473;12607.684807879876;7996.920030799618;8283.130271321665;8316.916830831615;9153.816923661623;9221.308401870021;8956.462612243286;9260.351775375659;9350.438973661601;10356.119729823049;10413.47932603382;8485.745427637181;8636.82726345457;8719.433236839584;8724.607392667218;8827.382083254111;8597.914020859711;8632.870506942276;8675.436096653697;8696.347773917108;8710.259627931697;7862.567558784322;7937.8412431752195;7939.166387529457;7939.28546430824;7949.8807517886125;5802.883942321046;5865.882682346244;5880.26496687914;6026.216591843032;6120.418560236771;5739.8852022960145;5832.212651292021;5902.763889444374;6151.630902145886;6190.133381326528 11:28:29 INFO - PROCESS | 1635 | |4;prototype.html;333.08401894789347;376.4503824416352;376.570709391294;379.06938466065805;390.1942488760708;5036.420811606667;5056.367954005749;5100.336956195694;5116.978793376614;5148.073346797575;4580.908381832362;4691.530846915307;5060.27950496831;5100.821471248507;5171.767270472828;3871.9612803871883;4204.2432362174795;4229.942514371415;4313.09425020746;4313.202057619351;3927.430522574225;3968.868872371375;4265.509466411356;4286.721363111404;4323.351497275403;3748.137928276498;3817.7136714746366;4011.7191796574252;4189.8477918572335;4394.857337092363;4049.473568436114;4341.677202696574;4365.847195348163;4387.999999999984;4395.077273613076;3287.589051368579;3293.5224392463197;3582.7850328980085;3915.2006315484564;3952.4664170336923;4819.566239038469;4863.3980820260995;4925.408950925876;4987.102321582114;5003.349564556621;4609.446866376023;4908.239222920417;4934.481879402647;5004.124278251292;5039.84880453587;4887.608991280689;5218.425973142951;5364.436734142898;5462.153366228241;5474.562035037227;3846.288401661262;4372.125574885035;4432.911341773179;4437.644988400953;4445.777711114463;4973.801047958077;5484.698341591211;5615.354234263071;5697.467570106478;5812.970935145339;4264.701470897069;4712;4886.560209581155;4952.752362381939;4982.576480999156;4666;4826.758662066883;4856.324568024026;5010.423801262828;5123.87190320245;4410.426644536254;5106.770195341219;5228.2680424740565;5288.3870419521645;5310.575153987672;5165.741712914414;5499.230107784913;5513.61404701675;5523.392426833045;5556.444355564492;1617.0782653887406;1655.6605895791333;1725.1288099509668;1738.5827401423567;1771.3446024970842;1088.8946098763272;1258.5532136091556;1343.7917122845975;1366.4602482019548;1469.1552357394542;4358.435901923094;4960.975195124073;5171.896562068738;5189.896202076013;5211.322528071327;5041.588355260533;5703.885922281531;5712.971435142797;5727;5750.971245143747;4356.455443069616;5129.230415506644;5160.587153027749;5176.559992400688;5208.348956380452;2621.449495605906;2792.539231026858;2823.164942787205;2832.730890565393;2950.9114726558237;5016.874578135503;5072.898542029138;5395.336759102505;5486.91769623456;5707.972564938351;4800.655816371422;4997.775100120431;5152;5832.766689332464;5929.331800689707;5018.996200759862;5815.9709201453725;5853.7365818538265;5860.970695146497;5882.058870580772;4890.951090489049;5557.944420555743;5649;5672.347680016851;5705.543556515552;4769.208479259067;4991.254809854493;5248.396434409939;5260.44765299638;5268.520738432141;5283.418823929442;5307.071262529119;5603.80386686464;5627.761892383669;5839.591228613956;4026.7919624112064;4076;4505.391772110723;4534.682572219913;4539.364488971612;5393.307822194715;5467.578429608251;5641.323041235078;5727.97136014334;5783.431089439296;5149.639525233198;5226.947730522646;5457.836264912059;5523.779048838162;5553.527950124285;5121.8719532012;5156.097682905402;5281.841544753664;5526.364468086222;5547.944520554743;5046.495350464924;5145.9193569350855;5323.760430780547;5329.893402132013;5400.918986215289;5501.477359650827;5510.889782204414;5516.9448305516435;5529.364123125892;5557.777688892561;4837.83176450029;5026.4973502649445;5062.746862656926;5305.9469405307;5366.785328586969 11:28:29 INFO - PROCESS | 1635 | |5;yui.html;649.909012738217;701.116593092703;728.566502930756;802.6227672993692;848.6775025490315;14884;14995.475158369445;15346.846531534682;15669.138197399152;15869.365225390984;14402.135871847697;14655.633609159777;14679.926600366984;14702.411903523873;16360.590985225375;929.7443203119145;956.9186619137382;961.6826447272418;963.8987906269846;968.5447839515416;941.3269512298705;958.3483231402644;970.3450171134491;978.1294647763497;978.4814048554277;1019.2152042926941;1022.0852337158238;1023.0587859169544;1023.5752162852407;1024.6311327921942;1014.7310962594918;1015.7003683913264;1017.3946501831435;1018.0532105142215;1018.6180182431589;755.1806290175167;755.4598462099625;755.607084316158;757.0650246945019;757.2881491398068;15656.217189140498;15962.760558591513;16011.519654410386;16262.349506019746;16533.256003479753;14064.507742228972;14153;14218.786718199235;14285.57143285703;14370.784438233435;11693.356865372401;11700;11827.645170644895;11914.285142891455;11992.220505667105;3635.600083990759;3637.781733096023;3802.8098595070137;3802.8478860845535;3888.475055867478;94526;111341;112658.43670781676;114251;119936;10889.346639201673;10937.726556836144;11045.83431248532;11055.436569208483;11426.257293276076;22875.19936802237;22923.656145157835;23147.7685223149;23377.649335259986;23451;7790.961045194851;7915.406344524184;7958.124606293303;7998.560079195584;8126.593670316461;4141.8964525886495;4165.10450253191;4195.916081678411;4211.915761684811;4349.956500435019;3498.6851183393615;3597.37046016946;3598.7660802047785;3606.1525541497726;3607.8196090195383;618.4279541424164;638.0524920493064;653.5066025151076;657.2572992518519;662.6620423584006;633.3444884544474;687.9793606191822;701.9227884932653;702.7680865314434;711.590835269722;10336.224783141295;10391.272610917164;10439.686809395731;10465.267431279737;10510.63212787564;695.982600434983;717.3292971072065;727.1855521815601;728.8542291541606;730.2186660273456;4263.955330943939;4782.545658162469;4971.45314015458;4977.875553111129;4978.90042199154;87445.56277218701;106101.93898060921;108717;109223;112865.30702039476;57974.42025579775;59418.10872836911;61831;91193.54403227942;95740;79221.20778792254;79629.60185199152;81311;81575;82981.58509207415;10008.39949603026;10871.619493317696;10920.126389888948;11363.431828408711;11379.658610241706;50536;50939.74530127326;51252;51912.221316679526;52027;66565;66574;67433;68844.6557767208;68937.65531172312;12447.688807779878;13029.543965961146;13031.609051728085;13108.279044652636;13122.540711075066;14723.852761472248;14733.92633036828;15177.24113794328;15284.388624454898;16014.839851601335;13657;13710.314484275947;13900.582982510541;14288.499902503363;14451.277436128361;13737.519186828413;13971.301434928417;14294.928525357307;14302.499412520512;14302.71394572082;11785.823212651989;12318.75362492763;13362.465501380224;13950.441982320594;14028.368723407268;8510.404271700922;9839.655612053544;10213.693589192337;10316.939238390927;10414.323069000793;10366.585336586451;10987.793558432302;11164.051055660035;11427.428628568372;11444.198906076495 11:28:29 INFO - PROCESS | 1635 | __end_tp_report 11:28:29 INFO - PROCESS | 1635 | __start_cc_report 11:28:29 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,1928 11:28:29 INFO - PROCESS | 1635 | __end_cc_report 11:28:29 INFO - PROCESS | 1635 | __startTimestamp1491848909299__endTimestamp 11:28:29 INFO - PROCESS | 1635 | 11:28:29 INFO - PROCESS | 1635 | ------- Summary: start ------- 11:28:29 INFO - PROCESS | 1635 | Number of tests: 6 11:28:29 INFO - PROCESS | 1635 | 11:28:29 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5889.06 Median:6197.66 stddev:654.75 (10.6%) stddev-sans-first:626.86 11:28:29 INFO - PROCESS | 1635 | Values: 3395.3 3400.2 3402.5 3410.1 3447.8 6014.0 6042.6 6044.8 6050.2 6084.4 5977.6 6041.0 6048.2 6048.7 6065.5 5347.1 5364.6 5390.2 5394.4 5502.2 4711.2 5298.9 5313.5 5323.7 5516.5 4472.6 5083.5 5432.0 5433.1 5441.1 5447.1 5447.7 5454.7 5458.9 5461.2 5214.8 5326.3 5380.6 5415.0 5422.6 5834.2 5837.6 5879.4 5967.2 6059.5 6253.3 6313.5 6322.3 6326.5 6330.8 6347.2 6350.5 6352.1 6375.2 6377.3 5767.3 5863.6 5915.6 5932.0 6101.8 5505.7 6311.6 6327.5 6342.2 6407.3 6281.6 6342.9 6346.2 6351.7 6352.9 6046.2 6351.8 6352.0 6357.1 6361.4 6197.7 6206.3 6262.2 6275.4 6310.2 5102.3 6296.7 6301.7 6303.1 6344.3 5127.5 5180.1 5182.3 5191.7 5197.0 4484.3 4503.3 4623.3 4625.2 4625.5 5857.8 5956.4 6011.5 6049.9 6052.7 6294.5 6334.6 6347.5 6349.9 6356.0 5987.1 6001.5 6006.2 6008.6 6030.5 5477.6 5492.1 5566.5 5572.0 5578.3 5534.9 6307.7 6430.5 6519.5 6540.5 6333.7 6336.6 6342.1 6343.5 6348.5 6530.7 6547.1 6551.2 6557.4 6567.7 6205.9 6228.3 6256.0 6262.0 6321.0 6256.7 6317.4 6337.8 6349.7 6357.7 6267.2 6296.9 6322.4 6325.9 6340.9 6311.3 6331.2 6346.0 6357.3 6363.7 5804.5 5980.0 6029.6 6242.7 6252.2 6258.8 6274.0 6343.6 6346.0 6346.1 6212.6 6219.9 6224.9 6230.6 6238.0 11:28:29 INFO - PROCESS | 1635 | 11:28:29 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:55112.46 Median:3300.81 stddev:109630.55 (3321.3%) stddev-sans-first:109862.24 11:28:29 INFO - PROCESS | 1635 | Values: 796.7 820.5 865.2 881.3 883.7 39824.4 40770.4 42485.9 42505.8 42550.7 40750.0 41002.2 41008.2 41063.6 41146.2 7420.7 9274.9 9412.5 9425.8 9458.1 7476.3 7564.3 7566.1 7572.2 10938.3 11183.8 11203.8 11206.3 11225.9 11274.4 9659.6 11143.1 11179.4 11187.9 11189.5 7293.9 7481.7 10976.3 11032.9 11045.9 28018.9 28212.6 28236.0 38708.8 39386.0 3755.0 3863.4 3885.8 3909.6 4086.0 27417.6 27433.9 27465.5 27491.0 28245.0 3047.3 3227.0 3281.8 3299.1 3302.6 352296.0 353615.0 353767.0 354818.0 358412.0 42163.0 42826.6 52065.5 52178.2 52294.0 53799.0 54907.5 54960.5 55026.0 57489.9 14679.2 14711.0 14754.0 14760.6 14761.4 12781.6 12784.4 12789.7 12790.0 12822.0 12080.9 12283.0 12292.9 12354.5 12383.4 5653.2 5727.0 5736.4 5740.3 5746.3 1104.5 1123.7 1125.0 1126.4 1172.4 26094.5 26763.9 27301.6 27320.9 27478.0 1237.9 1245.6 1270.9 1275.2 1284.1 13910.1 14071.5 14191.7 14191.8 14255.0 447425.0 448110.0 448437.5 449432.0 449929.0 56071.0 56101.4 56109.0 56117.0 56197.0 1412.8 1475.1 1476.2 1477.8 1478.4 25116.0 25740.3 26045.3 26560.6 26566.3 47347.0 47443.5 47453.6 47476.8 47548.8 431253.0 431292.0 431427.0 431841.0 432180.0 33551.8 34112.5 34151.8 34155.3 34298.7 31635.8 44387.6 44779.6 46391.1 46749.6 29051.7 29159.3 30386.6 31051.9 31070.5 30721.4 31005.4 31038.8 31380.2 31464.4 34594.1 34723.5 35116.6 35611.3 36840.0 27982.4 28258.3 29308.0 29481.0 29510.6 23285.9 23441.3 23655.3 23706.6 23827.6 11:28:29 INFO - PROCESS | 1635 | 11:28:29 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:42334.20 Median:27256.94 stddev:123649.69 (453.6%) stddev-sans-first:123956.50 11:28:29 INFO - PROCESS | 1635 | Values: 417.3 426.5 427.1 436.2 438.8 18938.6 19506.3 20888.9 20971.5 21010.0 21222.8 22466.8 23155.7 23261.7 23416.9 6756.4 7014.9 7148.1 7158.1 7226.2 5429.4 7148.2 7152.7 7152.7 7162.7 6805.3 7050.2 7127.1 7139.6 7150.2 7088.5 7113.0 7130.1 7175.4 7177.1 6719.1 6995.5 7000.8 7008.2 7014.3 29526.0 30348.4 30463.5 30490.0 30907.7 3005.9 3017.6 3027.9 3030.7 3032.9 15322.9 16964.7 17664.5 18528.6 18565.4 2032.2 2059.1 2090.5 2128.8 2138.6 134248.0 137861.0 140217.0 143539.0 145993.3 22939.3 24691.8 24708.5 24864.0 24987.9 42411.8 42533.4 43812.8 43950.1 44026.7 10036.6 10323.5 10500.7 10523.2 10686.3 8692.7 8693.1 8705.7 8868.9 8896.5 9292.0 9591.9 9649.5 9660.0 9691.0 4022.2 4077.3 4116.3 4118.4 4120.7 884.5 906.2 909.3 942.8 949.8 21064.9 22098.6 22128.1 22134.0 22230.9 916.5 928.9 930.5 942.6 950.3 10806.2 11201.6 11309.8 11467.5 11495.9 715549.0 718920.0 762248.0 765522.2 772900.0 84924.0 94462.3 97125.5 149105.5 160013.0 1220.4 1278.8 1301.5 1303.4 1304.9 19823.6 20092.9 20505.4 20551.7 20563.5 61965.0 62277.0 62618.1 63766.0 64231.7 82388.0 87041.1 88604.6 90421.6 90521.0 11490.4 11831.0 12160.6 12244.5 12266.6 12861.6 13131.4 13178.0 13253.7 13411.8 10218.8 10774.5 10806.7 10859.7 11105.8 8835.9 10475.5 10533.0 10566.6 10597.3 10882.8 10972.1 11119.2 11219.3 11367.7 11727.2 11757.7 11772.6 11812.9 12057.2 10851.9 11142.7 11198.9 11241.5 11255.0 11:28:29 INFO - PROCESS | 1635 | 11:28:29 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:6568.20 Median:3397.09 stddev:4069.60 (119.8%) stddev-sans-first:4063.54 11:28:29 INFO - PROCESS | 1635 | Values: 1548.4 1559.5 1582.9 1585.5 1595.8 10582.4 11032.7 11040.4 11041.8 11044.4 10698.9 10752.1 11004.9 11025.5 11030.4 2388.0 2417.5 2423.7 2424.9 2428.8 1963.4 2393.1 2396.7 2397.4 2398.1 2267.8 2280.4 2285.9 2388.5 2394.8 2186.6 2191.3 2192.3 2195.2 2249.6 2070.0 2360.2 2367.8 2369.6 2372.2 9468.5 10797.1 10875.0 10892.0 10943.6 5732.3 5905.8 5924.0 5927.8 5951.8 3160.1 3391.3 3395.9 3398.3 3422.4 1205.7 1219.6 1241.2 1246.4 1264.9 13461.2 13795.7 14540.0 15131.2 15132.5 4673.6 4790.0 4792.3 4795.2 4801.9 10945.0 11501.4 11528.8 11530.2 11546.7 4876.2 5050.7 5262.7 5426.6 5701.3 4652.3 4989.5 5166.1 5177.3 5195.8 3722.9 3870.1 3909.0 3958.5 3958.9 961.9 993.2 1030.3 1043.9 1047.6 865.0 916.5 930.6 942.5 951.8 6492.4 6551.1 6558.1 6582.1 6636.6 949.8 960.3 971.5 973.8 995.9 3647.4 4014.7 4081.7 4227.8 4239.9 12381.9 13003.0 13077.6 13090.6 13131.5 12788.6 12853.4 13029.2 13083.3 13293.1 9928.0 10173.9 10177.3 10179.7 10189.1 5465.7 6457.8 6605.2 6724.9 6836.4 10140.1 11821.2 12073.5 12139.2 12226.9 12189.2 12543.2 12567.8 12596.8 12607.7 7996.9 8283.1 8316.9 9153.8 9221.3 8956.5 9260.4 9350.4 10356.1 10413.5 8485.7 8636.8 8719.4 8724.6 8827.4 8597.9 8632.9 8675.4 8696.3 8710.3 7862.6 7937.8 7939.2 7939.3 7949.9 5802.9 5865.9 5880.3 6026.2 6120.4 5739.9 5832.2 5902.8 6151.6 6190.1 11:28:29 INFO - PROCESS | 1635 | 11:28:29 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4616.57 Median:5038.13 stddev:1252.19 (24.9%) stddev-sans-first:1213.72 11:28:29 INFO - PROCESS | 1635 | Values: 333.1 376.5 376.6 379.1 390.2 5036.4 5056.4 5100.3 5117.0 5148.1 4580.9 4691.5 5060.3 5100.8 5171.8 3872.0 4204.2 4229.9 4313.1 4313.2 3927.4 3968.9 4265.5 4286.7 4323.4 3748.1 3817.7 4011.7 4189.8 4394.9 4049.5 4341.7 4365.8 4388.0 4395.1 3287.6 3293.5 3582.8 3915.2 3952.5 4819.6 4863.4 4925.4 4987.1 5003.3 4609.4 4908.2 4934.5 5004.1 5039.8 4887.6 5218.4 5364.4 5462.2 5474.6 3846.3 4372.1 4432.9 4437.6 4445.8 4973.8 5484.7 5615.4 5697.5 5813.0 4264.7 4712.0 4886.6 4952.8 4982.6 4666.0 4826.8 4856.3 5010.4 5123.9 4410.4 5106.8 5228.3 5288.4 5310.6 5165.7 5499.2 5513.6 5523.4 5556.4 1617.1 1655.7 1725.1 1738.6 1771.3 1088.9 1258.6 1343.8 1366.5 1469.2 4358.4 4961.0 5171.9 5189.9 5211.3 5041.6 5703.9 5713.0 5727.0 5751.0 4356.5 5129.2 5160.6 5176.6 5208.3 2621.4 2792.5 2823.2 2832.7 2950.9 5016.9 5072.9 5395.3 5486.9 5708.0 4800.7 4997.8 5152.0 5832.8 5929.3 5019.0 5816.0 5853.7 5861.0 5882.1 4891.0 5557.9 5649.0 5672.3 5705.5 4769.2 4991.3 5248.4 5260.4 5268.5 5283.4 5307.1 5603.8 5627.8 5839.6 4026.8 4076.0 4505.4 4534.7 4539.4 5393.3 5467.6 5641.3 5728.0 5783.4 5149.6 5226.9 5457.8 5523.8 5553.5 5121.9 5156.1 5281.8 5526.4 5547.9 5046.5 5145.9 5323.8 5329.9 5400.9 5501.5 5510.9 5516.9 5529.4 5557.8 4837.8 5026.5 5062.7 5305.9 5366.8 11:28:29 INFO - PROCESS | 1635 | 11:28:29 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20519.44 Median:19704.23 stddev:29376.38 (149.1%) stddev-sans-first:29420.90 11:28:29 INFO - PROCESS | 1635 | Values: 649.9 701.1 728.6 802.6 848.7 14884.0 14995.5 15346.8 15669.1 15869.4 14402.1 14655.6 14679.9 14702.4 16360.6 929.7 956.9 961.7 963.9 968.5 941.3 958.3 970.3 978.1 978.5 1019.2 1022.1 1023.1 1023.6 1024.6 1014.7 1015.7 1017.4 1018.1 1018.6 755.2 755.5 755.6 757.1 757.3 15656.2 15962.8 16011.5 16262.3 16533.3 14064.5 14153.0 14218.8 14285.6 14370.8 11693.4 11700.0 11827.6 11914.3 11992.2 3635.6 3637.8 3802.8 3802.8 3888.5 94526.0 111341.0 112658.4 114251.0 119936.0 10889.3 10937.7 11045.8 11055.4 11426.3 22875.2 22923.7 23147.8 23377.6 23451.0 7791.0 7915.4 7958.1 7998.6 8126.6 4141.9 4165.1 4195.9 4211.9 4350.0 3498.7 3597.4 3598.8 3606.2 3607.8 618.4 638.1 653.5 657.3 662.7 633.3 688.0 701.9 702.8 711.6 10336.2 10391.3 10439.7 10465.3 10510.6 696.0 717.3 727.2 728.9 730.2 4264.0 4782.5 4971.5 4977.9 4978.9 87445.6 106101.9 108717.0 109223.0 112865.3 57974.4 59418.1 61831.0 91193.5 95740.0 79221.2 79629.6 81311.0 81575.0 82981.6 10008.4 10871.6 10920.1 11363.4 11379.7 50536.0 50939.7 51252.0 51912.2 52027.0 66565.0 66574.0 67433.0 68844.7 68937.7 12447.7 13029.5 13031.6 13108.3 13122.5 14723.9 14733.9 15177.2 15284.4 16014.8 13657.0 13710.3 13900.6 14288.5 14451.3 13737.5 13971.3 14294.9 14302.5 14302.7 11785.8 12318.8 13362.5 13950.4 14028.4 8510.4 9839.7 10213.7 10316.9 10414.3 10366.6 10987.8 11164.1 11427.4 11444.2 11:28:29 INFO - PROCESS | 1635 | -------- Summary: end -------- 11:28:29 INFO - PROCESS | 1635 | 11:28:29 INFO - TEST-INFO | 1635: exit 0 11:28:29 INFO - TEST-OK | dromaeo_css | took 1120556ms 11:28:29 INFO - TEST-START | kraken 11:28:29 INFO - operating with platform_type : mac_ 11:28:29 INFO - Initialising browser for kraken test... 11:28:29 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/tmpUHFYsJ/profile http://localhost:49237/getInfo.html) 11:28:32 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 11:28:32 INFO - PROCESS | 1645 | colorDepth:24 11:28:32 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 11:28:32 INFO - PROCESS | 1645 | __metrics 11:28:32 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:28:32 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:28:33 INFO - TEST-INFO | 1645: exit 0 11:28:33 INFO - Browser initialized. 11:28:33 INFO - Running cycle 1/1 for kraken test... 11:28:33 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/tmpUHFYsJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:28:45 INFO - PROCESS | 1646 | RSS: Main: 214962176 11:28:45 INFO - PROCESS | 1646 | 11:28:48 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 11:28:48 INFO - PROCESS | 1646 | RSS: Main: 267689984 11:28:48 INFO - PROCESS | 1646 | 11:28:51 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 11:28:52 INFO - PROCESS | 1646 | RSS: Main: 279130112 11:28:52 INFO - PROCESS | 1646 | 11:28:55 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 11:28:55 INFO - PROCESS | 1646 | RSS: Main: 376815616 11:28:55 INFO - PROCESS | 1646 | 11:28:57 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 11:28:58 INFO - PROCESS | 1646 | RSS: Main: 278962176 11:28:58 INFO - PROCESS | 1646 | 11:29:00 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:29:01 INFO - PROCESS | 1646 | RSS: Main: 280297472 11:29:01 INFO - PROCESS | 1646 | 11:29:04 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 11:29:04 INFO - PROCESS | 1646 | RSS: Main: 320929792 11:29:04 INFO - PROCESS | 1646 | 11:29:07 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 11:29:08 INFO - PROCESS | 1646 | RSS: Main: 321810432 11:29:08 INFO - PROCESS | 1646 | 11:29:11 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 11:29:12 INFO - PROCESS | 1646 | RSS: Main: 317456384 11:29:12 INFO - PROCESS | 1646 | 11:29:13 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:29:14 INFO - PROCESS | 1646 | RSS: Main: 295694336 11:29:14 INFO - PROCESS | 1646 | 11:29:16 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:29:16 INFO - PROCESS | 1646 | RSS: Main: 300453888 11:29:16 INFO - PROCESS | 1646 | 11:29:18 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:29:19 INFO - PROCESS | 1646 | RSS: Main: 305557504 11:29:19 INFO - PROCESS | 1646 | 11:29:22 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:29:23 INFO - PROCESS | 1646 | RSS: Main: 319627264 11:29:23 INFO - PROCESS | 1646 | 11:29:26 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:29:26 INFO - PROCESS | 1646 | RSS: Main: 312872960 11:29:26 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 11:29:28 INFO - PROCESS | 1646 | RSS: Main: 357916672 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | __start_tp_report 11:29:28 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 11:29:28 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 11:29:28 INFO - PROCESS | 1646 | |i|pagename|runs| 11:29:28 INFO - PROCESS | 1646 | |0;ai-astar;116;115;115;115;114;116;116;116;116;116 11:29:28 INFO - PROCESS | 1646 | |1;audio-beat-detection;146;109;107;145;109;107;101;108;127;108 11:29:28 INFO - PROCESS | 1646 | |2;audio-dft;187;185;183;186;184;187;184;186;188;185 11:29:28 INFO - PROCESS | 1646 | |3;audio-fft;83;61;54;82;66;78;80;82;94;62 11:29:28 INFO - PROCESS | 1646 | |4;audio-oscillator;89;70;87;69;70;70;82;71;93;106 11:29:28 INFO - PROCESS | 1646 | |5;imaging-gaussian-blur;91;90;89;89;89;89;90;89;89;89 11:29:28 INFO - PROCESS | 1646 | |6;imaging-darkroom;101;144;97;97;96;96;101;97;97;96 11:29:28 INFO - PROCESS | 1646 | |7;imaging-desaturate;86;84;84;84;84;85;87;84;84;96 11:29:28 INFO - PROCESS | 1646 | |8;json-parse-financial;64;61;61;63;64;65;65;66;64;64 11:29:28 INFO - PROCESS | 1646 | |9;json-stringify-tinderbox;54;57;70;64;61;58;58;59;57;59 11:29:28 INFO - PROCESS | 1646 | |10;stanford-crypto-aes;84;84;81;80;85;80;79;97;84;88 11:29:28 INFO - PROCESS | 1646 | |11;stanford-crypto-ccm;136;152;146;136;136;138;139;141;178;150 11:29:28 INFO - PROCESS | 1646 | |12;stanford-crypto-pbkdf2;166;164;157;165;165;165;177;221;182;165 11:29:28 INFO - PROCESS | 1646 | |13;stanford-crypto-sha256-iterative;66;66;65;65;63;65;64;65;64;64 11:29:28 INFO - PROCESS | 1646 | __end_tp_report 11:29:28 INFO - PROCESS | 1646 | __start_cc_report 11:29:28 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,2229 11:29:28 INFO - PROCESS | 1646 | __end_cc_report 11:29:28 INFO - PROCESS | 1646 | __startTimestamp1491848968818__endTimestamp 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | ------- Summary: start ------- 11:29:28 INFO - PROCESS | 1646 | Number of tests: 14 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#0] ai-astar Cycles:10 Average:115.50 Median:116.00 stddev:0.71 (0.6%) stddev-sans-first:0.73 11:29:28 INFO - PROCESS | 1646 | Values: 116.0 115.0 115.0 115.0 114.0 116.0 116.0 116.0 116.0 116.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#1] audio-beat-detection Cycles:10 Average:116.70 Median:109.00 stddev:16.57 (15.2%) stddev-sans-first:13.77 11:29:28 INFO - PROCESS | 1646 | Values: 146.0 109.0 107.0 145.0 109.0 107.0 101.0 108.0 127.0 108.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#2] audio-dft Cycles:10 Average:185.50 Median:186.00 stddev:1.58 (0.9%) stddev-sans-first:1.58 11:29:28 INFO - PROCESS | 1646 | Values: 187.0 185.0 183.0 186.0 184.0 187.0 184.0 186.0 188.0 185.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#3] audio-fft Cycles:10 Average:74.20 Median:81.00 stddev:12.64 (15.6%) stddev-sans-first:13.00 11:29:28 INFO - PROCESS | 1646 | Values: 83.0 61.0 54.0 82.0 66.0 78.0 80.0 82.0 94.0 62.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#4] audio-oscillator Cycles:10 Average:80.70 Median:76.50 stddev:12.81 (16.7%) stddev-sans-first:13.23 11:29:28 INFO - PROCESS | 1646 | Values: 89.0 70.0 87.0 69.0 70.0 70.0 82.0 71.0 93.0 106.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:89.00 stddev:0.70 (0.8%) stddev-sans-first:0.44 11:29:28 INFO - PROCESS | 1646 | Values: 91.0 90.0 89.0 89.0 89.0 89.0 90.0 89.0 89.0 89.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#6] imaging-darkroom Cycles:10 Average:102.20 Median:96.50 stddev:14.81 (15.3%) stddev-sans-first:15.70 11:29:28 INFO - PROCESS | 1646 | Values: 101.0 144.0 97.0 97.0 96.0 96.0 101.0 97.0 97.0 96.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:84.50 stddev:3.74 (4.4%) stddev-sans-first:3.96 11:29:28 INFO - PROCESS | 1646 | Values: 86.0 84.0 84.0 84.0 84.0 85.0 87.0 84.0 84.0 96.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:64.00 stddev:1.64 (2.6%) stddev-sans-first:1.73 11:29:28 INFO - PROCESS | 1646 | Values: 64.0 61.0 61.0 63.0 64.0 65.0 65.0 66.0 64.0 64.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:59.00 stddev:4.47 (7.6%) stddev-sans-first:4.24 11:29:28 INFO - PROCESS | 1646 | Values: 54.0 57.0 70.0 64.0 61.0 58.0 58.0 59.0 57.0 59.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.00 stddev:5.29 (6.3%) stddev-sans-first:5.61 11:29:28 INFO - PROCESS | 1646 | Values: 84.0 84.0 81.0 80.0 85.0 80.0 79.0 97.0 84.0 88.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:143.50 stddev:12.94 (9.0%) stddev-sans-first:13.29 11:29:28 INFO - PROCESS | 1646 | Values: 136.0 152.0 146.0 136.0 136.0 138.0 139.0 141.0 178.0 150.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:165.50 stddev:18.39 (11.1%) stddev-sans-first:19.34 11:29:28 INFO - PROCESS | 1646 | Values: 166.0 164.0 157.0 165.0 165.0 165.0 177.0 221.0 182.0 165.0 11:29:28 INFO - PROCESS | 1646 | 11:29:28 INFO - PROCESS | 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:0.95 (1.5%) stddev-sans-first:0.88 11:29:28 INFO - PROCESS | 1646 | Values: 66.0 66.0 65.0 65.0 63.0 65.0 64.0 65.0 64.0 64.0 11:29:28 INFO - PROCESS | 1646 | -------- Summary: end -------- 11:29:28 INFO - PROCESS | 1646 | 11:29:29 INFO - TEST-INFO | 1646: exit 0 11:29:29 INFO - TEST-OK | kraken | took 59763ms 11:29:29 INFO - SUITE-END | took 1180s 11:29:29 INFO - Completed test suite (00:19:40) 11:29:29 INFO - javascript benchmark 11:29:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7731.451621693602, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3395.270016946356, 3400.2179498715295, 3402.4726167444055, 3410.13041674373, 3447.758656894017, 6014.037753959367, 6042.637441753498, 6044.848878778027, 6050.213472248602, 6084.361142080084, 5977.551683623735, 6041.003234466308, 6048.153258543807, 6048.727807248673, 6065.48443382312, 5347.064263753838, 5364.5708343332635, 5390.21841832934, 5394.433584473632, 5502.174673798942, 4711.246200607903, 5298.867528311785, 5313.548348390372, 5323.733813309338, 5516.531094856942, 4472.575105364985, 5083.517065878754, 5432.0, 5433.103537916259, 5441.129419292914, 5447.12845944647, 5447.700376479292, 5454.7272636368225, 5458.9454105459035, 5461.18082287656, 5214.765335559909, 5326.334208223972, 5380.569554435636, 5415.0, 5422.6475279106735, 5834.183214349994, 5837.562182836262, 5879.412058794129, 5967.194428752106, 6059.515238780887, 6253.3433989430905, 6313.526485513559, 6322.304546499881, 6326.5255105867245, 6330.841728956767, 6347.238331400215, 6350.460210882081, 6352.07894855248, 6375.203099612549, 6377.298497165308, 5767.336756273083, 5863.618864773776, 5915.645061296336, 5932.021216499315, 6101.786437474667, 5505.669659820343, 6311.558190926591, 6327.462165715873, 6342.175517182736, 6407.327230640809, 6281.59169653971, 6342.873142537123, 6346.174997250328, 6351.714172862232, 6352.888244557184, 6046.244219472566, 6351.841203969938, 6352.015437607179, 6357.141785858942, 6361.363863613602, 6197.659128747872, 6206.286277078194, 6262.2172228471445, 6275.435210830956, 6310.242770867525, 5102.336696229467, 6296.716647750863, 6301.7164227609865, 6303.086052522359, 6344.270408903035, 5127.538521533005, 5180.145276029489, 5182.300389447451, 5191.662541934762, 5197.0, 4484.349769283435, 4503.279475283939, 4623.329617205486, 4625.190591646448, 4625.514320996314, 5857.794977175864, 5956.374580668968, 6011.458968692838, 6049.879002419927, 6052.727627256784, 6294.464970477469, 6334.5565810393755, 6347.5239357047485, 6349.936500635027, 6356.014817703263, 5987.072003839412, 6001.489873360813, 6006.219191505076, 6008.609440386361, 6030.48740857032, 5477.616566840363, 5492.148716948879, 5566.526845218121, 5572.024895643246, 5578.330600327987, 5534.944650553604, 6307.653079080603, 6430.517711171775, 6519.543631945908, 6540.50946179029, 6333.746650133943, 6336.556441049082, 6342.112104305309, 6343.524235682435, 6348.523860710373, 6530.7387704493185, 6547.1161393211305, 6551.181102362205, 6557.377049180321, 6567.704453299518, 6205.875882482234, 6228.34602366745, 6255.968720156552, 6262.0, 6321.0, 6256.749730010931, 6317.431431171217, 6337.7781777637565, 6349.714262858275, 6357.650329231752, 6267.2165979252595, 6296.905546416716, 6322.430981211529, 6325.936740632535, 6340.904886426615, 6311.274203466476, 6331.2085989251345, 6346.0, 6357.3006969234275, 6363.745450182126, 5804.477597016289, 5980.0431930890845, 6029.638221706711, 6242.719077641426, 6252.218472690914, 6258.843528911813, 6274.027525733519, 6343.619382837044, 6346.048092786119, 6346.111544383883, 6212.565120441525, 6219.875602488016, 6224.875502490016, 6230.626162430269, 6237.970734828702], "value": 5847.657885681535, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.7171654062809, 820.4707963363633, 865.238590040764, 881.2509367038023, 883.6509578716407, 39824.402633960475, 40770.388444173324, 42485.93785155369, 42505.7874710626, 42550.72347829562, 40750.0, 41002.17995640093, 41008.17983640325, 41063.58936410635, 41146.17707645845, 7420.666070026835, 9274.907250927472, 9412.52937353133, 9425.811483770354, 9458.101480359355, 7476.327130558248, 7564.319211270985, 7566.092068951733, 7572.167061623217, 10938.343699378023, 11183.776324473505, 11203.775924481546, 11206.271592346471, 11225.887741122608, 11274.379909104997, 9659.565319560566, 11143.108551315916, 11179.385133817637, 11187.944060279728, 11189.49647265871, 7293.8905916411295, 7481.738139165103, 10976.341419514854, 11032.889671103267, 11045.944770276177, 28018.85990570034, 28212.576811347848, 28236.0, 38708.806455967824, 39386.0153496162, 3755.0, 3863.420486926955, 3885.7668539887695, 3909.569947305794, 4085.9795701021303, 27417.588736168975, 27433.90264389442, 27465.45069098627, 27491.0, 28245.0, 3047.344820863525, 3227.01576019314, 3281.835908204604, 3299.059767966118, 3302.554155189067, 352296.0, 353615.0, 353767.0, 354818.0, 358411.9999999974, 42162.99999999939, 42826.57173428288, 52065.47934520606, 52178.21732674013, 52294.0, 53799.0, 54907.45092549103, 54960.450395495536, 55026.0, 57489.85020299571, 14679.19264440466, 14710.970232083651, 14754.0, 14760.630984225265, 14761.409543618134, 12781.61655150347, 12784.360781960864, 12789.680257993625, 12789.97680185583, 12822.0, 12080.939595301967, 12283.0, 12292.938535307445, 12354.505819767288, 12383.442745076492, 5653.152027195872, 5726.9713651431475, 5736.393809733281, 5740.253767010262, 5746.2529871116485, 1104.4588151805717, 1123.690984979121, 1124.983125253122, 1126.414264582429, 1172.372780562395, 26094.478110437685, 26763.86618066936, 27301.590476142876, 27320.863395682893, 27478.0, 1237.900967922564, 1245.6138597034949, 1270.8729127087217, 1275.2464668549649, 1284.1203775413871, 13910.07888343439, 14071.507497237548, 14191.71616567663, 14191.787123192953, 14255.0, 447425.0, 448110.0, 448437.5156248461, 449432.0, 449929.0, 56071.0, 56101.438985609624, 56109.0, 56117.0, 56197.0, 1412.7880817877194, 1475.0780762023521, 1476.180719700555, 1477.7561702318997, 1478.379080786051, 25116.0, 25740.32687875282, 26045.348866277738, 26560.601590976537, 26566.335841604116, 47347.0, 47443.52556474391, 47453.57639270827, 47476.7626161867, 47548.7622561885, 431253.0, 431292.0, 431427.0, 431841.0, 432180.0, 33551.83224083864, 34112.48831267583, 34151.82924085463, 34155.31689366149, 34298.65701342955, 31635.84182079167, 44387.55612443964, 44779.552204477535, 46391.134922087906, 46749.597512074695, 29051.709482905746, 29159.271018224714, 30386.578049933683, 31051.91318303939, 31070.533941990438, 30721.38557228798, 31005.449727513984, 31038.844805775825, 31380.215494612818, 31464.37071258516, 34594.135146621535, 34723.4791478123, 35116.64883351236, 35611.28777424489, 36840.0, 27982.44035119327, 28258.2935426616, 29307.974220902168, 29481.0, 29510.55734163946, 23285.88357058204, 23441.296761097194, 23655.344125911022, 23706.629337066155, 23827.64258536158], "value": 18713.201284527233, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.27810887165225, 426.4739707122594, 427.1435771278587, 436.16474451425523, 438.815697407089, 18938.62122757544, 19506.31727889524, 20888.851113188768, 20971.47571310718, 21010.0, 21222.787772122312, 22466.775332246674, 23155.652665210037, 23261.651075233884, 23416.882915585396, 6756.425703815169, 7014.859702805941, 7148.070750802389, 7158.141023077238, 7226.241244669287, 5429.4027656957705, 7148.249433809453, 7152.678129484186, 7152.749653762119, 7162.713491460361, 6805.319468053204, 7050.224475307712, 7127.073480447534, 7139.60732159731, 7150.213476517579, 7088.468364872603, 7113.0, 7130.144382674061, 7175.3900918422, 7177.138743350805, 6719.092922455455, 6995.475339349518, 7000.789976300719, 7008.19405768338, 7014.298570142996, 29526.0, 30348.393032139233, 30463.543046854316, 30490.0, 30907.69092309093, 3005.879764809405, 3017.637883453978, 3027.9091627250996, 3030.712082352173, 3032.8786848526033, 15322.923385383114, 16964.66070678592, 17664.467106578737, 18528.62942741151, 18565.35021274249, 2032.2379107834563, 2059.104289634014, 2090.4669309326177, 2128.7871212878745, 2138.6364318065966, 134247.98628020586, 137861.0, 140217.0, 143539.0, 145993.27003364914, 22939.311820645406, 24691.753082469306, 24708.505829883303, 24864.0, 24987.87554560049, 42411.78794106071, 42533.36199957003, 43812.780936095114, 43950.12099757987, 44026.679199624064, 10036.598536058476, 10323.48382580868, 10500.737481563023, 10523.21075919309, 10686.305390149615, 8692.739217823475, 8693.087225841322, 8705.651773928972, 8868.8669669955, 8896.510691911877, 9291.953540232256, 9591.856122158173, 9649.469279189572, 9659.985701501382, 9691.030896910393, 4022.2156679447226, 4077.3068578341754, 4116.303820997252, 4118.382242663624, 4120.681381957805, 884.5046773806689, 906.2387594420718, 909.2544113826599, 942.7548837302213, 949.838527450335, 21064.89467552683, 22098.558028839332, 22128.12334396518, 22134.0, 22230.888455577155, 916.4821875640242, 928.9442633442015, 930.4556834251903, 942.5664194470484, 950.334765664038, 10806.189535784848, 11201.551937922555, 11309.830352544719, 11467.54129834814, 11495.873263446289, 715549.0, 718920.0, 762248.0, 765522.1723891456, 772900.0, 84924.0, 94462.31400603577, 97125.51437242769, 149105.50894491133, 160013.0, 1220.3898050974512, 1278.7889998150388, 1301.4533895763802, 1303.4395210059765, 1304.9217046977212, 19823.60352792965, 20092.899535502813, 20505.384838454273, 20551.691724623844, 20563.4859128523, 61965.0, 62277.0, 62618.06072908819, 63766.0, 64231.67884160549, 82388.0, 87041.1295887033, 88604.5569772147, 90421.64367534623, 90521.0, 11490.393024011393, 11831.0, 12160.574379896661, 12244.510219591117, 12266.632001039983, 12861.614151575468, 13131.409086590935, 13178.0, 13253.734925301247, 13411.798823017467, 10218.795624087328, 10774.515146818256, 10806.729831753955, 10859.674209773719, 11105.777884442105, 8835.867461988204, 10475.528601213116, 10532.999365060306, 10566.577336906745, 10597.311174773615, 10882.836757448804, 10972.122230221723, 11119.166062545502, 11219.326840389604, 11367.658970230907, 11727.179097463437, 11757.706057348634, 11772.646820595395, 11812.940935295268, 12057.15599907998, 10851.891481085404, 11142.721431964266, 11198.88801112011, 11241.49413276388, 11255.043321317453], "value": 11811.635192205033, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1548.3961255110517, 1559.5321403578923, 1582.8575428211461, 1585.4847174668228, 1595.816481104673, 10582.417967011812, 11032.724181895437, 11040.392778397185, 11041.779164416717, 11044.392558409285, 10698.946505267482, 10752.139828813695, 11004.944975275132, 11025.503852326645, 11030.393328366936, 2387.9851063298115, 2417.5044115956275, 2423.6970378702663, 2424.9393765155837, 2428.8421252618614, 1963.4109767069892, 2393.090625562284, 2396.7363590005084, 2397.4485868250313, 2398.100712232913, 2267.7958983691465, 2280.4070941555233, 2285.8857057147075, 2388.486475407787, 2394.7605239476, 2186.5845489357052, 2191.254973309067, 2192.342297310808, 2195.1877805212016, 2249.5950728868797, 2069.9793002069937, 2360.244721689043, 2367.7632236776185, 2369.5616310982523, 2372.205311220743, 9468.479233642147, 10797.136229101732, 10875.02124808771, 10892.0, 10943.616973405893, 5732.340780810218, 5905.793297234576, 5923.970380148072, 5927.762889484416, 5951.761929522814, 3160.146760374686, 3391.3386889556546, 3395.8811441599673, 3398.303347813692, 3422.418188907867, 1205.722683782726, 1219.5975328141692, 1241.1622155045309, 1246.4391024039219, 1264.8671889451566, 13461.192328460225, 13795.724085518234, 14540.0, 15131.167785771779, 15132.546023619308, 4673.556012178838, 4790.0180463005, 4792.305115758196, 4795.184818580849, 4801.879953001203, 10945.01494865466, 11501.367424791717, 11528.76942461146, 11530.192886498033, 11546.653600392001, 4876.244182151772, 5050.696958182521, 5262.736863156827, 5426.620136590401, 5701.287339082614, 4652.325412815123, 4989.525995030466, 5166.095933211748, 5177.301064356339, 5195.792168313225, 3722.8883133506038, 3870.0905287257474, 3909.0, 3958.524977002778, 3958.940615890764, 961.927855410847, 993.1955116355775, 1030.253066526762, 1043.9217058720626, 1047.6019112737263, 864.9783755406165, 916.4592890194817, 930.5719369090159, 942.5240253671852, 951.7810903492235, 6492.441813964614, 6551.148350714472, 6558.114654521674, 6582.144321238304, 6636.634985075771, 949.8005418862116, 960.3373672166183, 971.4656938683696, 973.7614284500343, 995.9203263738883, 3647.398179300386, 4014.6751571981176, 4081.7346872453195, 4227.8097485613225, 4239.915201696011, 12381.876181238073, 13003.0, 13077.607671769862, 13090.607281781562, 13131.474741010252, 12788.552400665933, 12853.357332133355, 13029.218246905217, 13083.345832708326, 13293.13594616347, 9928.0, 10173.898261017295, 10177.33847299938, 10179.745506362253, 10189.133923616428, 5465.672059676433, 6457.806265812033, 6605.240397354366, 6724.899126513008, 6836.350546698056, 10140.087392134448, 11821.172517924007, 12073.517059317528, 12139.150259481752, 12226.938865305616, 12189.207701499729, 12543.24740515572, 12567.811482827949, 12596.811047834473, 12607.684807879876, 7996.920030799618, 8283.130271321665, 8316.916830831615, 9153.816923661623, 9221.308401870021, 8956.462612243286, 9260.351775375659, 9350.438973661601, 10356.119729823049, 10413.47932603382, 8485.745427637181, 8636.82726345457, 8719.433236839584, 8724.607392667218, 8827.382083254111, 8597.914020859711, 8632.870506942276, 8675.436096653697, 8696.347773917108, 8710.259627931697, 7862.567558784322, 7937.8412431752195, 7939.166387529457, 7939.28546430824, 7949.8807517886125, 5802.883942321046, 5865.882682346244, 5880.26496687914, 6026.216591843032, 6120.418560236771, 5739.8852022960145, 5832.212651292021, 5902.763889444374, 6151.630902145886, 6190.133381326528], "value": 5025.775563127463, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.08401894789347, 376.4503824416352, 376.570709391294, 379.06938466065805, 390.1942488760708, 5036.420811606667, 5056.367954005749, 5100.336956195694, 5116.978793376614, 5148.073346797575, 4580.908381832362, 4691.530846915307, 5060.27950496831, 5100.821471248507, 5171.767270472828, 3871.9612803871883, 4204.2432362174795, 4229.942514371415, 4313.09425020746, 4313.202057619351, 3927.430522574225, 3968.868872371375, 4265.509466411356, 4286.721363111404, 4323.351497275403, 3748.137928276498, 3817.7136714746366, 4011.7191796574252, 4189.8477918572335, 4394.857337092363, 4049.473568436114, 4341.677202696574, 4365.847195348163, 4387.999999999984, 4395.077273613076, 3287.589051368579, 3293.5224392463197, 3582.7850328980085, 3915.2006315484564, 3952.4664170336923, 4819.566239038469, 4863.3980820260995, 4925.408950925876, 4987.102321582114, 5003.349564556621, 4609.446866376023, 4908.239222920417, 4934.481879402647, 5004.124278251292, 5039.84880453587, 4887.608991280689, 5218.425973142951, 5364.436734142898, 5462.153366228241, 5474.562035037227, 3846.288401661262, 4372.125574885035, 4432.911341773179, 4437.644988400953, 4445.777711114463, 4973.801047958077, 5484.698341591211, 5615.354234263071, 5697.467570106478, 5812.970935145339, 4264.701470897069, 4712.0, 4886.560209581155, 4952.752362381939, 4982.576480999156, 4666.0, 4826.758662066883, 4856.324568024026, 5010.423801262828, 5123.87190320245, 4410.426644536254, 5106.770195341219, 5228.2680424740565, 5288.3870419521645, 5310.575153987672, 5165.741712914414, 5499.230107784913, 5513.61404701675, 5523.392426833045, 5556.444355564492, 1617.0782653887406, 1655.6605895791333, 1725.1288099509668, 1738.5827401423567, 1771.3446024970842, 1088.8946098763272, 1258.5532136091556, 1343.7917122845975, 1366.4602482019548, 1469.1552357394542, 4358.435901923094, 4960.975195124073, 5171.896562068738, 5189.896202076013, 5211.322528071327, 5041.588355260533, 5703.885922281531, 5712.971435142797, 5727.0, 5750.971245143747, 4356.455443069616, 5129.230415506644, 5160.587153027749, 5176.559992400688, 5208.348956380452, 2621.449495605906, 2792.539231026858, 2823.164942787205, 2832.730890565393, 2950.9114726558237, 5016.874578135503, 5072.898542029138, 5395.336759102505, 5486.91769623456, 5707.972564938351, 4800.655816371422, 4997.775100120431, 5152.0, 5832.766689332464, 5929.331800689707, 5018.996200759862, 5815.9709201453725, 5853.7365818538265, 5860.970695146497, 5882.058870580772, 4890.951090489049, 5557.944420555743, 5649.0, 5672.347680016851, 5705.543556515552, 4769.208479259067, 4991.254809854493, 5248.396434409939, 5260.44765299638, 5268.520738432141, 5283.418823929442, 5307.071262529119, 5603.80386686464, 5627.761892383669, 5839.591228613956, 4026.7919624112064, 4076.0, 4505.391772110723, 4534.682572219913, 4539.364488971612, 5393.307822194715, 5467.578429608251, 5641.323041235078, 5727.97136014334, 5783.431089439296, 5149.639525233198, 5226.947730522646, 5457.836264912059, 5523.779048838162, 5553.527950124285, 5121.8719532012, 5156.097682905402, 5281.841544753664, 5526.364468086222, 5547.944520554743, 5046.495350464924, 5145.9193569350855, 5323.760430780547, 5329.893402132013, 5400.918986215289, 5501.477359650827, 5510.889782204414, 5516.9448305516435, 5529.364123125892, 5557.777688892561, 4837.83176450029, 5026.4973502649445, 5062.746862656926, 5305.9469405307, 5366.785328586969], "value": 4257.602860959358, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.909012738217, 701.116593092703, 728.566502930756, 802.6227672993692, 848.6775025490315, 14884.0, 14995.475158369445, 15346.846531534682, 15669.138197399152, 15869.365225390984, 14402.135871847697, 14655.633609159777, 14679.926600366984, 14702.411903523873, 16360.590985225375, 929.7443203119145, 956.9186619137382, 961.6826447272418, 963.8987906269846, 968.5447839515416, 941.3269512298705, 958.3483231402644, 970.3450171134491, 978.1294647763497, 978.4814048554277, 1019.2152042926941, 1022.0852337158238, 1023.0587859169544, 1023.5752162852407, 1024.6311327921942, 1014.7310962594918, 1015.7003683913264, 1017.3946501831435, 1018.0532105142215, 1018.6180182431589, 755.1806290175167, 755.4598462099625, 755.607084316158, 757.0650246945019, 757.2881491398068, 15656.217189140498, 15962.760558591513, 16011.519654410386, 16262.349506019746, 16533.256003479753, 14064.507742228972, 14153.0, 14218.786718199235, 14285.57143285703, 14370.784438233435, 11693.356865372401, 11700.0, 11827.645170644895, 11914.285142891455, 11992.220505667105, 3635.600083990759, 3637.781733096023, 3802.8098595070137, 3802.8478860845535, 3888.475055867478, 94526.0, 111341.0, 112658.43670781676, 114251.0, 119936.0, 10889.346639201673, 10937.726556836144, 11045.83431248532, 11055.436569208483, 11426.257293276076, 22875.19936802237, 22923.656145157835, 23147.7685223149, 23377.649335259986, 23451.0, 7790.961045194851, 7915.406344524184, 7958.124606293303, 7998.560079195584, 8126.593670316461, 4141.8964525886495, 4165.10450253191, 4195.916081678411, 4211.915761684811, 4349.956500435019, 3498.6851183393615, 3597.37046016946, 3598.7660802047785, 3606.1525541497726, 3607.8196090195383, 618.4279541424164, 638.0524920493064, 653.5066025151076, 657.2572992518519, 662.6620423584006, 633.3444884544474, 687.9793606191822, 701.9227884932653, 702.7680865314434, 711.590835269722, 10336.224783141295, 10391.272610917164, 10439.686809395731, 10465.267431279737, 10510.63212787564, 695.982600434983, 717.3292971072065, 727.1855521815601, 728.8542291541606, 730.2186660273456, 4263.955330943939, 4782.545658162469, 4971.45314015458, 4977.875553111129, 4978.90042199154, 87445.56277218701, 106101.93898060921, 108717.0, 109223.0, 112865.30702039476, 57974.42025579775, 59418.10872836911, 61831.0, 91193.54403227942, 95740.0, 79221.20778792254, 79629.60185199152, 81311.0, 81575.0, 82981.58509207415, 10008.39949603026, 10871.619493317696, 10920.126389888948, 11363.431828408711, 11379.658610241706, 50536.0, 50939.74530127326, 51252.0, 51912.221316679526, 52027.0, 66565.0, 66574.0, 67433.0, 68844.6557767208, 68937.65531172312, 12447.688807779878, 13029.543965961146, 13031.609051728085, 13108.279044652636, 13122.540711075066, 14723.852761472248, 14733.92633036828, 15177.24113794328, 15284.388624454898, 16014.839851601335, 13657.0, 13710.314484275947, 13900.582982510541, 14288.499902503363, 14451.277436128361, 13737.519186828413, 13971.301434928417, 14294.928525357307, 14302.499412520512, 14302.71394572082, 11785.823212651989, 12318.75362492763, 13362.465501380224, 13950.441982320594, 14028.368723407268, 8510.404271700922, 9839.655612053544, 10213.693589192337, 10316.939238390927, 10414.323069000793, 10366.585336586451, 10987.793558432302, 11164.051055660035, 11427.428628568372, 11444.198906076495], "value": 7721.756219032461, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1440.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 115.0, 115.0, 114.0, 116.0, 116.0, 116.0, 116.0, 116.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 107.0, 145.0, 109.0, 107.0, 101.0, 108.0, 127.0, 108.0], "value": 116.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 183.0, 186.0, 184.0, 187.0, 184.0, 186.0, 188.0, 185.0], "value": 185.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 54.0, 82.0, 66.0, 78.0, 80.0, 82.0, 94.0, 62.0], "value": 74.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 87.0, 69.0, 70.0, 70.0, 82.0, 71.0, 93.0, 106.0], "value": 80.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 89.0, 89.0, 89.0, 89.0, 90.0, 89.0, 89.0, 89.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 144.0, 97.0, 97.0, 96.0, 96.0, 101.0, 97.0, 97.0, 96.0], "value": 102.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 84.0, 84.0, 85.0, 87.0, 84.0, 84.0, 96.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 61.0, 61.0, 63.0, 64.0, 65.0, 65.0, 66.0, 64.0, 64.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 70.0, 64.0, 61.0, 58.0, 58.0, 59.0, 57.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 81.0, 80.0, 85.0, 80.0, 79.0, 97.0, 84.0, 88.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 152.0, 146.0, 136.0, 136.0, 138.0, 139.0, 141.0, 178.0, 150.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 157.0, 165.0, 165.0, 165.0, 177.0, 221.0, 182.0, 165.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 63.0, 65.0, 64.0, 65.0, 64.0, 64.0], "value": 64.7, "unit": "score"}]}]} 11:29:30 INFO - Return code: 0 11:29:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:29:30 ERROR - # TBPL SUCCESS # 11:29:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:29:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:29:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:29:30 INFO - 2017-04-10 11:29:30.139 screenresolution[1650:9691] starting screenresolution argv=screenresolution get 11:29:30 INFO - 2017-04-10 11:29:30.142 screenresolution[1650:9691] Display 0: 1600x1200x32@60 11:29:30 INFO - 2017-04-10 11:29:30.148 screenresolution[1651:9694] starting screenresolution argv=screenresolution list 11:29:30 INFO - Available Modes on Display 0 11:29:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:29:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:29:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:29:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:29:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:29:30 INFO - Intel Iris: 11:29:30 INFO - Chipset Model: Intel Iris 11:29:30 INFO - Type: GPU 11:29:30 INFO - Bus: Built-In 11:29:30 INFO - VRAM (Dynamic, Max): 1536 MB 11:29:30 INFO - Vendor: Intel (0x8086) 11:29:30 INFO - Device ID: 0x0a2e 11:29:30 INFO - Revision ID: 0x0009 11:29:30 INFO - Displays: 11:29:30 INFO - Display: 11:29:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:29:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:29:30 INFO - Display Serial Number: 1600x1200 60 11:29:30 INFO - Main Display: Yes 11:29:30 INFO - Mirror: Off 11:29:30 INFO - Online: Yes 11:29:30 INFO - Rotation: Supported 11:29:30 INFO - Return code: 0 11:29:30 INFO - Running post-action listener: _resource_record_post_action 11:29:30 INFO - [mozharness: 2017-04-10 18:29:30.394007Z] Finished run-tests step (success) 11:29:30 INFO - Running post-run listener: _resource_record_post_run 11:29:30 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 36934656; Write bytes: 506336256; Read time: 381; Write time: 1605 11:29:30 INFO - TinderboxPrint: CPU usage
53.9% 11:29:30 INFO - TinderboxPrint: I/O read bytes / time
36,934,656 / 381 11:29:30 INFO - TinderboxPrint: I/O write bytes / time
506,336,256 / 1,605 11:29:30 INFO - TinderboxPrint: CPU idle
2,213.5 (46.1%) 11:29:30 INFO - TinderboxPrint: CPU system
952.8 (19.8%) 11:29:30 INFO - TinderboxPrint: CPU user
1,639.4 (34.1%) 11:29:30 INFO - TinderboxPrint: Swap in / out
245,125,120 / 0 11:29:30 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200700416; Write bytes: 200594944; Read time: 15660; Write time: 421 11:29:30 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 35980288; Write bytes: 295820800; Read time: 366; Write time: 1151 11:29:30 INFO - Running post-run listener: _upload_blobber_files 11:29:30 INFO - Blob upload gear active. 11:29:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:29:30 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 11:29:30 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'] 11:29:30 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 11:29:31 INFO - (blobuploader) - INFO - Open directory for files ... 11:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:29:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:32 INFO - (blobuploader) - INFO - Done attempting. 11:29:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:29:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:29:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:33 INFO - (blobuploader) - INFO - Done attempting. 11:29:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:29:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:29:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:34 INFO - (blobuploader) - INFO - Done attempting. 11:29:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:29:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:29:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:29:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:29:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:29:36 INFO - (blobuploader) - INFO - Done attempting. 11:29:36 INFO - (blobuploader) - INFO - Iteration through files over. 11:29:36 INFO - Return code: 0 11:29:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:29:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:29:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c5641e519334a8e9158a7b1a1a6f1a6f541efa4e4ef5e365821d7897594dfbee8221fbe3f654b1b8374e13cae4cc911b986a07998fcd60d8ea29cc8add02b96", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59ed61624e3a31a3cf17a7490b15a4fc0e7e1019679bd3d6af1cca3ba67fafa3859945eb80b58000d6011d963ae56809a77157ad79458e1f58f0dbcedb8d28b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b6ab7276f70b52f26d04de8688996541fd06e6ab9dd9121ea4ed04217146bbb9455e5944778057aa83172326511df7a135a11085bace63b871f5308e6afdaee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d66a9c8d045790f9e83246cb5cca7de05827e511d1398a2a8610edf468c7161a6a4d54aa3d76e552b14bb3bc3485ee99b6bb4cdd23554fbd18f3e545d8b20f1c"} 11:29:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:29:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:29:36 INFO - Contents: 11:29:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c5641e519334a8e9158a7b1a1a6f1a6f541efa4e4ef5e365821d7897594dfbee8221fbe3f654b1b8374e13cae4cc911b986a07998fcd60d8ea29cc8add02b96", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59ed61624e3a31a3cf17a7490b15a4fc0e7e1019679bd3d6af1cca3ba67fafa3859945eb80b58000d6011d963ae56809a77157ad79458e1f58f0dbcedb8d28b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b6ab7276f70b52f26d04de8688996541fd06e6ab9dd9121ea4ed04217146bbb9455e5944778057aa83172326511df7a135a11085bace63b871f5308e6afdaee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d66a9c8d045790f9e83246cb5cca7de05827e511d1398a2a8610edf468c7161a6a4d54aa3d76e552b14bb3bc3485ee99b6bb4cdd23554fbd18f3e545d8b20f1c"} 11:29:36 INFO - Running post-run listener: copy_logs_to_upload_dir 11:29:36 INFO - Copying logs to upload dir... 11:29:36 INFO - mkdir: /builds/slave/test/build/upload/logs 11:29:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.949249 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-10 11:29:36.866333) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 11:29:36.870265) ========= 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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c5641e519334a8e9158a7b1a1a6f1a6f541efa4e4ef5e365821d7897594dfbee8221fbe3f654b1b8374e13cae4cc911b986a07998fcd60d8ea29cc8add02b96", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59ed61624e3a31a3cf17a7490b15a4fc0e7e1019679bd3d6af1cca3ba67fafa3859945eb80b58000d6011d963ae56809a77157ad79458e1f58f0dbcedb8d28b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b6ab7276f70b52f26d04de8688996541fd06e6ab9dd9121ea4ed04217146bbb9455e5944778057aa83172326511df7a135a11085bace63b871f5308e6afdaee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d66a9c8d045790f9e83246cb5cca7de05827e511d1398a2a8610edf468c7161a6a4d54aa3d76e552b14bb3bc3485ee99b6bb4cdd23554fbd18f3e545d8b20f1c"} build_url:https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011138 build_url: 'https://queue.taskcluster.net/v1/task/G-M6lOp5SKWGhmfFuoBPwQ/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c5641e519334a8e9158a7b1a1a6f1a6f541efa4e4ef5e365821d7897594dfbee8221fbe3f654b1b8374e13cae4cc911b986a07998fcd60d8ea29cc8add02b96", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59ed61624e3a31a3cf17a7490b15a4fc0e7e1019679bd3d6af1cca3ba67fafa3859945eb80b58000d6011d963ae56809a77157ad79458e1f58f0dbcedb8d28b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3b6ab7276f70b52f26d04de8688996541fd06e6ab9dd9121ea4ed04217146bbb9455e5944778057aa83172326511df7a135a11085bace63b871f5308e6afdaee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d66a9c8d045790f9e83246cb5cca7de05827e511d1398a2a8610edf468c7161a6a4d54aa3d76e552b14bb3bc3485ee99b6bb4cdd23554fbd18f3e545d8b20f1c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 11:29:36.911869) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 11:29:36.912558) ========= 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.uS3fpznO4o/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.FDDwA1lWPQ/Listeners TMPDIR=/var/folders/mj/3qp78rx17nbd0k3gntr9djpr00000w/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.005165 ========= master_lag: 1.56 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 11:29:38.477140) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 11:29:38.477520) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 11:29:38.482137) ========= ========= Total master_lag: 2.01 =========