builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0256 starttime: 1485503059.42 results: success (0) buildid: 20170126230720 builduid: 92616bc5566a488b83b3daf7479e6271 revision: cceb7f61ef17d0c10d42f4e4a9820d40cc46528d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:19.422832) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:19.423238) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:19.423580) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:19.448013) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:19.448413) ========= 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.5vt4myWaM3/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.F2DYH7JI7e/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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-01-26 23:44:19-- 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% 19.3M=0.001s 2017-01-26 23:44:19 (19.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.507142 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:19.979797) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:19.980097) ========= 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.5vt4myWaM3/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.F2DYH7JI7e/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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.025595 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:20.030049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-26 23:44:20.030430) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cceb7f61ef17d0c10d42f4e4a9820d40cc46528d --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 cceb7f61ef17d0c10d42f4e4a9820d40cc46528d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5vt4myWaM3/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.F2DYH7JI7e/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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-01-26 23:44:20,112 truncating revision to first 12 chars 2017-01-26 23:44:20,112 Setting DEBUG logging. 2017-01-26 23:44:20,112 attempt 1/10 2017-01-26 23:44:20,112 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cceb7f61ef17?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-26 23:44:20,926 unpacking tar archive at: mozilla-aurora-cceb7f61ef17/testing/mozharness/ program finished with exit code 0 elapsedTime=1.081356 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-26 23:44:21.132968) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:21.133299) ========= script_repo_revision: cceb7f61ef17d0c10d42f4e4a9820d40cc46528d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:21.133718) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:21.133994) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-26 23:44:21.155678) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-01-26 23:44:21.156070) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5vt4myWaM3/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.F2DYH7JI7e/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:44:21 INFO - MultiFileLogger online at 20170126 23:44:21 in /builds/slave/test 23:44:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 23:44:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:44:21 INFO - {'append_to_log': False, 23:44:21 INFO - 'base_work_dir': '/builds/slave/test', 23:44:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 23:44:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:44:21 INFO - 'branch': 'Mozilla-Aurora', 23:44:21 INFO - 'buildbot_json_path': 'buildprops.json', 23:44:21 INFO - 'config_files': ('talos/mac_config.py',), 23:44:21 INFO - 'default_actions': ('clobber', 23:44:21 INFO - 'read-buildbot-config', 23:44:21 INFO - 'download-and-extract', 23:44:21 INFO - 'populate-webroot', 23:44:21 INFO - 'create-virtualenv', 23:44:21 INFO - 'install', 23:44:21 INFO - 'run-tests'), 23:44:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:44:21 INFO - 'download_minidump_stackwalk': True, 23:44:21 INFO - 'download_symbols': 'ondemand', 23:44:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:44:21 INFO - 'tooltool.py': '/tools/tooltool.py', 23:44:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:44:21 INFO - '/tools/misc-python/virtualenv.py')}, 23:44:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:44:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:44:21 INFO - 'installer_path': 'installer.exe', 23:44:21 INFO - 'log_level': 'info', 23:44:21 INFO - 'log_name': 'talos', 23:44:21 INFO - 'log_to_console': True, 23:44:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:44:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:44:21 INFO - 'opt_config_files': (), 23:44:21 INFO - 'pip_index': False, 23:44:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:44:21 INFO - 'cmd': ('bash', 23:44:21 INFO - '-c', 23:44:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:44:21 INFO - 'enabled': True, 23:44:21 INFO - 'halt_on_failure': False, 23:44:21 INFO - 'name': 'check_screen_resolution'},), 23:44:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:44:21 INFO - 'cmd': ('bash', 23:44:21 INFO - '-c', 23:44:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:44:21 INFO - 'enabled': True, 23:44:21 INFO - 'halt_on_failure': False, 23:44:21 INFO - 'name': 'check_screen_resolution'},), 23:44:21 INFO - 'run_cmd_checks_enabled': True, 23:44:21 INFO - 'sps_profile': False, 23:44:21 INFO - 'sps_profile_interval': 0, 23:44:21 INFO - 'suite': 'dromaeojs-e10s', 23:44:21 INFO - 'system_bits': '32', 23:44:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:44:21 INFO - 'title': 't-yosemite-r7-0256', 23:44:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:44:21 INFO - 'use_talos_json': True, 23:44:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:44:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:44:21 INFO - 'work_dir': 'build'} 23:44:21 INFO - [mozharness: 2017-01-27 07:44:21.332335Z] Running clobber step. 23:44:21 INFO - Running pre-action listener: _resource_record_pre_action 23:44:21 INFO - Running main action method: clobber 23:44:21 INFO - rmtree: /builds/slave/test/build 23:44:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:44:23 INFO - Running post-action listener: _resource_record_post_action 23:44:23 INFO - [mozharness: 2017-01-27 07:44:23.816692Z] Finished clobber step (success) 23:44:23 INFO - [mozharness: 2017-01-27 07:44:23.816888Z] Running read-buildbot-config step. 23:44:23 INFO - Running pre-action listener: _resource_record_pre_action 23:44:23 INFO - Running main action method: read_buildbot_config 23:44:23 INFO - Using buildbot properties: 23:44:23 INFO - { 23:44:23 INFO - "project": "", 23:44:23 INFO - "product": "firefox", 23:44:23 INFO - "script_repo_revision": "production", 23:44:23 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 23:44:23 INFO - "repository": "", 23:44:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 23:44:23 INFO - "buildid": "20170126230720", 23:44:23 INFO - "pgo_build": "False", 23:44:23 INFO - "basedir": "/builds/slave/test", 23:44:23 INFO - "buildnumber": 45, 23:44:23 INFO - "slavename": "t-yosemite-r7-0256", 23:44:23 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 23:44:23 INFO - "platform": "yosemite_r7", 23:44:23 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 23:44:23 INFO - "branch": "mozilla-aurora", 23:44:23 INFO - "repo_path": "releases/mozilla-aurora", 23:44:23 INFO - "revision": "cceb7f61ef17d0c10d42f4e4a9820d40cc46528d", 23:44:23 INFO - "stage_platform": "macosx64", 23:44:23 INFO - "builduid": "92616bc5566a488b83b3daf7479e6271", 23:44:23 INFO - "slavebuilddir": "test" 23:44:23 INFO - } 23:44:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 23:44:23 INFO - Running post-action listener: _resource_record_post_action 23:44:23 INFO - [mozharness: 2017-01-27 07:44:23.820380Z] Finished read-buildbot-config step (success) 23:44:23 INFO - [mozharness: 2017-01-27 07:44:23.820492Z] Running download-and-extract step. 23:44:23 INFO - Running pre-action listener: _resource_record_pre_action 23:44:23 INFO - Running main action method: download_and_extract 23:44:23 INFO - mkdir: /builds/slave/test/build/tests 23:44:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:44:23 INFO - trying https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 23:44:23 INFO - Downloading https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 23:44:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 23:44:24 INFO - Downloaded 1199 bytes. 23:44:24 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 23:44:24 INFO - Using the following test package requirements: 23:44:24 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 23:44:24 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 23:44:24 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 23:44:24 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 23:44:24 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 23:44:24 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 23:44:24 INFO - u'jsshell-mac.zip'], 23:44:24 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 23:44:24 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 23:44:24 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 23:44:24 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 23:44:24 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 23:44:24 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 23:44:24 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 23:44:24 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 23:44:24 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 23:44:24 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 23:44:24 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 23:44:24 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 23:44:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 23:44:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 23:44:24 INFO - Fetch https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 23:44:25 INFO - Expected file size: 16349889 23:44:25 INFO - Obtained file size: 16349889 23:44:32 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 23:44:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 23:44:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 23:44:32 INFO - Fetch https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 23:44:33 INFO - Expected file size: 16349889 23:44:33 INFO - Obtained file size: 16349889 23:44:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 23:44:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 23:44:39 INFO - Fetch https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 23:44:40 INFO - Expected file size: 14403485 23:44:40 INFO - Obtained file size: 14403485 23:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:44:41 INFO - trying https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 23:44:41 INFO - Downloading https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 23:44:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 23:44:43 INFO - Downloaded 73894163 bytes. 23:44:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 23:44:43 INFO - mkdir: /builds/slave/test/properties 23:44:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:44:43 INFO - Writing to file /builds/slave/test/properties/build_url 23:44:43 INFO - Contents: 23:44:43 INFO - build_url:https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 23:44:43 INFO - Running post-action listener: _resource_record_post_action 23:44:43 INFO - Running post-action listener: set_extra_try_arguments 23:44:43 INFO - [mozharness: 2017-01-27 07:44:43.823348Z] Finished download-and-extract step (success) 23:44:43 INFO - [mozharness: 2017-01-27 07:44:43.823501Z] Running populate-webroot step. 23:44:43 INFO - Running pre-action listener: _resource_record_pre_action 23:44:43 INFO - Running main action method: populate_webroot 23:44:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:44:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:44:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:44:43 INFO - '--tppagecycles', 23:44:43 INFO - '7'], 23:44:43 INFO - 'tests': ['tsvgm']}}, 23:44:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:44:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:44:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:44:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:44:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:44:43 INFO - 'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:44:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:44:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:44:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:44:43 INFO - 'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['damp', 'tps']}, 23:44:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:44:43 INFO - 'tests': ['damp', 'tps']}, 23:44:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['dromaeo_dom']}, 23:44:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:44:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['basic_compositor_video']}, 23:44:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:44:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['a11yr', 23:44:43 INFO - 'ts_paint', 23:44:43 INFO - 'tpaint', 23:44:43 INFO - 'sessionrestore', 23:44:43 INFO - 'sessionrestore_no_auto_restore', 23:44:43 INFO - 'tabpaint']}, 23:44:43 INFO - 'other-e10s': {'tests': ['a11yr', 23:44:43 INFO - 'ts_paint', 23:44:43 INFO - 'tpaint', 23:44:43 INFO - 'sessionrestore', 23:44:43 INFO - 'sessionrestore_no_auto_restore', 23:44:43 INFO - 'tabpaint']}, 23:44:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:44:43 INFO - 'ts_paint', 23:44:43 INFO - 'tpaint', 23:44:43 INFO - 'sessionrestore', 23:44:43 INFO - 'sessionrestore_no_auto_restore', 23:44:43 INFO - 'tabpaint']}, 23:44:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:44:43 INFO - 'ts_paint', 23:44:43 INFO - 'tpaint', 23:44:43 INFO - 'sessionrestore', 23:44:43 INFO - 'sessionrestore_no_auto_restore', 23:44:43 INFO - 'tabpaint']}, 23:44:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['a11yr', 23:44:43 INFO - 'ts_paint', 23:44:43 INFO - 'tpaint', 23:44:43 INFO - 'sessionrestore', 23:44:43 INFO - 'sessionrestore_no_auto_restore', 23:44:43 INFO - 'tabpaint']}, 23:44:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['a11yr', 23:44:43 INFO - 'ts_paint', 23:44:43 INFO - 'tpaint', 23:44:43 INFO - 'sessionrestore', 23:44:43 INFO - 'sessionrestore_no_auto_restore', 23:44:43 INFO - 'tabpaint']}, 23:44:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['tsvgx', 23:44:43 INFO - 'tsvgr_opacity', 23:44:43 INFO - 'tart', 23:44:43 INFO - 'tscrollx', 23:44:43 INFO - 'cart', 23:44:43 INFO - 'tsvg_static']}, 23:44:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:44:43 INFO - 'tsvgr_opacity', 23:44:43 INFO - 'tart', 23:44:43 INFO - 'tscrollx', 23:44:43 INFO - 'cart', 23:44:43 INFO - 'tsvg_static']}, 23:44:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:44:43 INFO - 'talos_options': ['--disable-e10s'], 23:44:43 INFO - 'tests': ['tp5o']}, 23:44:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:44:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:44:43 INFO - 'talos_options': ['--disable-e10s', 23:44:43 INFO - '--xperf_path', 23:44:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:44:43 INFO - 'tests': ['tp5n']}, 23:44:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:44:43 INFO - 'talos_options': ['--xperf_path', 23:44:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:44:43 INFO - 'tests': ['tp5n']}}, 23:44:43 INFO - 'talos.zip': {'path': '', 23:44:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:44:43 INFO - Running post-action listener: _resource_record_post_action 23:44:43 INFO - [mozharness: 2017-01-27 07:44:43.842007Z] Finished populate-webroot step (success) 23:44:43 INFO - [mozharness: 2017-01-27 07:44:43.842143Z] Running create-virtualenv step. 23:44:43 INFO - Running pre-action listener: _resource_record_pre_action 23:44:43 INFO - Running main action method: create_virtualenv 23:44:43 INFO - Creating virtualenv /builds/slave/test/build/venv 23:44:43 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 23:44:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:44:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:44:43 INFO - Using real prefix '/tools/python27' 23:44:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:44:44 INFO - Installing distribute.............................................................................................................................................................................................done. 23:44:47 INFO - Installing pip.................done. 23:44:47 INFO - Return code: 0 23:44:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:44:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:44:47 INFO - Reading from file tmpfile_stdout 23:44:47 INFO - Output received: 23:44:47 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) 23:44:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:44:47 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')]} 23:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:47 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')]} 23:44:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:47 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 0x10e751b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4b0573770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, '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.5vt4myWaM3/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.F2DYH7JI7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:44:47 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 23:44:47 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 23:44:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:44:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:47 INFO - 'HOME': '/Users/cltbld', 23:44:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:47 INFO - 'LOGNAME': 'cltbld', 23:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:47 INFO - 'MOZ_NO_REMOTE': '1', 23:44:47 INFO - 'NO_EM_RESTART': '1', 23:44:47 INFO - 'PAGER': '/bin/cat', 23:44:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:47 INFO - 'PWD': '/builds/slave/test', 23:44:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:47 INFO - 'SHELL': '/bin/bash', 23:44:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:44:47 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:44:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:47 INFO - 'USER': 'cltbld', 23:44:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:47 INFO - 'XPC_FLAGS': '0x0', 23:44:47 INFO - 'XPC_SERVICE_NAME': '0', 23:44:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:48 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 23:44:48 INFO - Cleaning up... 23:44:48 INFO - Return code: 0 23:44:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:44:48 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')]} 23:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:48 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')]} 23:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:48 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 0x10e751b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4b0573770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, '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.5vt4myWaM3/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.F2DYH7JI7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:44:48 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 23:44:48 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 23:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:48 INFO - 'HOME': '/Users/cltbld', 23:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:48 INFO - 'LOGNAME': 'cltbld', 23:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:48 INFO - 'MOZ_NO_REMOTE': '1', 23:44:48 INFO - 'NO_EM_RESTART': '1', 23:44:48 INFO - 'PAGER': '/bin/cat', 23:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:48 INFO - 'PWD': '/builds/slave/test', 23:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:48 INFO - 'SHELL': '/bin/bash', 23:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:44:48 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:48 INFO - 'USER': 'cltbld', 23:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:48 INFO - 'XPC_FLAGS': '0x0', 23:44:48 INFO - 'XPC_SERVICE_NAME': '0', 23:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:48 INFO - Downloading/unpacking psutil>=3.1.1 23:44:48 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 23:44:48 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 23:44:48 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 23:44:48 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 23:44:48 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 23:44:48 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 23:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:44:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:44:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:44:52 INFO - Installing collected packages: psutil 23:44:52 INFO - Running setup.py install for psutil 23:44:52 INFO - building 'psutil._psutil_osx' extension 23:44:52 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 23:44:52 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 23:44:52 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 23:44:52 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 23:44:53 INFO - building 'psutil._psutil_posix' extension 23:44:53 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 23:44:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:44:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:44:53 INFO - ^ 23:44:53 INFO - 1 warning generated. 23:44:53 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 23:44:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:44:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:44:53 INFO - Successfully installed psutil 23:44:53 INFO - Cleaning up... 23:44:53 INFO - Return code: 0 23:44:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:44:53 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')]} 23:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:53 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')]} 23:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:53 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 0x10e751b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4b0573770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, '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.5vt4myWaM3/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.F2DYH7JI7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:44:53 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 23:44:53 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 23:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:53 INFO - 'HOME': '/Users/cltbld', 23:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:53 INFO - 'LOGNAME': 'cltbld', 23:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:53 INFO - 'MOZ_NO_REMOTE': '1', 23:44:53 INFO - 'NO_EM_RESTART': '1', 23:44:53 INFO - 'PAGER': '/bin/cat', 23:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:53 INFO - 'PWD': '/builds/slave/test', 23:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:53 INFO - 'SHELL': '/bin/bash', 23:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:44:53 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:53 INFO - 'USER': 'cltbld', 23:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:53 INFO - 'XPC_FLAGS': '0x0', 23:44:53 INFO - 'XPC_SERVICE_NAME': '0', 23:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:44:53 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 23:44:53 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 23:44:53 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 23:44:53 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 23:44:53 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 23:44:53 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 23:44:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:44:56 INFO - Installing collected packages: mozsystemmonitor 23:44:56 INFO - Running setup.py install for mozsystemmonitor 23:44:56 INFO - Successfully installed mozsystemmonitor 23:44:56 INFO - Cleaning up... 23:44:56 INFO - Return code: 0 23:44:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:44:56 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')]} 23:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:44:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:56 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')]} 23:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:44:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:44:56 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 0x10e751b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4b0573770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, '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.5vt4myWaM3/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.F2DYH7JI7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:44:56 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 23:44:56 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 23:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:44:56 INFO - 'HOME': '/Users/cltbld', 23:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:44:56 INFO - 'LOGNAME': 'cltbld', 23:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:44:56 INFO - 'MOZ_NO_REMOTE': '1', 23:44:56 INFO - 'NO_EM_RESTART': '1', 23:44:56 INFO - 'PAGER': '/bin/cat', 23:44:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:44:56 INFO - 'PWD': '/builds/slave/test', 23:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:44:56 INFO - 'SHELL': '/bin/bash', 23:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:44:56 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:44:56 INFO - 'USER': 'cltbld', 23:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:44:56 INFO - 'XPC_FLAGS': '0x0', 23:44:56 INFO - 'XPC_SERVICE_NAME': '0', 23:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:44:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:44:56 INFO - Downloading/unpacking jsonschema==2.5.1 23:44:56 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 23:44:56 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 23:44:56 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 23:44:56 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 23:44:56 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 23:44:56 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 23:44:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:44:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:44:59 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 23:44:59 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 23:44:59 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 23:44:59 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 23:44:59 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 23:44:59 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 23:44:59 INFO - Downloading functools32-3.2.3-2.tar.gz 23:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:44:59 INFO - warning: no files found matching '*.txt' 23:44:59 INFO - no previously-included directories found matching 'build' 23:44:59 INFO - no previously-included directories found matching 'dist' 23:44:59 INFO - no previously-included directories found matching '.git*' 23:44:59 INFO - Installing collected packages: jsonschema, functools32 23:45:00 INFO - Running setup.py install for functools32 23:45:00 INFO - warning: no files found matching '*.txt' 23:45:00 INFO - no previously-included directories found matching 'build' 23:45:00 INFO - no previously-included directories found matching 'dist' 23:45:00 INFO - no previously-included directories found matching '.git*' 23:45:00 INFO - Successfully installed jsonschema functools32 23:45:00 INFO - Cleaning up... 23:45:00 INFO - Return code: 0 23:45:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:00 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 0x10e751b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4b0573770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, '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.5vt4myWaM3/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.F2DYH7JI7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:45:00 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 23:45:00 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 23:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:45:00 INFO - 'HOME': '/Users/cltbld', 23:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:45:00 INFO - 'LOGNAME': 'cltbld', 23:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:00 INFO - 'MOZ_NO_REMOTE': '1', 23:45:00 INFO - 'NO_EM_RESTART': '1', 23:45:00 INFO - 'PAGER': '/bin/cat', 23:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:00 INFO - 'PWD': '/builds/slave/test', 23:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:45:00 INFO - 'SHELL': '/bin/bash', 23:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:45:00 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:45:00 INFO - 'USER': 'cltbld', 23:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:00 INFO - 'XPC_FLAGS': '0x0', 23:45:00 INFO - 'XPC_SERVICE_NAME': '0', 23:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:45:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:45:00 INFO - Cleaning up... 23:45:00 INFO - Return code: 0 23:45:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:45:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:45:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:00 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 0x10e751b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4b0573770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, '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.5vt4myWaM3/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.F2DYH7JI7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:45:00 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 23:45:00 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 23:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:45:00 INFO - 'HOME': '/Users/cltbld', 23:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:45:00 INFO - 'LOGNAME': 'cltbld', 23:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:00 INFO - 'MOZ_NO_REMOTE': '1', 23:45:00 INFO - 'NO_EM_RESTART': '1', 23:45:00 INFO - 'PAGER': '/bin/cat', 23:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:00 INFO - 'PWD': '/builds/slave/test', 23:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:45:00 INFO - 'SHELL': '/bin/bash', 23:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:45:00 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:45:00 INFO - 'USER': 'cltbld', 23:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:00 INFO - 'XPC_FLAGS': '0x0', 23:45:00 INFO - 'XPC_SERVICE_NAME': '0', 23:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:45:00 INFO - Downloading/unpacking blobuploader==1.2.4 23:45:00 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 23:45:00 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 23:45:00 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 23:45:00 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 23:45:00 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 23:45:00 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 23:45:03 INFO - Downloading blobuploader-1.2.4.tar.gz 23:45:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:45:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:45:03 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 23:45:03 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 23:45:03 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 23:45:03 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 23:45:03 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 23:45:03 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 23:45:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:45:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:45: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 23:45: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 23:45: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 23:45: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 23:45: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 23:45: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 23:45:04 INFO - Downloading docopt-0.6.1.tar.gz 23:45:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:45:04 INFO - Installing collected packages: blobuploader, requests, docopt 23:45:04 INFO - Running setup.py install for blobuploader 23:45:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:45:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:45:04 INFO - Running setup.py install for requests 23:45:05 INFO - Running setup.py install for docopt 23:45:05 INFO - Successfully installed blobuploader requests docopt 23:45:05 INFO - Cleaning up... 23:45:05 INFO - Return code: 0 23:45:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:45:05 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')]} 23:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:05 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')]} 23:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:05 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 0x10e751b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4b0573770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, '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.5vt4myWaM3/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.F2DYH7JI7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:45:05 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 23:45:05 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 23:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:45:05 INFO - 'HOME': '/Users/cltbld', 23:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:45:05 INFO - 'LOGNAME': 'cltbld', 23:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:05 INFO - 'MOZ_NO_REMOTE': '1', 23:45:05 INFO - 'NO_EM_RESTART': '1', 23:45:05 INFO - 'PAGER': '/bin/cat', 23:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:05 INFO - 'PWD': '/builds/slave/test', 23:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:45:05 INFO - 'SHELL': '/bin/bash', 23:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:45:05 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:45:05 INFO - 'USER': 'cltbld', 23:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:05 INFO - 'XPC_FLAGS': '0x0', 23:45:05 INFO - 'XPC_SERVICE_NAME': '0', 23:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:45:05 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-tbdolB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:45:06 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-WsUJOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:45:06 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-fkQ4SF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:45:06 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-UurcuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:45:06 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-HpgcPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:45:06 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-5RFxx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:45:06 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-CWaAfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:45:06 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-BYUNt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:45:06 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-GqQumT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:45:06 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-qRN1bW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:45:06 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-7LH1ox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:45:07 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-sT5D2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:45:07 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-ys3BaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:45:07 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-XAIQIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:45:07 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-hqaqKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:45:07 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-yCASpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:45:07 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-xwgD1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:45:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:45:07 INFO - Running setup.py install for manifestparser 23:45:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:45:07 INFO - Running setup.py install for mozcrash 23:45:07 INFO - Running setup.py install for mozdebug 23:45:08 INFO - Running setup.py install for mozdevice 23:45:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:45:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:45:08 INFO - Running setup.py install for mozfile 23:45:08 INFO - Running setup.py install for mozhttpd 23:45:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:45:08 INFO - Running setup.py install for mozinfo 23:45:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:45:08 INFO - Running setup.py install for mozInstall 23:45:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:45:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:45:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:45:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:45:08 INFO - Running setup.py install for mozleak 23:45:08 INFO - Running setup.py install for mozlog 23:45:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:45:09 INFO - Running setup.py install for moznetwork 23:45:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:45:09 INFO - Running setup.py install for mozprocess 23:45:09 INFO - Running setup.py install for mozprofile 23:45:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:45:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:45:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:45:09 INFO - Running setup.py install for mozrunner 23:45:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:45:09 INFO - Running setup.py install for mozscreenshot 23:45:09 INFO - Running setup.py install for moztest 23:45:10 INFO - Running setup.py install for mozversion 23:45:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:45:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:45:10 INFO - Cleaning up... 23:45:10 INFO - Return code: 0 23:45:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:45:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:45:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:10 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 0x10e751b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4b0573770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, '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.5vt4myWaM3/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.F2DYH7JI7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:45:10 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 23:45:10 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 23:45:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:45:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:45:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:45:10 INFO - 'HOME': '/Users/cltbld', 23:45:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:45:10 INFO - 'LOGNAME': 'cltbld', 23:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:10 INFO - 'MOZ_NO_REMOTE': '1', 23:45:10 INFO - 'NO_EM_RESTART': '1', 23:45:10 INFO - 'PAGER': '/bin/cat', 23:45:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:10 INFO - 'PWD': '/builds/slave/test', 23:45:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:45:10 INFO - 'SHELL': '/bin/bash', 23:45:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:45:10 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:45:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:45:10 INFO - 'USER': 'cltbld', 23:45:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:45:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:10 INFO - 'XPC_FLAGS': '0x0', 23:45:10 INFO - 'XPC_SERVICE_NAME': '0', 23:45:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:45:10 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-2vCkBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:45:10 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)) 23:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:45:10 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-o_T3nI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:45:10 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)) 23:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:45:10 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-AfdZ9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:45:10 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)) 23:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:45:10 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-9aCSeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:45:10 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)) 23:45:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:45:10 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-5wZadd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:45:11 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)) 23:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:45:11 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-Pmldn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:45:11 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)) 23:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:45:11 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-O_pzNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:45:11 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)) 23:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:45:11 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-DUhmlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:45:11 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)) 23:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:45:11 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-xDw3GK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:45:11 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)) 23:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:45:11 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-mRqtto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:45:11 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)) 23:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:45:11 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-jF9tpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:45:11 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)) 23:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:45:11 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-aFsleP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:45:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 23:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:45:11 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-PeblD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:45:11 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)) 23:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:45:11 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-Ks5gK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:45:12 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)) 23:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:45:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-CZTm__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:45:12 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)) 23:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:45:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-FQF1b1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:45:12 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)) 23:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:45:12 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-WEGPbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:45:12 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)) 23:45:12 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)) 23:45:12 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)) 23:45:12 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)) 23:45:12 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)) 23:45:12 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)) 23:45:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:45: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 23:45: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 23:45: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 23:45: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 23:45: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 23:45: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 23:45:14 INFO - Downloading blessings-1.6.tar.gz 23:45:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:45:15 INFO - Installing collected packages: blessings 23:45:15 INFO - Running setup.py install for blessings 23:45:15 INFO - Successfully installed blessings 23:45:15 INFO - Cleaning up... 23:45:15 INFO - Return code: 0 23:45:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:45:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:45:15 INFO - Reading from file tmpfile_stdout 23:45:15 INFO - Current package versions: 23:45:15 INFO - blessings == 1.6 23:45:15 INFO - blobuploader == 1.2.4 23:45:15 INFO - docopt == 0.6.1 23:45:15 INFO - functools32 == 3.2.3-2 23:45:15 INFO - jsonschema == 2.5.1 23:45:15 INFO - manifestparser == 1.1 23:45:15 INFO - mozInstall == 1.12 23:45:15 INFO - mozcrash == 1.0 23:45:15 INFO - mozdebug == 0.1 23:45:15 INFO - mozdevice == 0.49 23:45:15 INFO - mozfile == 1.2 23:45:15 INFO - mozhttpd == 0.7 23:45:15 INFO - mozinfo == 0.9 23:45:15 INFO - mozleak == 0.1 23:45:15 INFO - mozlog == 3.4 23:45:15 INFO - moznetwork == 0.27 23:45:15 INFO - mozprocess == 0.24 23:45:15 INFO - mozprofile == 0.28 23:45:15 INFO - mozrunner == 6.13 23:45:15 INFO - mozscreenshot == 0.1 23:45:15 INFO - mozsystemmonitor == 0.3 23:45:15 INFO - moztest == 0.8 23:45:15 INFO - mozversion == 1.4 23:45:15 INFO - psutil == 3.1.1 23:45:15 INFO - requests == 1.2.3 23:45:15 INFO - wsgiref == 0.1.2 23:45:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:15 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 0x10e751b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4b0573770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, '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.5vt4myWaM3/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.F2DYH7JI7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:45:15 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 23:45:15 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 23:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:45:15 INFO - 'HOME': '/Users/cltbld', 23:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:45:15 INFO - 'LOGNAME': 'cltbld', 23:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:15 INFO - 'MOZ_NO_REMOTE': '1', 23:45:15 INFO - 'NO_EM_RESTART': '1', 23:45:15 INFO - 'PAGER': '/bin/cat', 23:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:15 INFO - 'PWD': '/builds/slave/test', 23:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:45:15 INFO - 'SHELL': '/bin/bash', 23:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:45:15 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:45:15 INFO - 'USER': 'cltbld', 23:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:15 INFO - 'XPC_FLAGS': '0x0', 23:45:15 INFO - 'XPC_SERVICE_NAME': '0', 23:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:45:15 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)) 23:45:15 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)) 23:45:15 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)) 23:45:15 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)) 23:45:15 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)) 23:45:15 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)) 23:45:15 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)) 23:45:15 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)) 23:45:15 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)) 23:45:15 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)) 23:45:15 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)) 23:45:15 INFO - Cleaning up... 23:45:15 INFO - Return code: 0 23:45:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:45:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e751b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e754768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4b0573770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, '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.5vt4myWaM3/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.F2DYH7JI7e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 23:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:45:15 INFO - 'HOME': '/Users/cltbld', 23:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:45:15 INFO - 'LOGNAME': 'cltbld', 23:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:15 INFO - 'MOZ_NO_REMOTE': '1', 23:45:15 INFO - 'NO_EM_RESTART': '1', 23:45:15 INFO - 'PAGER': '/bin/cat', 23:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:15 INFO - 'PWD': '/builds/slave/test', 23:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:45:15 INFO - 'SHELL': '/bin/bash', 23:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:45:15 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:45:15 INFO - 'USER': 'cltbld', 23:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:15 INFO - 'XPC_FLAGS': '0x0', 23:45:15 INFO - 'XPC_SERVICE_NAME': '0', 23:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:45:16 INFO - Cleaning up... 23:45:16 INFO - Return code: 0 23:45:16 INFO - Running post-action listener: _resource_record_post_action 23:45:16 INFO - Running post-action listener: _start_resource_monitoring 23:45:16 INFO - Starting resource monitoring. 23:45:16 INFO - [mozharness: 2017-01-27 07:45:16.107832Z] Finished create-virtualenv step (success) 23:45:16 INFO - [mozharness: 2017-01-27 07:45:16.108778Z] Running install step. 23:45:16 INFO - Running pre-action listener: _resource_record_pre_action 23:45:16 INFO - Running main action method: install 23:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:45:16 INFO - Reading from file tmpfile_stdout 23:45:16 INFO - Detecting whether we're running mozinstall >=1.0... 23:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:45:16 INFO - Reading from file tmpfile_stdout 23:45:16 INFO - Output received: 23:45:16 INFO - Usage: mozinstall [options] installer 23:45:16 INFO - Options: 23:45:16 INFO - -h, --help show this help message and exit 23:45:16 INFO - -d DEST, --destination=DEST 23:45:16 INFO - Directory to install application into. [default: 23:45:16 INFO - "/builds/slave/test"] 23:45:16 INFO - --app=APP Application being installed. [default: firefox] 23:45:16 INFO - mkdir: /builds/slave/test/build/application 23:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 23:45:36 INFO - Reading from file tmpfile_stdout 23:45:36 INFO - Output received: 23:45:36 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 23:45:36 INFO - Running post-action listener: _resource_record_post_action 23:45:36 INFO - [mozharness: 2017-01-27 07:45:36.428567Z] Finished install step (success) 23:45:36 INFO - [mozharness: 2017-01-27 07:45:36.428687Z] Running run-tests step. 23:45:36 INFO - Running pre-action listener: _resource_record_pre_action 23:45:36 INFO - Running main action method: run_tests 23:45:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:45:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:45:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:45:36 INFO - 2017-01-26 23:45:36.443 screenresolution[1639:8357] starting screenresolution argv=screenresolution get 23:45:36 INFO - 2017-01-26 23:45:36.445 screenresolution[1639:8357] Display 0: 1600x1200x32@60 23:45:36 INFO - 2017-01-26 23:45:36.451 screenresolution[1640:8359] starting screenresolution argv=screenresolution list 23:45:36 INFO - Available Modes on Display 0 23:45:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:45:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:45:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:45:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:45:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:45:36 INFO - Intel Iris: 23:45:36 INFO - Chipset Model: Intel Iris 23:45:36 INFO - Type: GPU 23:45:36 INFO - Bus: Built-In 23:45:36 INFO - VRAM (Dynamic, Max): 1536 MB 23:45:36 INFO - Vendor: Intel (0x8086) 23:45:36 INFO - Device ID: 0x0a2e 23:45:36 INFO - Revision ID: 0x0009 23:45:36 INFO - Displays: 23:45:36 INFO - Display: 23:45:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:45:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:45:36 INFO - Display Serial Number: 1600x1200 60 23:45:36 INFO - Main Display: Yes 23:45:36 INFO - Mirror: Off 23:45:36 INFO - Online: Yes 23:45:36 INFO - Rotation: Supported 23:45:36 INFO - Return code: 0 23:45:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:45:36 INFO - Python 2.7.3 23:45:36 INFO - Return code: 0 23:45:36 INFO - grabbing minidump binary from tooltool 23:45:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:45:36 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 0x10e52fd30>, '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 0x10e456db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e524be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:45:36 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 23:45:36 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 23:45:36 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 23:45:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:45:36 INFO - Return code: 0 23:45:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:45:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:45:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:45:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:45:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:45:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0256', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0256 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:45:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5vt4myWaM3/Render', 23:45:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:45:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:45:36 INFO - 'HOME': '/Users/cltbld', 23:45:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:45:36 INFO - 'LOGNAME': 'cltbld', 23:45:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:45:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:45:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:45:36 INFO - 'MOZ_NO_REMOTE': '1', 23:45:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:45:36 INFO - 'NO_EM_RESTART': '1', 23:45:36 INFO - 'PAGER': '/bin/cat', 23:45:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:45:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:45:36 INFO - 'PWD': '/builds/slave/test', 23:45:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:45:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:45:36 INFO - 'SHELL': '/bin/bash', 23:45:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F2DYH7JI7e/Listeners', 23:45:36 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 23:45:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:45:36 INFO - 'USER': 'cltbld', 23:45:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:45:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:45:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:45:36 INFO - 'XPC_FLAGS': '0x0', 23:45:36 INFO - 'XPC_SERVICE_NAME': '0', 23:45:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:45:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0256', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:45:37 INFO - mozversion application_buildid: 20170126230720 23:45:37 INFO - mozversion application_changeset: cceb7f61ef17d0c10d42f4e4a9820d40cc46528d 23:45:37 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:45:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:45:37 INFO - mozversion application_name: Firefox 23:45:37 INFO - mozversion application_remotingname: firefox-dev 23:45:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:45:37 INFO - mozversion application_vendor: Mozilla 23:45:37 INFO - mozversion application_version: 53.0a2 23:45:37 INFO - mozversion platform_buildid: 20170126230720 23:45:37 INFO - mozversion platform_changeset: cceb7f61ef17d0c10d42f4e4a9820d40cc46528d 23:45:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 23:45:37 INFO - mozversion platform_version: 53.0a2 23:45:37 INFO - using testdate: 1485503137 23:45:37 INFO - actual date: 1485503137 23:45:37 INFO - starting webserver on 'localhost:49246' 23:45:37 INFO - SUITE-START | Running 2 tests 23:45:37 INFO - TEST-START | dromaeo_css 23:45:37 INFO - operating with platform_type : mac_ 23:45:37 INFO - Initialising browser for dromaeo_css test... 23:45:37 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmp3NWab7/profile http://localhost:49246/getInfo.html) 23:45:40 INFO - PROCESS | 1650 | 2017-01-26 23:45:40.098 plugin-container[1652:8541] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 23:45:40 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:45:40 INFO - PROCESS | 1650 | 2017-01-26 23:45:40.101 plugin-container[1652:8541] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 23:45:40 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:45:40 INFO - PROCESS | 1650 | 2017-01-26 23:45:40.101 plugin-container[1652:8541] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:45:40 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 23:45:40 INFO - PROCESS | 1650 | colorDepth:24 23:45:40 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 23:45:40 INFO - PROCESS | 1650 | __metrics 23:45:40 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 23:45:40 INFO - TEST-INFO | 1650: exit 0 23:45:40 INFO - Browser initialized. 23:45:40 INFO - Running cycle 1/1 for dromaeo_css test... 23:45:40 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmp3NWab7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:45:43 INFO - PROCESS | 1654 | 2017-01-26 23:45:43.285 plugin-container[1655:8677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 23:45:43 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:45:43 INFO - PROCESS | 1654 | 2017-01-26 23:45:43.286 plugin-container[1655:8677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 23:45:43 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:45:43 INFO - PROCESS | 1654 | 2017-01-26 23:45:43.286 plugin-container[1655:8677] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:45:52 INFO - PROCESS | 1654 | RSS: Main: 218697728 23:45:52 INFO - PROCESS | 1654 | 23:48:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 23:48:57 INFO - PROCESS | 1654 | RSS: Main: 239083520 23:48:57 INFO - PROCESS | 1654 | 23:50:41 INFO - PROCESS | 1654 | ************************* 23:50:41 INFO - PROCESS | 1654 | A coding exception was thrown and uncaught in a Task. 23:50:41 INFO - PROCESS | 1654 | 23:50:41 ERROR - PROCESS | 1654 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:50:41 INFO - PROCESS | 1654 | Full stack: 23:50:41 INFO - PROCESS | 1654 | ************************* 23:51:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 23:52:00 INFO - PROCESS | 1654 | RSS: Main: 249155584 23:52:00 INFO - PROCESS | 1654 | 23:55:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 23:55:02 INFO - PROCESS | 1654 | RSS: Main: 256282624 23:55:02 INFO - PROCESS | 1654 | 23:58:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 23:58:05 INFO - PROCESS | 1654 | RSS: Main: 252727296 23:58:05 INFO - PROCESS | 1654 | 00:01:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 00:01:08 INFO - PROCESS | 1654 | RSS: Main: 259387392 00:01:08 INFO - PROCESS | 1654 | 00:04:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 00:04:11 INFO - PROCESS | 1654 | RSS: Main: 256151552 00:04:11 INFO - PROCESS | 1654 | 00:04:11 INFO - PROCESS | 1654 | __start_tp_report 00:04:11 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 00:04:11 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 00:04:11 INFO - PROCESS | 1654 | |i|pagename|runs| 00:04:11 INFO - PROCESS | 1654 | |0;dojo.html;3337.2324365395957;3382.813945233011;3403.693667569918;3413.266147778227;3416.2996585699934;5661.971690141549;5733.827985160452;5739.397363276858;5747.224124743158;5756.597038207327;5736.22560954271;5767.106098554731;5775.509081728048;5777.855553611162;5818.243628328311;4775.522447755231;5245.082110630643;5255.237990491377;5265.157574788034;5295.7881684732565;5305.098133317345;5305.946940530604;5327.174287985368;5334.146536554152;5349.893002139955;5168;5219.739013049351;5321.281626980347;5323.254744335796;5328.200769884529;5222.399424066243;5244.868878278055;5274.841754747364;5319.760610772486;5359.81240656579;5145.202493613496;5234.8691282717855;5239.921401178985;5251.68489890604;5302.215315961441;4868.391451068616;5541.418151094158;5725.742341594597;5916.230889984317;5928.49607783339;6130.049842274455;6147.47746441553;6177.1969643945995;6182.474489668383;6285.560010799246;6273.654948977804;6273.811785646438;6282;6282.277538083134;6322.841928951767;5826.533877289849;5885.941140588625;5895.528357731371;5901.675407852609;5943.70281485924;6201.379862013762;6227.875442491125;6240.251169859555;6246.969250073777;6273.404026617464;6129.509639228851;6169.722362493699;6202.875942481125;6218.253809542884;6226.750929962841;6210.099535567408;6275.843103922439;6327.778527751507;6327.841803954846;6348.873022539523;4960.776765045582;6090.390960903874;6163.982942814386;6164.938350616526;6254.280757712831;5237.266782650432;6068.757249709962;6271.310155882849;6292.119103325538;6303.338149494329;4904.509549045067;4909.754512274372;4930;4940.752962351868;4956.033573453215;4346.9347959780625;4367.432233809584;4370.694051416434;4402.6037656610415;4413.271810151353;4889.364382630307;5775.826725198251;5874.441928016831;5898.911516327258;5919.970400147971;6105.481034112061;6181.81454556364;6199.380061993765;6205.100260462208;6215.627062376181;5610.326760788732;5719.48524632785;5760.876629057378;5763.942360576424;5798.739056742456;5395.946040539623;5406.026915155231;5410.3507579090765;5411.540019098342;5440.537554307849;6344.206974128142;6351.777687780906;6361.840953976002;6368.554201206057;6372.267189273108;6159.983602705683;6213.254409470893;6215.223097112861;6218.844528886814;6253.499720022296;6255;6344.587601805868;6346.270178929298;6350.396712312323;6366.331535188739;6213.440790328892;6233.127362169209;6240.719167637377;6248.750049997949;6254.562180647311;4619.907601847877;4995.825146119869;6158.969205153946;6171.814845554641;6316.810495685137;6058.151858739692;6159.476444502267;6160.983437732724;6177.722002509988;6178.691065446799;6126.479249263863;6130.570860039755;6139.017757158833;6158.445739883432;6209.472194863487;5802.187693722966;5892.1161825726485;5990.910136347871;6050.485708714808;6050.757969681164;6051.576389652682;6063.363346848518;6103.542234332532;6142.805741014737;6145.754169833157;5980.192673988956;5994.3705910878725;6020.548458865515;6024.1265016573225;6031.215941927521 00:04:11 INFO - PROCESS | 1654 | |1;ext.html;452.9818807247708;490.19607843137237;495.0742112250092;497.1498737159457;503.05676855895194;38582.61417385825;41505.377419338736;41562.37656435156;41609.79195104024;41722.37416438748;42933.78533107338;43361.6991490255;43457.56542434583;43495;43755.78122109385;8486;8732.69435569752;9352.766180845465;9587.760305992337;9588.808223835518;11446.484908179153;11695.181337306392;11697.824532632018;11897.643070707849;11909.880901191007;11662.358570278631;11714.004309633649;11734.178607497479;11741.589044383449;11777;11655.941720291428;11685;11705;11716.00413964814;11721.120915931255;11452.942735286353;11527.711807204803;11594.014508766764;11611.361375124365;11611.593594224161;41703.374449383286;41785.791071044754;41940.161196775894;42132;42248.57751422477;4007.6192761687594;4049.5545489996075;4064.9390259146135;4129.97935010326;4241.003364209409;24892.253232403058;30002.69997300021;30163.09510714682;30698.669625256378;31570.73717051338;3221.371832492665;3255.886043988449;3301.290222602128;3310.470324748053;3322.302316513535;483027;488542;488843;488845;489934.99999999645;67105.63813352214;67236.66381668158;67573;67657.32342676609;67677;59689.70155149196;70948.93576596356;71667.2833271671;71852.2814771856;71935;16728.665426691397;17141.82858171427;17189.484315470556;17234.82765172357;17299.65400691979;11728.70678233051;12003.97966172885;12772.042096842773;13054.54309099177;13162.736745265041;19562.773266708307;19572.021398929995;19612.509687257934;19630.705539416922;19662.3287020602;6046.879062418815;6620.139381880324;6855.622940738307;6868.553544019623;6946.305369463114;883.3683916000091;886.5567216391804;891.6700820696384;897.5826240797929;925.2551695884878;30546;31121.066368008997;31136.53295200574;31454.89907853214;31610.841945790584;1039.293280569205;1087.6084609540717;1116.240956149826;1121.4785124916953;1204.9698757531132;14415.567532974028;15081.773773393408;15475.148866812207;15533.301001454962;15858.44495442677;425794;428998.85500572296;429121;430716;431364;57092.714536427055;57196.71401643049;57510.56223594444;57512.137317940265;57561.71219143961;1419.737348590514;1429.9642508937359;1441.2793603198402;1448.2758620689656;1452.9128252305106;22953.311400658007;23894;28576.42847143087;29098.56352154677;29755.553666694585;40778;41668;47928.76035619799;50455.747721262625;50523.74738126286;360932;362722;363215;364424;365432;27131.050413235444;34323.4851477233;38635.42046869243;39272;40510.594894051865;44991.32513012373;45450.86372840706;45681;45699.54300456953;45706;37234.62765372312;37707.62292377041;37772.81113594415;37925.81037094797;38251.80874095612;30392.848035759678;37625.62374376221;37765.24469510649;37777;37953.62046379501;35840;36876.446853297755;37249;38099.42850857184;38400.80799595984;27520.44959100847;32175.67824321727;33695.326093478485;33745;34226;22403.663945041164;25473.745262547138;25604.487910242064;25734.099306524182;25946.610800837625 00:04:11 INFO - PROCESS | 1654 | |2;jquery.html;359.3424034017747;390.69135383047376;395.11100024943875;405.18153330272867;413.20458118122605;19355.032248387593;21003.054862531186;21006.949652517353;21149.15403383863;21218.25736099239;21473.892630536826;21727.13091476339;21858.781412185872;21961.450963725914;22227.44431389212;5810.070388737792;5931.377205393436;6043.607165534248;6096.35988221237;6098.390160983889;4483.730976141425;5948.851278718023;6077.848053798668;6083.908741368883;6087.4825639820665;6653.567518111331;6810.489213308997;6814.761483348084;6819.761308354208;6822.863542729143;5678.914816277759;5944.97027514864;5996.940030599681;6799.898001529957;6819.147606549182;5849.824505264849;5913.467787899067;5914.881702365971;5925.377835327315;5926.792562260343;21735.782642173694;24713.011479540797;25984.74015259842;26592.936282548675;26745.46509069808;2635.973640263592;2673.4786716590274;2704.5267078261227;2725.4140359822536;2725.509408306504;9896.802063958681;9986.450745209011;10102;10492.842607360895;10505.632302869439;1788.6333301673255;1792.2920446423589;1840.2730921286152;1855.4062699936026;1860.6743819831474;143877.2806135973;148114;150012.2499387507;150915;151849;11979;12059.638210853689;12121.333326667118;12123.272603643809;12321.829426204496;15603.141827199614;15771.605709857346;15781.763273550905;15786.289616967035;15806.525804225892;8856.734297971072;8950;8998.370114091951;9003.684871029482;9157.81684366309;7736;8261.049979252342;8737.475751454933;8916;8917.821643567222;4760.595349395271;4777.8088876445245;4796.8081276748535;4814.759262036884;4873.926891096637;1667.466410748573;2019.7374341335533;2062.1648232465577;2062.669972804374;2062.804033616804;756.7956651704009;760.2891296637662;877.88587483628;912.8585069314267;929.4098247612815;20833.89583052075;21899.977506185925;22001.899905005004;22231.66652500214;22235.332940011827;919.1268295119663;922.8523436250168;932.2868005975434;941.2940294778916;942.189716843514;9392.483413412328;11278.492467838967;11321.773564528829;11404.828927566094;11414.714632134263;682374.3525129469;766479;775527;779352;791066;120421.39789300997;144029;147034.26482867516;148192.25903870413;166594;1012.8278192707257;1051.2536099369534;1066.842475913627;1067.4716015572187;1069.4118234970736;19567.412977610693;20186.29056748426;20599.79400205979;20639.174433022512;20736.274230401865;54843;56358.15462768144;56854;57154.71422643027;57212;64642;80861;81607;82006;82584.58707706664;8317;8364.87452688198;8368.623411946355;8621.91378086211;8684.305255579413;9179.219766319937;12376.690582735144;13678;13841.515546955809;14191.858081419054;8505.532195729294;10229;11114.110871130451;11335.489902954221;11918.463669134737;11297.322160670386;11577.479013444246;11659.88340116588;11822.586209482626;12095.032397408011;10048.849267261143;11835.28988260707;11934.761304774029;12017.63947081589;12017.819732703842;7627.4279429041935;8020.95727555414;8242.464239824392;8407.747767566983;8563;8344.624491898001;11596.188266821579;11616;11945.761084778082;11999.700007499883 00:04:11 INFO - PROCESS | 1654 | |3;mootools.html;1419.630895967049;1431.4632012995128;1448.1455940994815;1449.2826051104698;1458.0959804920947;7595.696172153121;7857.764267071997;8132.430729848906;8538.146185381467;8571.78570535737;6765.221999470063;8293.37799665026;8311.667533298662;8461.661533538667;8479.152084791533;2248.6739422783726;2294.5755035318434;2300.1144559344607;2303.79265866072;2307.7692230776956;2051.107768120865;2120.098957942876;2277.7266727992665;2306.9653955190684;2315.1781117703226;2133.6877820140658;2170.5658868226337;2239.317008312464;2248.5278091600785;2252.144185209618;2042;2270.920517781878;2281.2243837095302;2286.325533967484;2288.8626682399026;2250.268662684634;2267.025179172955;2289.553537060274;2299.1722979727283;2322.7561106083785;8538;8563.314934805201;8568.871466928002;8575.399722019461;8603.354748393895;5035.723035233061;5197.298364720752;5201.24581935621;5212.087884620177;5217.58259339252;3163.4463968805367;3393.33829903169;3418.453047512386;3444.2594842109065;3510.6489351064943;1218.9085818563553;1223.7797196504625;1255.3095797311441;1260.4517035089673;1271.1546821363781;10799.190060745397;10928.672139835817;10930.617428390047;10934.617288394948;10935.56257749689;4370.213361594943;4615.123126605934;4670.229412146958;4678.251479763221;4680;9557.426554406758;9562;9594.712158635251;9616.278779091455;9648.662296819577;5220.9477905220465;5224.608154388436;5225.712585807741;5240.580753539708;5242.1088414969545;4298.892527686833;4415.933760993587;4686.437627484724;4782.593479554207;4783.569478746929;3378.290558982641;3877.3796192609047;3899.434581985596;3959.9604003960167;3991.121953170356;1106.717786964319;1109.4175557832202;1116.553378648547;1150.263831148066;1196.9640910772691;764.6024067484876;784.7802615267734;795.9920400795918;797.8244786147038;836.8368168207141;5499.532539734088;5550.555955523549;5661.46216109469;5664.0937450007805;5739.540836733051;961.6153538584622;983.7098056073476;993.1508560181086;1006.6174853555602;1032.8019497383;3130.4208721386617;3177.698118678722;3224.7097761201135;3512.473129030615;3802.315583194996;7355.411567074621;10134.53629293259;10193.439360835077;10208.540615672313;10231;9928.404295742279;10066.647667331608;10183.847242291371;10215.23385746072;10260.230482713827;7778.105517865518;7866.134725180225;7913.683452661944;7915.406344524184;8018.518888866687;5442.891142177055;5450.155225939986;5569.693666848362;5603.663780173202;5673.149027645721;9166.450012999241;9343.065693430603;9434.575444105169;9442.763930901509;9473.005334439784;7552.697892084255;9202.815943680955;9450.243980481682;9587.952060239653;9601;7192.568445893054;7318.6706598202145;7325.450591205575;7340.192578816433;7343.37581305595;7833.882491762742;7862.135165131716;7881.448298619041;7909.367250620052;7974.481658692166;6481.4814814815645;7102.289771022856;7168.605726685082;7246.782596522113;7321.670524826289;6055.576109672457;7035.859282814213;7064.858702825812;7086.50394472382;7182.461315401261;6379.425851673372;6402.359764023747;6451.483881289391;6461.418472337512;6469.28837827848;4833;4862.367892174137;5010.599152067899;5014.899702006012;5093.668911520739;4115.238680844053;4948.653594248368;4984.551390374739;5002.624803139706;5046.318746969112 00:04:11 INFO - PROCESS | 1654 | |4;prototype.html;352.32178057239815;354.65066909094554;365.48831635710013;367.3626258441605;373.55360344388447;4430.689851710382;4784.425868895733;5247.081760691883;5347.716548028469;5452.9727351363235;4703.670743047988;5087.771050302736;5137.820176293831;5277.472252774721;5311.893762124755;3851.6726078283377;3880.2627500774834;4113.938290925638;4220.514640816299;4270.57294270572;3354.681305275995;3654.6843136470848;4057.4928133983253;4143.647789937859;4255.1915136124035;3765.585785563586;3877.3602355611292;3894.240623078501;4181.58184181581;4220.7256528325715;3534.9116272093147;4198.278705730651;4205.3271476563905;4234.87295381139;4245.702800803961;3410.8635654574036;3548.574171099459;3724.5344331958504;3733.51464309638;3756.211195648917;4360.7819609019425;4566.337881007268;5009.474005229471;5133.024725302181;5268.102543601004;3834.482344883461;4433.512313645497;4766.713997160182;4769.952300476985;4861.635377346678;5017.7491125443585;5077.136886729265;5703.429657034305;5713.74288157034;5721.71391430431;3929.332013557702;3955.0310174007554;4058.7767672777986;4061.1674606705556;4127.855525056609;4584.089103419319;4788.401449818773;5169.56058735008;5792.915994224104;5956.047032474783;3943.5662077171487;4154.833806647761;4161.417401563784;4173.5409104998425;4174.248635245624;3681.5029970954115;3933.6066393361007;4225.232472730367;4225.598568136023;4228.260054490452;4029.9194016119513;4581.839635612738;4597.563231493003;4602.585767280961;4620.075984803053;5648.4633959773755;5657.57568182388;5685.346185188673;5710.885782284414;5728.742206600713;1053.3890343600692;1117.8882111788755;1191.3388069621444;1211.255078126963;1295.430010795247;863.3897780639063;866.8093019535692;869.8956125265008;874.6938571500051;879.7580665317089;4806.735629540409;4856;4911.551092427742;4963.851084467472;5104.617153713486;4839.685420447659;5149.407818100892;5387.838364849061;5558.583106267046;5605.663660180402;4154.854580089683;4741;4841.8547443576745;4956.256561515802;4980.456058621767;2504.799616030713;2716.687580928179;2815.972170157878;2873.0264176577143;2934.985325073361;4986.80052797884;5251.842444726664;5527.585431092684;5557.694326812064;5660.452796241708;4795.712257264575;5068.391792984866;5155.484451554815;5656.971715141398;5669.780997085564;5547.362053363915;5668.971655141698;5714.571407144481;5757.740901659436;5772.538196944234;4513.932291015703;5028.773705183348;5137.768800404066;5526.198701188386;5540.279763630702;4576.37538673766;5499.872526132213;5514.558835293086;5519.289020403688;5523.944760552504;4996.2505624156665;5464.945350546603;5492.450754924475;5730.885382292414;5789.421057894177;3663.487111804403;3693.2982733281665;3707.4068149096015;3743.5507739071486;3748.2316125193724;4887.169181239269;4959.181735013683;5296.602754793329;5311.442298558596;5574.470425309589;5010.724410157476;5086.94783130119;5185.481451854785;5383.569314454756;5410.945890541202;4785.330053792402;4948.678335908155;5185.248139019745;5396.460353964572;5404;4928.531789479994;5278.81524146653;5345.839624811262;5472.589555783412;5569.66582005081;4618.960733834861;5015.2978582997675;5281.419043905091;5484.506394424361;5667.319921609268;4952.009598080326;5110.700184916728;5520.530754885719;5522.088855338824;5558.138488534284 00:04:11 INFO - PROCESS | 1654 | |5;yui.html;723.2658851265966;724.5073350121914;728.9234630363808;774.6126936531734;855.0210009539078;13126.868731312685;14954.32705528251;15606.609834754137;15674;15841.683166336681;14381.784273235884;14526.418943242257;14530.927345363285;14634.04878682885;15676.764848527282;847.2332539052168;856.9999999999984;889.6308032166643;891.0821853490883;899.1233547291371;878.9780255493632;884.1732979664034;886.1802832380023;888.3071204460531;889.8754174415587;906.9954650226773;937.6202637931649;937.873387092744;937.9671711490099;939.6241503398661;929.4794914847707;931.3201363005011;932.1703683721494;932.7201839448172;934.8223837470858;695.3811108113783;696.4985210648325;696.979090627282;697.4734075772759;699.9930000699978;15034.473793417288;15721.292541835644;15794.763078553831;15904.76142857858;16061.678766424606;13846.446142154302;13961;14161.858381416157;14184.43262269508;14241.430342786276;11756.529738810437;11964.760704785942;11992;12053.517859285617;12185.45165467556;3602.9042194785648;3647.8176091195332;3708.647678470541;3773.792441415721;3775.6790672792845;113647.74937378358;115857;117232.41383793026;117287.41356293164;117524.41237793757;10886.94556527228;11191.832122518168;11245.71885702864;11246.49390777417;11528.942355288169;18671.71992420115;22124.2256521021;22240.888795556242;22246.332610021724;22585.774142258695;7802.258785415376;7869.703084462422;8000.35997120229;8112.3104536113915;8166.387520935954;3712.9071773205346;4460.977695111504;4468.709533880287;4469.642428605704;4475.955240447619;3384.526166336715;3433.9484907726405;3443.6384179661277;3479.3911065563425;3539.239063601338;543.6194663735401;556.1740814889886;566.0207840436069;578.5747475605426;661.983854782906;693.4868197533789;706.5548704316351;707.1161048689139;730.3280981497036;758.0600055930606;8768.95615521931;10113.898861011443;10638.574457021787;10644.882287359871;10848.566057357775;626.1515646299199;672.3713328038202;678.3115138134797;681.8329509270261;694.4583225084442;5117.488251174853;5213.973930130325;5228.346456692913;5237.869053273698;5240.659357141773;106945.46527267314;107088;107926;109924.4503777476;110474.44762776134;58112;78749.60625196953;84601.57699211464;86005;87481;73691.1577210574;81366.59316703497;81922.59038704887;82578;82618;9977.10206081456;10435.895641043797;10664.326948961572;10669.679909602724;10827.350358978487;38481.883496407965;40792.59207407888;50119.99760004706;50452.74773626108;53572.928541429734;70537.94193087211;70935.64532177306;73326.63336683495;73629;74087.62956185185;12982.183272016415;13166.012549058667;13401.396937137657;13620.18278903269;13661.79507307411;14911.105333680014;14916.55250342448;15380.769288460906;15385.230738462807;15506.9224653876;13362.665933351744;13642;14129.222892740998;14325.39406985616;14702.485413010494;13114.803277951029;13854;14102.989457420796;14257.50098746539;14885.181315027778;11393.658190254306;12496.187747796364;13736.862631373959;13756.724865502834;13873.959453040861;11372.260803047775;11643.359615221245;11663.708407289885;11918.284902905853;12109.273443593413;11214.158938079514;11421.657350279505;11617;11651.242669226813;11901.523939042341 00:04:11 INFO - PROCESS | 1654 | __end_tp_report 00:04:11 INFO - PROCESS | 1654 | __start_cc_report 00:04:11 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,372 00:04:11 INFO - PROCESS | 1654 | __end_cc_report 00:04:11 INFO - PROCESS | 1654 | __startTimestamp1485504251356__endTimestamp 00:04:11 INFO - PROCESS | 1654 | 00:04:11 INFO - PROCESS | 1654 | ------- Summary: start ------- 00:04:11 INFO - PROCESS | 1654 | Number of tests: 6 00:04:11 INFO - PROCESS | 1654 | 00:04:11 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5736.08 Median:5990.91 stddev:653.99 (10.9%) stddev-sans-first:628.34 00:04:11 INFO - PROCESS | 1654 | Values: 3337.2 3382.8 3403.7 3413.3 3416.3 5662.0 5733.8 5739.4 5747.2 5756.6 5736.2 5767.1 5775.5 5777.9 5818.2 4775.5 5245.1 5255.2 5265.2 5295.8 5305.1 5305.9 5327.2 5334.1 5349.9 5168.0 5219.7 5321.3 5323.3 5328.2 5222.4 5244.9 5274.8 5319.8 5359.8 5145.2 5234.9 5239.9 5251.7 5302.2 4868.4 5541.4 5725.7 5916.2 5928.5 6130.0 6147.5 6177.2 6182.5 6285.6 6273.7 6273.8 6282.0 6282.3 6322.8 5826.5 5885.9 5895.5 5901.7 5943.7 6201.4 6227.9 6240.3 6247.0 6273.4 6129.5 6169.7 6202.9 6218.3 6226.8 6210.1 6275.8 6327.8 6327.8 6348.9 4960.8 6090.4 6164.0 6164.9 6254.3 5237.3 6068.8 6271.3 6292.1 6303.3 4904.5 4909.8 4930.0 4940.8 4956.0 4346.9 4367.4 4370.7 4402.6 4413.3 4889.4 5775.8 5874.4 5898.9 5920.0 6105.5 6181.8 6199.4 6205.1 6215.6 5610.3 5719.5 5760.9 5763.9 5798.7 5395.9 5406.0 5410.4 5411.5 5440.5 6344.2 6351.8 6361.8 6368.6 6372.3 6160.0 6213.3 6215.2 6218.8 6253.5 6255.0 6344.6 6346.3 6350.4 6366.3 6213.4 6233.1 6240.7 6248.8 6254.6 4619.9 4995.8 6159.0 6171.8 6316.8 6058.2 6159.5 6161.0 6177.7 6178.7 6126.5 6130.6 6139.0 6158.4 6209.5 5802.2 5892.1 5990.9 6050.5 6050.8 6051.6 6063.4 6103.5 6142.8 6145.8 5980.2 5994.4 6020.5 6024.1 6031.2 00:04:11 INFO - PROCESS | 1654 | 00:04:11 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:58975.02 Median:37241.81 stddev:113688.91 (305.3%) stddev-sans-first:113922.93 00:04:11 INFO - PROCESS | 1654 | Values: 453.0 490.2 495.1 497.1 503.1 38582.6 41505.4 41562.4 41609.8 41722.4 42933.8 43361.7 43457.6 43495.0 43755.8 8486.0 8732.7 9352.8 9587.8 9588.8 11446.5 11695.2 11697.8 11897.6 11909.9 11662.4 11714.0 11734.2 11741.6 11777.0 11655.9 11685.0 11705.0 11716.0 11721.1 11452.9 11527.7 11594.0 11611.4 11611.6 41703.4 41785.8 41940.2 42132.0 42248.6 4007.6 4049.6 4064.9 4130.0 4241.0 24892.3 30002.7 30163.1 30698.7 31570.7 3221.4 3255.9 3301.3 3310.5 3322.3 483027.0 488542.0 488843.0 488845.0 489935.0 67105.6 67236.7 67573.0 67657.3 67677.0 59689.7 70948.9 71667.3 71852.3 71935.0 16728.7 17141.8 17189.5 17234.8 17299.7 11728.7 12004.0 12772.0 13054.5 13162.7 19562.8 19572.0 19612.5 19630.7 19662.3 6046.9 6620.1 6855.6 6868.6 6946.3 883.4 886.6 891.7 897.6 925.3 30546.0 31121.1 31136.5 31454.9 31610.8 1039.3 1087.6 1116.2 1121.5 1205.0 14415.6 15081.8 15475.1 15533.3 15858.4 425794.0 428998.9 429121.0 430716.0 431364.0 57092.7 57196.7 57510.6 57512.1 57561.7 1419.7 1430.0 1441.3 1448.3 1452.9 22953.3 23894.0 28576.4 29098.6 29755.6 40778.0 41668.0 47928.8 50455.7 50523.7 360932.0 362722.0 363215.0 364424.0 365432.0 27131.1 34323.5 38635.4 39272.0 40510.6 44991.3 45450.9 45681.0 45699.5 45706.0 37234.6 37707.6 37772.8 37925.8 38251.8 30392.8 37625.6 37765.2 37777.0 37953.6 35840.0 36876.4 37249.0 38099.4 38400.8 27520.4 32175.7 33695.3 33745.0 34226.0 22403.7 25473.7 25604.5 25734.1 25946.6 00:04:11 INFO - PROCESS | 1654 | 00:04:11 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:41220.60 Median:14725.00 stddev:126570.37 (859.6%) stddev-sans-first:126888.24 00:04:11 INFO - PROCESS | 1654 | Values: 359.3 390.7 395.1 405.2 413.2 19355.0 21003.1 21006.9 21149.2 21218.3 21473.9 21727.1 21858.8 21961.5 22227.4 5810.1 5931.4 6043.6 6096.4 6098.4 4483.7 5948.9 6077.8 6083.9 6087.5 6653.6 6810.5 6814.8 6819.8 6822.9 5678.9 5945.0 5996.9 6799.9 6819.1 5849.8 5913.5 5914.9 5925.4 5926.8 21735.8 24713.0 25984.7 26592.9 26745.5 2636.0 2673.5 2704.5 2725.4 2725.5 9896.8 9986.5 10102.0 10492.8 10505.6 1788.6 1792.3 1840.3 1855.4 1860.7 143877.3 148114.0 150012.2 150915.0 151849.0 11979.0 12059.6 12121.3 12123.3 12321.8 15603.1 15771.6 15781.8 15786.3 15806.5 8856.7 8950.0 8998.4 9003.7 9157.8 7736.0 8261.0 8737.5 8916.0 8917.8 4760.6 4777.8 4796.8 4814.8 4873.9 1667.5 2019.7 2062.2 2062.7 2062.8 756.8 760.3 877.9 912.9 929.4 20833.9 21900.0 22001.9 22231.7 22235.3 919.1 922.9 932.3 941.3 942.2 9392.5 11278.5 11321.8 11404.8 11414.7 682374.4 766479.0 775527.0 779352.0 791066.0 120421.4 144029.0 147034.3 148192.3 166594.0 1012.8 1051.3 1066.8 1067.5 1069.4 19567.4 20186.3 20599.8 20639.2 20736.3 54843.0 56358.2 56854.0 57154.7 57212.0 64642.0 80861.0 81607.0 82006.0 82584.6 8317.0 8364.9 8368.6 8621.9 8684.3 9179.2 12376.7 13678.0 13841.5 14191.9 8505.5 10229.0 11114.1 11335.5 11918.5 11297.3 11577.5 11659.9 11822.6 12095.0 10048.8 11835.3 11934.8 12017.6 12017.8 7627.4 8021.0 8242.5 8407.7 8563.0 8344.6 11596.2 11616.0 11945.8 11999.7 00:04:11 INFO - PROCESS | 1654 | 00:04:11 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5414.84 Median:5145.48 stddev:2996.31 (58.2%) stddev-sans-first:2989.67 00:04:11 INFO - PROCESS | 1654 | Values: 1419.6 1431.5 1448.1 1449.3 1458.1 7595.7 7857.8 8132.4 8538.1 8571.8 6765.2 8293.4 8311.7 8461.7 8479.2 2248.7 2294.6 2300.1 2303.8 2307.8 2051.1 2120.1 2277.7 2307.0 2315.2 2133.7 2170.6 2239.3 2248.5 2252.1 2042.0 2270.9 2281.2 2286.3 2288.9 2250.3 2267.0 2289.6 2299.2 2322.8 8538.0 8563.3 8568.9 8575.4 8603.4 5035.7 5197.3 5201.2 5212.1 5217.6 3163.4 3393.3 3418.5 3444.3 3510.6 1218.9 1223.8 1255.3 1260.5 1271.2 10799.2 10928.7 10930.6 10934.6 10935.6 4370.2 4615.1 4670.2 4678.3 4680.0 9557.4 9562.0 9594.7 9616.3 9648.7 5220.9 5224.6 5225.7 5240.6 5242.1 4298.9 4415.9 4686.4 4782.6 4783.6 3378.3 3877.4 3899.4 3960.0 3991.1 1106.7 1109.4 1116.6 1150.3 1197.0 764.6 784.8 796.0 797.8 836.8 5499.5 5550.6 5661.5 5664.1 5739.5 961.6 983.7 993.2 1006.6 1032.8 3130.4 3177.7 3224.7 3512.5 3802.3 7355.4 10134.5 10193.4 10208.5 10231.0 9928.4 10066.6 10183.8 10215.2 10260.2 7778.1 7866.1 7913.7 7915.4 8018.5 5442.9 5450.2 5569.7 5603.7 5673.1 9166.5 9343.1 9434.6 9442.8 9473.0 7552.7 9202.8 9450.2 9588.0 9601.0 7192.6 7318.7 7325.5 7340.2 7343.4 7833.9 7862.1 7881.4 7909.4 7974.5 6481.5 7102.3 7168.6 7246.8 7321.7 6055.6 7035.9 7064.9 7086.5 7182.5 6379.4 6402.4 6451.5 6461.4 6469.3 4833.0 4862.4 5010.6 5014.9 5093.7 4115.2 4948.7 4984.6 5002.6 5046.3 00:04:11 INFO - PROCESS | 1654 | 00:04:11 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4442.44 Median:4938.61 stddev:1319.54 (26.7%) stddev-sans-first:1287.04 00:04:11 INFO - PROCESS | 1654 | Values: 352.3 354.7 365.5 367.4 373.6 4430.7 4784.4 5247.1 5347.7 5453.0 4703.7 5087.8 5137.8 5277.5 5311.9 3851.7 3880.3 4113.9 4220.5 4270.6 3354.7 3654.7 4057.5 4143.6 4255.2 3765.6 3877.4 3894.2 4181.6 4220.7 3534.9 4198.3 4205.3 4234.9 4245.7 3410.9 3548.6 3724.5 3733.5 3756.2 4360.8 4566.3 5009.5 5133.0 5268.1 3834.5 4433.5 4766.7 4770.0 4861.6 5017.7 5077.1 5703.4 5713.7 5721.7 3929.3 3955.0 4058.8 4061.2 4127.9 4584.1 4788.4 5169.6 5792.9 5956.0 3943.6 4154.8 4161.4 4173.5 4174.2 3681.5 3933.6 4225.2 4225.6 4228.3 4029.9 4581.8 4597.6 4602.6 4620.1 5648.5 5657.6 5685.3 5710.9 5728.7 1053.4 1117.9 1191.3 1211.3 1295.4 863.4 866.8 869.9 874.7 879.8 4806.7 4856.0 4911.6 4963.9 5104.6 4839.7 5149.4 5387.8 5558.6 5605.7 4154.9 4741.0 4841.9 4956.3 4980.5 2504.8 2716.7 2816.0 2873.0 2935.0 4986.8 5251.8 5527.6 5557.7 5660.5 4795.7 5068.4 5155.5 5657.0 5669.8 5547.4 5669.0 5714.6 5757.7 5772.5 4513.9 5028.8 5137.8 5526.2 5540.3 4576.4 5499.9 5514.6 5519.3 5523.9 4996.3 5464.9 5492.5 5730.9 5789.4 3663.5 3693.3 3707.4 3743.6 3748.2 4887.2 4959.2 5296.6 5311.4 5574.5 5010.7 5086.9 5185.5 5383.6 5410.9 4785.3 4948.7 5185.2 5396.5 5404.0 4928.5 5278.8 5345.8 5472.6 5569.7 4619.0 5015.3 5281.4 5484.5 5667.3 4952.0 5110.7 5520.5 5522.1 5558.1 00:04:11 INFO - PROCESS | 1654 | 00:04:11 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:20922.62 Median:3461.51 stddev:30398.98 (878.2%) stddev-sans-first:30446.42 00:04:11 INFO - PROCESS | 1654 | Values: 723.3 724.5 728.9 774.6 855.0 13126.9 14954.3 15606.6 15674.0 15841.7 14381.8 14526.4 14530.9 14634.0 15676.8 847.2 857.0 889.6 891.1 899.1 879.0 884.2 886.2 888.3 889.9 907.0 937.6 937.9 938.0 939.6 929.5 931.3 932.2 932.7 934.8 695.4 696.5 697.0 697.5 700.0 15034.5 15721.3 15794.8 15904.8 16061.7 13846.4 13961.0 14161.9 14184.4 14241.4 11756.5 11964.8 11992.0 12053.5 12185.5 3602.9 3647.8 3708.6 3773.8 3775.7 113647.7 115857.0 117232.4 117287.4 117524.4 10886.9 11191.8 11245.7 11246.5 11528.9 18671.7 22124.2 22240.9 22246.3 22585.8 7802.3 7869.7 8000.4 8112.3 8166.4 3712.9 4461.0 4468.7 4469.6 4476.0 3384.5 3433.9 3443.6 3479.4 3539.2 543.6 556.2 566.0 578.6 662.0 693.5 706.6 707.1 730.3 758.1 8769.0 10113.9 10638.6 10644.9 10848.6 626.2 672.4 678.3 681.8 694.5 5117.5 5214.0 5228.3 5237.9 5240.7 106945.5 107088.0 107926.0 109924.5 110474.4 58112.0 78749.6 84601.6 86005.0 87481.0 73691.2 81366.6 81922.6 82578.0 82618.0 9977.1 10435.9 10664.3 10669.7 10827.4 38481.9 40792.6 50120.0 50452.7 53572.9 70537.9 70935.6 73326.6 73629.0 74087.6 12982.2 13166.0 13401.4 13620.2 13661.8 14911.1 14916.6 15380.8 15385.2 15506.9 13362.7 13642.0 14129.2 14325.4 14702.5 13114.8 13854.0 14103.0 14257.5 14885.2 11393.7 12496.2 13736.9 13756.7 13874.0 11372.3 11643.4 11663.7 11918.3 12109.3 11214.2 11421.7 11617.0 11651.2 11901.5 00:04:11 INFO - PROCESS | 1654 | -------- Summary: end -------- 00:04:11 INFO - PROCESS | 1654 | 00:04:11 INFO - TEST-INFO | 1654: exit 0 00:04:11 INFO - TEST-OK | dromaeo_css | took 1114919ms 00:04:11 INFO - TEST-START | kraken 00:04:11 INFO - operating with platform_type : mac_ 00:04:11 INFO - Initialising browser for kraken test... 00:04:11 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpd6nNqP/profile http://localhost:49246/getInfo.html) 00:04:14 INFO - PROCESS | 1663 | 2017-01-27 00:04:14.792 plugin-container[1664:10859] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 00:04:14 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:04:14 INFO - PROCESS | 1663 | 2017-01-27 00:04:14.795 plugin-container[1664:10859] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 00:04:14 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:04:14 INFO - PROCESS | 1663 | 2017-01-27 00:04:14.795 plugin-container[1664:10859] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:04:14 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 00:04:14 INFO - PROCESS | 1663 | colorDepth:24 00:04:14 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 00:04:14 INFO - PROCESS | 1663 | __metrics 00:04:14 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 00:04:15 INFO - TEST-INFO | 1663: exit 0 00:04:15 INFO - Browser initialized. 00:04:15 INFO - Running cycle 1/1 for kraken test... 00:04:15 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpd6nNqP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:04:18 INFO - PROCESS | 1665 | 2017-01-27 00:04:18.249 plugin-container[1666:10987] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 00:04:18 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:04:18 INFO - PROCESS | 1665 | 2017-01-27 00:04:18.249 plugin-container[1666:10987] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 00:04:18 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:04:18 INFO - PROCESS | 1665 | 2017-01-27 00:04:18.249 plugin-container[1666:10987] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:04:27 INFO - PROCESS | 1665 | RSS: Main: 218439680 00:04:27 INFO - PROCESS | 1665 | 00:04:31 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 00:04:32 INFO - PROCESS | 1665 | RSS: Main: 233668608 00:04:32 INFO - PROCESS | 1665 | 00:04:34 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 00:04:35 INFO - PROCESS | 1665 | RSS: Main: 242733056 00:04:35 INFO - PROCESS | 1665 | 00:04:39 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 00:04:39 INFO - PROCESS | 1665 | RSS: Main: 242950144 00:04:39 INFO - PROCESS | 1665 | 00:04:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 00:04:42 INFO - PROCESS | 1665 | RSS: Main: 245518336 00:04:42 INFO - PROCESS | 1665 | 00:04:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:04:44 INFO - PROCESS | 1665 | RSS: Main: 245854208 00:04:44 INFO - PROCESS | 1665 | 00:04:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 00:04:48 INFO - PROCESS | 1665 | RSS: Main: 249901056 00:04:48 INFO - PROCESS | 1665 | 00:04:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 00:04:52 INFO - PROCESS | 1665 | RSS: Main: 249081856 00:04:52 INFO - PROCESS | 1665 | 00:04:55 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 00:04:56 INFO - PROCESS | 1665 | RSS: Main: 248639488 00:04:56 INFO - PROCESS | 1665 | 00:04:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:04:58 INFO - PROCESS | 1665 | RSS: Main: 248381440 00:04:58 INFO - PROCESS | 1665 | 00:05:00 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:05:01 INFO - PROCESS | 1665 | RSS: Main: 248348672 00:05:01 INFO - PROCESS | 1665 | 00:05:03 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:05:04 INFO - PROCESS | 1665 | RSS: Main: 248414208 00:05:04 INFO - PROCESS | 1665 | 00:05:06 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:05:07 INFO - PROCESS | 1665 | RSS: Main: 248827904 00:05:07 INFO - PROCESS | 1665 | 00:05:10 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:05:11 INFO - PROCESS | 1665 | RSS: Main: 248745984 00:05:11 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 00:05:13 INFO - PROCESS | 1665 | RSS: Main: 252190720 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | __start_tp_report 00:05:13 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 00:05:13 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 00:05:13 INFO - PROCESS | 1665 | |i|pagename|runs| 00:05:13 INFO - PROCESS | 1665 | |0;ai-astar;151;109;175;174;163;120;118;118;117;140 00:05:13 INFO - PROCESS | 1665 | |1;audio-beat-detection;138;104;140;163;112;113;124;145;154;143 00:05:13 INFO - PROCESS | 1665 | |2;audio-dft;273;272;194;196;235;283;201;200;191;201 00:05:13 INFO - PROCESS | 1665 | |3;audio-fft;85;82;87;74;76;84;82;50;85;84 00:05:13 INFO - PROCESS | 1665 | |4;audio-oscillator;83;71;85;61;71;81;111;98;98;80 00:05:13 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;96;90;93;90;92;95;119;93;93;89 00:05:13 INFO - PROCESS | 1665 | |6;imaging-darkroom;105;122;155;147;98;119;154;112;156;153 00:05:13 INFO - PROCESS | 1665 | |7;imaging-desaturate;127;95;151;154;92;126;109;79;135;77 00:05:13 INFO - PROCESS | 1665 | |8;json-parse-financial;52;68;76;56;56;53;54;76;93;93 00:05:13 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;63;71;69;85;73;71;71;71;99;104 00:05:13 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;89;83;87;85;84;84;85;87;88;88 00:05:13 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;144;141;130;141;152;155;146;152;160;167 00:05:13 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;169;183;226;203;171;172;169;169;157;167 00:05:13 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;65;66;67;67;72;63;55;85;83;88 00:05:13 INFO - PROCESS | 1665 | __end_tp_report 00:05:13 INFO - PROCESS | 1665 | __start_cc_report 00:05:13 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,922 00:05:13 INFO - PROCESS | 1665 | __end_cc_report 00:05:13 INFO - PROCESS | 1665 | __startTimestamp1485504313364__endTimestamp 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | ------- Summary: start ------- 00:05:13 INFO - PROCESS | 1665 | Number of tests: 14 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:138.50 Median:145.50 stddev:25.53 (17.5%) stddev-sans-first:26.68 00:05:13 INFO - PROCESS | 1665 | Values: 151.0 109.0 175.0 174.0 163.0 120.0 118.0 118.0 117.0 140.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:141.50 stddev:19.49 (13.8%) stddev-sans-first:20.61 00:05:13 INFO - PROCESS | 1665 | Values: 138.0 104.0 140.0 163.0 112.0 113.0 124.0 145.0 154.0 143.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:224.60 Median:218.00 stddev:37.58 (17.2%) stddev-sans-first:35.54 00:05:13 INFO - PROCESS | 1665 | Values: 273.0 272.0 194.0 196.0 235.0 283.0 201.0 200.0 191.0 201.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:78.90 Median:84.00 stddev:10.95 (13.0%) stddev-sans-first:11.39 00:05:13 INFO - PROCESS | 1665 | Values: 85.0 82.0 87.0 74.0 76.0 84.0 82.0 50.0 85.0 84.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:83.90 Median:82.00 stddev:14.96 (18.2%) stddev-sans-first:15.87 00:05:13 INFO - PROCESS | 1665 | Values: 83.0 71.0 85.0 61.0 71.0 81.0 111.0 98.0 98.0 80.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:95.00 Median:93.00 stddev:8.72 (9.4%) stddev-sans-first:9.24 00:05:13 INFO - PROCESS | 1665 | Values: 96.0 90.0 93.0 90.0 92.0 95.0 119.0 93.0 93.0 89.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:132.10 Median:153.50 stddev:23.12 (15.1%) stddev-sans-first:22.34 00:05:13 INFO - PROCESS | 1665 | Values: 105.0 122.0 155.0 147.0 98.0 119.0 154.0 112.0 156.0 153.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:114.50 Median:115.50 stddev:28.25 (24.5%) stddev-sans-first:29.60 00:05:13 INFO - PROCESS | 1665 | Values: 127.0 95.0 151.0 154.0 92.0 126.0 109.0 79.0 135.0 77.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:72.00 stddev:16.13 (22.4%) stddev-sans-first:16.08 00:05:13 INFO - PROCESS | 1665 | Values: 52.0 68.0 76.0 56.0 56.0 53.0 54.0 76.0 93.0 93.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:77.70 Median:71.00 stddev:13.71 (19.3%) stddev-sans-first:13.47 00:05:13 INFO - PROCESS | 1665 | Values: 63.0 71.0 69.0 85.0 73.0 71.0 71.0 71.0 99.0 104.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:87.00 stddev:2.05 (2.4%) stddev-sans-first:1.87 00:05:13 INFO - PROCESS | 1665 | Values: 89.0 83.0 87.0 85.0 84.0 84.0 85.0 87.0 88.0 88.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:152.00 stddev:10.65 (7.0%) stddev-sans-first:11.16 00:05:13 INFO - PROCESS | 1665 | Values: 144.0 141.0 130.0 141.0 152.0 155.0 146.0 152.0 160.0 167.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:171.50 stddev:20.66 (12.0%) stddev-sans-first:21.62 00:05:13 INFO - PROCESS | 1665 | Values: 169.0 183.0 226.0 203.0 171.0 172.0 169.0 169.0 157.0 167.0 00:05:13 INFO - PROCESS | 1665 | 00:05:13 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:69.50 stddev:10.76 (15.5%) stddev-sans-first:11.19 00:05:13 INFO - PROCESS | 1665 | Values: 65.0 66.0 67.0 67.0 72.0 63.0 55.0 85.0 83.0 88.0 00:05:13 INFO - PROCESS | 1665 | -------- Summary: end -------- 00:05:13 INFO - PROCESS | 1665 | 00:05:14 INFO - TEST-INFO | 1665: exit 0 00:05:14 INFO - TEST-OK | kraken | took 62393ms 00:05:14 INFO - SUITE-END | took 1177s 00:05:14 INFO - Completed test suite (00:19:37) 00:05:14 INFO - javascript benchmark 00:05:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7330.529013076702, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.2324365395957, 3382.813945233011, 3403.693667569918, 3413.266147778227, 3416.2996585699934, 5661.971690141549, 5733.827985160452, 5739.397363276858, 5747.224124743158, 5756.597038207327, 5736.22560954271, 5767.106098554731, 5775.509081728048, 5777.855553611162, 5818.243628328311, 4775.522447755231, 5245.082110630643, 5255.237990491377, 5265.157574788034, 5295.7881684732565, 5305.098133317345, 5305.946940530604, 5327.174287985368, 5334.146536554152, 5349.893002139955, 5168.0, 5219.739013049351, 5321.281626980347, 5323.254744335796, 5328.200769884529, 5222.399424066243, 5244.868878278055, 5274.841754747364, 5319.760610772486, 5359.81240656579, 5145.202493613496, 5234.8691282717855, 5239.921401178985, 5251.68489890604, 5302.215315961441, 4868.391451068616, 5541.418151094158, 5725.742341594597, 5916.230889984317, 5928.49607783339, 6130.049842274455, 6147.47746441553, 6177.1969643945995, 6182.474489668383, 6285.560010799246, 6273.654948977804, 6273.811785646438, 6282.0, 6282.277538083134, 6322.841928951767, 5826.533877289849, 5885.941140588625, 5895.528357731371, 5901.675407852609, 5943.70281485924, 6201.379862013762, 6227.875442491125, 6240.251169859555, 6246.969250073777, 6273.404026617464, 6129.509639228851, 6169.722362493699, 6202.875942481125, 6218.253809542884, 6226.750929962841, 6210.099535567408, 6275.843103922439, 6327.778527751507, 6327.841803954846, 6348.873022539523, 4960.776765045582, 6090.390960903874, 6163.982942814386, 6164.938350616526, 6254.280757712831, 5237.266782650432, 6068.757249709962, 6271.310155882849, 6292.119103325538, 6303.338149494329, 4904.509549045067, 4909.754512274372, 4930.0, 4940.752962351868, 4956.033573453215, 4346.9347959780625, 4367.432233809584, 4370.694051416434, 4402.6037656610415, 4413.271810151353, 4889.364382630307, 5775.826725198251, 5874.441928016831, 5898.911516327258, 5919.970400147971, 6105.481034112061, 6181.81454556364, 6199.380061993765, 6205.100260462208, 6215.627062376181, 5610.326760788732, 5719.48524632785, 5760.876629057378, 5763.942360576424, 5798.739056742456, 5395.946040539623, 5406.026915155231, 5410.3507579090765, 5411.540019098342, 5440.537554307849, 6344.206974128142, 6351.777687780906, 6361.840953976002, 6368.554201206057, 6372.267189273108, 6159.983602705683, 6213.254409470893, 6215.223097112861, 6218.844528886814, 6253.499720022296, 6255.0, 6344.587601805868, 6346.270178929298, 6350.396712312323, 6366.331535188739, 6213.440790328892, 6233.127362169209, 6240.719167637377, 6248.750049997949, 6254.562180647311, 4619.907601847877, 4995.825146119869, 6158.969205153946, 6171.814845554641, 6316.810495685137, 6058.151858739692, 6159.476444502267, 6160.983437732724, 6177.722002509988, 6178.691065446799, 6126.479249263863, 6130.570860039755, 6139.017757158833, 6158.445739883432, 6209.472194863487, 5802.187693722966, 5892.1161825726485, 5990.910136347871, 6050.485708714808, 6050.757969681164, 6051.576389652682, 6063.363346848518, 6103.542234332532, 6142.805741014737, 6145.754169833157, 5980.192673988956, 5994.3705910878725, 6020.548458865515, 6024.1265016573225, 6031.215941927521], "value": 5695.960693562551, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.9818807247708, 490.19607843137237, 495.0742112250092, 497.1498737159457, 503.05676855895194, 38582.61417385825, 41505.377419338736, 41562.37656435156, 41609.79195104024, 41722.37416438748, 42933.78533107338, 43361.6991490255, 43457.56542434583, 43495.0, 43755.78122109385, 8486.0, 8732.69435569752, 9352.766180845465, 9587.760305992337, 9588.808223835518, 11446.484908179153, 11695.181337306392, 11697.824532632018, 11897.643070707849, 11909.880901191007, 11662.358570278631, 11714.004309633649, 11734.178607497479, 11741.589044383449, 11777.0, 11655.941720291428, 11685.0, 11705.0, 11716.00413964814, 11721.120915931255, 11452.942735286353, 11527.711807204803, 11594.014508766764, 11611.361375124365, 11611.593594224161, 41703.374449383286, 41785.791071044754, 41940.161196775894, 42132.0, 42248.57751422477, 4007.6192761687594, 4049.5545489996075, 4064.9390259146135, 4129.97935010326, 4241.003364209409, 24892.253232403058, 30002.69997300021, 30163.09510714682, 30698.669625256378, 31570.73717051338, 3221.371832492665, 3255.886043988449, 3301.290222602128, 3310.470324748053, 3322.302316513535, 483027.0, 488542.0, 488843.0, 488845.0, 489934.99999999645, 67105.63813352214, 67236.66381668158, 67573.0, 67657.32342676609, 67677.0, 59689.70155149196, 70948.93576596356, 71667.2833271671, 71852.2814771856, 71935.0, 16728.665426691397, 17141.82858171427, 17189.484315470556, 17234.82765172357, 17299.65400691979, 11728.70678233051, 12003.97966172885, 12772.042096842773, 13054.54309099177, 13162.736745265041, 19562.773266708307, 19572.021398929995, 19612.509687257934, 19630.705539416922, 19662.3287020602, 6046.879062418815, 6620.139381880324, 6855.622940738307, 6868.553544019623, 6946.305369463114, 883.3683916000091, 886.5567216391804, 891.6700820696384, 897.5826240797929, 925.2551695884878, 30546.0, 31121.066368008997, 31136.53295200574, 31454.89907853214, 31610.841945790584, 1039.293280569205, 1087.6084609540717, 1116.240956149826, 1121.4785124916953, 1204.9698757531132, 14415.567532974028, 15081.773773393408, 15475.148866812207, 15533.301001454962, 15858.44495442677, 425794.0, 428998.85500572296, 429121.0, 430716.0, 431364.0, 57092.714536427055, 57196.71401643049, 57510.56223594444, 57512.137317940265, 57561.71219143961, 1419.737348590514, 1429.9642508937359, 1441.2793603198402, 1448.2758620689656, 1452.9128252305106, 22953.311400658007, 23894.0, 28576.42847143087, 29098.56352154677, 29755.553666694585, 40778.0, 41668.0, 47928.76035619799, 50455.747721262625, 50523.74738126286, 360932.0, 362722.0, 363215.0, 364424.0, 365432.0, 27131.050413235444, 34323.4851477233, 38635.42046869243, 39272.0, 40510.594894051865, 44991.32513012373, 45450.86372840706, 45681.0, 45699.54300456953, 45706.0, 37234.62765372312, 37707.62292377041, 37772.81113594415, 37925.81037094797, 38251.80874095612, 30392.848035759678, 37625.62374376221, 37765.24469510649, 37777.0, 37953.62046379501, 35840.0, 36876.446853297755, 37249.0, 38099.42850857184, 38400.80799595984, 27520.44959100847, 32175.67824321727, 33695.326093478485, 33745.0, 34226.0, 22403.663945041164, 25473.745262547138, 25604.487910242064, 25734.099306524182, 25946.610800837625], "value": 20092.90020774539, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.3424034017747, 390.69135383047376, 395.11100024943875, 405.18153330272867, 413.20458118122605, 19355.032248387593, 21003.054862531186, 21006.949652517353, 21149.15403383863, 21218.25736099239, 21473.892630536826, 21727.13091476339, 21858.781412185872, 21961.450963725914, 22227.44431389212, 5810.070388737792, 5931.377205393436, 6043.607165534248, 6096.35988221237, 6098.390160983889, 4483.730976141425, 5948.851278718023, 6077.848053798668, 6083.908741368883, 6087.4825639820665, 6653.567518111331, 6810.489213308997, 6814.761483348084, 6819.761308354208, 6822.863542729143, 5678.914816277759, 5944.97027514864, 5996.940030599681, 6799.898001529957, 6819.147606549182, 5849.824505264849, 5913.467787899067, 5914.881702365971, 5925.377835327315, 5926.792562260343, 21735.782642173694, 24713.011479540797, 25984.74015259842, 26592.936282548675, 26745.46509069808, 2635.973640263592, 2673.4786716590274, 2704.5267078261227, 2725.4140359822536, 2725.509408306504, 9896.802063958681, 9986.450745209011, 10102.0, 10492.842607360895, 10505.632302869439, 1788.6333301673255, 1792.2920446423589, 1840.2730921286152, 1855.4062699936026, 1860.6743819831474, 143877.2806135973, 148114.0, 150012.2499387507, 150915.0, 151849.0, 11979.0, 12059.638210853689, 12121.333326667118, 12123.272603643809, 12321.829426204496, 15603.141827199614, 15771.605709857346, 15781.763273550905, 15786.289616967035, 15806.525804225892, 8856.734297971072, 8950.0, 8998.370114091951, 9003.684871029482, 9157.81684366309, 7736.0, 8261.049979252342, 8737.475751454933, 8916.0, 8917.821643567222, 4760.595349395271, 4777.8088876445245, 4796.8081276748535, 4814.759262036884, 4873.926891096637, 1667.466410748573, 2019.7374341335533, 2062.1648232465577, 2062.669972804374, 2062.804033616804, 756.7956651704009, 760.2891296637662, 877.88587483628, 912.8585069314267, 929.4098247612815, 20833.89583052075, 21899.977506185925, 22001.899905005004, 22231.66652500214, 22235.332940011827, 919.1268295119663, 922.8523436250168, 932.2868005975434, 941.2940294778916, 942.189716843514, 9392.483413412328, 11278.492467838967, 11321.773564528829, 11404.828927566094, 11414.714632134263, 682374.3525129469, 766479.0, 775527.0, 779352.0, 791066.0, 120421.39789300997, 144029.0, 147034.26482867516, 148192.25903870413, 166594.0, 1012.8278192707257, 1051.2536099369534, 1066.842475913627, 1067.4716015572187, 1069.4118234970736, 19567.412977610693, 20186.29056748426, 20599.79400205979, 20639.174433022512, 20736.274230401865, 54843.0, 56358.15462768144, 56854.0, 57154.71422643027, 57212.0, 64642.0, 80861.0, 81607.0, 82006.0, 82584.58707706664, 8317.0, 8364.87452688198, 8368.623411946355, 8621.91378086211, 8684.305255579413, 9179.219766319937, 12376.690582735144, 13678.0, 13841.515546955809, 14191.858081419054, 8505.532195729294, 10229.0, 11114.110871130451, 11335.489902954221, 11918.463669134737, 11297.322160670386, 11577.479013444246, 11659.88340116588, 11822.586209482626, 12095.032397408011, 10048.849267261143, 11835.28988260707, 11934.761304774029, 12017.63947081589, 12017.819732703842, 7627.4279429041935, 8020.95727555414, 8242.464239824392, 8407.747767566983, 8563.0, 8344.624491898001, 11596.188266821579, 11616.0, 11945.761084778082, 11999.700007499883], "value": 10029.158712730256, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1419.630895967049, 1431.4632012995128, 1448.1455940994815, 1449.2826051104698, 1458.0959804920947, 7595.696172153121, 7857.764267071997, 8132.430729848906, 8538.146185381467, 8571.78570535737, 6765.221999470063, 8293.37799665026, 8311.667533298662, 8461.661533538667, 8479.152084791533, 2248.6739422783726, 2294.5755035318434, 2300.1144559344607, 2303.79265866072, 2307.7692230776956, 2051.107768120865, 2120.098957942876, 2277.7266727992665, 2306.9653955190684, 2315.1781117703226, 2133.6877820140658, 2170.5658868226337, 2239.317008312464, 2248.5278091600785, 2252.144185209618, 2042.0, 2270.920517781878, 2281.2243837095302, 2286.325533967484, 2288.8626682399026, 2250.268662684634, 2267.025179172955, 2289.553537060274, 2299.1722979727283, 2322.7561106083785, 8538.0, 8563.314934805201, 8568.871466928002, 8575.399722019461, 8603.354748393895, 5035.723035233061, 5197.298364720752, 5201.24581935621, 5212.087884620177, 5217.58259339252, 3163.4463968805367, 3393.33829903169, 3418.453047512386, 3444.2594842109065, 3510.6489351064943, 1218.9085818563553, 1223.7797196504625, 1255.3095797311441, 1260.4517035089673, 1271.1546821363781, 10799.190060745397, 10928.672139835817, 10930.617428390047, 10934.617288394948, 10935.56257749689, 4370.213361594943, 4615.123126605934, 4670.229412146958, 4678.251479763221, 4680.0, 9557.426554406758, 9562.0, 9594.712158635251, 9616.278779091455, 9648.662296819577, 5220.9477905220465, 5224.608154388436, 5225.712585807741, 5240.580753539708, 5242.1088414969545, 4298.892527686833, 4415.933760993587, 4686.437627484724, 4782.593479554207, 4783.569478746929, 3378.290558982641, 3877.3796192609047, 3899.434581985596, 3959.9604003960167, 3991.121953170356, 1106.717786964319, 1109.4175557832202, 1116.553378648547, 1150.263831148066, 1196.9640910772691, 764.6024067484876, 784.7802615267734, 795.9920400795918, 797.8244786147038, 836.8368168207141, 5499.532539734088, 5550.555955523549, 5661.46216109469, 5664.0937450007805, 5739.540836733051, 961.6153538584622, 983.7098056073476, 993.1508560181086, 1006.6174853555602, 1032.8019497383, 3130.4208721386617, 3177.698118678722, 3224.7097761201135, 3512.473129030615, 3802.315583194996, 7355.411567074621, 10134.53629293259, 10193.439360835077, 10208.540615672313, 10231.0, 9928.404295742279, 10066.647667331608, 10183.847242291371, 10215.23385746072, 10260.230482713827, 7778.105517865518, 7866.134725180225, 7913.683452661944, 7915.406344524184, 8018.518888866687, 5442.891142177055, 5450.155225939986, 5569.693666848362, 5603.663780173202, 5673.149027645721, 9166.450012999241, 9343.065693430603, 9434.575444105169, 9442.763930901509, 9473.005334439784, 7552.697892084255, 9202.815943680955, 9450.243980481682, 9587.952060239653, 9601.0, 7192.568445893054, 7318.6706598202145, 7325.450591205575, 7340.192578816433, 7343.37581305595, 7833.882491762742, 7862.135165131716, 7881.448298619041, 7909.367250620052, 7974.481658692166, 6481.4814814815645, 7102.289771022856, 7168.605726685082, 7246.782596522113, 7321.670524826289, 6055.576109672457, 7035.859282814213, 7064.858702825812, 7086.50394472382, 7182.461315401261, 6379.425851673372, 6402.359764023747, 6451.483881289391, 6461.418472337512, 6469.28837827848, 4833.0, 4862.367892174137, 5010.599152067899, 5014.899702006012, 5093.668911520739, 4115.238680844053, 4948.653594248368, 4984.551390374739, 5002.624803139706, 5046.318746969112], "value": 4376.179625322563, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.32178057239815, 354.65066909094554, 365.48831635710013, 367.3626258441605, 373.55360344388447, 4430.689851710382, 4784.425868895733, 5247.081760691883, 5347.716548028469, 5452.9727351363235, 4703.670743047988, 5087.771050302736, 5137.820176293831, 5277.472252774721, 5311.893762124755, 3851.6726078283377, 3880.2627500774834, 4113.938290925638, 4220.514640816299, 4270.57294270572, 3354.681305275995, 3654.6843136470848, 4057.4928133983253, 4143.647789937859, 4255.1915136124035, 3765.585785563586, 3877.3602355611292, 3894.240623078501, 4181.58184181581, 4220.7256528325715, 3534.9116272093147, 4198.278705730651, 4205.3271476563905, 4234.87295381139, 4245.702800803961, 3410.8635654574036, 3548.574171099459, 3724.5344331958504, 3733.51464309638, 3756.211195648917, 4360.7819609019425, 4566.337881007268, 5009.474005229471, 5133.024725302181, 5268.102543601004, 3834.482344883461, 4433.512313645497, 4766.713997160182, 4769.952300476985, 4861.635377346678, 5017.7491125443585, 5077.136886729265, 5703.429657034305, 5713.74288157034, 5721.71391430431, 3929.332013557702, 3955.0310174007554, 4058.7767672777986, 4061.1674606705556, 4127.855525056609, 4584.089103419319, 4788.401449818773, 5169.56058735008, 5792.915994224104, 5956.047032474783, 3943.5662077171487, 4154.833806647761, 4161.417401563784, 4173.5409104998425, 4174.248635245624, 3681.5029970954115, 3933.6066393361007, 4225.232472730367, 4225.598568136023, 4228.260054490452, 4029.9194016119513, 4581.839635612738, 4597.563231493003, 4602.585767280961, 4620.075984803053, 5648.4633959773755, 5657.57568182388, 5685.346185188673, 5710.885782284414, 5728.742206600713, 1053.3890343600692, 1117.8882111788755, 1191.3388069621444, 1211.255078126963, 1295.430010795247, 863.3897780639063, 866.8093019535692, 869.8956125265008, 874.6938571500051, 879.7580665317089, 4806.735629540409, 4856.0, 4911.551092427742, 4963.851084467472, 5104.617153713486, 4839.685420447659, 5149.407818100892, 5387.838364849061, 5558.583106267046, 5605.663660180402, 4154.854580089683, 4741.0, 4841.8547443576745, 4956.256561515802, 4980.456058621767, 2504.799616030713, 2716.687580928179, 2815.972170157878, 2873.0264176577143, 2934.985325073361, 4986.80052797884, 5251.842444726664, 5527.585431092684, 5557.694326812064, 5660.452796241708, 4795.712257264575, 5068.391792984866, 5155.484451554815, 5656.971715141398, 5669.780997085564, 5547.362053363915, 5668.971655141698, 5714.571407144481, 5757.740901659436, 5772.538196944234, 4513.932291015703, 5028.773705183348, 5137.768800404066, 5526.198701188386, 5540.279763630702, 4576.37538673766, 5499.872526132213, 5514.558835293086, 5519.289020403688, 5523.944760552504, 4996.2505624156665, 5464.945350546603, 5492.450754924475, 5730.885382292414, 5789.421057894177, 3663.487111804403, 3693.2982733281665, 3707.4068149096015, 3743.5507739071486, 3748.2316125193724, 4887.169181239269, 4959.181735013683, 5296.602754793329, 5311.442298558596, 5574.470425309589, 5010.724410157476, 5086.94783130119, 5185.481451854785, 5383.569314454756, 5410.945890541202, 4785.330053792402, 4948.678335908155, 5185.248139019745, 5396.460353964572, 5404.0, 4928.531789479994, 5278.81524146653, 5345.839624811262, 5472.589555783412, 5569.66582005081, 4618.960733834861, 5015.2978582997675, 5281.419043905091, 5484.506394424361, 5667.319921609268, 4952.009598080326, 5110.700184916728, 5520.530754885719, 5522.088855338824, 5558.138488534284], "value": 4031.4599684235627, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.2658851265966, 724.5073350121914, 728.9234630363808, 774.6126936531734, 855.0210009539078, 13126.868731312685, 14954.32705528251, 15606.609834754137, 15674.0, 15841.683166336681, 14381.784273235884, 14526.418943242257, 14530.927345363285, 14634.04878682885, 15676.764848527282, 847.2332539052168, 856.9999999999984, 889.6308032166643, 891.0821853490883, 899.1233547291371, 878.9780255493632, 884.1732979664034, 886.1802832380023, 888.3071204460531, 889.8754174415587, 906.9954650226773, 937.6202637931649, 937.873387092744, 937.9671711490099, 939.6241503398661, 929.4794914847707, 931.3201363005011, 932.1703683721494, 932.7201839448172, 934.8223837470858, 695.3811108113783, 696.4985210648325, 696.979090627282, 697.4734075772759, 699.9930000699978, 15034.473793417288, 15721.292541835644, 15794.763078553831, 15904.76142857858, 16061.678766424606, 13846.446142154302, 13961.0, 14161.858381416157, 14184.43262269508, 14241.430342786276, 11756.529738810437, 11964.760704785942, 11992.0, 12053.517859285617, 12185.45165467556, 3602.9042194785648, 3647.8176091195332, 3708.647678470541, 3773.792441415721, 3775.6790672792845, 113647.74937378358, 115857.0, 117232.41383793026, 117287.41356293164, 117524.41237793757, 10886.94556527228, 11191.832122518168, 11245.71885702864, 11246.49390777417, 11528.942355288169, 18671.71992420115, 22124.2256521021, 22240.888795556242, 22246.332610021724, 22585.774142258695, 7802.258785415376, 7869.703084462422, 8000.35997120229, 8112.3104536113915, 8166.387520935954, 3712.9071773205346, 4460.977695111504, 4468.709533880287, 4469.642428605704, 4475.955240447619, 3384.526166336715, 3433.9484907726405, 3443.6384179661277, 3479.3911065563425, 3539.239063601338, 543.6194663735401, 556.1740814889886, 566.0207840436069, 578.5747475605426, 661.983854782906, 693.4868197533789, 706.5548704316351, 707.1161048689139, 730.3280981497036, 758.0600055930606, 8768.95615521931, 10113.898861011443, 10638.574457021787, 10644.882287359871, 10848.566057357775, 626.1515646299199, 672.3713328038202, 678.3115138134797, 681.8329509270261, 694.4583225084442, 5117.488251174853, 5213.973930130325, 5228.346456692913, 5237.869053273698, 5240.659357141773, 106945.46527267314, 107088.0, 107926.0, 109924.4503777476, 110474.44762776134, 58112.0, 78749.60625196953, 84601.57699211464, 86005.0, 87481.0, 73691.1577210574, 81366.59316703497, 81922.59038704887, 82578.0, 82618.0, 9977.10206081456, 10435.895641043797, 10664.326948961572, 10669.679909602724, 10827.350358978487, 38481.883496407965, 40792.59207407888, 50119.99760004706, 50452.74773626108, 53572.928541429734, 70537.94193087211, 70935.64532177306, 73326.63336683495, 73629.0, 74087.62956185185, 12982.183272016415, 13166.012549058667, 13401.396937137657, 13620.18278903269, 13661.79507307411, 14911.105333680014, 14916.55250342448, 15380.769288460906, 15385.230738462807, 15506.9224653876, 13362.665933351744, 13642.0, 14129.222892740998, 14325.39406985616, 14702.485413010494, 13114.803277951029, 13854.0, 14102.989457420796, 14257.50098746539, 14885.181315027778, 11393.658190254306, 12496.187747796364, 13736.862631373959, 13756.724865502834, 13873.959453040861, 11372.260803047775, 11643.359615221245, 11663.708407289885, 11918.284902905853, 12109.273443593413, 11214.158938079514, 11421.657350279505, 11617.0, 11651.242669226813, 11901.523939042341], "value": 7661.8026767912725, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1630.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 109.0, 175.0, 174.0, 163.0, 120.0, 118.0, 118.0, 117.0, 140.0], "value": 138.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 104.0, 140.0, 163.0, 112.0, 113.0, 124.0, 145.0, 154.0, 143.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [273.0, 272.0, 194.0, 196.0, 235.0, 283.0, 201.0, 200.0, 191.0, 201.0], "value": 224.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 87.0, 74.0, 76.0, 84.0, 82.0, 50.0, 85.0, 84.0], "value": 78.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 85.0, 61.0, 71.0, 81.0, 111.0, 98.0, 98.0, 80.0], "value": 83.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 90.0, 93.0, 90.0, 92.0, 95.0, 119.0, 93.0, 93.0, 89.0], "value": 95.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 122.0, 155.0, 147.0, 98.0, 119.0, 154.0, 112.0, 156.0, 153.0], "value": 132.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 95.0, 151.0, 154.0, 92.0, 126.0, 109.0, 79.0, 135.0, 77.0], "value": 114.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 68.0, 76.0, 56.0, 56.0, 53.0, 54.0, 76.0, 93.0, 93.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 69.0, 85.0, 73.0, 71.0, 71.0, 71.0, 99.0, 104.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 87.0, 85.0, 84.0, 84.0, 85.0, 87.0, 88.0, 88.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 141.0, 130.0, 141.0, 152.0, 155.0, 146.0, 152.0, 160.0, 167.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 183.0, 226.0, 203.0, 171.0, 172.0, 169.0, 169.0, 157.0, 167.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 67.0, 72.0, 63.0, 55.0, 85.0, 83.0, 88.0], "value": 71.1, "unit": "score"}]}]} 00:05:14 INFO - Return code: 0 00:05:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:05:14 ERROR - # TBPL SUCCESS # 00:05:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:05:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:05:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:05:14 INFO - 2017-01-27 00:05:14.658 screenresolution[1670:11382] starting screenresolution argv=screenresolution get 00:05:14 INFO - 2017-01-27 00:05:14.661 screenresolution[1670:11382] Display 0: 1600x1200x32@60 00:05:14 INFO - 2017-01-27 00:05:14.668 screenresolution[1671:11385] starting screenresolution argv=screenresolution list 00:05:14 INFO - Available Modes on Display 0 00:05:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:05:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:05:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:05:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:05:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:05:14 INFO - Intel Iris: 00:05:14 INFO - Chipset Model: Intel Iris 00:05:14 INFO - Type: GPU 00:05:14 INFO - Bus: Built-In 00:05:14 INFO - VRAM (Dynamic, Max): 1536 MB 00:05:14 INFO - Vendor: Intel (0x8086) 00:05:14 INFO - Device ID: 0x0a2e 00:05:14 INFO - Revision ID: 0x0009 00:05:14 INFO - Displays: 00:05:14 INFO - Display: 00:05:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:05:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:05:14 INFO - Display Serial Number: 1600x1200 60 00:05:14 INFO - Main Display: Yes 00:05:14 INFO - Mirror: Off 00:05:14 INFO - Online: Yes 00:05:14 INFO - Rotation: Supported 00:05:14 INFO - Return code: 0 00:05:14 INFO - Running post-action listener: _resource_record_post_action 00:05:14 INFO - [mozharness: 2017-01-27 08:05:14.910646Z] Finished run-tests step (success) 00:05:14 INFO - Running post-run listener: _resource_record_post_run 00:05:14 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 38187520; Write bytes: 525544960; Read time: 329; Write time: 1680 00:05:14 INFO - TinderboxPrint: CPU usage
54.5% 00:05:14 INFO - TinderboxPrint: I/O read bytes / time
38,187,520 / 329 00:05:14 INFO - TinderboxPrint: I/O write bytes / time
525,544,960 / 1,680 00:05:14 INFO - TinderboxPrint: CPU idle
2,182.9 (45.5%) 00:05:14 INFO - TinderboxPrint: CPU system
969.6 (20.2%) 00:05:14 INFO - TinderboxPrint: CPU user
1,641.2 (34.2%) 00:05:14 INFO - TinderboxPrint: Swap in / out
238,624,768 / 0 00:05:14 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205283840; Write bytes: 198647808; Read time: 15673; Write time: 442 00:05:14 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36106752; Write bytes: 315936256; Read time: 311; Write time: 1202 00:05:15 INFO - Running post-run listener: _upload_blobber_files 00:05:15 INFO - Blob upload gear active. 00:05:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:05:15 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 00:05:15 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'] 00:05:15 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 00:05:15 INFO - (blobuploader) - INFO - Open directory for files ... 00:05:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:05:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:05:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:05:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:05:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:05:16 INFO - (blobuploader) - INFO - Done attempting. 00:05:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:05:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:05:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:05:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:05:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:05:17 INFO - (blobuploader) - INFO - Done attempting. 00:05:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:05:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:05:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:05:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:05:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:05:18 INFO - (blobuploader) - INFO - Done attempting. 00:05:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:05:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:05:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:05:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:05:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:05:20 INFO - (blobuploader) - INFO - Done attempting. 00:05:20 INFO - (blobuploader) - INFO - Iteration through files over. 00:05:20 INFO - Return code: 0 00:05:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:05:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:05:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/947afc1eb6343e8f79349d14f20d6856f2f10497f1b2afa531a3b1ec43b55fc8d16c807f4633b3bf71fe1ff4ac992adfc3c0096aa8b703f6288d627a7909e855", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ae11c7f94eaa3767aeafa173f071101963dbdc78581c9a48db01b989c8286dc8c175f1639408d44cf20f4b2bcbffbb8970f0a3f09e5f55780992cedb8acaaa1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/970b4b7ea6053a4745783c174bd8032bee79301ddc5c5e11d2612d91535d26349d1467b03c320fc607865ab9153626430ecd37df4ff291c5bcf799117243fcf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a383419eabe45caa603ce6ba092d542ed3163c747021d6a16c51018a03969d64a61f356473db335214979ba3f348ad5143596b98c076b5435be3b99913b85c13"} 00:05:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:05:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:05:20 INFO - Contents: 00:05:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/947afc1eb6343e8f79349d14f20d6856f2f10497f1b2afa531a3b1ec43b55fc8d16c807f4633b3bf71fe1ff4ac992adfc3c0096aa8b703f6288d627a7909e855", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ae11c7f94eaa3767aeafa173f071101963dbdc78581c9a48db01b989c8286dc8c175f1639408d44cf20f4b2bcbffbb8970f0a3f09e5f55780992cedb8acaaa1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/970b4b7ea6053a4745783c174bd8032bee79301ddc5c5e11d2612d91535d26349d1467b03c320fc607865ab9153626430ecd37df4ff291c5bcf799117243fcf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a383419eabe45caa603ce6ba092d542ed3163c747021d6a16c51018a03969d64a61f356473db335214979ba3f348ad5143596b98c076b5435be3b99913b85c13"} 00:05:20 INFO - Running post-run listener: copy_logs_to_upload_dir 00:05:20 INFO - Copying logs to upload dir... 00:05:20 INFO - mkdir: /builds/slave/test/build/upload/logs 00:05:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.618885 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-01-27 00:05:20.820795) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-27 00:05:20.824898) ========= 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.5vt4myWaM3/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.F2DYH7JI7e/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/947afc1eb6343e8f79349d14f20d6856f2f10497f1b2afa531a3b1ec43b55fc8d16c807f4633b3bf71fe1ff4ac992adfc3c0096aa8b703f6288d627a7909e855", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ae11c7f94eaa3767aeafa173f071101963dbdc78581c9a48db01b989c8286dc8c175f1639408d44cf20f4b2bcbffbb8970f0a3f09e5f55780992cedb8acaaa1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/970b4b7ea6053a4745783c174bd8032bee79301ddc5c5e11d2612d91535d26349d1467b03c320fc607865ab9153626430ecd37df4ff291c5bcf799117243fcf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a383419eabe45caa603ce6ba092d542ed3163c747021d6a16c51018a03969d64a61f356473db335214979ba3f348ad5143596b98c076b5435be3b99913b85c13"} build_url:https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011228 build_url: 'https://queue.taskcluster.net/v1/task/TFhmtp86QV-6eTOtBZJYQQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/947afc1eb6343e8f79349d14f20d6856f2f10497f1b2afa531a3b1ec43b55fc8d16c807f4633b3bf71fe1ff4ac992adfc3c0096aa8b703f6288d627a7909e855", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ae11c7f94eaa3767aeafa173f071101963dbdc78581c9a48db01b989c8286dc8c175f1639408d44cf20f4b2bcbffbb8970f0a3f09e5f55780992cedb8acaaa1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/970b4b7ea6053a4745783c174bd8032bee79301ddc5c5e11d2612d91535d26349d1467b03c320fc607865ab9153626430ecd37df4ff291c5bcf799117243fcf1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a383419eabe45caa603ce6ba092d542ed3163c747021d6a16c51018a03969d64a61f356473db335214979ba3f348ad5143596b98c076b5435be3b99913b85c13"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-27 00:05:20.860534) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-27 00:05:20.860877) ========= 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.5vt4myWaM3/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.F2DYH7JI7e/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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.005233 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-27 00:05:20.910481) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-27 00:05:20.910807) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-27 00:05:20.911148) ========= ========= Total master_lag: 0.18 =========