builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0266 starttime: 1481403379.86 results: success (0) buildid: 20161210112351 builduid: 24ea621872654573a7b1492f3ceb0bdc revision: 85efd4833716400d0ce85c2a30e408050afb1406 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:19.855390) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:19.855772) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:19.856049) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:20.089096) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:20.089359) ========= 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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-10 12:56:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 38.8M=0s 2016-12-10 12:56:20 (38.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.549767 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:20.674093) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:20.674446) ========= 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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.024647 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:20.723684) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-10 12:56:20.724041) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 85efd4833716400d0ce85c2a30e408050afb1406 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 85efd4833716400d0ce85c2a30e408050afb1406 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.siZAQ4zX4j/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.JzXzaHtLUB/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-10 12:56:20,818 truncating revision to first 12 chars 2016-12-10 12:56:20,818 Setting DEBUG logging. 2016-12-10 12:56:20,818 attempt 1/10 2016-12-10 12:56:20,818 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/85efd4833716?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-10 12:56:22,018 unpacking tar archive at: graphics-85efd4833716/testing/mozharness/ program finished with exit code 0 elapsedTime=1.462323 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-10 12:56:22.212556) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:22.212859) ========= script_repo_revision: 85efd4833716400d0ce85c2a30e408050afb1406 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:22.213261) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:22.213545) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-10 12:56:22.252832) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-12-10 12:56:22.253236) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.siZAQ4zX4j/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.JzXzaHtLUB/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:56:22 INFO - MultiFileLogger online at 20161210 12:56:22 in /builds/slave/test 12:56:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 12:56:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:56:22 INFO - {'append_to_log': False, 12:56:22 INFO - 'base_work_dir': '/builds/slave/test', 12:56:22 INFO - 'blob_upload_branch': 'Graphics', 12:56:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:56:22 INFO - 'branch': 'Graphics', 12:56:22 INFO - 'buildbot_json_path': 'buildprops.json', 12:56:22 INFO - 'config_files': ('talos/mac_config.py',), 12:56:22 INFO - 'default_actions': ('clobber', 12:56:22 INFO - 'read-buildbot-config', 12:56:22 INFO - 'download-and-extract', 12:56:22 INFO - 'populate-webroot', 12:56:22 INFO - 'create-virtualenv', 12:56:22 INFO - 'install', 12:56:22 INFO - 'run-tests'), 12:56:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:56:22 INFO - 'download_minidump_stackwalk': True, 12:56:22 INFO - 'download_symbols': 'ondemand', 12:56:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:56:22 INFO - 'tooltool.py': '/tools/tooltool.py', 12:56:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:56:22 INFO - '/tools/misc-python/virtualenv.py')}, 12:56:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:56:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:56:22 INFO - 'installer_path': 'installer.exe', 12:56:22 INFO - 'log_level': 'info', 12:56:22 INFO - 'log_name': 'talos', 12:56:22 INFO - 'log_to_console': True, 12:56:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:56:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:56:22 INFO - 'opt_config_files': (), 12:56:22 INFO - 'pip_index': False, 12:56:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:56:22 INFO - 'cmd': ('bash', 12:56:22 INFO - '-c', 12:56:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:56:22 INFO - 'enabled': True, 12:56:22 INFO - 'halt_on_failure': False, 12:56:22 INFO - 'name': 'check_screen_resolution'},), 12:56:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:56:22 INFO - 'cmd': ('bash', 12:56:22 INFO - '-c', 12:56:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:56:22 INFO - 'enabled': True, 12:56:22 INFO - 'halt_on_failure': False, 12:56:22 INFO - 'name': 'check_screen_resolution'},), 12:56:22 INFO - 'run_cmd_checks_enabled': True, 12:56:22 INFO - 'sps_profile': False, 12:56:22 INFO - 'sps_profile_interval': 0, 12:56:22 INFO - 'suite': 'dromaeojs-e10s', 12:56:22 INFO - 'system_bits': '32', 12:56:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:56:22 INFO - 'title': 't-yosemite-r7-0266', 12:56:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:56:22 INFO - 'use_talos_json': True, 12:56:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:56:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:56:22 INFO - 'work_dir': 'build'} 12:56:22 INFO - [mozharness: 2016-12-10 20:56:22.457225Z] Running clobber step. 12:56:22 INFO - Running pre-action listener: _resource_record_pre_action 12:56:22 INFO - Running main action method: clobber 12:56:22 INFO - rmtree: /builds/slave/test/build 12:56:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:56:24 INFO - Running post-action listener: _resource_record_post_action 12:56:24 INFO - [mozharness: 2016-12-10 20:56:24.936501Z] Finished clobber step (success) 12:56:24 INFO - [mozharness: 2016-12-10 20:56:24.936635Z] Running read-buildbot-config step. 12:56:24 INFO - Running pre-action listener: _resource_record_pre_action 12:56:24 INFO - Running main action method: read_buildbot_config 12:56:24 INFO - Using buildbot properties: 12:56:24 INFO - { 12:56:24 INFO - "project": "", 12:56:24 INFO - "product": "firefox", 12:56:24 INFO - "script_repo_revision": "default", 12:56:24 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 12:56:24 INFO - "repository": "", 12:56:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 12:56:24 INFO - "buildid": "20161210112351", 12:56:24 INFO - "pgo_build": "False", 12:56:24 INFO - "basedir": "/builds/slave/test", 12:56:24 INFO - "buildnumber": 9, 12:56:24 INFO - "slavename": "t-yosemite-r7-0266", 12:56:24 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:56:24 INFO - "platform": "yosemite_r7", 12:56:24 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 12:56:24 INFO - "branch": "graphics", 12:56:24 INFO - "repo_path": "projects/graphics", 12:56:24 INFO - "revision": "85efd4833716400d0ce85c2a30e408050afb1406", 12:56:24 INFO - "stage_platform": "macosx64", 12:56:24 INFO - "builduid": "24ea621872654573a7b1492f3ceb0bdc", 12:56:24 INFO - "slavebuilddir": "test" 12:56:24 INFO - } 12:56:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg. 12:56:24 INFO - Running post-action listener: _resource_record_post_action 12:56:24 INFO - [mozharness: 2016-12-10 20:56:24.939894Z] Finished read-buildbot-config step (success) 12:56:24 INFO - [mozharness: 2016-12-10 20:56:24.940014Z] Running download-and-extract step. 12:56:24 INFO - Running pre-action listener: _resource_record_pre_action 12:56:24 INFO - Running main action method: download_and_extract 12:56:24 INFO - mkdir: /builds/slave/test/build/tests 12:56:24 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')]} 12:56:24 INFO - trying https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json 12:56:24 INFO - Downloading https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json 12:56:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json'}, attempt #1 12:56:25 INFO - Downloaded 1235 bytes. 12:56:25 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json 12:56:25 INFO - Using the following test package requirements: 12:56:25 INFO - {u'common': [u'firefox-53.0a1.en-US.mac64.common.tests.zip'], 12:56:25 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:56:25 INFO - u'firefox-53.0a1.en-US.mac64.cppunittest.tests.zip'], 12:56:25 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:56:25 INFO - u'firefox-53.0a1.en-US.mac64.gtest.tests.zip'], 12:56:25 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:56:25 INFO - u'jsshell-mac64.zip'], 12:56:25 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:56:25 INFO - u'firefox-53.0a1.en-US.mac64.mochitest.tests.zip'], 12:56:25 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac64.common.tests.zip'], 12:56:25 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:56:25 INFO - u'firefox-53.0a1.en-US.mac64.reftest.tests.zip'], 12:56:25 INFO - u'talos': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:56:25 INFO - u'firefox-53.0a1.en-US.mac64.talos.tests.zip'], 12:56:25 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:56:25 INFO - u'firefox-53.0a1.en-US.mac64.web-platform.tests.zip'], 12:56:25 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 12:56:25 INFO - u'firefox-53.0a1.en-US.mac64.xpcshell.tests.zip']} 12:56:25 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac64.common.tests.zip'] for test suite category: common 12:56:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip 12:56:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip'}, attempt #1 12:56:25 INFO - Fetch https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip into memory 12:56:26 INFO - Expected file size: 16287270 12:56:26 INFO - Obtained file size: 16287270 12:56:33 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac64.common.tests.zip', u'firefox-53.0a1.en-US.mac64.talos.tests.zip'] for test suite category: talos 12:56:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip 12:56:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip'}, attempt #1 12:56:33 INFO - Fetch https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip into memory 12:56:34 INFO - Expected file size: 16287270 12:56:34 INFO - Obtained file size: 16287270 12:56:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip 12:56:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip'}, attempt #1 12:56:40 INFO - Fetch https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip into memory 12:56:41 INFO - Expected file size: 14409734 12:56:41 INFO - Obtained file size: 14409734 12:56: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')]} 12:56:41 INFO - trying https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 12:56:41 INFO - Downloading https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg 12:56:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg'}, attempt #1 12:56:43 INFO - Downloaded 69777274 bytes. 12:56:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 12:56:43 INFO - mkdir: /builds/slave/test/properties 12:56:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:56:43 INFO - Writing to file /builds/slave/test/properties/build_url 12:56:43 INFO - Contents: 12:56:43 INFO - build_url:https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 12:56:44 INFO - Running post-action listener: _resource_record_post_action 12:56:44 INFO - Running post-action listener: set_extra_try_arguments 12:56:44 INFO - [mozharness: 2016-12-10 20:56:44.523365Z] Finished download-and-extract step (success) 12:56:44 INFO - [mozharness: 2016-12-10 20:56:44.523487Z] Running populate-webroot step. 12:56:44 INFO - Running pre-action listener: _resource_record_pre_action 12:56:44 INFO - Running main action method: populate_webroot 12:56:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:56:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:56:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:56:44 INFO - '--tppagecycles', 12:56:44 INFO - '7'], 12:56:44 INFO - 'tests': ['tsvgm']}}, 12:56:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:56:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:56:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:56:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:56:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:56:44 INFO - 'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:56:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:56:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:56:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:56:44 INFO - 'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['damp', 'tps']}, 12:56:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:56:44 INFO - 'tests': ['damp', 'tps']}, 12:56:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['dromaeo_dom']}, 12:56:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:56:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['basic_compositor_video']}, 12:56:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:56:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['a11yr', 12:56:44 INFO - 'ts_paint', 12:56:44 INFO - 'tpaint', 12:56:44 INFO - 'sessionrestore', 12:56:44 INFO - 'sessionrestore_no_auto_restore', 12:56:44 INFO - 'tabpaint']}, 12:56:44 INFO - 'other-e10s': {'tests': ['a11yr', 12:56:44 INFO - 'ts_paint', 12:56:44 INFO - 'tpaint', 12:56:44 INFO - 'sessionrestore', 12:56:44 INFO - 'sessionrestore_no_auto_restore', 12:56:44 INFO - 'tabpaint']}, 12:56:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:56:44 INFO - 'ts_paint', 12:56:44 INFO - 'tpaint', 12:56:44 INFO - 'sessionrestore', 12:56:44 INFO - 'sessionrestore_no_auto_restore', 12:56:44 INFO - 'tabpaint']}, 12:56:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:56:44 INFO - 'ts_paint', 12:56:44 INFO - 'tpaint', 12:56:44 INFO - 'sessionrestore', 12:56:44 INFO - 'sessionrestore_no_auto_restore', 12:56:44 INFO - 'tabpaint']}, 12:56:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['a11yr', 12:56:44 INFO - 'ts_paint', 12:56:44 INFO - 'tpaint', 12:56:44 INFO - 'sessionrestore', 12:56:44 INFO - 'sessionrestore_no_auto_restore', 12:56:44 INFO - 'tabpaint']}, 12:56:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['a11yr', 12:56:44 INFO - 'ts_paint', 12:56:44 INFO - 'tpaint', 12:56:44 INFO - 'sessionrestore', 12:56:44 INFO - 'sessionrestore_no_auto_restore', 12:56:44 INFO - 'tabpaint']}, 12:56:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['tsvgx', 12:56:44 INFO - 'tsvgr_opacity', 12:56:44 INFO - 'tart', 12:56:44 INFO - 'tscrollx', 12:56:44 INFO - 'cart', 12:56:44 INFO - 'tsvg_static']}, 12:56:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:56:44 INFO - 'tsvgr_opacity', 12:56:44 INFO - 'tart', 12:56:44 INFO - 'tscrollx', 12:56:44 INFO - 'cart', 12:56:44 INFO - 'tsvg_static']}, 12:56:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:56:44 INFO - 'talos_options': ['--disable-e10s'], 12:56:44 INFO - 'tests': ['tp5o']}, 12:56:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:56:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:56:44 INFO - 'talos_options': ['--disable-e10s', 12:56:44 INFO - '--xperf_path', 12:56:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:56:44 INFO - 'tests': ['tp5n']}, 12:56:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:56:44 INFO - 'talos_options': ['--xperf_path', 12:56:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:56:44 INFO - 'tests': ['tp5n']}}, 12:56:44 INFO - 'talos.zip': {'path': '', 12:56:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:56:44 INFO - Running post-action listener: _resource_record_post_action 12:56:44 INFO - [mozharness: 2016-12-10 20:56:44.541481Z] Finished populate-webroot step (success) 12:56:44 INFO - [mozharness: 2016-12-10 20:56:44.541556Z] Running create-virtualenv step. 12:56:44 INFO - Running pre-action listener: _resource_record_pre_action 12:56:44 INFO - Running main action method: create_virtualenv 12:56:44 INFO - Creating virtualenv /builds/slave/test/build/venv 12:56:44 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 12:56:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:56:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:56:44 INFO - Using real prefix '/tools/python27' 12:56:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:56:45 INFO - Installing distribute.............................................................................................................................................................................................done. 12:56:48 INFO - Installing pip.................done. 12:56:48 INFO - Return code: 0 12:56:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:56:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:56:48 INFO - Reading from file tmpfile_stdout 12:56:48 INFO - Output received: 12:56:48 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) 12:56:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:56: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')]} 12:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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')]} 12:56:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107724570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc57b5857a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, '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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:56: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', 'pip>=1.5'] in /builds/slave/test/build 12:56: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 pip>=1.5 12:56:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:56:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:48 INFO - 'HOME': '/Users/cltbld', 12:56:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:48 INFO - 'LOGNAME': 'cltbld', 12:56:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:48 INFO - 'MOZ_NO_REMOTE': '1', 12:56:48 INFO - 'NO_EM_RESTART': '1', 12:56:48 INFO - 'PAGER': '/bin/cat', 12:56:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:48 INFO - 'PWD': '/builds/slave/test', 12:56:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:48 INFO - 'SHELL': '/bin/bash', 12:56:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:56:48 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:56:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:48 INFO - 'USER': 'cltbld', 12:56:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:48 INFO - 'XPC_FLAGS': '0x0', 12:56:48 INFO - 'XPC_SERVICE_NAME': '0', 12:56:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56: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 12:56:48 INFO - Cleaning up... 12:56:48 INFO - Return code: 0 12:56:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:56: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')]} 12:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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')]} 12:56:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56: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 0x1077ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107724570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc57b5857a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, '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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:56: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 12:56: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 12:56:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:56:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:48 INFO - 'HOME': '/Users/cltbld', 12:56:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:48 INFO - 'LOGNAME': 'cltbld', 12:56:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:48 INFO - 'MOZ_NO_REMOTE': '1', 12:56:48 INFO - 'NO_EM_RESTART': '1', 12:56:48 INFO - 'PAGER': '/bin/cat', 12:56:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:48 INFO - 'PWD': '/builds/slave/test', 12:56:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:48 INFO - 'SHELL': '/bin/bash', 12:56:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:56:48 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:56:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:48 INFO - 'USER': 'cltbld', 12:56:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:48 INFO - 'XPC_FLAGS': '0x0', 12:56:48 INFO - 'XPC_SERVICE_NAME': '0', 12:56:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:49 INFO - Downloading/unpacking psutil>=3.1.1 12:56:49 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 12:56:49 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 12:56:49 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 12:56:49 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 12:56:49 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 12:56:49 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 12:56:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:56:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:56:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:56:52 INFO - Installing collected packages: psutil 12:56:52 INFO - Running setup.py install for psutil 12:56:52 INFO - building 'psutil._psutil_osx' extension 12:56: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 12:56:53 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 12:56:53 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 12:56:53 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 12:56:53 INFO - building 'psutil._psutil_posix' extension 12:56: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 12:56:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:56:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:56:53 INFO - ^ 12:56:53 INFO - 1 warning generated. 12:56: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 12:56:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:56:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:56:53 INFO - Successfully installed psutil 12:56:53 INFO - Cleaning up... 12:56:54 INFO - Return code: 0 12:56:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:56:54 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')]} 12:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:54 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')]} 12:56:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:54 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 0x1077ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107724570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc57b5857a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, '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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:56:54 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 12:56:54 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 12:56:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:56:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:54 INFO - 'HOME': '/Users/cltbld', 12:56:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:54 INFO - 'LOGNAME': 'cltbld', 12:56:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:54 INFO - 'MOZ_NO_REMOTE': '1', 12:56:54 INFO - 'NO_EM_RESTART': '1', 12:56:54 INFO - 'PAGER': '/bin/cat', 12:56:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:54 INFO - 'PWD': '/builds/slave/test', 12:56:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:54 INFO - 'SHELL': '/bin/bash', 12:56:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:56:54 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:56:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:54 INFO - 'USER': 'cltbld', 12:56:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:54 INFO - 'XPC_FLAGS': '0x0', 12:56:54 INFO - 'XPC_SERVICE_NAME': '0', 12:56:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:56:54 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 12:56:54 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 12:56:54 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 12:56:54 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 12:56:54 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 12:56:54 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 12:56:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:56:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:56:56 INFO - Installing collected packages: mozsystemmonitor 12:56:56 INFO - Running setup.py install for mozsystemmonitor 12:56:57 INFO - Successfully installed mozsystemmonitor 12:56:57 INFO - Cleaning up... 12:56:57 INFO - Return code: 0 12:56:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:56:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:56:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1077ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107724570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc57b5857a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, '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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:56:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:56:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:56:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:56:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:57 INFO - 'HOME': '/Users/cltbld', 12:56:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:57 INFO - 'LOGNAME': 'cltbld', 12:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:57 INFO - 'MOZ_NO_REMOTE': '1', 12:56:57 INFO - 'NO_EM_RESTART': '1', 12:56:57 INFO - 'PAGER': '/bin/cat', 12:56:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:57 INFO - 'PWD': '/builds/slave/test', 12:56:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:57 INFO - 'SHELL': '/bin/bash', 12:56:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:56:57 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:56:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:57 INFO - 'USER': 'cltbld', 12:56:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:57 INFO - 'XPC_FLAGS': '0x0', 12:56:57 INFO - 'XPC_SERVICE_NAME': '0', 12:56:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:57 INFO - Downloading/unpacking jsonschema==2.5.1 12:56:57 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 12:56:57 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 12:56:57 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 12:56:57 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 12:56:57 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 12:56:57 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 12:57:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:57:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:57: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 12:57: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 12:57: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 12:57: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 12:57: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 12:57: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 12:57:00 INFO - Downloading functools32-3.2.3-2.tar.gz 12:57:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:57:00 INFO - warning: no files found matching '*.txt' 12:57:00 INFO - no previously-included directories found matching 'build' 12:57:00 INFO - no previously-included directories found matching 'dist' 12:57:00 INFO - no previously-included directories found matching '.git*' 12:57:00 INFO - Installing collected packages: jsonschema, functools32 12:57:00 INFO - Running setup.py install for functools32 12:57:00 INFO - warning: no files found matching '*.txt' 12:57:00 INFO - no previously-included directories found matching 'build' 12:57:00 INFO - no previously-included directories found matching 'dist' 12:57:00 INFO - no previously-included directories found matching '.git*' 12:57:00 INFO - Successfully installed jsonschema functools32 12:57:00 INFO - Cleaning up... 12:57:00 INFO - Return code: 0 12:57:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:57: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')]} 12:57:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:57:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57: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')]} 12:57:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:57:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57: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 0x1077ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107724570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc57b5857a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, '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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:57: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 12:57: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 12:57:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:57:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:57:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:57:00 INFO - 'HOME': '/Users/cltbld', 12:57:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:57:00 INFO - 'LOGNAME': 'cltbld', 12:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:57:00 INFO - 'MOZ_NO_REMOTE': '1', 12:57:00 INFO - 'NO_EM_RESTART': '1', 12:57:00 INFO - 'PAGER': '/bin/cat', 12:57:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:57:00 INFO - 'PWD': '/builds/slave/test', 12:57:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:57:00 INFO - 'SHELL': '/bin/bash', 12:57:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:57:00 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:57:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:57:00 INFO - 'USER': 'cltbld', 12:57:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:57:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:57:00 INFO - 'XPC_FLAGS': '0x0', 12:57:00 INFO - 'XPC_SERVICE_NAME': '0', 12:57:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:57:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:57:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:57:01 INFO - Cleaning up... 12:57:01 INFO - Return code: 0 12:57:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:57:01 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')]} 12:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:57:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57:01 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')]} 12:57:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:57:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57:01 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 0x1077ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107724570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc57b5857a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, '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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:57:01 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 12:57:01 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 12:57:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:57:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:57:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:57:01 INFO - 'HOME': '/Users/cltbld', 12:57:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:57:01 INFO - 'LOGNAME': 'cltbld', 12:57:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:57:01 INFO - 'MOZ_NO_REMOTE': '1', 12:57:01 INFO - 'NO_EM_RESTART': '1', 12:57:01 INFO - 'PAGER': '/bin/cat', 12:57:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:57:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:57:01 INFO - 'PWD': '/builds/slave/test', 12:57:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:57:01 INFO - 'SHELL': '/bin/bash', 12:57:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:57:01 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:57:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:57:01 INFO - 'USER': 'cltbld', 12:57:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:57:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:57:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:57:01 INFO - 'XPC_FLAGS': '0x0', 12:57:01 INFO - 'XPC_SERVICE_NAME': '0', 12:57:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:57:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:57:01 INFO - Downloading/unpacking blobuploader==1.2.4 12:57:01 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 12:57:01 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 12:57:01 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 12:57:01 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 12:57:01 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 12:57:01 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 12:57:03 INFO - Downloading blobuploader-1.2.4.tar.gz 12:57:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:57:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:57: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 12:57: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 12:57: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 12:57: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 12:57: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 12:57: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 12:57:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:57:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:57: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 12:57: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 12:57: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 12:57: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 12:57: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 12:57: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 12:57:05 INFO - Downloading docopt-0.6.1.tar.gz 12:57:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:57:05 INFO - Installing collected packages: blobuploader, requests, docopt 12:57:05 INFO - Running setup.py install for blobuploader 12:57:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:57:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:57:05 INFO - Running setup.py install for requests 12:57:05 INFO - Running setup.py install for docopt 12:57:06 INFO - Successfully installed blobuploader requests docopt 12:57:06 INFO - Cleaning up... 12:57:06 INFO - Return code: 0 12:57:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:57:06 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')]} 12:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:57:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57:06 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')]} 12:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:57:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57:06 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 0x1077ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107724570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc57b5857a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, '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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:57:06 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 12:57:06 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 12:57:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:57:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:57:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:57:06 INFO - 'HOME': '/Users/cltbld', 12:57:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:57:06 INFO - 'LOGNAME': 'cltbld', 12:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:57:06 INFO - 'MOZ_NO_REMOTE': '1', 12:57:06 INFO - 'NO_EM_RESTART': '1', 12:57:06 INFO - 'PAGER': '/bin/cat', 12:57:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:57:06 INFO - 'PWD': '/builds/slave/test', 12:57:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:57:06 INFO - 'SHELL': '/bin/bash', 12:57:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:57:06 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:57:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:57:06 INFO - 'USER': 'cltbld', 12:57:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:57:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:57:06 INFO - 'XPC_FLAGS': '0x0', 12:57:06 INFO - 'XPC_SERVICE_NAME': '0', 12:57:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:57:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:57:06 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-_USI8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:57:06 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-4YrJ0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:57:06 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-hoxTme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:57:06 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-jeAnrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:57:06 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-43lAUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:57:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:57:06 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-VhWIcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:57:07 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-x8MSLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:57:07 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-JADSRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:57:07 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-UTn_WE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:57:07 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-5x33sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:57:07 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-bvoamM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:57:07 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-dE7QED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:57:07 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-VVSEOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:57:07 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-UOUVh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:57:07 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-MKLt3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:57:08 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-WpDOsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:57:08 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-BXY3PJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:57:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:57:08 INFO - Running setup.py install for manifestparser 12:57:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:57:08 INFO - Running setup.py install for mozcrash 12:57:08 INFO - Running setup.py install for mozdebug 12:57:08 INFO - Running setup.py install for mozdevice 12:57:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:57:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:57:08 INFO - Running setup.py install for mozfile 12:57:08 INFO - Running setup.py install for mozhttpd 12:57:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:57:09 INFO - Running setup.py install for mozinfo 12:57:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:57:09 INFO - Running setup.py install for mozInstall 12:57:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:57:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:57:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:57:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:57:09 INFO - Running setup.py install for mozleak 12:57:09 INFO - Running setup.py install for mozlog 12:57:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:57:09 INFO - Running setup.py install for moznetwork 12:57:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:57:09 INFO - Running setup.py install for mozprocess 12:57:09 INFO - Running setup.py install for mozprofile 12:57:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:57:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:57:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:57:10 INFO - Running setup.py install for mozrunner 12:57:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:57:10 INFO - Running setup.py install for mozscreenshot 12:57:10 INFO - Running setup.py install for moztest 12:57:10 INFO - Running setup.py install for mozversion 12:57:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:57:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:57:10 INFO - Cleaning up... 12:57:10 INFO - Return code: 0 12:57:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:57: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')]} 12:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57: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')]} 12:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57: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 0x1077ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107724570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc57b5857a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, '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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:57: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 12:57: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 12:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:57:10 INFO - 'HOME': '/Users/cltbld', 12:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:57:10 INFO - 'LOGNAME': 'cltbld', 12:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:57:10 INFO - 'MOZ_NO_REMOTE': '1', 12:57:10 INFO - 'NO_EM_RESTART': '1', 12:57:10 INFO - 'PAGER': '/bin/cat', 12:57:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:57:10 INFO - 'PWD': '/builds/slave/test', 12:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:57:10 INFO - 'SHELL': '/bin/bash', 12:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:57:10 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:57:10 INFO - 'USER': 'cltbld', 12:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:57:10 INFO - 'XPC_FLAGS': '0x0', 12:57:10 INFO - 'XPC_SERVICE_NAME': '0', 12:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:57:11 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-EZhsAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:57:11 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)) 12:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:57:11 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-NbZDCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:57:11 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)) 12:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:57:11 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-65JzED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:57:11 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)) 12:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:57:11 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-FuCI3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:57:11 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)) 12:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:57:11 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-i4Mg1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:57: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)) 12:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:57:11 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-ClV_SN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:57: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)) 12:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:57:11 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-pxCasA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:57: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)) 12:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:57:11 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-IhzQTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:57: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)) 12:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:57:11 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-1yVQIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:57: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)) 12:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:57:12 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-jGgier-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:57:12 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)) 12:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:57:12 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-19Keeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:57:12 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)) 12:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:57:12 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-5lmbF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:57:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:57:12 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-0uU7H8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:57:12 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)) 12:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:57:12 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Vk4QHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:57: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)) 12:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:57:12 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-PE3aIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:57: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)) 12:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:57:12 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-AAvvLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:57:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:57:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:57:12 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-VV2oDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:57: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)) 12:57: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)) 12:57: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)) 12:57: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)) 12:57: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)) 12:57: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)) 12:57:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:57: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 12:57: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 12:57: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 12:57: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 12:57: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 12:57: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 12:57:15 INFO - Downloading blessings-1.6.tar.gz 12:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:57:15 INFO - Installing collected packages: blessings 12:57:15 INFO - Running setup.py install for blessings 12:57:15 INFO - Successfully installed blessings 12:57:15 INFO - Cleaning up... 12:57:15 INFO - Return code: 0 12:57:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:57:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:57:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:57:16 INFO - Reading from file tmpfile_stdout 12:57:16 INFO - Current package versions: 12:57:16 INFO - blessings == 1.6 12:57:16 INFO - blobuploader == 1.2.4 12:57:16 INFO - docopt == 0.6.1 12:57:16 INFO - functools32 == 3.2.3-2 12:57:16 INFO - jsonschema == 2.5.1 12:57:16 INFO - manifestparser == 1.1 12:57:16 INFO - mozInstall == 1.12 12:57:16 INFO - mozcrash == 1.0 12:57:16 INFO - mozdebug == 0.1 12:57:16 INFO - mozdevice == 0.49 12:57:16 INFO - mozfile == 1.2 12:57:16 INFO - mozhttpd == 0.7 12:57:16 INFO - mozinfo == 0.9 12:57:16 INFO - mozleak == 0.1 12:57:16 INFO - mozlog == 3.4 12:57:16 INFO - moznetwork == 0.27 12:57:16 INFO - mozprocess == 0.23 12:57:16 INFO - mozprofile == 0.28 12:57:16 INFO - mozrunner == 6.13 12:57:16 INFO - mozscreenshot == 0.1 12:57:16 INFO - mozsystemmonitor == 0.3 12:57:16 INFO - moztest == 0.7 12:57:16 INFO - mozversion == 1.4 12:57:16 INFO - psutil == 3.1.1 12:57:16 INFO - requests == 1.2.3 12:57:16 INFO - wsgiref == 0.1.2 12:57:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57:16 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 0x1077ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107724570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc57b5857a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, '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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:57:16 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 12:57:16 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 12:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:57:16 INFO - 'HOME': '/Users/cltbld', 12:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:57:16 INFO - 'LOGNAME': 'cltbld', 12:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:57:16 INFO - 'MOZ_NO_REMOTE': '1', 12:57:16 INFO - 'NO_EM_RESTART': '1', 12:57:16 INFO - 'PAGER': '/bin/cat', 12:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:57:16 INFO - 'PWD': '/builds/slave/test', 12:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:57:16 INFO - 'SHELL': '/bin/bash', 12:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:57:16 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:57:16 INFO - 'USER': 'cltbld', 12:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:57:16 INFO - 'XPC_FLAGS': '0x0', 12:57:16 INFO - 'XPC_SERVICE_NAME': '0', 12:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:57:16 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)) 12:57:16 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)) 12:57:16 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)) 12:57:16 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)) 12:57:16 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)) 12:57:16 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)) 12:57:16 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)) 12:57:16 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)) 12:57:16 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)) 12:57:16 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)) 12:57:16 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)) 12:57:16 INFO - Cleaning up... 12:57:16 INFO - Return code: 0 12:57:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:57:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077ae3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107724570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc57b5857a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, '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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 12:57:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:57:16 INFO - 'HOME': '/Users/cltbld', 12:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:57:16 INFO - 'LOGNAME': 'cltbld', 12:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:57:16 INFO - 'MOZ_NO_REMOTE': '1', 12:57:16 INFO - 'NO_EM_RESTART': '1', 12:57:16 INFO - 'PAGER': '/bin/cat', 12:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:57:16 INFO - 'PWD': '/builds/slave/test', 12:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:57:16 INFO - 'SHELL': '/bin/bash', 12:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:57:16 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:57:16 INFO - 'USER': 'cltbld', 12:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:57:16 INFO - 'XPC_FLAGS': '0x0', 12:57:16 INFO - 'XPC_SERVICE_NAME': '0', 12:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:57:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:57:16 INFO - Cleaning up... 12:57:16 INFO - Return code: 0 12:57:16 INFO - Running post-action listener: _resource_record_post_action 12:57:16 INFO - Running post-action listener: _start_resource_monitoring 12:57:16 INFO - Starting resource monitoring. 12:57:16 INFO - [mozharness: 2016-12-10 20:57:16.653355Z] Finished create-virtualenv step (success) 12:57:16 INFO - [mozharness: 2016-12-10 20:57:16.654355Z] Running install step. 12:57:16 INFO - Running pre-action listener: _resource_record_pre_action 12:57:16 INFO - Running main action method: install 12:57:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:57:16 INFO - Reading from file tmpfile_stdout 12:57:16 INFO - Detecting whether we're running mozinstall >=1.0... 12:57:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:57:17 INFO - Reading from file tmpfile_stdout 12:57:17 INFO - Output received: 12:57:17 INFO - Usage: mozinstall [options] installer 12:57:17 INFO - Options: 12:57:17 INFO - -h, --help show this help message and exit 12:57:17 INFO - -d DEST, --destination=DEST 12:57:17 INFO - Directory to install application into. [default: 12:57:17 INFO - "/builds/slave/test"] 12:57:17 INFO - --app=APP Application being installed. [default: firefox] 12:57:17 INFO - mkdir: /builds/slave/test/build/application 12:57:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg', '--destination', '/builds/slave/test/build/application'] 12:57:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg --destination /builds/slave/test/build/application 12:57:39 INFO - Reading from file tmpfile_stdout 12:57:39 INFO - Output received: 12:57:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:57:39 INFO - Running post-action listener: _resource_record_post_action 12:57:39 INFO - [mozharness: 2016-12-10 20:57:39.240123Z] Finished install step (success) 12:57:39 INFO - [mozharness: 2016-12-10 20:57:39.240261Z] Running run-tests step. 12:57:39 INFO - Running pre-action listener: _resource_record_pre_action 12:57:39 INFO - Running main action method: run_tests 12:57:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:57:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:57:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:57:39 INFO - 2016-12-10 12:57:39.254 screenresolution[1633:8920] starting screenresolution argv=screenresolution get 12:57:39 INFO - 2016-12-10 12:57:39.257 screenresolution[1633:8920] Display 0: 1600x1200x32@60 12:57:39 INFO - 2016-12-10 12:57:39.262 screenresolution[1634:8923] starting screenresolution argv=screenresolution list 12:57:39 INFO - Available Modes on Display 0 12:57:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:57:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:57:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:57:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:57:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:57:39 INFO - Intel Iris: 12:57:39 INFO - Chipset Model: Intel Iris 12:57:39 INFO - Type: GPU 12:57:39 INFO - Bus: Built-In 12:57:39 INFO - VRAM (Dynamic, Max): 1536 MB 12:57:39 INFO - Vendor: Intel (0x8086) 12:57:39 INFO - Device ID: 0x0a2e 12:57:39 INFO - Revision ID: 0x0009 12:57:39 INFO - Displays: 12:57:39 INFO - Display: 12:57:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:57:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:57:39 INFO - Display Serial Number: 1600x1200 60 12:57:39 INFO - Main Display: Yes 12:57:39 INFO - Mirror: Off 12:57:39 INFO - Online: Yes 12:57:39 INFO - Rotation: Supported 12:57:39 INFO - Return code: 0 12:57:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:57:39 INFO - Python 2.7.3 12:57:39 INFO - Return code: 0 12:57:39 INFO - grabbing minidump binary from tooltool 12:57:39 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')]} 12:57:39 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 0x1075c3d30>, '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 0x10742adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1075bbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:57:39 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 12:57:39 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 12:57:39 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 12:57:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:57:39 INFO - Return code: 0 12:57:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:57:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:57:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:57:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:57:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:57:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 12:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip --title t-yosemite-r7-0266 --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 12:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.siZAQ4zX4j/Render', 12:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:57:39 INFO - 'HOME': '/Users/cltbld', 12:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:57:39 INFO - 'LOGNAME': 'cltbld', 12:57:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:57:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:57:39 INFO - 'MOZ_NO_REMOTE': '1', 12:57:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:57:39 INFO - 'NO_EM_RESTART': '1', 12:57:39 INFO - 'PAGER': '/bin/cat', 12:57:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:57:39 INFO - 'PWD': '/builds/slave/test', 12:57:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:57:39 INFO - 'SHELL': '/bin/bash', 12:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JzXzaHtLUB/Listeners', 12:57:39 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 12:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:57:39 INFO - 'USER': 'cltbld', 12:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:57:39 INFO - 'XPC_FLAGS': '0x0', 12:57:39 INFO - 'XPC_SERVICE_NAME': '0', 12:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:57:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 12:57:39 INFO - mozversion application_buildid: 20161210112351 12:57:39 INFO - mozversion application_changeset: 85efd4833716400d0ce85c2a30e408050afb1406 12:57:39 INFO - mozversion application_display_name: Nightly 12:57:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:57:39 INFO - mozversion application_name: Firefox 12:57:39 INFO - mozversion application_remotingname: firefox 12:57:39 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 12:57:39 INFO - mozversion application_vendor: Mozilla 12:57:39 INFO - mozversion application_version: 53.0a1 12:57:39 INFO - mozversion platform_buildid: 20161210112351 12:57:39 INFO - mozversion platform_changeset: 85efd4833716400d0ce85c2a30e408050afb1406 12:57:39 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 12:57:39 INFO - mozversion platform_version: 53.0a1 12:57:39 INFO - using testdate: 1481403459 12:57:39 INFO - actual date: 1481403459 12:57:39 INFO - starting webserver on 'localhost:49241' 12:57:39 INFO - SUITE-START | Running 2 tests 12:57:39 INFO - TEST-START | dromaeo_css 12:57:39 INFO - operating with platform_type : mac_ 12:57:39 INFO - Initialising browser for dromaeo_css test... 12:57:39 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmp5rHT_a/profile http://localhost:49241/getInfo.html) 12:57:42 INFO - PROCESS | 1644 | 2016-12-10 12:57:42.767 plugin-container[1646:9084] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 12:57:42 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:57:42 INFO - PROCESS | 1644 | 2016-12-10 12:57:42.770 plugin-container[1646:9084] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 12:57:42 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:57:42 INFO - PROCESS | 1644 | 2016-12-10 12:57:42.770 plugin-container[1646:9084] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:57:42 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 12:57:42 INFO - PROCESS | 1644 | colorDepth:24 12:57:42 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 12:57:42 INFO - PROCESS | 1644 | __metrics 12:57:42 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 12:57:43 INFO - TEST-INFO | 1644: exit 0 12:57:43 INFO - Browser initialized. 12:57:43 INFO - Running cycle 1/1 for dromaeo_css test... 12:57:43 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmp5rHT_a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:57:45 INFO - PROCESS | 1648 | 2016-12-10 12:57:45.893 plugin-container[1649:9209] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 12:57:45 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:57:45 INFO - PROCESS | 1648 | 2016-12-10 12:57:45.893 plugin-container[1649:9209] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 12:57:45 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:57:45 INFO - PROCESS | 1648 | 2016-12-10 12:57:45.893 plugin-container[1649:9209] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:57:55 INFO - PROCESS | 1648 | RSS: Main: 236978176 12:57:55 INFO - PROCESS | 1648 | 12:58:45 INFO - PROCESS | 1648 | 1481403525158 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:00:59 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 13:01:00 INFO - PROCESS | 1648 | RSS: Main: 255946752 13:01:00 INFO - PROCESS | 1648 | 13:04:03 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 13:04:04 INFO - PROCESS | 1648 | RSS: Main: 267587584 13:04:04 INFO - PROCESS | 1648 | 13:07:06 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 13:07:06 INFO - PROCESS | 1648 | RSS: Main: 273641472 13:07:06 INFO - PROCESS | 1648 | 13:10:08 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 13:10:09 INFO - PROCESS | 1648 | RSS: Main: 272441344 13:10:09 INFO - PROCESS | 1648 | 13:13:12 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 13:13:13 INFO - PROCESS | 1648 | RSS: Main: 277934080 13:13:13 INFO - PROCESS | 1648 | 13:16:15 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 13:16:16 INFO - PROCESS | 1648 | RSS: Main: 280924160 13:16:16 INFO - PROCESS | 1648 | 13:16:16 INFO - PROCESS | 1648 | __start_tp_report 13:16:16 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 13:16:16 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 13:16:16 INFO - PROCESS | 1648 | |i|pagename|runs| 13:16:16 INFO - PROCESS | 1648 | |0;dojo.html;3286.8003178839717;3333.0500807269927;3334.516495108208;3343.849526771295;3345.414552453321;5488.835334939959;5530.668159910403;5544.334679838415;5580.832575022756;5626.240457538231;5461.0716178249695;5572.219889215513;5582.106862901936;5600.803971860985;5601.131824567188;5085.262636917645;5110.770015349318;5189.299444574972;5191.610629202806;5210.452902445246;5111.156659151254;5189.377274727038;5220.008198442303;5233.1103712368795;5240.764165612538;5211.5309622134;5212.1660534314515;5213.322268105141;5213.713245771481;5228.503292187236;5188.610854185951;5196.428392876781;5207.010667973111;5210.713410762407;5245;5038.84883453497;5162.870928226786;5168.3539557555305;5183.714895680736;5233.031889100528;5580.63725857822;5583.776648934037;5629.408912064257;5664.57515686321;5685.545156387479;4751.95248047522;4798.992211635561;6114.266288045463;6125.754969801203;6126.509879209652;5064.898702025939;6070.878582428327;6102.731632724008;6130.489860652512;6143;5716.228309178291;5726.169705392736;5728.169415434739;5756.8848623027725;5777.884442311172;6216.937830621636;6251.843703907348;6259.593126446766;6266.717997690115;6272.2159730033745;4571.428571428572;5907.379725128885;5949.196858424144;6048.123022161762;6069.514438844882;6036.3360030396625;6191.442770150708;6194.845128871814;6195.907061394083;6227.346128656426;6049.5462840287155;6123.173371594867;6163;6172.444479996821;6214.316425193225;5926.377730338339;6051.425114614105;6123.846903827351;6133.417325354084;6154.5384096192965;4830.903381932412;4839.516048395132;4854.17479028566;4855.6601037927;4877.414710234723;4269.87190384289;4289.163613095417;4314.525402205755;4319.200947824663;4323.200207961537;5712.771489140471;5723.656580605177;5725.427457254241;5763.538916886639;5803.216565763652;6113.174721412642;6123.4488895999575;6134.417230363198;6148.415900489446;6162.075688646739;5575.470330318694;5639.436056394327;5645.717714114277;5692.345380281238;5702.372738998707;5305.151175811851;5310.548603368679;5333.2266821310695;5360.142377219626;5441.891162176735;6265.467435268053;6272.278687950944;6273.749050037947;6289.811305660837;6293.150424692608;6154.474012950098;6226.346233645402;6251.71867265965;6253.7185826637;6262.15460912771;6155.415235552616;6156.076588511759;6174.166487524127;6206.472449841814;6217.626942383471;6139.8465038372615;6143.416375444325;6161.661108639068;6176.536759742948;6179.258488981351;6131.908021379772;6140.8771824564155;6211.9068213977735;6240.53196010292;6246.3753624637175;5322.946770532245;5858.179854820243;5893.941060589511;5991.460768530853;6109.2974307955155;5856.853578660568;5879.941200587939;5893.764249430146;5902.996490596523;5955.940440595538;5633.183188437735;5811.56413268998;5813.331466881363;5820.563457740602;5821.679807610622;5841.853953651193;5913.05391137416;5928.318243402064;5960.254968128984;6030.638161710312;5740.598158128891;5777.62445441045;5837.416258374129;5869.4717475427415;5875.50058245054 13:16:16 INFO - PROCESS | 1648 | |1;ext.html;447.52785810969414;458.86004768545604;478.2300496201118;480.81248313157863;509.31242822190046;36520.634793652054;38645.84062478127;38785;38940.805295973514;38996;38588.42117368235;38964.02589935253;39032.829015129595;39090.8045459773;39153.21693566127;8355.749327520154;8613.913860861374;9177.77055573611;9409.388389754678;9440.905590944105;11002.449877506133;11335.659930202066;11348.14888883333;11466.025387842043;11481.253718508271;11280.210385273034;11323.943380283086;11343;11347.092232621411;11379.374134422646;9393.95303023484;11244.550217991271;11274.774504509905;11274.830877536844;11280.548778048867;11062;11169.72075698106;11173.050290725298;11174.329540227613;11177.049950754195;37182.256354873025;37281.81359093187;37341;37453.25093498142;37694.24611507754;3327.9833600832085;3345.8996230113135;3450.051235910145;3548.0738102945834;3556.8399422025814;28628.856855715796;28796.424071518453;29501.852490737623;29628;29641.40717185644;3162.794418362799;3191.936161276785;3224.7581431392505;3271.3620843935205;3283.0315057057987;438748;442389;443930;445469;445472;54262.64343391447;54523.72738136284;54557.72721136369;54697.72651136798;54772.9999999992;57318.713406432704;57443.42556574464;57463.71268143632;57574.7121264391;57742;15086.924565377103;15660.608484787743;15676.92161539185;15697.058176509447;15712.68574628501;10980.396078215776;11191.216614837045;11953.22304050234;12128.393580320948;12229.93885030569;14663.633409164642;14863;14871.405143794127;14906.776398354034;15045.172515511533;5876.001079816441;6554.475641948633;6682.365175308338;6692.423416984496;6724.193096828314;828.4325237212529;994.5623925472769;1056.5668076088766;1063.7234319077095;1136.9886301136883;27576.724232757817;28570.00004999815;28992.710072899423;29050.709492905222;29708.405831883672;1039.261545826737;1040.8230600797883;1092.5029111754134;1101.4217535793773;1210.6005018343926;12162.574309899288;14458.277086145861;15038.097714137186;15288.082715037133;15463.304151312994;500980;504668;585366;593628;606153.907691385;55115.622109447584;55503.72248138814;55591.722041389534;55621.72189139028;55787.16319255214;1366.8086467894502;1372.6431127906817;1394.8326200855963;1396.218117854005;1396.427464739452;23240.3027909163;28038.43923121567;29001;29557;29569.408611828072;47220.52779472162;47221.05557888892;47292;47301.29048064213;47303.05393892034;487847;517105;571028;572055;575097;23251.069957201522;23926.282211533682;24030.39924001914;24631.630525541772;37649.4352584706;31965.520517191795;39128.804355978034;39368.80315598518;39642;39741.00647483836;33467.163320916196;33493.330133397685;33791.83104084464;33855.322893541495;34523.65476345205;33584;33600.663993359754;34195.82902085473;34516.82741586276;34722.65277347194;32389.028329150162;32561.511577325884;32898.83550582232;32945.081532877404;33069.50395744114;27613.585796212672;29186;30299.545506817856;30366.089017329516;30372;22842.885785570965;23108.537829243658;23216.924244239122;23373.649395259425;23379.415514612272 13:16:16 INFO - PROCESS | 1648 | |2;jquery.html;307.2748313979009;351.90850378901484;379.07316610886375;382.9215010922761;398.6883154421952;17767.46697599071;19394.90302548487;20542.794572054274;20798.272060477866;21049.842258675766;19728.70406943897;20088.296909608165;20240;20327;20520.076596553154;5230.010679601961;5281.947180528184;5537.99999999999;5880.647161170322;5880.882382352351;5350.438203988564;5853;5854.20968169296;5859.355470898219;5863.9706801466145;5348.759305831247;5611.130274807411;5774.7401366938375;5805.042168042268;5813.854653633651;4937.308776771255;5649.661020338773;5652.971735141318;5681.914771278434;5721.856953576153;5331.2269720890645;5508.338999320067;5575.330960284751;5644.8306550803545;5652.463016013472;10804.621838235702;12659.683507912283;12685.619431417072;12810.935945320214;12969.092163548556;2180.890955452237;2286.1084177170915;2309.399556115405;2701.3651791828906;3250.512423136525;13335.666608334772;14843.628909277246;15516.689666206612;15663.765043524356;16500.917495412566;1859.9907000465046;1976.2984140630156;2047.4471892588917;2049.938501844947;2051.415346626219;88402;99967;102140;105891;111557.44221278842;16401.343946242254;17050.82949170517;17279.91360043217;17280.22238999248;18300;33698.49452258218;34068;34108.48837267443;34169.65830341714;34177.829110854786;8962.103789621116;10245.385276883411;10417;10640.78718425627;10794.974477424632;8517.701880434279;8544.530050847263;8607.870881936777;8621.482711037357;8830.249428798625;9332.626694932127;9568.09103135201;9685.9031409685;9708.854367184498;9713.27150463704;3160.3995240904155;3337.5327454156436;3517.577890653087;3690.8033012516207;3722.813859307024;810.7473952743026;832.2800777327689;922.9861552076724;951.1819834941945;1040.47976011994;16870.831291686925;20369;20648.277310294066;21299.8935005324;21327.573448530944;834.6369329341755;880.9427387219811;920.6915683246187;968.4334664221368;970.1042388470411;10879.782404351869;11242.437878106062;11283.548658053585;11294.265872718248;11420.94289528547;561730;568015;574410;578188;580081;101184.97630047357;105266;109791;112558;124650;955.1403736636944;972.1687956796978;1004.8944860789658;1007.189212683782;1012.7468132966758;15763.605909851887;19016.71474927905;19040;19094.809051909302;19127.71308430347;41230.793846031775;46980.53019469762;50947.76695643569;51232.97534049373;52663;73471.63264183645;73907.63046184735;74402.62798685972;74940.87588686284;75638.24361756312;6929.757458488929;7989.7203597873795;8023.077346105039;8051.235132662622;8154.551499667338;10165.542550585093;10846.511906964048;12170.026397888325;12252.693682657648;12606.243625382507;8682.913170868464;10663.626773062906;10734.516946737573;10770.299930504492;10995.835062473909;10545.894541054799;10549.78900421972;10720.195985300978;10914.290571112851;11030.22788404804;9475.24198064167;10783.568657253936;11089.778204436028;11114.444277785917;11236.83144752813;7874.409419293691;7916.406269529693;7917.683292668229;7927.960360198162;8049.83900322002;8081.313088387553;8707.912920870964;10146.391216527032;10416;10983.176261780558 13:16:16 INFO - PROCESS | 1648 | |3;mootools.html;1468.7356275870343;1486.7472529669956;1675.6313611005573;1741.939032133875;1747.5019619408472;7492.7377541786045;7719.652615632296;7915.564643944588;8331.333493320535;8373.743938409234;7836.568988705619;8098.716544920929;8125.024997000353;8204.794880128;8212.384071194654;2262.242148880127;2320.211128216406;2322.628379459287;2327.9185228517003;2328.359701082199;2285.3715228312262;2290.061074959267;2300.907963681451;2328.9985306318276;2332.88335583221;1949.4054313434397;2263.7057182566246;2269.1944359752297;2276.112316196685;2279.908803647852;2266.195500597289;2274.613315736329;2298.9655155172686;2300.5168914527885;2307.596170670143;2202.394341556069;2268.625676763332;2270.103309192876;2277.9544409111727;2281.178775640768;8131.878021829677;8157.857899894019;8171.550564718938;8199.42604017719;8201.79495512611;4988.700677959334;5134.897302053975;5158.045761534128;5161.896762064737;5169.793208271664;3206.64726880043;3235.8058516489086;3451.3960056990177;3487.5989261235027;3512.8067956262394;1035.4977835749655;1045.811753884308;1057.0856209378908;1079.9190060745398;1082.5020490574345;10598.682039538826;10600.734981625445;10662.946685266525;10732.980366865213;10760.139188864872;4136.482939632546;4270.850520231839;4410.272305069628;4509.34614480905;4637.86086417408;9033.277337812959;9091.545422728837;9117.908820911838;9130.726078217664;9157.450552966844;5102.948970510248;5127.307813445137;5134.358205224347;5135.998480296306;5136.049830781318;4233.872983810491;4250.086231460251;4542.432195975503;4634.86095417138;4693.037927224911;3371.9325613488086;3442.3631628148873;3707.4994875691978;3746.8501259949844;3895.35726605107;1044.8119338518989;1062.219268837404;1110.9611163609236;1147.4148184426003;1247.8939290160438;679.7960611816534;880.9427387219938;881.0968757024101;904.5432056811268;912.1380295620708;4680.906381872412;4998.875028124326;5573.219749235111;5742.827715168552;5862;879.2262808728278;879.9736007919772;891.6121487153109;916.1800188830958;922.8338898998245;3637.0907273181706;3754.3992961126637;3816.5687867049855;3846.538415390115;3929.351656976624;9379.812403751886;9837.508124593885;9854.718886544702;9867.161291290176;9874.901250987541;9776.364536305118;9777.120059194707;9787.559559819825;9794.118529332394;9811.362261452983;7333.926660733431;7536.886946695804;7710.653020614086;7714.382849372037;7716;5375.838724838261;5417.1061774808295;5527.115661494142;5565.944340556543;5600.887982240414;9135.908640913505;9245.58394872219;9280.675176368795;9298.72103836886;9388;9093.22707569864;9175.632974680939;9287;9361.063893610584;9397.671081512115;6576.342365763577;6633.402993730588;6647.700853461509;6663.766768163091;6732.259451460433;7639.847203055797;7720.652570634447;7742.032245969254;7747.186545412876;7762.650680719494;6680.832726570363;6856.5200435969;6906.896396554156;7014.579125252501;7054.788356349318;6669.166354205724;6687.732490700318;6874.278200788845;6874.9656251718425;6960.47796415281;5725.914111288417;6491.188601424822;6607.075009498578;6676.632785196861;6684.4652427804685;3993.7204395692024;4695.413073365829;4962.6774259673;5019.924701129413;5107.872303192301;4063.6952228583564;4766.904661906673;5098.515641014098;5161.7677204525135;5175.301334319819 13:16:16 INFO - PROCESS | 1648 | |4;prototype.html;351.3517560101612;358.38894684562814;364.47515577568316;367.9392900171472;369.6580662886831;5282.339707536558;5382.865428364293;5516;5602.355729091156;5635.492805647496;4779.402574678165;4894.804207831682;5258.237555554442;5548.835953977952;5558.333000039991;3815.790131542764;4140.846759684962;4310.612044915957;4328.740275583459;4370.366296886949;3965.8611948581806;4231.7037807353345;4340.9565904341025;4354.216241076605;4369.012099494722;3601.549806274216;3763.2473505298913;4031.5284921003918;4137.710360274782;4201.159768046387;3767.9434808477895;4151.667866570667;4167.854125105607;4173.791310434496;4184.205001049791;3609.9097522561883;3632.745707800455;3640.199156185661;3641.4509843109663;3645.4167333226555;5055.873603159914;5260.922360419159;5286.36563612369;5302.496262855023;5378.273933019032;4710.199266124767;4736.739479328636;4782.689125206851;4788.318541616706;4789.557325763851;5642.802501912454;5803.274590676166;5811.506021988136;5831.154482599999;5883.911741323884;3589.766665166756;3739.6066517428867;3906.882793516199;3914.9267824757712;3922.705797065203;5635.435876676102;6148.188123363499;6159.568830181889;6161.445469907685;6204.317525072239;4002.3396139636634;4009.398590211433;4015.638592526687;4017.6183262590007;4033.798310084484;3335.533025376449;3371.578552680915;3991.4212439196735;4018.33697439925;4078.225137223918;4525.208088584484;4703.976480117578;4704.764761761967;4712.363830882787;4767.6662633615315;5820.912686309709;5888.617239879394;5911.379305172981;5915.674637894957;5917.289925208916;1114.4817659788075;1148.4831825678477;1283.9807402888964;1285.7685616589104;1298.9350532473338;857.4126723194631;874.3660845886808;887.8401887660283;895.5790778334173;900.6082354175954;4681.016986432819;5240.842774716764;5270.7628156733035;5288.550473209743;5301.920471192935;5493.956148331804;5519.779208831602;5658.321001479849;5784.768609255582;5798.536117110705;4896.681715688469;5032.370953630796;5209.609279304067;5221.425643179247;5251.921221181686;2497.637842512862;2637.3802156493207;2756;3027.576139340494;3046.2232130806947;5289.523942845162;5730.885382292414;5806.670728905513;5974.982633769919;6016.374689665177;5782.59521833476;5826.388229235869;5920.851978700481;6226.661267827439;6258.780942666985;6191.442770150708;6298.905516417258;6320.209973753281;6336.493080553545;6343.55595108338;4571.971306456021;5055.443901170795;5108.59131269505;5277.28756617867;6064.3935606439;5034.546890779848;5108.361454818148;5576.940381327535;6006.249218847644;6013.699315034143;5554.750036248298;6326.051092336186;6352.428281454691;6357.745690172526;6439.581427207217;3578.695810856002;3780.2593119987787;3982.064214909552;3990.4612877261197;4023.75857448554;5209.817656382009;5268.710220937885;6051.33435322123;6063.393660633901;6075.878482430415;5404.541093699957;5766.308043034863;5796.246487956539;5975.223220981418;5993.943089024541;4966.279889415942;5013.423456302572;5027.296178535081;5483.643563168382;5641.351244606923;4294.677899157513;5730.426957304237;5756.158029430542;5823.864346452401;5866.2373891395555;4802.638153292779;4982.676126051795;5882.911756323744;5906.232189815297;5932.8813423732145;4510.233260345814;5373.650712703662;5633.338165241182;5700.714964251688;5770.662881532986 13:16:16 INFO - PROCESS | 1648 | |5;yui.html;635.1191096423981;649.8245473722098;681.9829504262395;803.3894240377311;814.8125931035862;14415.495457658984;15334.923325383385;15349.079055256692;15408.69182616347;15427.460038898627;13666.38501267443;13848.930755346235;13960.581182564514;13982.091164074329;14010.719785604282;848.6011574559963;860.5181098584782;869.395769939892;872.3849685971406;878.2447095497866;868.2446271743592;870.6256309786789;872.4459967920353;877.1666916429385;878.2798105553449;915.2997956563235;916.2990312411011;916.3860213656866;917.6421195733669;920.185635712396;775.9068911730628;901.3510272603714;903.5391950105461;903.9367244292902;905.1672461335587;667.0960848050918;680.5474359550942;680.7277089164324;681.6421378776132;682.0212994353119;12690.87309126906;12900.677483062904;12931.159474634213;13258.535951241756;13467.66330841727;9724.75688107796;11722.765544689144;11917.046636269077;12052.096092793076;12174.147809653328;10489.21330900178;10520.894791052067;10540.314879532805;10745.51645175969;10884.782304353868;3501.77238479498;3590.622984586633;3597.1546686528645;3598.334308152974;3602.6937710294656;111429;112716.4364178182;114435;114660.85339146585;114754.42622786833;8659.696910608098;8750.912490875136;8838.116188381238;8959.23846473044;9171.63313467454;18907.43277701671;19386.321478748177;19449;19472.513187170433;19490.31783887557;7739.845203095907;7761.262680045386;7850.528968261922;7873.724419645285;8004.679812807422;4241.87274381769;4295.420118284054;4301.2472817257485;4310.053813618251;4322.546132656089;3038.9696103038686;3439.94840077399;3584.5160903277724;3599;3628.9092772680365;620.426105852085;644.5810223354763;650.1385663995226;663.556764038215;709.8048036789924;626.7305058824658;633.3191818794814;650.8047585724264;676.8713944350657;683.719674933275;7682.577458239851;7971.920280797234;8036.196380362034;8440.53577053268;9702.641630171782;624.9126519855419;625.4246093593905;626.589583822597;667.512715717529;683.5591043776736;4239.851605193803;4565.474970378449;4583.9083218335445;4592.288195329729;4702.694324868872;62036;103169.90490285303;103191;104418.47790760998;107097.92902071035;70332;79321.81017284746;92670.53664731633;93975.53012235032;96664;69678;80004.59997699974;80256;80744;82028;7916;8664.653413863374;8795.032546419761;8821.691240806542;8881.73354799357;54869;55288.44711552833;55288.44711552833;55537;55573.722131389084;77295;77603.61198194198;78005;78160;78539;10829.025387714828;11182.049525790073;11501.424928753362;11524.769504609692;12025.63923082309;11403.657890263305;14311.856881431053;14900.925495372454;15293.388264469742;15522.223888805289;12986.350682466027;13574.04981651275;13697.931510342385;13725.588232353046;13755.518556850862;13148.40832162574;13570.06468124233;13662.18026918388;13969.231692256617;14181.078229915023;12835.101542891907;12961.546345877849;13165.93417032947;13282;13748.518801841887;8948.576926319662;10968.781008322021;11141.888581114084;11198.496067676811;11251.831222531833;9605.519724013911;11214;11547.133964952494;11580;11594.130440217186 13:16:16 INFO - PROCESS | 1648 | __end_tp_report 13:16:16 INFO - PROCESS | 1648 | __start_cc_report 13:16:16 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,380 13:16:16 INFO - PROCESS | 1648 | __end_cc_report 13:16:16 INFO - PROCESS | 1648 | __startTimestamp1481404576010__endTimestamp 13:16:16 INFO - PROCESS | 1648 | 13:16:16 INFO - PROCESS | 1648 | ------- Summary: start ------- 13:16:16 INFO - PROCESS | 1648 | Number of tests: 6 13:16:16 INFO - PROCESS | 1648 | 13:16:16 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5634.74 Median:5803.22 stddev:635.81 (11.0%) stddev-sans-first:610.50 13:16:16 INFO - PROCESS | 1648 | Values: 3286.8 3333.1 3334.5 3343.8 3345.4 5488.8 5530.7 5544.3 5580.8 5626.2 5461.1 5572.2 5582.1 5600.8 5601.1 5085.3 5110.8 5189.3 5191.6 5210.5 5111.2 5189.4 5220.0 5233.1 5240.8 5211.5 5212.2 5213.3 5213.7 5228.5 5188.6 5196.4 5207.0 5210.7 5245.0 5038.8 5162.9 5168.4 5183.7 5233.0 5580.6 5583.8 5629.4 5664.6 5685.5 4752.0 4799.0 6114.3 6125.8 6126.5 5064.9 6070.9 6102.7 6130.5 6143.0 5716.2 5726.2 5728.2 5756.9 5777.9 6216.9 6251.8 6259.6 6266.7 6272.2 4571.4 5907.4 5949.2 6048.1 6069.5 6036.3 6191.4 6194.8 6195.9 6227.3 6049.5 6123.2 6163.0 6172.4 6214.3 5926.4 6051.4 6123.8 6133.4 6154.5 4830.9 4839.5 4854.2 4855.7 4877.4 4269.9 4289.2 4314.5 4319.2 4323.2 5712.8 5723.7 5725.4 5763.5 5803.2 6113.2 6123.4 6134.4 6148.4 6162.1 5575.5 5639.4 5645.7 5692.3 5702.4 5305.2 5310.5 5333.2 5360.1 5441.9 6265.5 6272.3 6273.7 6289.8 6293.2 6154.5 6226.3 6251.7 6253.7 6262.2 6155.4 6156.1 6174.2 6206.5 6217.6 6139.8 6143.4 6161.7 6176.5 6179.3 6131.9 6140.9 6211.9 6240.5 6246.4 5322.9 5858.2 5893.9 5991.5 6109.3 5856.9 5879.9 5893.8 5903.0 5955.9 5633.2 5811.6 5813.3 5820.6 5821.7 5841.9 5913.1 5928.3 5960.3 6030.6 5740.6 5777.6 5837.4 5869.5 5875.5 13:16:16 INFO - PROCESS | 1648 | 13:16:16 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:64086.10 Median:33007.29 stddev:138528.76 (419.7%) stddev-sans-first:138834.97 13:16:16 INFO - PROCESS | 1648 | Values: 447.5 458.9 478.2 480.8 509.3 36520.6 38645.8 38785.0 38940.8 38996.0 38588.4 38964.0 39032.8 39090.8 39153.2 8355.7 8613.9 9177.8 9409.4 9440.9 11002.4 11335.7 11348.1 11466.0 11481.3 11280.2 11323.9 11343.0 11347.1 11379.4 9394.0 11244.6 11274.8 11274.8 11280.5 11062.0 11169.7 11173.1 11174.3 11177.0 37182.3 37281.8 37341.0 37453.3 37694.2 3328.0 3345.9 3450.1 3548.1 3556.8 28628.9 28796.4 29501.9 29628.0 29641.4 3162.8 3191.9 3224.8 3271.4 3283.0 438748.0 442389.0 443930.0 445469.0 445472.0 54262.6 54523.7 54557.7 54697.7 54773.0 57318.7 57443.4 57463.7 57574.7 57742.0 15086.9 15660.6 15676.9 15697.1 15712.7 10980.4 11191.2 11953.2 12128.4 12229.9 14663.6 14863.0 14871.4 14906.8 15045.2 5876.0 6554.5 6682.4 6692.4 6724.2 828.4 994.6 1056.6 1063.7 1137.0 27576.7 28570.0 28992.7 29050.7 29708.4 1039.3 1040.8 1092.5 1101.4 1210.6 12162.6 14458.3 15038.1 15288.1 15463.3 500980.0 504668.0 585366.0 593628.0 606153.9 55115.6 55503.7 55591.7 55621.7 55787.2 1366.8 1372.6 1394.8 1396.2 1396.4 23240.3 28038.4 29001.0 29557.0 29569.4 47220.5 47221.1 47292.0 47301.3 47303.1 487847.0 517105.0 571028.0 572055.0 575097.0 23251.1 23926.3 24030.4 24631.6 37649.4 31965.5 39128.8 39368.8 39642.0 39741.0 33467.2 33493.3 33791.8 33855.3 34523.7 33584.0 33600.7 34195.8 34516.8 34722.7 32389.0 32561.5 32898.8 32945.1 33069.5 27613.6 29186.0 30299.5 30366.1 30372.0 22842.9 23108.5 23216.9 23373.6 23379.4 13:16:16 INFO - PROCESS | 1648 | 13:16:16 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:33698.71 Median:33883.25 stddev:94867.81 (280.0%) stddev-sans-first:95100.81 13:16:16 INFO - PROCESS | 1648 | Values: 307.3 351.9 379.1 382.9 398.7 17767.5 19394.9 20542.8 20798.3 21049.8 19728.7 20088.3 20240.0 20327.0 20520.1 5230.0 5281.9 5538.0 5880.6 5880.9 5350.4 5853.0 5854.2 5859.4 5864.0 5348.8 5611.1 5774.7 5805.0 5813.9 4937.3 5649.7 5653.0 5681.9 5721.9 5331.2 5508.3 5575.3 5644.8 5652.5 10804.6 12659.7 12685.6 12810.9 12969.1 2180.9 2286.1 2309.4 2701.4 3250.5 13335.7 14843.6 15516.7 15663.8 16500.9 1860.0 1976.3 2047.4 2049.9 2051.4 88402.0 99967.0 102140.0 105891.0 111557.4 16401.3 17050.8 17279.9 17280.2 18300.0 33698.5 34068.0 34108.5 34169.7 34177.8 8962.1 10245.4 10417.0 10640.8 10795.0 8517.7 8544.5 8607.9 8621.5 8830.2 9332.6 9568.1 9685.9 9708.9 9713.3 3160.4 3337.5 3517.6 3690.8 3722.8 810.7 832.3 923.0 951.2 1040.5 16870.8 20369.0 20648.3 21299.9 21327.6 834.6 880.9 920.7 968.4 970.1 10879.8 11242.4 11283.5 11294.3 11420.9 561730.0 568015.0 574410.0 578188.0 580081.0 101185.0 105266.0 109791.0 112558.0 124650.0 955.1 972.2 1004.9 1007.2 1012.7 15763.6 19016.7 19040.0 19094.8 19127.7 41230.8 46980.5 50947.8 51233.0 52663.0 73471.6 73907.6 74402.6 74940.9 75638.2 6929.8 7989.7 8023.1 8051.2 8154.6 10165.5 10846.5 12170.0 12252.7 12606.2 8682.9 10663.6 10734.5 10770.3 10995.8 10545.9 10549.8 10720.2 10914.3 11030.2 9475.2 10783.6 11089.8 11114.4 11236.8 7874.4 7916.4 7917.7 7928.0 8049.8 8081.3 8707.9 10146.4 10416.0 10983.2 13:16:16 INFO - PROCESS | 1648 | 13:16:16 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5321.10 Median:5172.55 stddev:2909.24 (56.2%) stddev-sans-first:2902.99 13:16:16 INFO - PROCESS | 1648 | Values: 1468.7 1486.7 1675.6 1741.9 1747.5 7492.7 7719.7 7915.6 8331.3 8373.7 7836.6 8098.7 8125.0 8204.8 8212.4 2262.2 2320.2 2322.6 2327.9 2328.4 2285.4 2290.1 2300.9 2329.0 2332.9 1949.4 2263.7 2269.2 2276.1 2279.9 2266.2 2274.6 2299.0 2300.5 2307.6 2202.4 2268.6 2270.1 2278.0 2281.2 8131.9 8157.9 8171.6 8199.4 8201.8 4988.7 5134.9 5158.0 5161.9 5169.8 3206.6 3235.8 3451.4 3487.6 3512.8 1035.5 1045.8 1057.1 1079.9 1082.5 10598.7 10600.7 10662.9 10733.0 10760.1 4136.5 4270.9 4410.3 4509.3 4637.9 9033.3 9091.5 9117.9 9130.7 9157.5 5102.9 5127.3 5134.4 5136.0 5136.0 4233.9 4250.1 4542.4 4634.9 4693.0 3371.9 3442.4 3707.5 3746.9 3895.4 1044.8 1062.2 1111.0 1147.4 1247.9 679.8 880.9 881.1 904.5 912.1 4680.9 4998.9 5573.2 5742.8 5862.0 879.2 880.0 891.6 916.2 922.8 3637.1 3754.4 3816.6 3846.5 3929.4 9379.8 9837.5 9854.7 9867.2 9874.9 9776.4 9777.1 9787.6 9794.1 9811.4 7333.9 7536.9 7710.7 7714.4 7716.0 5375.8 5417.1 5527.1 5565.9 5600.9 9135.9 9245.6 9280.7 9298.7 9388.0 9093.2 9175.6 9287.0 9361.1 9397.7 6576.3 6633.4 6647.7 6663.8 6732.3 7639.8 7720.7 7742.0 7747.2 7762.7 6680.8 6856.5 6906.9 7014.6 7054.8 6669.2 6687.7 6874.3 6875.0 6960.5 5725.9 6491.2 6607.1 6676.6 6684.5 3993.7 4695.4 4962.7 5019.9 5107.9 4063.7 4766.9 5098.5 5161.8 5175.3 13:16:16 INFO - PROCESS | 1648 | 13:16:16 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4669.07 Median:5231.13 stddev:1463.40 (28.0%) stddev-sans-first:1431.18 13:16:16 INFO - PROCESS | 1648 | Values: 351.4 358.4 364.5 367.9 369.7 5282.3 5382.9 5516.0 5602.4 5635.5 4779.4 4894.8 5258.2 5548.8 5558.3 3815.8 4140.8 4310.6 4328.7 4370.4 3965.9 4231.7 4341.0 4354.2 4369.0 3601.5 3763.2 4031.5 4137.7 4201.2 3767.9 4151.7 4167.9 4173.8 4184.2 3609.9 3632.7 3640.2 3641.5 3645.4 5055.9 5260.9 5286.4 5302.5 5378.3 4710.2 4736.7 4782.7 4788.3 4789.6 5642.8 5803.3 5811.5 5831.2 5883.9 3589.8 3739.6 3906.9 3914.9 3922.7 5635.4 6148.2 6159.6 6161.4 6204.3 4002.3 4009.4 4015.6 4017.6 4033.8 3335.5 3371.6 3991.4 4018.3 4078.2 4525.2 4704.0 4704.8 4712.4 4767.7 5820.9 5888.6 5911.4 5915.7 5917.3 1114.5 1148.5 1284.0 1285.8 1298.9 857.4 874.4 887.8 895.6 900.6 4681.0 5240.8 5270.8 5288.6 5301.9 5494.0 5519.8 5658.3 5784.8 5798.5 4896.7 5032.4 5209.6 5221.4 5251.9 2497.6 2637.4 2756.0 3027.6 3046.2 5289.5 5730.9 5806.7 5975.0 6016.4 5782.6 5826.4 5920.9 6226.7 6258.8 6191.4 6298.9 6320.2 6336.5 6343.6 4572.0 5055.4 5108.6 5277.3 6064.4 5034.5 5108.4 5576.9 6006.2 6013.7 5554.8 6326.1 6352.4 6357.7 6439.6 3578.7 3780.3 3982.1 3990.5 4023.8 5209.8 5268.7 6051.3 6063.4 6075.9 5404.5 5766.3 5796.2 5975.2 5993.9 4966.3 5013.4 5027.3 5483.6 5641.4 4294.7 5730.4 5756.2 5823.9 5866.2 4802.6 4982.7 5882.9 5906.2 5932.9 4510.2 5373.7 5633.3 5700.7 5770.7 13:16:16 INFO - PROCESS | 1648 | 13:16:16 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:20444.33 Median:29785.85 stddev:30349.16 (101.9%) stddev-sans-first:30397.85 13:16:16 INFO - PROCESS | 1648 | Values: 635.1 649.8 682.0 803.4 814.8 14415.5 15334.9 15349.1 15408.7 15427.5 13666.4 13848.9 13960.6 13982.1 14010.7 848.6 860.5 869.4 872.4 878.2 868.2 870.6 872.4 877.2 878.3 915.3 916.3 916.4 917.6 920.2 775.9 901.4 903.5 903.9 905.2 667.1 680.5 680.7 681.6 682.0 12690.9 12900.7 12931.2 13258.5 13467.7 9724.8 11722.8 11917.0 12052.1 12174.1 10489.2 10520.9 10540.3 10745.5 10884.8 3501.8 3590.6 3597.2 3598.3 3602.7 111429.0 112716.4 114435.0 114660.9 114754.4 8659.7 8750.9 8838.1 8959.2 9171.6 18907.4 19386.3 19449.0 19472.5 19490.3 7739.8 7761.3 7850.5 7873.7 8004.7 4241.9 4295.4 4301.2 4310.1 4322.5 3039.0 3439.9 3584.5 3599.0 3628.9 620.4 644.6 650.1 663.6 709.8 626.7 633.3 650.8 676.9 683.7 7682.6 7971.9 8036.2 8440.5 9702.6 624.9 625.4 626.6 667.5 683.6 4239.9 4565.5 4583.9 4592.3 4702.7 62036.0 103169.9 103191.0 104418.5 107097.9 70332.0 79321.8 92670.5 93975.5 96664.0 69678.0 80004.6 80256.0 80744.0 82028.0 7916.0 8664.7 8795.0 8821.7 8881.7 54869.0 55288.4 55288.4 55537.0 55573.7 77295.0 77603.6 78005.0 78160.0 78539.0 10829.0 11182.0 11501.4 11524.8 12025.6 11403.7 14311.9 14900.9 15293.4 15522.2 12986.4 13574.0 13697.9 13725.6 13755.5 13148.4 13570.1 13662.2 13969.2 14181.1 12835.1 12961.5 13165.9 13282.0 13748.5 8948.6 10968.8 11141.9 11198.5 11251.8 9605.5 11214.0 11547.1 11580.0 11594.1 13:16:16 INFO - PROCESS | 1648 | -------- Summary: end -------- 13:16:16 INFO - PROCESS | 1648 | 13:16:16 INFO - TEST-INFO | 1648: exit 0 13:16:16 INFO - TEST-OK | dromaeo_css | took 1116809ms 13:16:16 INFO - TEST-START | kraken 13:16:16 INFO - operating with platform_type : mac_ 13:16:16 INFO - Initialising browser for kraken test... 13:16:16 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpSJa5IV/profile http://localhost:49241/getInfo.html) 13:16:19 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 13:16:19 INFO - PROCESS | 1655 | colorDepth:24 13:16:19 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 13:16:19 INFO - PROCESS | 1655 | __metrics 13:16:19 INFO - PROCESS | 1655 | 2016-12-10 13:16:19.472 plugin-container[1656:11316] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 13:16:19 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:16:19 INFO - PROCESS | 1655 | 2016-12-10 13:16:19.476 plugin-container[1656:11316] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e23, name = 'com.apple.CFPasteboardClient' 13:16:19 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:16:19 INFO - PROCESS | 1655 | 2016-12-10 13:16:19.476 plugin-container[1656:11316] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:16:19 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 13:16:20 INFO - TEST-INFO | 1655: exit 0 13:16:20 INFO - Browser initialized. 13:16:20 INFO - Running cycle 1/1 for kraken test... 13:16:20 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpSJa5IV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:16:22 INFO - PROCESS | 1657 | 2016-12-10 13:16:22.744 plugin-container[1658:11435] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 13:16:22 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:16:22 INFO - PROCESS | 1657 | 2016-12-10 13:16:22.745 plugin-container[1658:11435] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 13:16:22 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:16:22 INFO - PROCESS | 1657 | 2016-12-10 13:16:22.746 plugin-container[1658:11435] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:16:32 INFO - PROCESS | 1657 | RSS: Main: 236879872 13:16:32 INFO - PROCESS | 1657 | 13:16:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 13:16:36 INFO - PROCESS | 1657 | RSS: Main: 249430016 13:16:36 INFO - PROCESS | 1657 | 13:16:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 13:16:39 INFO - PROCESS | 1657 | RSS: Main: 257118208 13:16:39 INFO - PROCESS | 1657 | 13:16:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 13:16:43 INFO - PROCESS | 1657 | RSS: Main: 259657728 13:16:43 INFO - PROCESS | 1657 | 13:16:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 13:16:46 INFO - PROCESS | 1657 | RSS: Main: 261959680 13:16:46 INFO - PROCESS | 1657 | 13:16:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:16:48 INFO - PROCESS | 1657 | RSS: Main: 262537216 13:16:48 INFO - PROCESS | 1657 | 13:16:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 13:16:52 INFO - PROCESS | 1657 | RSS: Main: 268312576 13:16:52 INFO - PROCESS | 1657 | 13:16:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 13:16:56 INFO - PROCESS | 1657 | RSS: Main: 267374592 13:16:56 INFO - PROCESS | 1657 | 13:16:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 13:17:00 INFO - PROCESS | 1657 | RSS: Main: 267907072 13:17:00 INFO - PROCESS | 1657 | 13:17:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:17:02 INFO - PROCESS | 1657 | RSS: Main: 267079680 13:17:02 INFO - PROCESS | 1657 | 13:17:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:17:05 INFO - PROCESS | 1657 | RSS: Main: 267120640 13:17:05 INFO - PROCESS | 1657 | 13:17:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:17:08 INFO - PROCESS | 1657 | RSS: Main: 267161600 13:17:08 INFO - PROCESS | 1657 | 13:17:11 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:17:12 INFO - PROCESS | 1657 | RSS: Main: 267464704 13:17:12 INFO - PROCESS | 1657 | 13:17:15 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:17:15 INFO - PROCESS | 1657 | RSS: Main: 267223040 13:17:15 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 13:17:17 INFO - PROCESS | 1657 | RSS: Main: 269414400 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | __start_tp_report 13:17:17 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 13:17:17 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 13:17:17 INFO - PROCESS | 1657 | |i|pagename|runs| 13:17:17 INFO - PROCESS | 1657 | |0;ai-astar;105;119;116;120;117;119;119;119;162;174 13:17:17 INFO - PROCESS | 1657 | |1;audio-beat-detection;150;118;116;153;125;145;144;142;138;121 13:17:17 INFO - PROCESS | 1657 | |2;audio-dft;203;231;193;198;198;203;196;194;197;198 13:17:17 INFO - PROCESS | 1657 | |3;audio-fft;82;58;59;84;58;61;59;57;70;58 13:17:17 INFO - PROCESS | 1657 | |4;audio-oscillator;83;70;85;74;70;70;107;100;89;82 13:17:17 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;111;113;116;114;111;135;162;115;137;127 13:17:17 INFO - PROCESS | 1657 | |6;imaging-darkroom;96;146;115;157;152;99;94;154;98;100 13:17:17 INFO - PROCESS | 1657 | |7;imaging-desaturate;128;88;145;140;96;149;142;102;93;144 13:17:17 INFO - PROCESS | 1657 | |8;json-parse-financial;56;57;56;57;58;58;55;58;57;57 13:17:17 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;67;84;104;107;83;68;68;70;70;81 13:17:17 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;80;87;114;80;108;110;121;114;165;93 13:17:17 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;138;134;147;152;159;170;169;164;167;160 13:17:17 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;176;177;177;179;180;183;176;175;184;183 13:17:17 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;65;68;68;70;69;68;69;78;83;89 13:17:17 INFO - PROCESS | 1657 | __end_tp_report 13:17:17 INFO - PROCESS | 1657 | __start_cc_report 13:17:17 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,956 13:17:17 INFO - PROCESS | 1657 | __end_cc_report 13:17:17 INFO - PROCESS | 1657 | __startTimestamp1481404637740__endTimestamp 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | ------- Summary: start ------- 13:17:17 INFO - PROCESS | 1657 | Number of tests: 14 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:127.00 Median:119.00 stddev:22.22 (18.7%) stddev-sans-first:22.10 13:17:17 INFO - PROCESS | 1657 | Values: 105.0 119.0 116.0 120.0 117.0 119.0 119.0 119.0 162.0 174.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:143.00 stddev:13.88 (9.7%) stddev-sans-first:13.65 13:17:17 INFO - PROCESS | 1657 | Values: 150.0 118.0 116.0 153.0 125.0 145.0 144.0 142.0 138.0 121.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:201.10 Median:198.00 stddev:11.00 (5.6%) stddev-sans-first:11.65 13:17:17 INFO - PROCESS | 1657 | Values: 203.0 231.0 193.0 198.0 198.0 203.0 196.0 194.0 197.0 198.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:64.60 Median:60.00 stddev:10.39 (17.3%) stddev-sans-first:8.92 13:17:17 INFO - PROCESS | 1657 | Values: 82.0 58.0 59.0 84.0 58.0 61.0 59.0 57.0 70.0 58.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:83.00 Median:78.00 stddev:12.88 (16.5%) stddev-sans-first:13.67 13:17:17 INFO - PROCESS | 1657 | Values: 83.0 70.0 85.0 74.0 70.0 70.0 107.0 100.0 89.0 82.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:124.10 Median:121.50 stddev:16.49 (13.6%) stddev-sans-first:16.79 13:17:17 INFO - PROCESS | 1657 | Values: 111.0 113.0 116.0 114.0 111.0 135.0 162.0 115.0 137.0 127.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:121.10 Median:125.50 stddev:27.52 (21.9%) stddev-sans-first:27.65 13:17:17 INFO - PROCESS | 1657 | Values: 96.0 146.0 115.0 157.0 152.0 99.0 94.0 154.0 98.0 100.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:122.70 Median:147.00 stddev:24.88 (16.9%) stddev-sans-first:26.31 13:17:17 INFO - PROCESS | 1657 | Values: 128.0 88.0 145.0 140.0 96.0 149.0 142.0 102.0 93.0 144.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:0.99 (1.7%) stddev-sans-first:1.00 13:17:17 INFO - PROCESS | 1657 | Values: 56.0 57.0 56.0 57.0 58.0 58.0 55.0 58.0 57.0 57.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:80.20 Median:70.00 stddev:14.86 (21.2%) stddev-sans-first:14.97 13:17:17 INFO - PROCESS | 1657 | Values: 67.0 84.0 104.0 107.0 83.0 68.0 68.0 70.0 70.0 81.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:107.20 Median:122.50 stddev:25.21 (20.6%) stddev-sans-first:24.75 13:17:17 INFO - PROCESS | 1657 | Values: 80.0 87.0 114.0 80.0 108.0 110.0 121.0 114.0 165.0 93.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:156.00 Median:162.00 stddev:12.82 (7.9%) stddev-sans-first:11.83 13:17:17 INFO - PROCESS | 1657 | Values: 138.0 134.0 147.0 152.0 159.0 170.0 169.0 164.0 167.0 160.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.00 Median:179.50 stddev:3.33 (1.9%) stddev-sans-first:3.35 13:17:17 INFO - PROCESS | 1657 | Values: 176.0 177.0 177.0 179.0 180.0 183.0 176.0 175.0 184.0 183.0 13:17:17 INFO - PROCESS | 1657 | 13:17:17 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:69.50 stddev:7.89 (11.4%) stddev-sans-first:7.86 13:17:17 INFO - PROCESS | 1657 | Values: 65.0 68.0 68.0 70.0 69.0 68.0 69.0 78.0 83.0 89.0 13:17:17 INFO - PROCESS | 1657 | -------- Summary: end -------- 13:17:17 INFO - PROCESS | 1657 | 13:17:18 INFO - TEST-INFO | 1657: exit 0 13:17:18 INFO - TEST-OK | kraken | took 61968ms 13:17:18 INFO - SUITE-END | took 1178s 13:17:18 INFO - Completed test suite (00:19:39) 13:17:18 INFO - javascript benchmark 13:17:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7206.121838954472, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3286.8003178839717, 3333.0500807269927, 3334.516495108208, 3343.849526771295, 3345.414552453321, 5488.835334939959, 5530.668159910403, 5544.334679838415, 5580.832575022756, 5626.240457538231, 5461.0716178249695, 5572.219889215513, 5582.106862901936, 5600.803971860985, 5601.131824567188, 5085.262636917645, 5110.770015349318, 5189.299444574972, 5191.610629202806, 5210.452902445246, 5111.156659151254, 5189.377274727038, 5220.008198442303, 5233.1103712368795, 5240.764165612538, 5211.5309622134, 5212.1660534314515, 5213.322268105141, 5213.713245771481, 5228.503292187236, 5188.610854185951, 5196.428392876781, 5207.010667973111, 5210.713410762407, 5245.0, 5038.84883453497, 5162.870928226786, 5168.3539557555305, 5183.714895680736, 5233.031889100528, 5580.63725857822, 5583.776648934037, 5629.408912064257, 5664.57515686321, 5685.545156387479, 4751.95248047522, 4798.992211635561, 6114.266288045463, 6125.754969801203, 6126.509879209652, 5064.898702025939, 6070.878582428327, 6102.731632724008, 6130.489860652512, 6143.0, 5716.228309178291, 5726.169705392736, 5728.169415434739, 5756.8848623027725, 5777.884442311172, 6216.937830621636, 6251.843703907348, 6259.593126446766, 6266.717997690115, 6272.2159730033745, 4571.428571428572, 5907.379725128885, 5949.196858424144, 6048.123022161762, 6069.514438844882, 6036.3360030396625, 6191.442770150708, 6194.845128871814, 6195.907061394083, 6227.346128656426, 6049.5462840287155, 6123.173371594867, 6163.0, 6172.444479996821, 6214.316425193225, 5926.377730338339, 6051.425114614105, 6123.846903827351, 6133.417325354084, 6154.5384096192965, 4830.903381932412, 4839.516048395132, 4854.17479028566, 4855.6601037927, 4877.414710234723, 4269.87190384289, 4289.163613095417, 4314.525402205755, 4319.200947824663, 4323.200207961537, 5712.771489140471, 5723.656580605177, 5725.427457254241, 5763.538916886639, 5803.216565763652, 6113.174721412642, 6123.4488895999575, 6134.417230363198, 6148.415900489446, 6162.075688646739, 5575.470330318694, 5639.436056394327, 5645.717714114277, 5692.345380281238, 5702.372738998707, 5305.151175811851, 5310.548603368679, 5333.2266821310695, 5360.142377219626, 5441.891162176735, 6265.467435268053, 6272.278687950944, 6273.749050037947, 6289.811305660837, 6293.150424692608, 6154.474012950098, 6226.346233645402, 6251.71867265965, 6253.7185826637, 6262.15460912771, 6155.415235552616, 6156.076588511759, 6174.166487524127, 6206.472449841814, 6217.626942383471, 6139.8465038372615, 6143.416375444325, 6161.661108639068, 6176.536759742948, 6179.258488981351, 6131.908021379772, 6140.8771824564155, 6211.9068213977735, 6240.53196010292, 6246.3753624637175, 5322.946770532245, 5858.179854820243, 5893.941060589511, 5991.460768530853, 6109.2974307955155, 5856.853578660568, 5879.941200587939, 5893.764249430146, 5902.996490596523, 5955.940440595538, 5633.183188437735, 5811.56413268998, 5813.331466881363, 5820.563457740602, 5821.679807610622, 5841.853953651193, 5913.05391137416, 5928.318243402064, 5960.254968128984, 6030.638161710312, 5740.598158128891, 5777.62445441045, 5837.416258374129, 5869.4717475427415, 5875.50058245054], "value": 5595.690188878346, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [447.52785810969414, 458.86004768545604, 478.2300496201118, 480.81248313157863, 509.31242822190046, 36520.634793652054, 38645.84062478127, 38785.0, 38940.805295973514, 38996.0, 38588.42117368235, 38964.02589935253, 39032.829015129595, 39090.8045459773, 39153.21693566127, 8355.749327520154, 8613.913860861374, 9177.77055573611, 9409.388389754678, 9440.905590944105, 11002.449877506133, 11335.659930202066, 11348.14888883333, 11466.025387842043, 11481.253718508271, 11280.210385273034, 11323.943380283086, 11343.0, 11347.092232621411, 11379.374134422646, 9393.95303023484, 11244.550217991271, 11274.774504509905, 11274.830877536844, 11280.548778048867, 11062.0, 11169.72075698106, 11173.050290725298, 11174.329540227613, 11177.049950754195, 37182.256354873025, 37281.81359093187, 37341.0, 37453.25093498142, 37694.24611507754, 3327.9833600832085, 3345.8996230113135, 3450.051235910145, 3548.0738102945834, 3556.8399422025814, 28628.856855715796, 28796.424071518453, 29501.852490737623, 29628.0, 29641.40717185644, 3162.794418362799, 3191.936161276785, 3224.7581431392505, 3271.3620843935205, 3283.0315057057987, 438748.0, 442389.0, 443930.0, 445469.0, 445472.0, 54262.64343391447, 54523.72738136284, 54557.72721136369, 54697.72651136798, 54772.9999999992, 57318.713406432704, 57443.42556574464, 57463.71268143632, 57574.7121264391, 57742.0, 15086.924565377103, 15660.608484787743, 15676.92161539185, 15697.058176509447, 15712.68574628501, 10980.396078215776, 11191.216614837045, 11953.22304050234, 12128.393580320948, 12229.93885030569, 14663.633409164642, 14863.0, 14871.405143794127, 14906.776398354034, 15045.172515511533, 5876.001079816441, 6554.475641948633, 6682.365175308338, 6692.423416984496, 6724.193096828314, 828.4325237212529, 994.5623925472769, 1056.5668076088766, 1063.7234319077095, 1136.9886301136883, 27576.724232757817, 28570.00004999815, 28992.710072899423, 29050.709492905222, 29708.405831883672, 1039.261545826737, 1040.8230600797883, 1092.5029111754134, 1101.4217535793773, 1210.6005018343926, 12162.574309899288, 14458.277086145861, 15038.097714137186, 15288.082715037133, 15463.304151312994, 500980.0, 504668.0, 585366.0, 593628.0, 606153.907691385, 55115.622109447584, 55503.72248138814, 55591.722041389534, 55621.72189139028, 55787.16319255214, 1366.8086467894502, 1372.6431127906817, 1394.8326200855963, 1396.218117854005, 1396.427464739452, 23240.3027909163, 28038.43923121567, 29001.0, 29557.0, 29569.408611828072, 47220.52779472162, 47221.05557888892, 47292.0, 47301.29048064213, 47303.05393892034, 487847.0, 517105.0, 571028.0, 572055.0, 575097.0, 23251.069957201522, 23926.282211533682, 24030.39924001914, 24631.630525541772, 37649.4352584706, 31965.520517191795, 39128.804355978034, 39368.80315598518, 39642.0, 39741.00647483836, 33467.163320916196, 33493.330133397685, 33791.83104084464, 33855.322893541495, 34523.65476345205, 33584.0, 33600.663993359754, 34195.82902085473, 34516.82741586276, 34722.65277347194, 32389.028329150162, 32561.511577325884, 32898.83550582232, 32945.081532877404, 33069.50395744114, 27613.585796212672, 29186.0, 30299.545506817856, 30366.089017329516, 30372.0, 22842.885785570965, 23108.537829243658, 23216.924244239122, 23373.649395259425, 23379.415514612272], "value": 19018.287181962878, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.2748313979009, 351.90850378901484, 379.07316610886375, 382.9215010922761, 398.6883154421952, 17767.46697599071, 19394.90302548487, 20542.794572054274, 20798.272060477866, 21049.842258675766, 19728.70406943897, 20088.296909608165, 20240.0, 20327.0, 20520.076596553154, 5230.010679601961, 5281.947180528184, 5537.99999999999, 5880.647161170322, 5880.882382352351, 5350.438203988564, 5853.0, 5854.20968169296, 5859.355470898219, 5863.9706801466145, 5348.759305831247, 5611.130274807411, 5774.7401366938375, 5805.042168042268, 5813.854653633651, 4937.308776771255, 5649.661020338773, 5652.971735141318, 5681.914771278434, 5721.856953576153, 5331.2269720890645, 5508.338999320067, 5575.330960284751, 5644.8306550803545, 5652.463016013472, 10804.621838235702, 12659.683507912283, 12685.619431417072, 12810.935945320214, 12969.092163548556, 2180.890955452237, 2286.1084177170915, 2309.399556115405, 2701.3651791828906, 3250.512423136525, 13335.666608334772, 14843.628909277246, 15516.689666206612, 15663.765043524356, 16500.917495412566, 1859.9907000465046, 1976.2984140630156, 2047.4471892588917, 2049.938501844947, 2051.415346626219, 88402.0, 99967.0, 102140.0, 105891.0, 111557.44221278842, 16401.343946242254, 17050.82949170517, 17279.91360043217, 17280.22238999248, 18300.0, 33698.49452258218, 34068.0, 34108.48837267443, 34169.65830341714, 34177.829110854786, 8962.103789621116, 10245.385276883411, 10417.0, 10640.78718425627, 10794.974477424632, 8517.701880434279, 8544.530050847263, 8607.870881936777, 8621.482711037357, 8830.249428798625, 9332.626694932127, 9568.09103135201, 9685.9031409685, 9708.854367184498, 9713.27150463704, 3160.3995240904155, 3337.5327454156436, 3517.577890653087, 3690.8033012516207, 3722.813859307024, 810.7473952743026, 832.2800777327689, 922.9861552076724, 951.1819834941945, 1040.47976011994, 16870.831291686925, 20369.0, 20648.277310294066, 21299.8935005324, 21327.573448530944, 834.6369329341755, 880.9427387219811, 920.6915683246187, 968.4334664221368, 970.1042388470411, 10879.782404351869, 11242.437878106062, 11283.548658053585, 11294.265872718248, 11420.94289528547, 561730.0, 568015.0, 574410.0, 578188.0, 580081.0, 101184.97630047357, 105266.0, 109791.0, 112558.0, 124650.0, 955.1403736636944, 972.1687956796978, 1004.8944860789658, 1007.189212683782, 1012.7468132966758, 15763.605909851887, 19016.71474927905, 19040.0, 19094.809051909302, 19127.71308430347, 41230.793846031775, 46980.53019469762, 50947.76695643569, 51232.97534049373, 52663.0, 73471.63264183645, 73907.63046184735, 74402.62798685972, 74940.87588686284, 75638.24361756312, 6929.757458488929, 7989.7203597873795, 8023.077346105039, 8051.235132662622, 8154.551499667338, 10165.542550585093, 10846.511906964048, 12170.026397888325, 12252.693682657648, 12606.243625382507, 8682.913170868464, 10663.626773062906, 10734.516946737573, 10770.299930504492, 10995.835062473909, 10545.894541054799, 10549.78900421972, 10720.195985300978, 10914.290571112851, 11030.22788404804, 9475.24198064167, 10783.568657253936, 11089.778204436028, 11114.444277785917, 11236.83144752813, 7874.409419293691, 7916.406269529693, 7917.683292668229, 7927.960360198162, 8049.83900322002, 8081.313088387553, 8707.912920870964, 10146.391216527032, 10416.0, 10983.176261780558], "value": 9972.207283044256, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1468.7356275870343, 1486.7472529669956, 1675.6313611005573, 1741.939032133875, 1747.5019619408472, 7492.7377541786045, 7719.652615632296, 7915.564643944588, 8331.333493320535, 8373.743938409234, 7836.568988705619, 8098.716544920929, 8125.024997000353, 8204.794880128, 8212.384071194654, 2262.242148880127, 2320.211128216406, 2322.628379459287, 2327.9185228517003, 2328.359701082199, 2285.3715228312262, 2290.061074959267, 2300.907963681451, 2328.9985306318276, 2332.88335583221, 1949.4054313434397, 2263.7057182566246, 2269.1944359752297, 2276.112316196685, 2279.908803647852, 2266.195500597289, 2274.613315736329, 2298.9655155172686, 2300.5168914527885, 2307.596170670143, 2202.394341556069, 2268.625676763332, 2270.103309192876, 2277.9544409111727, 2281.178775640768, 8131.878021829677, 8157.857899894019, 8171.550564718938, 8199.42604017719, 8201.79495512611, 4988.700677959334, 5134.897302053975, 5158.045761534128, 5161.896762064737, 5169.793208271664, 3206.64726880043, 3235.8058516489086, 3451.3960056990177, 3487.5989261235027, 3512.8067956262394, 1035.4977835749655, 1045.811753884308, 1057.0856209378908, 1079.9190060745398, 1082.5020490574345, 10598.682039538826, 10600.734981625445, 10662.946685266525, 10732.980366865213, 10760.139188864872, 4136.482939632546, 4270.850520231839, 4410.272305069628, 4509.34614480905, 4637.86086417408, 9033.277337812959, 9091.545422728837, 9117.908820911838, 9130.726078217664, 9157.450552966844, 5102.948970510248, 5127.307813445137, 5134.358205224347, 5135.998480296306, 5136.049830781318, 4233.872983810491, 4250.086231460251, 4542.432195975503, 4634.86095417138, 4693.037927224911, 3371.9325613488086, 3442.3631628148873, 3707.4994875691978, 3746.8501259949844, 3895.35726605107, 1044.8119338518989, 1062.219268837404, 1110.9611163609236, 1147.4148184426003, 1247.8939290160438, 679.7960611816534, 880.9427387219938, 881.0968757024101, 904.5432056811268, 912.1380295620708, 4680.906381872412, 4998.875028124326, 5573.219749235111, 5742.827715168552, 5862.0, 879.2262808728278, 879.9736007919772, 891.6121487153109, 916.1800188830958, 922.8338898998245, 3637.0907273181706, 3754.3992961126637, 3816.5687867049855, 3846.538415390115, 3929.351656976624, 9379.812403751886, 9837.508124593885, 9854.718886544702, 9867.161291290176, 9874.901250987541, 9776.364536305118, 9777.120059194707, 9787.559559819825, 9794.118529332394, 9811.362261452983, 7333.926660733431, 7536.886946695804, 7710.653020614086, 7714.382849372037, 7716.0, 5375.838724838261, 5417.1061774808295, 5527.115661494142, 5565.944340556543, 5600.887982240414, 9135.908640913505, 9245.58394872219, 9280.675176368795, 9298.72103836886, 9388.0, 9093.22707569864, 9175.632974680939, 9287.0, 9361.063893610584, 9397.671081512115, 6576.342365763577, 6633.402993730588, 6647.700853461509, 6663.766768163091, 6732.259451460433, 7639.847203055797, 7720.652570634447, 7742.032245969254, 7747.186545412876, 7762.650680719494, 6680.832726570363, 6856.5200435969, 6906.896396554156, 7014.579125252501, 7054.788356349318, 6669.166354205724, 6687.732490700318, 6874.278200788845, 6874.9656251718425, 6960.47796415281, 5725.914111288417, 6491.188601424822, 6607.075009498578, 6676.632785196861, 6684.4652427804685, 3993.7204395692024, 4695.413073365829, 4962.6774259673, 5019.924701129413, 5107.872303192301, 4063.6952228583564, 4766.904661906673, 5098.515641014098, 5161.7677204525135, 5175.301334319819], "value": 4317.053962977116, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.3517560101612, 358.38894684562814, 364.47515577568316, 367.9392900171472, 369.6580662886831, 5282.339707536558, 5382.865428364293, 5516.0, 5602.355729091156, 5635.492805647496, 4779.402574678165, 4894.804207831682, 5258.237555554442, 5548.835953977952, 5558.333000039991, 3815.790131542764, 4140.846759684962, 4310.612044915957, 4328.740275583459, 4370.366296886949, 3965.8611948581806, 4231.7037807353345, 4340.9565904341025, 4354.216241076605, 4369.012099494722, 3601.549806274216, 3763.2473505298913, 4031.5284921003918, 4137.710360274782, 4201.159768046387, 3767.9434808477895, 4151.667866570667, 4167.854125105607, 4173.791310434496, 4184.205001049791, 3609.9097522561883, 3632.745707800455, 3640.199156185661, 3641.4509843109663, 3645.4167333226555, 5055.873603159914, 5260.922360419159, 5286.36563612369, 5302.496262855023, 5378.273933019032, 4710.199266124767, 4736.739479328636, 4782.689125206851, 4788.318541616706, 4789.557325763851, 5642.802501912454, 5803.274590676166, 5811.506021988136, 5831.154482599999, 5883.911741323884, 3589.766665166756, 3739.6066517428867, 3906.882793516199, 3914.9267824757712, 3922.705797065203, 5635.435876676102, 6148.188123363499, 6159.568830181889, 6161.445469907685, 6204.317525072239, 4002.3396139636634, 4009.398590211433, 4015.638592526687, 4017.6183262590007, 4033.798310084484, 3335.533025376449, 3371.578552680915, 3991.4212439196735, 4018.33697439925, 4078.225137223918, 4525.208088584484, 4703.976480117578, 4704.764761761967, 4712.363830882787, 4767.6662633615315, 5820.912686309709, 5888.617239879394, 5911.379305172981, 5915.674637894957, 5917.289925208916, 1114.4817659788075, 1148.4831825678477, 1283.9807402888964, 1285.7685616589104, 1298.9350532473338, 857.4126723194631, 874.3660845886808, 887.8401887660283, 895.5790778334173, 900.6082354175954, 4681.016986432819, 5240.842774716764, 5270.7628156733035, 5288.550473209743, 5301.920471192935, 5493.956148331804, 5519.779208831602, 5658.321001479849, 5784.768609255582, 5798.536117110705, 4896.681715688469, 5032.370953630796, 5209.609279304067, 5221.425643179247, 5251.921221181686, 2497.637842512862, 2637.3802156493207, 2756.0, 3027.576139340494, 3046.2232130806947, 5289.523942845162, 5730.885382292414, 5806.670728905513, 5974.982633769919, 6016.374689665177, 5782.59521833476, 5826.388229235869, 5920.851978700481, 6226.661267827439, 6258.780942666985, 6191.442770150708, 6298.905516417258, 6320.209973753281, 6336.493080553545, 6343.55595108338, 4571.971306456021, 5055.443901170795, 5108.59131269505, 5277.28756617867, 6064.3935606439, 5034.546890779848, 5108.361454818148, 5576.940381327535, 6006.249218847644, 6013.699315034143, 5554.750036248298, 6326.051092336186, 6352.428281454691, 6357.745690172526, 6439.581427207217, 3578.695810856002, 3780.2593119987787, 3982.064214909552, 3990.4612877261197, 4023.75857448554, 5209.817656382009, 5268.710220937885, 6051.33435322123, 6063.393660633901, 6075.878482430415, 5404.541093699957, 5766.308043034863, 5796.246487956539, 5975.223220981418, 5993.943089024541, 4966.279889415942, 5013.423456302572, 5027.296178535081, 5483.643563168382, 5641.351244606923, 4294.677899157513, 5730.426957304237, 5756.158029430542, 5823.864346452401, 5866.2373891395555, 4802.638153292779, 4982.676126051795, 5882.911756323744, 5906.232189815297, 5932.8813423732145, 4510.233260345814, 5373.650712703662, 5633.338165241182, 5700.714964251688, 5770.662881532986], "value": 4208.201805045538, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.1191096423981, 649.8245473722098, 681.9829504262395, 803.3894240377311, 814.8125931035862, 14415.495457658984, 15334.923325383385, 15349.079055256692, 15408.69182616347, 15427.460038898627, 13666.38501267443, 13848.930755346235, 13960.581182564514, 13982.091164074329, 14010.719785604282, 848.6011574559963, 860.5181098584782, 869.395769939892, 872.3849685971406, 878.2447095497866, 868.2446271743592, 870.6256309786789, 872.4459967920353, 877.1666916429385, 878.2798105553449, 915.2997956563235, 916.2990312411011, 916.3860213656866, 917.6421195733669, 920.185635712396, 775.9068911730628, 901.3510272603714, 903.5391950105461, 903.9367244292902, 905.1672461335587, 667.0960848050918, 680.5474359550942, 680.7277089164324, 681.6421378776132, 682.0212994353119, 12690.87309126906, 12900.677483062904, 12931.159474634213, 13258.535951241756, 13467.66330841727, 9724.75688107796, 11722.765544689144, 11917.046636269077, 12052.096092793076, 12174.147809653328, 10489.21330900178, 10520.894791052067, 10540.314879532805, 10745.51645175969, 10884.782304353868, 3501.77238479498, 3590.622984586633, 3597.1546686528645, 3598.334308152974, 3602.6937710294656, 111429.0, 112716.4364178182, 114435.0, 114660.85339146585, 114754.42622786833, 8659.696910608098, 8750.912490875136, 8838.116188381238, 8959.23846473044, 9171.63313467454, 18907.43277701671, 19386.321478748177, 19449.0, 19472.513187170433, 19490.31783887557, 7739.845203095907, 7761.262680045386, 7850.528968261922, 7873.724419645285, 8004.679812807422, 4241.87274381769, 4295.420118284054, 4301.2472817257485, 4310.053813618251, 4322.546132656089, 3038.9696103038686, 3439.94840077399, 3584.5160903277724, 3599.0, 3628.9092772680365, 620.426105852085, 644.5810223354763, 650.1385663995226, 663.556764038215, 709.8048036789924, 626.7305058824658, 633.3191818794814, 650.8047585724264, 676.8713944350657, 683.719674933275, 7682.577458239851, 7971.920280797234, 8036.196380362034, 8440.53577053268, 9702.641630171782, 624.9126519855419, 625.4246093593905, 626.589583822597, 667.512715717529, 683.5591043776736, 4239.851605193803, 4565.474970378449, 4583.9083218335445, 4592.288195329729, 4702.694324868872, 62036.0, 103169.90490285303, 103191.0, 104418.47790760998, 107097.92902071035, 70332.0, 79321.81017284746, 92670.53664731633, 93975.53012235032, 96664.0, 69678.0, 80004.59997699974, 80256.0, 80744.0, 82028.0, 7916.0, 8664.653413863374, 8795.032546419761, 8821.691240806542, 8881.73354799357, 54869.0, 55288.44711552833, 55288.44711552833, 55537.0, 55573.722131389084, 77295.0, 77603.61198194198, 78005.0, 78160.0, 78539.0, 10829.025387714828, 11182.049525790073, 11501.424928753362, 11524.769504609692, 12025.63923082309, 11403.657890263305, 14311.856881431053, 14900.925495372454, 15293.388264469742, 15522.223888805289, 12986.350682466027, 13574.04981651275, 13697.931510342385, 13725.588232353046, 13755.518556850862, 13148.40832162574, 13570.06468124233, 13662.18026918388, 13969.231692256617, 14181.078229915023, 12835.101542891907, 12961.546345877849, 13165.93417032947, 13282.0, 13748.518801841887, 8948.576926319662, 10968.781008322021, 11141.888581114084, 11198.496067676811, 11251.831222531833, 9605.519724013911, 11214.0, 11547.133964952494, 11580.0, 11594.130440217186], "value": 7262.116702887141, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1630.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 119.0, 116.0, 120.0, 117.0, 119.0, 119.0, 119.0, 162.0, 174.0], "value": 127.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 118.0, 116.0, 153.0, 125.0, 145.0, 144.0, 142.0, 138.0, 121.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 231.0, 193.0, 198.0, 198.0, 203.0, 196.0, 194.0, 197.0, 198.0], "value": 201.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 59.0, 84.0, 58.0, 61.0, 59.0, 57.0, 70.0, 58.0], "value": 64.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 85.0, 74.0, 70.0, 70.0, 107.0, 100.0, 89.0, 82.0], "value": 83.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 116.0, 114.0, 111.0, 135.0, 162.0, 115.0, 137.0, 127.0], "value": 124.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 146.0, 115.0, 157.0, 152.0, 99.0, 94.0, 154.0, 98.0, 100.0], "value": 121.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 88.0, 145.0, 140.0, 96.0, 149.0, 142.0, 102.0, 93.0, 144.0], "value": 122.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 57.0, 58.0, 58.0, 55.0, 58.0, 57.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 84.0, 104.0, 107.0, 83.0, 68.0, 68.0, 70.0, 70.0, 81.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 87.0, 114.0, 80.0, 108.0, 110.0, 121.0, 114.0, 165.0, 93.0], "value": 107.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 134.0, 147.0, 152.0, 159.0, 170.0, 169.0, 164.0, 167.0, 160.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 177.0, 179.0, 180.0, 183.0, 176.0, 175.0, 184.0, 183.0], "value": 179.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 68.0, 70.0, 69.0, 68.0, 69.0, 78.0, 83.0, 89.0], "value": 72.7, "unit": "score"}]}]} 13:17:18 INFO - Return code: 0 13:17:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:17:19 ERROR - # TBPL SUCCESS # 13:17:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:17:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:17:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:17:19 INFO - 2016-12-10 13:17:19.050 screenresolution[1662:11822] starting screenresolution argv=screenresolution get 13:17:19 INFO - 2016-12-10 13:17:19.052 screenresolution[1662:11822] Display 0: 1600x1200x32@60 13:17:19 INFO - 2016-12-10 13:17:19.058 screenresolution[1663:11825] starting screenresolution argv=screenresolution list 13:17:19 INFO - Available Modes on Display 0 13:17:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:17:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:17:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:17:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:17:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:17:19 INFO - Intel Iris: 13:17:19 INFO - Chipset Model: Intel Iris 13:17:19 INFO - Type: GPU 13:17:19 INFO - Bus: Built-In 13:17:19 INFO - VRAM (Dynamic, Max): 1536 MB 13:17:19 INFO - Vendor: Intel (0x8086) 13:17:19 INFO - Device ID: 0x0a2e 13:17:19 INFO - Revision ID: 0x0009 13:17:19 INFO - Displays: 13:17:19 INFO - Display: 13:17:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:17:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:17:19 INFO - Display Serial Number: 1600x1200 60 13:17:19 INFO - Main Display: Yes 13:17:19 INFO - Mirror: Off 13:17:19 INFO - Online: Yes 13:17:19 INFO - Rotation: Supported 13:17:19 INFO - Return code: 0 13:17:19 INFO - Running post-action listener: _resource_record_post_action 13:17:19 INFO - [mozharness: 2016-12-10 21:17:19.297587Z] Finished run-tests step (success) 13:17:19 INFO - Running post-run listener: _resource_record_post_run 13:17:19 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 32121344; Write bytes: 555781120; Read time: 204; Write time: 1686 13:17:19 INFO - TinderboxPrint: CPU usage
54.6% 13:17:19 INFO - TinderboxPrint: I/O read bytes / time
32,121,344 / 204 13:17:19 INFO - TinderboxPrint: I/O write bytes / time
555,781,120 / 1,686 13:17:19 INFO - TinderboxPrint: CPU idle
2,181.4 (45.4%) 13:17:19 INFO - TinderboxPrint: CPU system
970.0 (20.2%) 13:17:19 INFO - TinderboxPrint: CPU user
1,658.3 (34.5%) 13:17:19 INFO - TinderboxPrint: Swap in / out
277,938,176 / 0 13:17:19 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 239378944; Write bytes: 234931712; Read time: 17364; Write time: 489 13:17:19 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 30278144; Write bytes: 301180416; Read time: 183; Write time: 1154 13:17:19 INFO - Running post-run listener: _upload_blobber_files 13:17:19 INFO - Blob upload gear active. 13:17:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:17:19 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 13:17:19 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:17:19 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:17:20 INFO - (blobuploader) - INFO - Open directory for files ... 13:17:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:17:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:17:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:17:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:17:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:17:21 INFO - (blobuploader) - INFO - Done attempting. 13:17:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:17:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:17:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:17:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:17:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:17:22 INFO - (blobuploader) - INFO - Done attempting. 13:17:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:17:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:17:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:17:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:17:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:17:24 INFO - (blobuploader) - INFO - Done attempting. 13:17:24 INFO - (blobuploader) - INFO - Iteration through files over. 13:17:24 INFO - Return code: 0 13:17:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:17:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:17:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1361595afd0be0ef7dbfd7bd19994c9bc5207a8ca0cced6930033a55fa913d5c151a0d63b8b61ee9bc3a7086a8b96e572c054af7c269e713ec923ca2ce4e2e3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bf1fd502812f32a3376d4b8a4f4bde12181e1a529dcfebe450b1a02f6fb305a32e10b7659365a9b03b8ff3b066aad2ee08f18fa82c1f14eb0c4b35d4070e6d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fdeacb7b3e12844fcf05abcbe3b202993728a42f359b82864ac8d1727bd7870f044fa09e9c3bdcc3104fcef686b4dc8b4f2e2bbf2c9341d565a5b8f7a701264c"} 13:17:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:17:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:17:24 INFO - Contents: 13:17:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1361595afd0be0ef7dbfd7bd19994c9bc5207a8ca0cced6930033a55fa913d5c151a0d63b8b61ee9bc3a7086a8b96e572c054af7c269e713ec923ca2ce4e2e3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bf1fd502812f32a3376d4b8a4f4bde12181e1a529dcfebe450b1a02f6fb305a32e10b7659365a9b03b8ff3b066aad2ee08f18fa82c1f14eb0c4b35d4070e6d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fdeacb7b3e12844fcf05abcbe3b202993728a42f359b82864ac8d1727bd7870f044fa09e9c3bdcc3104fcef686b4dc8b4f2e2bbf2c9341d565a5b8f7a701264c"} 13:17:24 INFO - Running post-run listener: copy_logs_to_upload_dir 13:17:24 INFO - Copying logs to upload dir... 13:17:24 INFO - mkdir: /builds/slave/test/build/upload/logs 13:17:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.266372 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-12-10 13:17:24.561893) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-10 13:17:24.565796) ========= 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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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/Graphics/sha512/1361595afd0be0ef7dbfd7bd19994c9bc5207a8ca0cced6930033a55fa913d5c151a0d63b8b61ee9bc3a7086a8b96e572c054af7c269e713ec923ca2ce4e2e3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bf1fd502812f32a3376d4b8a4f4bde12181e1a529dcfebe450b1a02f6fb305a32e10b7659365a9b03b8ff3b066aad2ee08f18fa82c1f14eb0c4b35d4070e6d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fdeacb7b3e12844fcf05abcbe3b202993728a42f359b82864ac8d1727bd7870f044fa09e9c3bdcc3104fcef686b4dc8b4f2e2bbf2c9341d565a5b8f7a701264c"} build_url:https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg program finished with exit code 0 elapsedTime=0.011036 build_url: 'https://queue.taskcluster.net/v1/task/XLny4nnqQoCg3oh7BKr0fA/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/1361595afd0be0ef7dbfd7bd19994c9bc5207a8ca0cced6930033a55fa913d5c151a0d63b8b61ee9bc3a7086a8b96e572c054af7c269e713ec923ca2ce4e2e3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bf1fd502812f32a3376d4b8a4f4bde12181e1a529dcfebe450b1a02f6fb305a32e10b7659365a9b03b8ff3b066aad2ee08f18fa82c1f14eb0c4b35d4070e6d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fdeacb7b3e12844fcf05abcbe3b202993728a42f359b82864ac8d1727bd7870f044fa09e9c3bdcc3104fcef686b4dc8b4f2e2bbf2c9341d565a5b8f7a701264c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-10 13:17:24.600411) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 13:17:24.600746) ========= 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.siZAQ4zX4j/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.JzXzaHtLUB/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.005140 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 13:17:24.649435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-10 13:17:24.649775) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-10 13:17:24.650190) ========= ========= Total master_lag: 0.20 =========