builder: graphics_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0060 starttime: 1482941300.72 results: success (0) buildid: 20161228072937 builduid: 19fed2e515844d38b83f2ef9c30114e4 revision: a646815f589b78e773c2c58fc460d7c6dfe0b977 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:20.719476) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:20.719961) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:20.720245) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:20.745514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:20.745934) ========= 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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-28 08:08: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% 149M=0s 2016-12-28 08:08:20 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118687 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:20.891395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:20.891754) ========= 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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.019667 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:20.940316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 08:08:20.940691) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev a646815f589b78e773c2c58fc460d7c6dfe0b977 --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 a646815f589b78e773c2c58fc460d7c6dfe0b977 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qhBX0A5tEb/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.4fv4I6Yhji/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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-28 08:08:21,015 truncating revision to first 12 chars 2016-12-28 08:08:21,015 Setting DEBUG logging. 2016-12-28 08:08:21,015 attempt 1/10 2016-12-28 08:08:21,015 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/a646815f589b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-28 08:08:21,877 unpacking tar archive at: graphics-a646815f589b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.145669 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-28 08:08:22.113197) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:22.113539) ========= script_repo_revision: a646815f589b78e773c2c58fc460d7c6dfe0b977 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:22.113930) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:22.114195) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-28 08:08:22.135005) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-28 08:08:22.135424) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:08:22 INFO - MultiFileLogger online at 20161228 08:08:22 in /builds/slave/test 08:08:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 08:08:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:08:22 INFO - {'append_to_log': False, 08:08:22 INFO - 'base_work_dir': '/builds/slave/test', 08:08:22 INFO - 'blob_upload_branch': 'Graphics', 08:08:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:08:22 INFO - 'branch': 'Graphics', 08:08:22 INFO - 'buildbot_json_path': 'buildprops.json', 08:08:22 INFO - 'config_files': ('talos/mac_config.py',), 08:08:22 INFO - 'default_actions': ('clobber', 08:08:22 INFO - 'read-buildbot-config', 08:08:22 INFO - 'download-and-extract', 08:08:22 INFO - 'populate-webroot', 08:08:22 INFO - 'create-virtualenv', 08:08:22 INFO - 'install', 08:08:22 INFO - 'run-tests'), 08:08:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:08:22 INFO - 'download_minidump_stackwalk': True, 08:08:22 INFO - 'download_symbols': 'ondemand', 08:08:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:08:22 INFO - 'tooltool.py': '/tools/tooltool.py', 08:08:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:08:22 INFO - '/tools/misc-python/virtualenv.py')}, 08:08:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:08:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:08:22 INFO - 'installer_path': 'installer.exe', 08:08:22 INFO - 'log_level': 'info', 08:08:22 INFO - 'log_name': 'talos', 08:08:22 INFO - 'log_to_console': True, 08:08:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:08:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:08:22 INFO - 'opt_config_files': (), 08:08:22 INFO - 'pip_index': False, 08:08:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:22 INFO - 'cmd': ('bash', 08:08:22 INFO - '-c', 08:08:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:22 INFO - 'enabled': True, 08:08:22 INFO - 'halt_on_failure': False, 08:08:22 INFO - 'name': 'check_screen_resolution'},), 08:08:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:08:22 INFO - 'cmd': ('bash', 08:08:22 INFO - '-c', 08:08:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:08:22 INFO - 'enabled': True, 08:08:22 INFO - 'halt_on_failure': False, 08:08:22 INFO - 'name': 'check_screen_resolution'},), 08:08:22 INFO - 'run_cmd_checks_enabled': True, 08:08:22 INFO - 'sps_profile': False, 08:08:22 INFO - 'sps_profile_interval': 0, 08:08:22 INFO - 'suite': 'dromaeojs', 08:08:22 INFO - 'system_bits': '32', 08:08:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:08:22 INFO - 'title': 't-yosemite-r7-0060', 08:08:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:08:22 INFO - 'use_talos_json': True, 08:08:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:08:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:08:22 INFO - 'work_dir': 'build'} 08:08:22 INFO - [mozharness: 2016-12-28 16:08:22.323860Z] Running clobber step. 08:08:22 INFO - Running pre-action listener: _resource_record_pre_action 08:08:22 INFO - Running main action method: clobber 08:08:22 INFO - rmtree: /builds/slave/test/build 08:08:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:08:23 INFO - Running post-action listener: _resource_record_post_action 08:08:23 INFO - [mozharness: 2016-12-28 16:08:23.860193Z] Finished clobber step (success) 08:08:23 INFO - [mozharness: 2016-12-28 16:08:23.860459Z] Running read-buildbot-config step. 08:08:23 INFO - Running pre-action listener: _resource_record_pre_action 08:08:23 INFO - Running main action method: read_buildbot_config 08:08:23 INFO - Using buildbot properties: 08:08:23 INFO - { 08:08:23 INFO - "project": "", 08:08:23 INFO - "product": "firefox", 08:08:23 INFO - "script_repo_revision": "default", 08:08:23 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs", 08:08:23 INFO - "repository": "", 08:08:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs", 08:08:23 INFO - "buildid": "20161228072937", 08:08:23 INFO - "pgo_build": "False", 08:08:23 INFO - "basedir": "/builds/slave/test", 08:08:23 INFO - "buildnumber": 15, 08:08:23 INFO - "slavename": "t-yosemite-r7-0060", 08:08:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 08:08:23 INFO - "platform": "yosemite_r7", 08:08:23 INFO - "scheduler": "tests-graphics-macosx64-talos", 08:08:23 INFO - "branch": "graphics", 08:08:23 INFO - "repo_path": "projects/graphics", 08:08:23 INFO - "revision": "a646815f589b78e773c2c58fc460d7c6dfe0b977", 08:08:23 INFO - "stage_platform": "macosx64", 08:08:23 INFO - "builduid": "19fed2e515844d38b83f2ef9c30114e4", 08:08:23 INFO - "slavebuilddir": "test" 08:08:23 INFO - } 08:08:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:08:23 INFO - Running post-action listener: _resource_record_post_action 08:08:23 INFO - [mozharness: 2016-12-28 16:08:23.866366Z] Finished read-buildbot-config step (success) 08:08:23 INFO - [mozharness: 2016-12-28 16:08:23.866587Z] Running download-and-extract step. 08:08:23 INFO - Running pre-action listener: _resource_record_pre_action 08:08:23 INFO - Running main action method: download_and_extract 08:08:23 INFO - mkdir: /builds/slave/test/build/tests 08:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:23 INFO - trying https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:08:23 INFO - Downloading https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:08:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:08:25 INFO - Downloaded 1199 bytes. 08:08:25 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:08:25 INFO - Using the following test package requirements: 08:08:25 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:08:25 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:08:25 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:08:25 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:08:25 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:08:25 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:08:25 INFO - u'jsshell-mac.zip'], 08:08:25 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:08:25 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:08:25 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:08:25 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:08:25 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:08:25 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:08:25 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:08:25 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:08:25 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:08:25 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:08:25 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:08:25 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:08:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:08:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:08:25 INFO - Fetch https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:08:26 INFO - Expected file size: 16294425 08:08:26 INFO - Obtained file size: 16294425 08:08:33 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:08:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:08:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:08:33 INFO - Fetch https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:08:34 INFO - Expected file size: 16294425 08:08:34 INFO - Obtained file size: 16294425 08:08:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:08:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:08:40 INFO - Fetch https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:08:41 INFO - Expected file size: 14409776 08:08:41 INFO - Obtained file size: 14409776 08:08:42 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')]} 08:08:42 INFO - trying https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:08:42 INFO - Downloading https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:08:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:08:44 INFO - Downloaded 69613313 bytes. 08:08:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:08:44 INFO - mkdir: /builds/slave/test/properties 08:08:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:08:44 INFO - Writing to file /builds/slave/test/properties/build_url 08:08:44 INFO - Contents: 08:08:44 INFO - build_url:https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:08:44 INFO - Running post-action listener: _resource_record_post_action 08:08:44 INFO - Running post-action listener: set_extra_try_arguments 08:08:44 INFO - [mozharness: 2016-12-28 16:08:44.953800Z] Finished download-and-extract step (success) 08:08:44 INFO - [mozharness: 2016-12-28 16:08:44.953955Z] Running populate-webroot step. 08:08:44 INFO - Running pre-action listener: _resource_record_pre_action 08:08:44 INFO - Running main action method: populate_webroot 08:08:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:08:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:08:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:08:44 INFO - '--tppagecycles', 08:08:44 INFO - '7'], 08:08:44 INFO - 'tests': ['tsvgm']}}, 08:08:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:08:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:08:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:08:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:08:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:08:44 INFO - 'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:08:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:08:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:08:44 INFO - 'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['damp', 'tps']}, 08:08:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:08:44 INFO - 'tests': ['damp', 'tps']}, 08:08:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['dromaeo_dom']}, 08:08:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:08:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['basic_compositor_video']}, 08:08:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:08:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['a11yr', 08:08:44 INFO - 'ts_paint', 08:08:44 INFO - 'tpaint', 08:08:44 INFO - 'sessionrestore', 08:08:44 INFO - 'sessionrestore_no_auto_restore', 08:08:44 INFO - 'tabpaint']}, 08:08:44 INFO - 'other-e10s': {'tests': ['a11yr', 08:08:44 INFO - 'ts_paint', 08:08:44 INFO - 'tpaint', 08:08:44 INFO - 'sessionrestore', 08:08:44 INFO - 'sessionrestore_no_auto_restore', 08:08:44 INFO - 'tabpaint']}, 08:08:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:08:44 INFO - 'ts_paint', 08:08:44 INFO - 'tpaint', 08:08:44 INFO - 'sessionrestore', 08:08:44 INFO - 'sessionrestore_no_auto_restore', 08:08:44 INFO - 'tabpaint']}, 08:08:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:08:44 INFO - 'ts_paint', 08:08:44 INFO - 'tpaint', 08:08:44 INFO - 'sessionrestore', 08:08:44 INFO - 'sessionrestore_no_auto_restore', 08:08:44 INFO - 'tabpaint']}, 08:08:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['a11yr', 08:08:44 INFO - 'ts_paint', 08:08:44 INFO - 'tpaint', 08:08:44 INFO - 'sessionrestore', 08:08:44 INFO - 'sessionrestore_no_auto_restore', 08:08:44 INFO - 'tabpaint']}, 08:08:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['a11yr', 08:08:44 INFO - 'ts_paint', 08:08:44 INFO - 'tpaint', 08:08:44 INFO - 'sessionrestore', 08:08:44 INFO - 'sessionrestore_no_auto_restore', 08:08:44 INFO - 'tabpaint']}, 08:08:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['tsvgx', 08:08:44 INFO - 'tsvgr_opacity', 08:08:44 INFO - 'tart', 08:08:44 INFO - 'tscrollx', 08:08:44 INFO - 'cart', 08:08:44 INFO - 'tsvg_static']}, 08:08:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:08:44 INFO - 'tsvgr_opacity', 08:08:44 INFO - 'tart', 08:08:44 INFO - 'tscrollx', 08:08:44 INFO - 'cart', 08:08:44 INFO - 'tsvg_static']}, 08:08:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:08:44 INFO - 'talos_options': ['--disable-e10s'], 08:08:44 INFO - 'tests': ['tp5o']}, 08:08:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:08:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:08:44 INFO - 'talos_options': ['--disable-e10s', 08:08:44 INFO - '--xperf_path', 08:08:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:44 INFO - 'tests': ['tp5n']}, 08:08:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:08:44 INFO - 'talos_options': ['--xperf_path', 08:08:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:08:44 INFO - 'tests': ['tp5n']}}, 08:08:44 INFO - 'talos.zip': {'path': '', 08:08:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:08:44 INFO - Running post-action listener: _resource_record_post_action 08:08:44 INFO - [mozharness: 2016-12-28 16:08:44.972102Z] Finished populate-webroot step (success) 08:08:44 INFO - [mozharness: 2016-12-28 16:08:44.972205Z] Running create-virtualenv step. 08:08:44 INFO - Running pre-action listener: _resource_record_pre_action 08:08:44 INFO - Running main action method: create_virtualenv 08:08:44 INFO - Creating virtualenv /builds/slave/test/build/venv 08:08: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 08:08:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:08:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:08:45 INFO - Using real prefix '/tools/python27' 08:08:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:08:45 INFO - Installing distribute.............................................................................................................................................................................................done. 08:08:48 INFO - Installing pip.................done. 08:08:48 INFO - Return code: 0 08:08:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:08:48 INFO - Reading from file tmpfile_stdout 08:08:48 INFO - Output received: 08:08: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) 08:08:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:08: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')]} 08:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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')]} 08:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08: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 0x104962b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104965768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb90cb7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, '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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:08: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 08:08: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 08:08:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:08:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:48 INFO - 'HOME': '/Users/cltbld', 08:08:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:48 INFO - 'LOGNAME': 'cltbld', 08:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:48 INFO - 'MOZ_NO_REMOTE': '1', 08:08:48 INFO - 'NO_EM_RESTART': '1', 08:08:48 INFO - 'PAGER': '/bin/cat', 08:08:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:48 INFO - 'PWD': '/builds/slave/test', 08:08:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:48 INFO - 'SHELL': '/bin/bash', 08:08:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:08:48 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:08:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:48 INFO - 'USER': 'cltbld', 08:08:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:48 INFO - 'XPC_FLAGS': '0x0', 08:08:48 INFO - 'XPC_SERVICE_NAME': '0', 08:08:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:49 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 08:08:49 INFO - Cleaning up... 08:08:49 INFO - Return code: 0 08:08:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:49 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 0x104962b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104965768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb90cb7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, '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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:08:49 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 08:08:49 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 08:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:49 INFO - 'HOME': '/Users/cltbld', 08:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:49 INFO - 'LOGNAME': 'cltbld', 08:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:49 INFO - 'MOZ_NO_REMOTE': '1', 08:08:49 INFO - 'NO_EM_RESTART': '1', 08:08:49 INFO - 'PAGER': '/bin/cat', 08:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:49 INFO - 'PWD': '/builds/slave/test', 08:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:49 INFO - 'SHELL': '/bin/bash', 08:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:08:49 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:49 INFO - 'USER': 'cltbld', 08:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:49 INFO - 'XPC_FLAGS': '0x0', 08:08:49 INFO - 'XPC_SERVICE_NAME': '0', 08:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:49 INFO - Downloading/unpacking psutil>=3.1.1 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:08:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:53 INFO - Installing collected packages: psutil 08:08:53 INFO - Running setup.py install for psutil 08:08:53 INFO - building 'psutil._psutil_osx' extension 08:08: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:08: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 08:08: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 08:08: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 08:08:53 INFO - building 'psutil._psutil_posix' extension 08:08: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 08:08:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:08:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:08:53 INFO - ^ 08:08:53 INFO - 1 warning generated. 08:08: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 08:08:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:08:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:08:53 INFO - Successfully installed psutil 08:08:53 INFO - Cleaning up... 08:08:53 INFO - Return code: 0 08:08:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104962b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104965768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb90cb7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, '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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:08:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:08:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:08:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:08:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:53 INFO - 'HOME': '/Users/cltbld', 08:08:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:53 INFO - 'LOGNAME': 'cltbld', 08:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:53 INFO - 'MOZ_NO_REMOTE': '1', 08:08:53 INFO - 'NO_EM_RESTART': '1', 08:08:53 INFO - 'PAGER': '/bin/cat', 08:08:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:53 INFO - 'PWD': '/builds/slave/test', 08:08:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:53 INFO - 'SHELL': '/bin/bash', 08:08:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:08:53 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:08:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:53 INFO - 'USER': 'cltbld', 08:08:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:53 INFO - 'XPC_FLAGS': '0x0', 08:08:53 INFO - 'XPC_SERVICE_NAME': '0', 08:08:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:08:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:08:56 INFO - Installing collected packages: mozsystemmonitor 08:08:56 INFO - Running setup.py install for mozsystemmonitor 08:08:56 INFO - Successfully installed mozsystemmonitor 08:08:56 INFO - Cleaning up... 08:08:57 INFO - Return code: 0 08:08:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104962b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104965768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb90cb7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, '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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:57 INFO - 'HOME': '/Users/cltbld', 08:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:57 INFO - 'LOGNAME': 'cltbld', 08:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:57 INFO - 'MOZ_NO_REMOTE': '1', 08:08:57 INFO - 'NO_EM_RESTART': '1', 08:08:57 INFO - 'PAGER': '/bin/cat', 08:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:57 INFO - 'PWD': '/builds/slave/test', 08:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:57 INFO - 'SHELL': '/bin/bash', 08:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:08:57 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:57 INFO - 'USER': 'cltbld', 08:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:57 INFO - 'XPC_FLAGS': '0x0', 08:08:57 INFO - 'XPC_SERVICE_NAME': '0', 08:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:57 INFO - Downloading/unpacking jsonschema==2.5.1 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08: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 08:08:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:08:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:00 INFO - Downloading functools32-3.2.3-2.tar.gz 08:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:09:00 INFO - warning: no files found matching '*.txt' 08:09:00 INFO - no previously-included directories found matching 'build' 08:09:00 INFO - no previously-included directories found matching 'dist' 08:09:00 INFO - no previously-included directories found matching '.git*' 08:09:00 INFO - Installing collected packages: jsonschema, functools32 08:09:00 INFO - Running setup.py install for functools32 08:09:00 INFO - warning: no files found matching '*.txt' 08:09:00 INFO - no previously-included directories found matching 'build' 08:09:00 INFO - no previously-included directories found matching 'dist' 08:09:00 INFO - no previously-included directories found matching '.git*' 08:09:00 INFO - Successfully installed jsonschema functools32 08:09:00 INFO - Cleaning up... 08:09:00 INFO - Return code: 0 08:09:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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 0x104962b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104965768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb90cb7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, '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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:09: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 08:09: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 08:09:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:09:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:00 INFO - 'HOME': '/Users/cltbld', 08:09:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:00 INFO - 'LOGNAME': 'cltbld', 08:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:00 INFO - 'MOZ_NO_REMOTE': '1', 08:09:00 INFO - 'NO_EM_RESTART': '1', 08:09:00 INFO - 'PAGER': '/bin/cat', 08:09:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:00 INFO - 'PWD': '/builds/slave/test', 08:09:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:00 INFO - 'SHELL': '/bin/bash', 08:09:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:09:00 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:09:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:00 INFO - 'USER': 'cltbld', 08:09:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:00 INFO - 'XPC_FLAGS': '0x0', 08:09:00 INFO - 'XPC_SERVICE_NAME': '0', 08:09:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:09:00 INFO - Cleaning up... 08:09:00 INFO - Return code: 0 08:09:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104962b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104965768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb90cb7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, '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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:09:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:09:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:09:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:00 INFO - 'HOME': '/Users/cltbld', 08:09:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:00 INFO - 'LOGNAME': 'cltbld', 08:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:00 INFO - 'MOZ_NO_REMOTE': '1', 08:09:00 INFO - 'NO_EM_RESTART': '1', 08:09:00 INFO - 'PAGER': '/bin/cat', 08:09:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:00 INFO - 'PWD': '/builds/slave/test', 08:09:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:00 INFO - 'SHELL': '/bin/bash', 08:09:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:09:00 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:09:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:00 INFO - 'USER': 'cltbld', 08:09:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:00 INFO - 'XPC_FLAGS': '0x0', 08:09:00 INFO - 'XPC_SERVICE_NAME': '0', 08:09:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:01 INFO - Downloading/unpacking blobuploader==1.2.4 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:03 INFO - Downloading blobuploader-1.2.4.tar.gz 08:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:09:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:09:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:04 INFO - Downloading docopt-0.6.1.tar.gz 08:09:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:09:05 INFO - Installing collected packages: blobuploader, requests, docopt 08:09:05 INFO - Running setup.py install for blobuploader 08:09:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:09:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:09:05 INFO - Running setup.py install for requests 08:09:05 INFO - Running setup.py install for docopt 08:09:05 INFO - Successfully installed blobuploader requests docopt 08:09:05 INFO - Cleaning up... 08:09:05 INFO - Return code: 0 08:09:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104962b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104965768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb90cb7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, '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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:09:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:09:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:09:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:09:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:05 INFO - 'HOME': '/Users/cltbld', 08:09:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:05 INFO - 'LOGNAME': 'cltbld', 08:09:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:05 INFO - 'MOZ_NO_REMOTE': '1', 08:09:05 INFO - 'NO_EM_RESTART': '1', 08:09:05 INFO - 'PAGER': '/bin/cat', 08:09:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:05 INFO - 'PWD': '/builds/slave/test', 08:09:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:05 INFO - 'SHELL': '/bin/bash', 08:09:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:09:05 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:09:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:05 INFO - 'USER': 'cltbld', 08:09:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:05 INFO - 'XPC_FLAGS': '0x0', 08:09:05 INFO - 'XPC_SERVICE_NAME': '0', 08:09:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-uXSIjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-4Qk1DV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-jUSfaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-sYFuwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-D6XusV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-az5MQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-3Sv00f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-wy77QD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-N8uKsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-30mbJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-YF549Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-kUMUKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-d6XGvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-GjRvXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-vGNsFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-3DUmJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-5ATjwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:09:07 INFO - Running setup.py install for manifestparser 08:09:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:09:08 INFO - Running setup.py install for mozcrash 08:09:08 INFO - Running setup.py install for mozdebug 08:09:08 INFO - Running setup.py install for mozdevice 08:09:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:09:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:09:08 INFO - Running setup.py install for mozfile 08:09:08 INFO - Running setup.py install for mozhttpd 08:09:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:09:08 INFO - Running setup.py install for mozinfo 08:09:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:09:08 INFO - Running setup.py install for mozInstall 08:09:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:09:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:09:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:09:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:09:09 INFO - Running setup.py install for mozleak 08:09:09 INFO - Running setup.py install for mozlog 08:09:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:09:09 INFO - Running setup.py install for moznetwork 08:09:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:09:09 INFO - Running setup.py install for mozprocess 08:09:09 INFO - Running setup.py install for mozprofile 08:09:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:09:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:09:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:09:09 INFO - Running setup.py install for mozrunner 08:09:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:09:10 INFO - Running setup.py install for mozscreenshot 08:09:10 INFO - Running setup.py install for moztest 08:09:10 INFO - Running setup.py install for mozversion 08:09:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:09:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:09:10 INFO - Cleaning up... 08:09:10 INFO - Return code: 0 08:09:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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 0x104962b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104965768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb90cb7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, '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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:09: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 08:09: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 08:09:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:09:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:10 INFO - 'HOME': '/Users/cltbld', 08:09:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:10 INFO - 'LOGNAME': 'cltbld', 08:09:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:10 INFO - 'MOZ_NO_REMOTE': '1', 08:09:10 INFO - 'NO_EM_RESTART': '1', 08:09:10 INFO - 'PAGER': '/bin/cat', 08:09:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:10 INFO - 'PWD': '/builds/slave/test', 08:09:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:10 INFO - 'SHELL': '/bin/bash', 08:09:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:09:10 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:09:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:10 INFO - 'USER': 'cltbld', 08:09:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:10 INFO - 'XPC_FLAGS': '0x0', 08:09:10 INFO - 'XPC_SERVICE_NAME': '0', 08:09:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:10 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-3vXPR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:10 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:10 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-w388TR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09: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)) 08:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-f66m4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09: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)) 08:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-ILL46Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09: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)) 08:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-BbiUiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09: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)) 08:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-XtStA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09: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)) 08:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-sSlVA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09: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)) 08:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-S9tclw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09: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)) 08:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-OIaYVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09: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)) 08:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-R5ZU25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-uP0sDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:09:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-Vj5r2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09: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)) 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-RaCcxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09: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)) 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-hiLB_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09: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)) 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-0x1CH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09: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)) 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-35bf6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09: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)) 08:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-oUYzVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:15 INFO - Downloading blessings-1.6.tar.gz 08:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:09:15 INFO - Installing collected packages: blessings 08:09:15 INFO - Running setup.py install for blessings 08:09:15 INFO - Successfully installed blessings 08:09:15 INFO - Cleaning up... 08:09:15 INFO - Return code: 0 08:09:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:09:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:09:15 INFO - Reading from file tmpfile_stdout 08:09:15 INFO - Current package versions: 08:09:15 INFO - blessings == 1.6 08:09:15 INFO - blobuploader == 1.2.4 08:09:15 INFO - docopt == 0.6.1 08:09:15 INFO - functools32 == 3.2.3-2 08:09:15 INFO - jsonschema == 2.5.1 08:09:15 INFO - manifestparser == 1.1 08:09:15 INFO - mozInstall == 1.12 08:09:15 INFO - mozcrash == 1.0 08:09:15 INFO - mozdebug == 0.1 08:09:15 INFO - mozdevice == 0.49 08:09:15 INFO - mozfile == 1.2 08:09:15 INFO - mozhttpd == 0.7 08:09:15 INFO - mozinfo == 0.9 08:09:15 INFO - mozleak == 0.1 08:09:15 INFO - mozlog == 3.4 08:09:15 INFO - moznetwork == 0.27 08:09:15 INFO - mozprocess == 0.23 08:09:15 INFO - mozprofile == 0.28 08:09:15 INFO - mozrunner == 6.13 08:09:15 INFO - mozscreenshot == 0.1 08:09:15 INFO - mozsystemmonitor == 0.3 08:09:15 INFO - moztest == 0.7 08:09:15 INFO - mozversion == 1.4 08:09:15 INFO - psutil == 3.1.1 08:09:15 INFO - requests == 1.2.3 08:09:15 INFO - wsgiref == 0.1.2 08:09:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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 0x104962b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104965768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb90cb7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, '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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:09: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 08:09: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 08:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:16 INFO - 'HOME': '/Users/cltbld', 08:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:16 INFO - 'LOGNAME': 'cltbld', 08:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:16 INFO - 'MOZ_NO_REMOTE': '1', 08:09:16 INFO - 'NO_EM_RESTART': '1', 08:09:16 INFO - 'PAGER': '/bin/cat', 08:09:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:16 INFO - 'PWD': '/builds/slave/test', 08:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:16 INFO - 'SHELL': '/bin/bash', 08:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:09:16 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:16 INFO - 'USER': 'cltbld', 08:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:16 INFO - 'XPC_FLAGS': '0x0', 08:09:16 INFO - 'XPC_SERVICE_NAME': '0', 08:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09: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)) 08:09:16 INFO - Cleaning up... 08:09:16 INFO - Return code: 0 08:09:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104962b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104965768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feb90cb7180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, '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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 08:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:17 INFO - 'HOME': '/Users/cltbld', 08:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:17 INFO - 'LOGNAME': 'cltbld', 08:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:17 INFO - 'MOZ_NO_REMOTE': '1', 08:09:17 INFO - 'NO_EM_RESTART': '1', 08:09:17 INFO - 'PAGER': '/bin/cat', 08:09:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:17 INFO - 'PWD': '/builds/slave/test', 08:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:17 INFO - 'SHELL': '/bin/bash', 08:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:09:17 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:17 INFO - 'USER': 'cltbld', 08:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:17 INFO - 'XPC_FLAGS': '0x0', 08:09:17 INFO - 'XPC_SERVICE_NAME': '0', 08:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:09:17 INFO - Cleaning up... 08:09:17 INFO - Return code: 0 08:09:17 INFO - Running post-action listener: _resource_record_post_action 08:09:17 INFO - Running post-action listener: _start_resource_monitoring 08:09:17 INFO - Starting resource monitoring. 08:09:17 INFO - [mozharness: 2016-12-28 16:09:17.619168Z] Finished create-virtualenv step (success) 08:09:17 INFO - [mozharness: 2016-12-28 16:09:17.620123Z] Running install step. 08:09:17 INFO - Running pre-action listener: _resource_record_pre_action 08:09:17 INFO - Running main action method: install 08:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:09:17 INFO - Reading from file tmpfile_stdout 08:09:17 INFO - Detecting whether we're running mozinstall >=1.0... 08:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:09:17 INFO - Reading from file tmpfile_stdout 08:09:17 INFO - Output received: 08:09:17 INFO - Usage: mozinstall [options] installer 08:09:17 INFO - Options: 08:09:17 INFO - -h, --help show this help message and exit 08:09:17 INFO - -d DEST, --destination=DEST 08:09:17 INFO - Directory to install application into. [default: 08:09:17 INFO - "/builds/slave/test"] 08:09:17 INFO - --app=APP Application being installed. [default: firefox] 08:09:17 INFO - mkdir: /builds/slave/test/build/application 08:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:09:40 INFO - Reading from file tmpfile_stdout 08:09:40 INFO - Output received: 08:09:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:09:40 INFO - Running post-action listener: _resource_record_post_action 08:09:40 INFO - [mozharness: 2016-12-28 16:09:40.078342Z] Finished install step (success) 08:09:40 INFO - [mozharness: 2016-12-28 16:09:40.078473Z] Running run-tests step. 08:09:40 INFO - Running pre-action listener: _resource_record_pre_action 08:09:40 INFO - Running main action method: run_tests 08:09:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:09:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:09:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:09:40 INFO - 2016-12-28 08:09:40.092 screenresolution[1903:14333] starting screenresolution argv=screenresolution get 08:09:40 INFO - 2016-12-28 08:09:40.095 screenresolution[1903:14333] Display 0: 1600x1200x32@60 08:09:40 INFO - 2016-12-28 08:09:40.101 screenresolution[1904:14336] starting screenresolution argv=screenresolution list 08:09:40 INFO - Available Modes on Display 0 08:09:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:09:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:09:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:09:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:09:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:09:40 INFO - Intel Iris: 08:09:40 INFO - Chipset Model: Intel Iris 08:09:40 INFO - Type: GPU 08:09:40 INFO - Bus: Built-In 08:09:40 INFO - VRAM (Dynamic, Max): 1536 MB 08:09:40 INFO - Vendor: Intel (0x8086) 08:09:40 INFO - Device ID: 0x0a2e 08:09:40 INFO - Revision ID: 0x0009 08:09:40 INFO - Displays: 08:09:40 INFO - Display: 08:09:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:09:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:09:40 INFO - Display Serial Number: 1600x1200 60 08:09:40 INFO - Main Display: Yes 08:09:40 INFO - Mirror: Off 08:09:40 INFO - Online: Yes 08:09:40 INFO - Rotation: Supported 08:09:40 INFO - Return code: 0 08:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:09:40 INFO - Python 2.7.3 08:09:40 INFO - Return code: 0 08:09:40 INFO - grabbing minidump binary from tooltool 08:09:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:09:40 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 0x104740d30>, '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 0x104667db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104735be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:09:40 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 08:09:40 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 08:09:40 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 08:09:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:09:40 INFO - Return code: 0 08:09:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:09:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:09:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:09:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:09:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0060 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qhBX0A5tEb/Render', 08:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:40 INFO - 'HOME': '/Users/cltbld', 08:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:40 INFO - 'LOGNAME': 'cltbld', 08:09:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:09:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:40 INFO - 'MOZ_NO_REMOTE': '1', 08:09:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:09:40 INFO - 'NO_EM_RESTART': '1', 08:09:40 INFO - 'PAGER': '/bin/cat', 08:09:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:40 INFO - 'PWD': '/builds/slave/test', 08:09:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:40 INFO - 'SHELL': '/bin/bash', 08:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4fv4I6Yhji/Listeners', 08:09:40 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 08:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:40 INFO - 'USER': 'cltbld', 08:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:40 INFO - 'XPC_FLAGS': '0x0', 08:09:40 INFO - 'XPC_SERVICE_NAME': '0', 08:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:09:40 INFO - mozversion application_buildid: 20161228072937 08:09:40 INFO - mozversion application_changeset: a646815f589b78e773c2c58fc460d7c6dfe0b977 08:09:40 INFO - mozversion application_display_name: Nightly 08:09:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:09:40 INFO - mozversion application_name: Firefox 08:09:40 INFO - mozversion application_remotingname: firefox 08:09:40 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 08:09:40 INFO - mozversion application_vendor: Mozilla 08:09:40 INFO - mozversion application_version: 53.0a1 08:09:40 INFO - mozversion platform_buildid: 20161228072937 08:09:40 INFO - mozversion platform_changeset: a646815f589b78e773c2c58fc460d7c6dfe0b977 08:09:40 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 08:09:40 INFO - mozversion platform_version: 53.0a1 08:09:40 INFO - using testdate: 1482941380 08:09:40 INFO - actual date: 1482941380 08:09:40 INFO - starting webserver on 'localhost:51544' 08:09:40 INFO - SUITE-START | Running 2 tests 08:09:40 INFO - TEST-START | dromaeo_css 08:09:40 INFO - operating with platform_type : mac_ 08:09:40 INFO - Initialising browser for dromaeo_css test... 08:09:40 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmp3GMAKy/profile http://localhost:51544/getInfo.html) 08:09:41 INFO - PROCESS | 1914 | 1482941381176 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:09:43 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 08:09:43 INFO - PROCESS | 1914 | colorDepth:24 08:09:43 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 08:09:43 INFO - PROCESS | 1914 | __metrics 08:09:43 INFO - PROCESS | 1914 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:09:43 INFO - PROCESS | 1914 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:09:44 INFO - TEST-INFO | 1914: exit 0 08:09:44 INFO - Browser initialized. 08:09:44 INFO - Running cycle 1/1 for dromaeo_css test... 08:09:44 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmp3GMAKy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:56 INFO - PROCESS | 1916 | RSS: Main: 232976384 08:09:56 INFO - PROCESS | 1916 | 08:13:00 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51544/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51544/tests/dromaeo/cssquery-ext.html) 08:13:01 INFO - PROCESS | 1916 | RSS: Main: 344145920 08:13:01 INFO - PROCESS | 1916 | 08:14:45 INFO - PROCESS | 1916 | ************************* 08:14:45 INFO - PROCESS | 1916 | A coding exception was thrown and uncaught in a Task. 08:14:45 INFO - PROCESS | 1916 | 08:14:45 INFO - PROCESS | 1916 | Full message: ReferenceError: fetch is not defined 08:14:45 INFO - PROCESS | 1916 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 08:14:45 INFO - PROCESS | 1916 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 08:14:45 INFO - PROCESS | 1916 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 08:14:45 INFO - PROCESS | 1916 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:14:45 INFO - PROCESS | 1916 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:14:45 INFO - PROCESS | 1916 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:14:45 INFO - PROCESS | 1916 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 08:14:45 INFO - PROCESS | 1916 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 08:14:45 INFO - PROCESS | 1916 | TaskImpl@resource://gre/modules/Task.jsm:277:3 08:14:45 INFO - PROCESS | 1916 | asyncFunction@resource://gre/modules/Task.jsm:252:14 08:14:45 INFO - PROCESS | 1916 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 08:14:45 INFO - PROCESS | 1916 | 08:14:45 INFO - PROCESS | 1916 | ************************* 08:16:04 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51544/tests/dromaeo/cssquery-ext.html (next: http://localhost:51544/tests/dromaeo/cssquery-jquery.html) 08:16:05 INFO - PROCESS | 1916 | RSS: Main: 365768704 08:16:05 INFO - PROCESS | 1916 | 08:19:07 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51544/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51544/tests/dromaeo/cssquery-mootools.html) 08:19:08 INFO - PROCESS | 1916 | RSS: Main: 332017664 08:19:08 INFO - PROCESS | 1916 | 08:22:11 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51544/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51544/tests/dromaeo/cssquery-prototype.html) 08:22:11 INFO - PROCESS | 1916 | RSS: Main: 349302784 08:22:11 INFO - PROCESS | 1916 | 08:25:15 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51544/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51544/tests/dromaeo/cssquery-yui.html) 08:25:15 INFO - PROCESS | 1916 | RSS: Main: 327618560 08:25:15 INFO - PROCESS | 1916 | 08:28:19 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:51544/tests/dromaeo/cssquery-yui.html (next: http://localhost:51544/tests/dromaeo/cssquery-dojo.html) 08:28:19 INFO - PROCESS | 1916 | RSS: Main: 545058816 08:28:19 INFO - PROCESS | 1916 | 08:28:19 INFO - PROCESS | 1916 | __start_tp_report 08:28:19 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 08:28:19 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 08:28:19 INFO - PROCESS | 1916 | |i|pagename|runs| 08:28:19 INFO - PROCESS | 1916 | |0;dojo.html;3239.4492936200845;3240.125166205123;3248.3178532508173;3267.55887955126;3315.8673653053866;5336.30628018358;5394.514493695567;5397.514223719864;5464.344278686558;5497.422770609083;5424;5442.210879422481;5458.918116228259;5497.39528651848;5500.779968801253;4989.027139707759;5030.37120359955;5033.723145227011;5070.492950704918;5131.640785145041;4996.7001979881325;5063.8480845574695;5102.464241254671;5131.486851314876;5136.306598609196;4364.7817609119575;5101.642884998052;5105.846824595268;5106.489351064901;5145.845624631267;4950.183219768734;4960.355153829979;4976.925346119811;5014;5019.84940451787;5035.269885866529;5068.847934561969;5075.720835354054;5079.288899554065;5105.978804239167;5401.675899446046;5439.075357189287;5459.454054594509;5466.535344495723;5491.697956612385;5795.3045634523705;5944.989351810203;5992.460678538909;6008.729607167688;6032.517398608101;5933.910991335133;5946.821595352147;6025.367336429699;6050.969745151246;6070.696465176724;5570.6657600544095;5581.469760372758;5586.553075753971;5599.216109744639;5629.380768115504;6010.398960103955;6172.259328880473;6172.722227499773;6189.659568723763;6200.689965501707;5664.8867022659315;5743.994800909824;5744.167095771089;5803.042497987784;5883;5975.820725378246;6017.729202185912;6066.150738896558;6082.057281121433;6091.299500557493;5740.425957404226;5795.362510123968;5805;5857.355690874;5936.7922122725495;5053.620978426633;5946.43508866657;5962.880742385128;5987.700614969234;6032.607880487754;4724.196886529229;4738.981119059419;4740.502247264057;4742.288656701453;4780.784864681098;4100.077482566398;4103.097318589965;4150.584941505886;4201.45381100455;4227.577242275748;5402.459754024566;5413.83758487246;5422.593305502103;5432.239486471897;5459.454054594509;4760.833370832004;5260.552853007461;5510.862228444321;5698.0598201296325;5711.571632127607;5388.110961691355;5388.191771234347;5420.83737487876;5473.370562385377;5481.177823326533;5137.640365174477;5145.8936328689515;5171.4052883918075;5172.991266703032;5221.477852214748;5168.224766285088;6212.937870621417;6213.440790328892;6217.6891155442045;6276.592021518495;5820.621659592282;6000.399960003964;6044.697765111639;6049.153118563493;6050.062240352752;6158.661273629993;6168.475679567287;6182.876342473215;6204.3795620439405;6206.06908963641;5882.5293976480925;5893.233879595625;5894.056950887837;5901.232839730807;5914.260717410324;5784.21913041734;5836.970815146067;5920.970395147997;5968.671723055274;5999.1901093351835;5635.0702134147405;5664.348599911063;5687.544996400361;5713.514351280021;5743.138529220483;5665.631733937281;5699;5700.059490184239;5763.308402991668;5782.855428614318;5574.721263936868;5611.859703507445;5621.690807005654;5650.971745141413;5654.123610840326;4878.512148785093;5696.715164241854;5751.511121554715;5818.94181058184;5821.79623713168;5453.700046497481;5502.8349149525575;5503.009458297545;5540.030494663499;5649.661020338793 08:28:19 INFO - PROCESS | 1916 | |1;ext.html;464.6770494506317;486.1613716339315;504.8662104542295;523.7800123947942;531.7128750474744;30377.84811075944;38919;40655.796721016355;40711.18577628446;40825.59174408255;41616.167676646524;42060.57939420613;42143.78928105355;42189.57810421903;42197;9147.131022552847;9359;9366.110219529133;9368.297377696666;9382.967873533906;8395.748127556182;8658.437201581908;10929.672109836718;11229.989300962912;11253.943730281337;11232;11278.54885804571;11300.886991130066;11306.095512358992;11309.773804523946;8369.49783013015;11140.108791296718;11226.494807733672;11232.943835280854;11247.100231981503;8404.453710508797;8551.144885511461;8569.22876941072;8602.440841345355;9295.62961444834;32555;39073.60926390728;39127.804360978014;39131.41302880459;39167.41248881269;3886.8339498150726;3918.5885482024264;3936.5079365079364;3961.841526338972;4073.9388909166387;27113.59329610038;29285.121446356643;30375.848120759474;30536.236594085105;30627.928022519103;3113.7353324967403;3133.122725636825;3145.182252614337;3260.4294248506417;3286.4413049781356;464552.67723661504;465055;465537;467506;468556;50862.491375086516;60334;61210.387896121356;61251.38748612546;61337.693311533156;63782.72434551283;63984;64036.67981660062;64075.679621602525;64084.67957660182;15154.469593564172;15681.607959801097;15688.372465101496;15689.764653530206;15702.371905123895;10697.732556686145;10959.561617535368;11931.403429828473;12015.33915634631;12016.579419720269;15431.537053888176;17275.3953611623;17566.824331756776;17635.294588216584;17916.731249031276;6433.453156481658;6538.803835884931;6560.311167327457;6570.5400621956005;6589.967050164719;846.7629063862005;858.6007506509378;863.0980625246632;865.2947847504264;867.1545243387646;24800.37999050002;28278.717212827607;28907;29258.33544924554;29475.968341107957;1020.260311274335;1027.7893031928436;1059.7986382587437;1063.5267306048734;1081.567373050786;14227.146371217554;14894.404223831141;14948.252587370587;15096.641302282847;15315.46395876139;494639;497412;499876;506600;524086.37956809974;56938.715306424034;57204;57271.713641431525;57312;57359.71320143456;1359.2931675528669;1383.1493631416606;1405.220102842891;1405.5221224783418;1408.7393832141086;27133.457330852878;28313.575296370982;28460.49175343492;28502.42995140044;28787;49389.25916111189;49742;49835.501644984535;49839.50160498494;49888.50111498839;465397;474593;476205.61897190294;476445;477396;33062.66937330596;37212.44181337228;38043.42934855924;38073.23853522969;38393.692635435924;36004.91985240447;36846.70667555774;46565.53434465612;46916;48706.75646621744;36081.458778118875;37656.43515347217;37797.05507362338;39598;39749.403758944216;37259.254814904096;38086.23827523379;38832;39370.212595747354;39471.802640986614;31564.842175788974;36573;36935;37691.43462848114;38196.61803381931;30686.92595759138;31908;31946.227250564658;33953;34137;24236.1517346892;24320.148794792098;24443.755562444858;24819.751802481744;25237.621435678113 08:28:19 INFO - PROCESS | 1916 | |2;jquery.html;363.12668033379725;372.37441098953764;378.6119227791513;393.091957577995;402.8267844826725;18355.632887342246;19165.904170479127;19421.611567768654;19810.40568782937;19941.40175794725;19338.613227735437;19469.415917522496;19561;19882.403527894152;20215.787052776806;4075.6331930126285;5560.86097847553;5817;5824.883502329951;5835.387284335147;5697.8575535611735;5729.799457019005;5783.566232532556;5784.826455206329;5827.621204621707;5473.562115030808;5672.06410942194;5682.431756824326;5737.9384813809575;5754.942450575503;5661.5187709044785;5676.205331253607;5714.857128571777;5743.684097374643;5758;4799.832005879813;4962.925556116661;5015.448300686958;5031.52200540948;5051.368578927634;24336.201336846312;24398.39004024896;24613.50772984548;24678;24686.876565617058;2412.7345991940874;2605.2575016120504;2616.2674451153707;2622.93442663933;2731.6995130535825;9555.426674399558;9647.517624118766;9695.70912872615;10052.59789608422;10114.544845481898;1735.9913200433916;1781.2429717370067;1795.721663142215;1796.1109250920747;1811.1759149586906;103656.48171759168;108349.99999999921;108498.45750771275;108740.36889446665;109354;9738.659146929824;11103.167262455348;11310.94344528272;11329.263597866113;11536.134789890792;14185.929070354789;14200.786988195185;14213.78679319811;14216.43134274638;14230.359633816503;7943;8048.678052877936;8101.311388532041;8190.918090819016;8212.917870821335;7817.491863028885;7834.334081603013;7853;7984.84030319402;7994.720184793504;4350.760708161019;4353.1293741251875;4370.672199585044;4533.659975501851;4548;1767.487428645704;1841.8526517878538;1888.376835644234;1892.3565987564293;1900.6958886578082;966.4925913895121;973.9172170365457;987.116530705015;987.6740675577042;1038.6053299746047;20278.69581956272;20574.69137962932;20879.582408351747;20892.58214835695;20988.580228395345;1005.4470041477158;1016.588281745894;1039.3140527251978;1039.880413752413;1042.7497400623795;10642.946785266024;10786.220273654915;11013.394263315595;11036.558537658564;11106.722331941766;533432;546136;550525;558209;558295;88867;92287;96775;119582;128716;896.2471523919938;949.3544389814992;1006.7281833904805;1010.2372708604956;1014.5789497358513;18491.722624160917;19253.09046193212;19573;19599.412017639494;19637.429005679227;50995.745021274655;52161;53611.463885360645;53998.550253704656;55818.88362232814;75893;76587;77142;77249;78014.60992695;11170.553177872795;11325.603603873826;11561.421928903354;11807.704807379885;11870.109741769229;8743.34424918121;8892.86660700103;8923.41997770143;9342.57093155798;13317.001224908365;7751.6124193788955;7828.256315650005;8076.71731489395;8203.917960820316;8358.996920369595;7059.435245180476;7127.752643287507;7767.495112817649;8082.19178082187;8208.876866847122;8026.036875574969;8275.048369437593;8328.125546817497;8407.2433480987;8407.90697209359;11392.658220253406;11888.465019074336;12037.75924481523;12136.514539418678;12271.57049503263;11494.712632184262;11654.88345116538;12016.579419720269;12087.335196563921;12207.206531575419 08:28:19 INFO - PROCESS | 1916 | |3;mootools.html;1228.5147366790118;1393.400837639815;1409.9929500352496;1414.7948547460612;1420.7284480390051;7937.44437889348;8414.495130292187;8431.747047588568;8435.198656127674;8447.873281900776;7411.740589079383;7662.923370766304;7687.346575541086;7975.880361794563;8048.8390232195325;2230.397792595998;2252.9098836046537;2276.7723227677184;2277.3054218463362;2280.327303445479;2312.2947501012186;2333.323336232499;2348.9530209395803;2349.7767712067325;2351.400392899817;2026.898655067248;2104.4212841468566;2232.1406258590478;2307.204014614955;2310.780475854791;2298.9310320690406;2303.021215983217;2304.112916527149;2310.3646497213317;2312.537492501506;2245.8989345479495;2255.22194842779;2257.0407576779803;2264.7735226477384;2273.8517048890203;8117.553534555597;8326.04250511187;8342.16578342167;8342.582870856493;8343.45767525115;4992.725400102993;5156.690598564098;5161.225816127573;5183.792648294101;5186.377634683824;3259.6251431085257;3430.1939044324563;3511.3504001759757;3516.507688923553;3527.682508574215;1126.8985791278824;1211.4487908001838;1212.69076385522;1692.5683950592643;1739.0695977652022;10072.841623213351;10119.190464762802;10719.410432426213;10774;10912.34525928439;3749.5687995880276;4151.605597468236;4327.913441731148;4388.473383193973;4399.1641588098155;8600.612972416257;8607.311415086779;8614.91385086141;8624.698135565224;8633.69782057625;4420.756858372756;5035.144025515671;5039.966806804597;5042.546170844642;5060.5698515625845;4120.052387950788;4257.638100761408;4396.010897548026;4604.58558729716;4682.1338052460405;3276.3774882772195;3445.9655403446145;3572.4105522589;3680.343845269586;3690.6862916652385;996.446971930571;1113.3820729495208;1129.4522156754133;1144.5364627325946;1153.2734377342222;835.8913341265596;847.3729440214197;858.2318824651902;909.4725059465494;915.2540679346312;5421.376541697677;5422.186671999231;5615.943840561624;5772.634174471561;5834.824955251349;858.5664239559108;909.9135582119688;925.7639301977953;943.0522325063405;992.533509250651;3293.2919422324317;3380.577427821522;3501.089716673683;3644.7061293240517;3703.962960370362;9664.951675241578;9919.057689519484;9988.850167247498;10063.345882517759;10067.647632332833;9538.046195380546;9881.604735810488;9998.750031249278;10000.24998125129;10114.848277275847;7685.846283074307;7873.25204105609;7875.251851074138;7891.289783919475;7917.3270272026375;5260.684358938476;5434.483724046209;5448.700321482198;5488.313960754906;5573.164025396223;8871.379003469696;9229.907700922904;9371.437713737198;9385.483798391155;9387.624495020124;8926.553672316488;9001.234895033995;9276.860847087164;9409.811803764023;9412.95293523528;6576.210854697467;6805;7076.2923707628825;7110.217876033529;7236.20401755817;7455.366293865082;7624.313811756969;7753.806154846174;7882.132965373919;7949.801254968441;6986;7117.288271172842;7121.572705637678;7185.173705023989;7265.673044712895;6964.965175174092;6966.024756533988;6989.30106989297;7032.542884712682;7044.6125463100025;5316.229146773619;6088.4215999479975;6279.277883043508;6292.402221788922;6308;4620.330052142353;4680.742559159279;4696.765161741968;4722.173619616622;4862.319275301531;4257.808398622132;4776.95223047779;4798.016406636561;4808.134535783593;4834.564889159852 08:28:19 INFO - PROCESS | 1916 | |4;prototype.html;308.58649409790877;348.67776689520076;356.410141216287;367.20683324020087;370.9391141335779;4956.686478083311;5026.396832380119;5029.8179927717;5034.2494841343205;5035.723035233061;4712.622990160788;4807.663463557552;4886.218205087187;4893.241547560125;4996.525330093636;3354.664533546638;3631.7276204284785;3867.845286188556;3952.9604703953023;4015.859444919414;3613.7831730096277;3992.5608183099835;4011.4785077939828;4027.5166979962446;4037.636612704856;3626.6997032700247;3827.7129215308823;3891.6497515223623;3967.2660557796758;4010.899727506821;3029.8485075746235;3831.540215174169;3845.5000849889334;3849.4217370877805;3931.0761970936805;3332.8500217490273;3337.0989832745267;3341.3794309759746;3348.7823291485975;3355.8825441109443;3930.213957208541;4318.568143185687;4530.3431002504785;4760.643481476691;4916.975415122902;3854.4411060396365;3925.8429662813455;4214.145775858643;4341.5441378655105;4374.715643483163;4475.9705267788595;4817.4219093708625;5253.343332083489;5261.657992230493;5279.155335146396;3635.056323400174;3644.890653280406;3653.6894363979095;3655.1958569114754;3659.505966694489;5079.949200508022;5354.705491198021;5457.454254574551;5541.778328866881;5544.667319960775;3049.0243122201127;3621.9796026410663;3726.1988672435014;3733.5519737631657;3733.842508822274;3301.4552598821406;3677.6506231907924;3746.7189960752507;3787.1289603390824;3795.1081495848453;3927.8625248116177;4366.6506679465565;4376.934345984812;4405.45808966859;4425.822967081345;5023.673461225008;5323.653962492425;5334.759935802898;5344.518993290545;5357.330333708286;1161.6108603617884;1164.5865717670274;1211.4003568233782;1215.854097508287;1217.8856346443083;752;770.6686124966207;792.8731402975611;803.8472690188845;841.8526757817358;3968.983572484932;4246.320588705854;4416.580424859633;4653.511381304914;4851.854444366674;4158.9376159357635;4419.867403977886;4892.412910450768;5055.368078990126;5097.8725531861255;3848.13416981177;4122.484689413824;4259.552746961586;4706.10583989041;4762.571368576845;2693.1785805329564;2698.446818402223;2699.4196247806817;2704.17522655588;2730.2901245676267;4891.241857512091;5056.797728090835;5112.028714544224;5377.865553361197;5432.511074003359;4551.407007547398;4759.190937540627;4905.945221777335;5169.974150129226;5426.511613954763;5101.413337466239;5130.33305670268;5189.50699683537;5357.267793425303;5894.351621321651;4839.782209800638;5472.261244731911;5504.2018907257425;5507.366652834815;5533.833984980457;4823.686460380064;5348.465153484621;5401.8919621606565;5464.9070185963765;5526.309211348582;5751.827445176651;5764.625299355528;5767.452092051249;5771;5813.505852002627;3591.2941352857183;3711.3505136601525;3714.888553343404;3728.5339332583426;3746.6815320698047;5192.948070519397;5457.290552228185;5504.7522861470525;5507.284053073236;5509.47659972302;4444.82220711168;4583.587477127075;5131.4868513148385;5359.365393555003;5364.463553644604;4373.741562535931;4560.110778398181;4735.242361222188;5077.213031980049;5337.626366154332;4899.3010277240455;5148.459411761711;5214.921776173436;5258.263843061898;5262.421133675216;5035.949640503694;5077.365329333833;5216.817411390583;5240.170308033619;5248.973755131353;4252.170826688766;4591.678582499193;5211.218317252443;5226.660267082628;5230.5801597649925 08:28:19 INFO - PROCESS | 1916 | |5;yui.html;701.1060897355874;736.1313649893124;759.4000739415861;786.135251223654;810.1210186947162;14560.126392416449;15520.844791552081;15560.377584896605;15585;15599.1420471874;12372.938135309334;12383.814242786366;12400.689982750435;12416.441260143292;12430.067744919144;581.2850194261061;590.5187272373003;707.0666719929695;864.740577826651;883.218351758695;853.4623187391966;878.1964502480223;879.0813599788237;896.3143195455482;900.2527901841456;919.9724008279729;940.3370623710268;942.8727121838533;943.7263193673826;947.2658689515646;728.9927100728978;936.2089034765612;936.7564433247337;937.236152535688;939.6664184214608;553.510143522981;590.8670549126455;686.2656957055902;690.5304393012749;696.5437638346891;14042.017058805888;14528.055676381002;14556;14566;14590.270486475634;11245.55017799287;12957.676058098528;13062.150960187557;13152;13248.536301229411;12232.812250757686;12288.631341059783;12323.568675096418;12362.75274494514;12526.248425094524;4132.380142978547;4275.91448171038;4402.273624851896;4490.7530085845265;4562.77186140695;101485.4925725374;103438.48280758623;105271;105905;106184;10422.687319380431;10533.9473302633;10555.788884222273;10695;10710.303830251009;19272.710909336372;20442.28452004173;20608.38174854727;20674.379768606967;20678.793212067685;7297.671604777798;7640.656170472342;7654.540727556365;7664.348530374869;7672.884906726404;4081.0205550667624;4144.772037537964;4204.159168166379;4207.221663992172;4277.016286254179;2917.416516696669;3238.0933338665213;3280.4095262852916;3344.6822551857535;3349.7655164138278;658.2758965138405;669.1301308299192;674.4777996771234;684.4387602166175;685.9676187338024;616.2789536242607;662.6548107342103;665.1552528289046;668.8361351468824;692.7956252905406;8677.783055423664;9264.536773161315;9290.670205969554;9422.717318480456;9476.763080923032;624.6033768556999;626.2547568393597;661.1945977732909;679.1646275081678;689.6207086102623;4452.265376212888;4534.886627834265;4538.500764915857;4558.720086205652;4594.471635761864;95814;96475.51762241144;96933;97138.0286197129;98942.5793065523;70954.64522677353;85403;86200.56899715586;86481;89062;73897;74803.62598187083;74903.62548187224;74943;78608;8702.736473822317;8773.166549177819;8778.297736181217;8853.69012084574;8914.197722205032;49235.75382123067;50547.74726126493;50977.490225097274;51557.74221128871;52300.21549676834;64590;64821.35178648153;66280;66395.66802165959;68804.65597671979;9530.618775248913;11212.383318917538;11570.19008669385;11697;12189.573364932185;12464.626061217816;12835.679108022374;12942.676433089247;13114.868851311365;13116.672083197614;11808.527658893549;11898.98445223339;11907.76184476323;12150.331731754839;12311.322877241837;11377.658670239905;11759.882401175879;11789.941050294694;11797.29216247028;11802.468888900192;11857.762844743229;11974.341411222467;12073.939630302144;12182.390880455765;12318.753624927273;9876.456794876063;9929.801403971736;10022.3485473444;10222.897771022195;10611.469426528489;8165.836683266421;9592.390360991607;9778.951105244429;9870.161036311994;9921.69699622905 08:28:19 INFO - PROCESS | 1916 | __end_tp_report 08:28:19 INFO - PROCESS | 1916 | __start_cc_report 08:28:19 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,1236 08:28:19 INFO - PROCESS | 1916 | __end_cc_report 08:28:19 INFO - PROCESS | 1916 | __startTimestamp1482942499535__endTimestamp 08:28:19 INFO - PROCESS | 1916 | 08:28:19 INFO - PROCESS | 1916 | ------- Summary: start ------- 08:28:19 INFO - PROCESS | 1916 | Number of tests: 6 08:28:19 INFO - PROCESS | 1916 | 08:28:19 INFO - PROCESS | 1916 | [#0] dojo.html Cycles:165 Average:5465.59 Median:5581.47 stddev:609.67 (10.9%) stddev-sans-first:586.00 08:28:19 INFO - PROCESS | 1916 | Values: 3239.4 3240.1 3248.3 3267.6 3315.9 5336.3 5394.5 5397.5 5464.3 5497.4 5424.0 5442.2 5458.9 5497.4 5500.8 4989.0 5030.4 5033.7 5070.5 5131.6 4996.7 5063.8 5102.5 5131.5 5136.3 4364.8 5101.6 5105.8 5106.5 5145.8 4950.2 4960.4 4976.9 5014.0 5019.8 5035.3 5068.8 5075.7 5079.3 5106.0 5401.7 5439.1 5459.5 5466.5 5491.7 5795.3 5945.0 5992.5 6008.7 6032.5 5933.9 5946.8 6025.4 6051.0 6070.7 5570.7 5581.5 5586.6 5599.2 5629.4 6010.4 6172.3 6172.7 6189.7 6200.7 5664.9 5744.0 5744.2 5803.0 5883.0 5975.8 6017.7 6066.2 6082.1 6091.3 5740.4 5795.4 5805.0 5857.4 5936.8 5053.6 5946.4 5962.9 5987.7 6032.6 4724.2 4739.0 4740.5 4742.3 4780.8 4100.1 4103.1 4150.6 4201.5 4227.6 5402.5 5413.8 5422.6 5432.2 5459.5 4760.8 5260.6 5510.9 5698.1 5711.6 5388.1 5388.2 5420.8 5473.4 5481.2 5137.6 5145.9 5171.4 5173.0 5221.5 5168.2 6212.9 6213.4 6217.7 6276.6 5820.6 6000.4 6044.7 6049.2 6050.1 6158.7 6168.5 6182.9 6204.4 6206.1 5882.5 5893.2 5894.1 5901.2 5914.3 5784.2 5837.0 5921.0 5968.7 5999.2 5635.1 5664.3 5687.5 5713.5 5743.1 5665.6 5699.0 5700.1 5763.3 5782.9 5574.7 5611.9 5621.7 5651.0 5654.1 4878.5 5696.7 5751.5 5818.9 5821.8 5453.7 5502.8 5503.0 5540.0 5649.7 08:28:19 INFO - PROCESS | 1916 | 08:28:19 INFO - PROCESS | 1916 | [#1] ext.html Cycles:180 Average:62655.67 Median:38612.85 stddev:128002.01 (331.5%) stddev-sans-first:128275.92 08:28:19 INFO - PROCESS | 1916 | Values: 464.7 486.2 504.9 523.8 531.7 30377.8 38919.0 40655.8 40711.2 40825.6 41616.2 42060.6 42143.8 42189.6 42197.0 9147.1 9359.0 9366.1 9368.3 9383.0 8395.7 8658.4 10929.7 11230.0 11253.9 11232.0 11278.5 11300.9 11306.1 11309.8 8369.5 11140.1 11226.5 11232.9 11247.1 8404.5 8551.1 8569.2 8602.4 9295.6 32555.0 39073.6 39127.8 39131.4 39167.4 3886.8 3918.6 3936.5 3961.8 4073.9 27113.6 29285.1 30375.8 30536.2 30627.9 3113.7 3133.1 3145.2 3260.4 3286.4 464552.7 465055.0 465537.0 467506.0 468556.0 50862.5 60334.0 61210.4 61251.4 61337.7 63782.7 63984.0 64036.7 64075.7 64084.7 15154.5 15681.6 15688.4 15689.8 15702.4 10697.7 10959.6 11931.4 12015.3 12016.6 15431.5 17275.4 17566.8 17635.3 17916.7 6433.5 6538.8 6560.3 6570.5 6590.0 846.8 858.6 863.1 865.3 867.2 24800.4 28278.7 28907.0 29258.3 29476.0 1020.3 1027.8 1059.8 1063.5 1081.6 14227.1 14894.4 14948.3 15096.6 15315.5 494639.0 497412.0 499876.0 506600.0 524086.4 56938.7 57204.0 57271.7 57312.0 57359.7 1359.3 1383.1 1405.2 1405.5 1408.7 27133.5 28313.6 28460.5 28502.4 28787.0 49389.3 49742.0 49835.5 49839.5 49888.5 465397.0 474593.0 476205.6 476445.0 477396.0 33062.7 37212.4 38043.4 38073.2 38393.7 36004.9 36846.7 46565.5 46916.0 48706.8 36081.5 37656.4 37797.1 39598.0 39749.4 37259.3 38086.2 38832.0 39370.2 39471.8 31564.8 36573.0 36935.0 37691.4 38196.6 30686.9 31908.0 31946.2 33953.0 34137.0 24236.2 24320.1 24443.8 24819.8 25237.6 08:28:19 INFO - PROCESS | 1916 | 08:28:19 INFO - PROCESS | 1916 | [#2] jquery.html Cycles:180 Average:32470.02 Median:385.85 stddev:91438.39 (23697.8%) stddev-sans-first:91663.12 08:28:19 INFO - PROCESS | 1916 | Values: 363.1 372.4 378.6 393.1 402.8 18355.6 19165.9 19421.6 19810.4 19941.4 19338.6 19469.4 19561.0 19882.4 20215.8 4075.6 5560.9 5817.0 5824.9 5835.4 5697.9 5729.8 5783.6 5784.8 5827.6 5473.6 5672.1 5682.4 5737.9 5754.9 5661.5 5676.2 5714.9 5743.7 5758.0 4799.8 4962.9 5015.4 5031.5 5051.4 24336.2 24398.4 24613.5 24678.0 24686.9 2412.7 2605.3 2616.3 2622.9 2731.7 9555.4 9647.5 9695.7 10052.6 10114.5 1736.0 1781.2 1795.7 1796.1 1811.2 103656.5 108350.0 108498.5 108740.4 109354.0 9738.7 11103.2 11310.9 11329.3 11536.1 14185.9 14200.8 14213.8 14216.4 14230.4 7943.0 8048.7 8101.3 8190.9 8212.9 7817.5 7834.3 7853.0 7984.8 7994.7 4350.8 4353.1 4370.7 4533.7 4548.0 1767.5 1841.9 1888.4 1892.4 1900.7 966.5 973.9 987.1 987.7 1038.6 20278.7 20574.7 20879.6 20892.6 20988.6 1005.4 1016.6 1039.3 1039.9 1042.7 10642.9 10786.2 11013.4 11036.6 11106.7 533432.0 546136.0 550525.0 558209.0 558295.0 88867.0 92287.0 96775.0 119582.0 128716.0 896.2 949.4 1006.7 1010.2 1014.6 18491.7 19253.1 19573.0 19599.4 19637.4 50995.7 52161.0 53611.5 53998.6 55818.9 75893.0 76587.0 77142.0 77249.0 78014.6 11170.6 11325.6 11561.4 11807.7 11870.1 8743.3 8892.9 8923.4 9342.6 13317.0 7751.6 7828.3 8076.7 8203.9 8359.0 7059.4 7127.8 7767.5 8082.2 8208.9 8026.0 8275.0 8328.1 8407.2 8407.9 11392.7 11888.5 12037.8 12136.5 12271.6 11494.7 11654.9 12016.6 12087.3 12207.2 08:28:19 INFO - PROCESS | 1916 | 08:28:19 INFO - PROCESS | 1916 | [#3] mootools.html Cycles:180 Average:5311.72 Median:5172.51 stddev:2917.07 (56.4%) stddev-sans-first:2909.11 08:28:19 INFO - PROCESS | 1916 | Values: 1228.5 1393.4 1410.0 1414.8 1420.7 7937.4 8414.5 8431.7 8435.2 8447.9 7411.7 7662.9 7687.3 7975.9 8048.8 2230.4 2252.9 2276.8 2277.3 2280.3 2312.3 2333.3 2349.0 2349.8 2351.4 2026.9 2104.4 2232.1 2307.2 2310.8 2298.9 2303.0 2304.1 2310.4 2312.5 2245.9 2255.2 2257.0 2264.8 2273.9 8117.6 8326.0 8342.2 8342.6 8343.5 4992.7 5156.7 5161.2 5183.8 5186.4 3259.6 3430.2 3511.4 3516.5 3527.7 1126.9 1211.4 1212.7 1692.6 1739.1 10072.8 10119.2 10719.4 10774.0 10912.3 3749.6 4151.6 4327.9 4388.5 4399.2 8600.6 8607.3 8614.9 8624.7 8633.7 4420.8 5035.1 5040.0 5042.5 5060.6 4120.1 4257.6 4396.0 4604.6 4682.1 3276.4 3446.0 3572.4 3680.3 3690.7 996.4 1113.4 1129.5 1144.5 1153.3 835.9 847.4 858.2 909.5 915.3 5421.4 5422.2 5615.9 5772.6 5834.8 858.6 909.9 925.8 943.1 992.5 3293.3 3380.6 3501.1 3644.7 3704.0 9665.0 9919.1 9988.9 10063.3 10067.6 9538.0 9881.6 9998.8 10000.2 10114.8 7685.8 7873.3 7875.3 7891.3 7917.3 5260.7 5434.5 5448.7 5488.3 5573.2 8871.4 9229.9 9371.4 9385.5 9387.6 8926.6 9001.2 9276.9 9409.8 9413.0 6576.2 6805.0 7076.3 7110.2 7236.2 7455.4 7624.3 7753.8 7882.1 7949.8 6986.0 7117.3 7121.6 7185.2 7265.7 6965.0 6966.0 6989.3 7032.5 7044.6 5316.2 6088.4 6279.3 6292.4 6308.0 4620.3 4680.7 4696.8 4722.2 4862.3 4257.8 4777.0 4798.0 4808.1 4834.6 08:28:19 INFO - PROCESS | 1916 | 08:28:19 INFO - PROCESS | 1916 | [#4] prototype.html Cycles:180 Average:4238.99 Median:4709.36 stddev:1291.07 (27.4%) stddev-sans-first:1260.54 08:28:19 INFO - PROCESS | 1916 | Values: 308.6 348.7 356.4 367.2 370.9 4956.7 5026.4 5029.8 5034.2 5035.7 4712.6 4807.7 4886.2 4893.2 4996.5 3354.7 3631.7 3867.8 3953.0 4015.9 3613.8 3992.6 4011.5 4027.5 4037.6 3626.7 3827.7 3891.6 3967.3 4010.9 3029.8 3831.5 3845.5 3849.4 3931.1 3332.9 3337.1 3341.4 3348.8 3355.9 3930.2 4318.6 4530.3 4760.6 4917.0 3854.4 3925.8 4214.1 4341.5 4374.7 4476.0 4817.4 5253.3 5261.7 5279.2 3635.1 3644.9 3653.7 3655.2 3659.5 5079.9 5354.7 5457.5 5541.8 5544.7 3049.0 3622.0 3726.2 3733.6 3733.8 3301.5 3677.7 3746.7 3787.1 3795.1 3927.9 4366.7 4376.9 4405.5 4425.8 5023.7 5323.7 5334.8 5344.5 5357.3 1161.6 1164.6 1211.4 1215.9 1217.9 752.0 770.7 792.9 803.8 841.9 3969.0 4246.3 4416.6 4653.5 4851.9 4158.9 4419.9 4892.4 5055.4 5097.9 3848.1 4122.5 4259.6 4706.1 4762.6 2693.2 2698.4 2699.4 2704.2 2730.3 4891.2 5056.8 5112.0 5377.9 5432.5 4551.4 4759.2 4905.9 5170.0 5426.5 5101.4 5130.3 5189.5 5357.3 5894.4 4839.8 5472.3 5504.2 5507.4 5533.8 4823.7 5348.5 5401.9 5464.9 5526.3 5751.8 5764.6 5767.5 5771.0 5813.5 3591.3 3711.4 3714.9 3728.5 3746.7 5192.9 5457.3 5504.8 5507.3 5509.5 4444.8 4583.6 5131.5 5359.4 5364.5 4373.7 4560.1 4735.2 5077.2 5337.6 4899.3 5148.5 5214.9 5258.3 5262.4 5035.9 5077.4 5216.8 5240.2 5249.0 4252.2 4591.7 5211.2 5226.7 5230.6 08:28:19 INFO - PROCESS | 1916 | 08:28:19 INFO - PROCESS | 1916 | [#5] yui.html Cycles:180 Average:19561.04 Median:51928.98 stddev:28417.78 (54.7%) stddev-sans-first:28462.22 08:28:19 INFO - PROCESS | 1916 | Values: 701.1 736.1 759.4 786.1 810.1 14560.1 15520.8 15560.4 15585.0 15599.1 12372.9 12383.8 12400.7 12416.4 12430.1 581.3 590.5 707.1 864.7 883.2 853.5 878.2 879.1 896.3 900.3 920.0 940.3 942.9 943.7 947.3 729.0 936.2 936.8 937.2 939.7 553.5 590.9 686.3 690.5 696.5 14042.0 14528.1 14556.0 14566.0 14590.3 11245.6 12957.7 13062.2 13152.0 13248.5 12232.8 12288.6 12323.6 12362.8 12526.2 4132.4 4275.9 4402.3 4490.8 4562.8 101485.5 103438.5 105271.0 105905.0 106184.0 10422.7 10533.9 10555.8 10695.0 10710.3 19272.7 20442.3 20608.4 20674.4 20678.8 7297.7 7640.7 7654.5 7664.3 7672.9 4081.0 4144.8 4204.2 4207.2 4277.0 2917.4 3238.1 3280.4 3344.7 3349.8 658.3 669.1 674.5 684.4 686.0 616.3 662.7 665.2 668.8 692.8 8677.8 9264.5 9290.7 9422.7 9476.8 624.6 626.3 661.2 679.2 689.6 4452.3 4534.9 4538.5 4558.7 4594.5 95814.0 96475.5 96933.0 97138.0 98942.6 70954.6 85403.0 86200.6 86481.0 89062.0 73897.0 74803.6 74903.6 74943.0 78608.0 8702.7 8773.2 8778.3 8853.7 8914.2 49235.8 50547.7 50977.5 51557.7 52300.2 64590.0 64821.4 66280.0 66395.7 68804.7 9530.6 11212.4 11570.2 11697.0 12189.6 12464.6 12835.7 12942.7 13114.9 13116.7 11808.5 11899.0 11907.8 12150.3 12311.3 11377.7 11759.9 11789.9 11797.3 11802.5 11857.8 11974.3 12073.9 12182.4 12318.8 9876.5 9929.8 10022.3 10222.9 10611.5 8165.8 9592.4 9779.0 9870.2 9921.7 08:28:19 INFO - PROCESS | 1916 | -------- Summary: end -------- 08:28:19 INFO - PROCESS | 1916 | 08:28:20 INFO - TEST-INFO | 1916: exit 0 08:28:20 INFO - TEST-OK | dromaeo_css | took 1119579ms 08:28:20 INFO - TEST-START | kraken 08:28:20 INFO - operating with platform_type : mac_ 08:28:20 INFO - Initialising browser for kraken test... 08:28:20 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmp_c6fSv/profile http://localhost:51544/getInfo.html) 08:28:20 INFO - PROCESS | 1919 | 1482942500731 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 08:28:22 INFO - PROCESS | 1919 | __metrics Screen width/height:1600/1200 08:28:22 INFO - PROCESS | 1919 | colorDepth:24 08:28:22 INFO - PROCESS | 1919 | Browser inner width/height: 1024/689 08:28:22 INFO - PROCESS | 1919 | __metrics 08:28:22 INFO - PROCESS | 1919 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:28:22 INFO - PROCESS | 1919 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:28:23 INFO - TEST-INFO | 1919: exit 0 08:28:23 INFO - Browser initialized. 08:28:23 INFO - Running cycle 1/1 for kraken test... 08:28:23 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmp_c6fSv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:28:35 INFO - PROCESS | 1920 | RSS: Main: 234442752 08:28:35 INFO - PROCESS | 1920 | 08:28:38 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51544/tests/kraken/driver.html?testName=audio-beat-detection) 08:28:39 INFO - PROCESS | 1920 | RSS: Main: 287113216 08:28:39 INFO - PROCESS | 1920 | 08:28:41 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51544/tests/kraken/driver.html?testName=audio-dft) 08:28:42 INFO - PROCESS | 1920 | RSS: Main: 288522240 08:28:42 INFO - PROCESS | 1920 | 08:28:45 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51544/tests/kraken/driver.html?testName=audio-fft) 08:28:46 INFO - PROCESS | 1920 | RSS: Main: 377470976 08:28:46 INFO - PROCESS | 1920 | 08:28:48 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51544/tests/kraken/driver.html?testName=audio-oscillator) 08:28:48 INFO - PROCESS | 1920 | RSS: Main: 281382912 08:28:48 INFO - PROCESS | 1920 | 08:28:50 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51544/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:28:51 INFO - PROCESS | 1920 | RSS: Main: 284823552 08:28:51 INFO - PROCESS | 1920 | 08:28:54 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51544/tests/kraken/driver.html?testName=imaging-darkroom) 08:28:54 INFO - PROCESS | 1920 | RSS: Main: 326176768 08:28:54 INFO - PROCESS | 1920 | 08:28:57 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51544/tests/kraken/driver.html?testName=imaging-desaturate) 08:28:58 INFO - PROCESS | 1920 | RSS: Main: 328372224 08:28:58 INFO - PROCESS | 1920 | 08:29:01 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51544/tests/kraken/driver.html?testName=json-parse-financial) 08:29:01 INFO - PROCESS | 1920 | RSS: Main: 326336512 08:29:01 INFO - PROCESS | 1920 | 08:29:03 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51544/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:29:04 INFO - PROCESS | 1920 | RSS: Main: 303968256 08:29:04 INFO - PROCESS | 1920 | 08:29:05 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51544/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:29:06 INFO - PROCESS | 1920 | RSS: Main: 309051392 08:29:06 INFO - PROCESS | 1920 | 08:29:08 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51544/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:29:09 INFO - PROCESS | 1920 | RSS: Main: 309882880 08:29:09 INFO - PROCESS | 1920 | 08:29:12 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51544/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:29:13 INFO - PROCESS | 1920 | RSS: Main: 325038080 08:29:13 INFO - PROCESS | 1920 | 08:29:16 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51544/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:29:16 INFO - PROCESS | 1920 | RSS: Main: 318885888 08:29:16 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:51544/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51544/tests/kraken/driver.html?testName=ai-astar) 08:29:18 INFO - PROCESS | 1920 | RSS: Main: 362967040 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | __start_tp_report 08:29:18 INFO - PROCESS | 1920 | _x_x_mozilla_page_load 08:29:18 INFO - PROCESS | 1920 | _x_x_mozilla_page_load_details 08:29:18 INFO - PROCESS | 1920 | |i|pagename|runs| 08:29:18 INFO - PROCESS | 1920 | |0;ai-astar;115;113;116;115;113;115;113;114;115;113 08:29:18 INFO - PROCESS | 1920 | |1;audio-beat-detection;144;105;104;146;111;109;110;109;130;109 08:29:18 INFO - PROCESS | 1920 | |2;audio-dft;186;184;187;185;186;187;182;181;192;265 08:29:18 INFO - PROCESS | 1920 | |3;audio-fft;82;59;59;87;61;60;58;75;97;73 08:29:18 INFO - PROCESS | 1920 | |4;audio-oscillator;84;70;84;69;68;67;82;69;67;83 08:29:18 INFO - PROCESS | 1920 | |5;imaging-gaussian-blur;89;86;88;86;86;85;88;86;87;85 08:29:18 INFO - PROCESS | 1920 | |6;imaging-darkroom;99;125;93;93;94;94;94;94;93;93 08:29:18 INFO - PROCESS | 1920 | |7;imaging-desaturate;87;88;86;89;88;84;87;85;86;87 08:29:18 INFO - PROCESS | 1920 | |8;json-parse-financial;58;55;55;54;55;56;56;55;55;55 08:29:18 INFO - PROCESS | 1920 | |9;json-stringify-tinderbox;64;83;74;73;72;69;69;68;68;73 08:29:18 INFO - PROCESS | 1920 | |10;stanford-crypto-aes;87;86;87;87;87;88;85;90;87;87 08:29:18 INFO - PROCESS | 1920 | |11;stanford-crypto-ccm;141;142;141;145;147;153;139;141;183;152 08:29:18 INFO - PROCESS | 1920 | |12;stanford-crypto-pbkdf2;171;175;173;229;184;175;174;177;174;175 08:29:18 INFO - PROCESS | 1920 | |13;stanford-crypto-sha256-iterative;68;68;67;66;71;83;82;82;83;77 08:29:18 INFO - PROCESS | 1920 | __end_tp_report 08:29:18 INFO - PROCESS | 1920 | __start_cc_report 08:29:18 INFO - PROCESS | 1920 | _x_x_mozilla_cycle_collect,1991 08:29:18 INFO - PROCESS | 1920 | __end_cc_report 08:29:18 INFO - PROCESS | 1920 | __startTimestamp1482942558798__endTimestamp 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | ------- Summary: start ------- 08:29:18 INFO - PROCESS | 1920 | Number of tests: 14 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.00 stddev:1.14 (1.0%) stddev-sans-first:1.17 08:29:18 INFO - PROCESS | 1920 | Values: 115.0 113.0 116.0 115.0 113.0 115.0 113.0 114.0 115.0 113.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#1] audio-beat-detection Cycles:10 Average:117.70 Median:110.50 stddev:16.07 (14.5%) stddev-sans-first:13.94 08:29:18 INFO - PROCESS | 1920 | Values: 144.0 105.0 104.0 146.0 111.0 109.0 110.0 109.0 130.0 109.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#2] audio-dft Cycles:10 Average:193.50 Median:186.50 stddev:25.30 (13.6%) stddev-sans-first:26.69 08:29:18 INFO - PROCESS | 1920 | Values: 186.0 184.0 187.0 185.0 186.0 187.0 182.0 181.0 192.0 265.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#3] audio-fft Cycles:10 Average:71.10 Median:74.00 stddev:13.95 (18.8%) stddev-sans-first:14.23 08:29:18 INFO - PROCESS | 1920 | Values: 82.0 59.0 59.0 87.0 61.0 60.0 58.0 75.0 97.0 73.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#4] audio-oscillator Cycles:10 Average:74.30 Median:76.00 stddev:7.78 (10.2%) stddev-sans-first:7.41 08:29:18 INFO - PROCESS | 1920 | Values: 84.0 70.0 84.0 69.0 68.0 67.0 82.0 69.0 67.0 83.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:86.50 stddev:1.35 (1.6%) stddev-sans-first:1.12 08:29:18 INFO - PROCESS | 1920 | Values: 89.0 86.0 88.0 86.0 86.0 85.0 88.0 86.0 87.0 85.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:94.00 stddev:9.93 (10.6%) stddev-sans-first:10.51 08:29:18 INFO - PROCESS | 1920 | Values: 99.0 125.0 93.0 93.0 94.0 94.0 94.0 94.0 93.0 93.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#7] imaging-desaturate Cycles:10 Average:86.70 Median:87.00 stddev:1.49 (1.7%) stddev-sans-first:1.58 08:29:18 INFO - PROCESS | 1920 | Values: 87.0 88.0 86.0 89.0 88.0 84.0 87.0 85.0 86.0 87.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:1.07 (2.0%) stddev-sans-first:0.60 08:29:18 INFO - PROCESS | 1920 | Values: 58.0 55.0 55.0 54.0 55.0 56.0 56.0 55.0 55.0 55.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.50 stddev:5.12 (7.1%) stddev-sans-first:4.70 08:29:18 INFO - PROCESS | 1920 | Values: 64.0 83.0 74.0 73.0 72.0 69.0 69.0 68.0 68.0 73.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:87.00 stddev:1.29 (1.5%) stddev-sans-first:1.36 08:29:18 INFO - PROCESS | 1920 | Values: 87.0 86.0 87.0 87.0 87.0 88.0 85.0 90.0 87.0 87.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#11] stanford-crypto-ccm Cycles:10 Average:148.40 Median:146.00 stddev:13.07 (9.0%) stddev-sans-first:13.59 08:29:18 INFO - PROCESS | 1920 | Values: 141.0 142.0 141.0 145.0 147.0 153.0 139.0 141.0 183.0 152.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.70 Median:175.00 stddev:17.31 (9.9%) stddev-sans-first:18.01 08:29:18 INFO - PROCESS | 1920 | Values: 171.0 175.0 173.0 229.0 184.0 175.0 174.0 177.0 174.0 175.0 08:29:18 INFO - PROCESS | 1920 | 08:29:18 INFO - PROCESS | 1920 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:79.50 stddev:7.36 (9.3%) stddev-sans-first:7.40 08:29:18 INFO - PROCESS | 1920 | Values: 68.0 68.0 67.0 66.0 71.0 83.0 82.0 82.0 83.0 77.0 08:29:18 INFO - PROCESS | 1920 | -------- Summary: end -------- 08:29:18 INFO - PROCESS | 1920 | 08:29:19 INFO - TEST-INFO | 1920: exit 0 08:29:19 INFO - TEST-OK | kraken | took 59446ms 08:29:19 INFO - SUITE-END | took 1179s 08:29:19 INFO - Completed test suite (00:19:39) 08:29:19 INFO - javascript benchmark 08:29:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3239.4492936200845, 3240.125166205123, 3248.3178532508173, 3267.55887955126, 3315.8673653053866, 5336.30628018358, 5394.514493695567, 5397.514223719864, 5464.344278686558, 5497.422770609083, 5424.0, 5442.210879422481, 5458.918116228259, 5497.39528651848, 5500.779968801253, 4989.027139707759, 5030.37120359955, 5033.723145227011, 5070.492950704918, 5131.640785145041, 4996.7001979881325, 5063.8480845574695, 5102.464241254671, 5131.486851314876, 5136.306598609196, 4364.7817609119575, 5101.642884998052, 5105.846824595268, 5106.489351064901, 5145.845624631267, 4950.183219768734, 4960.355153829979, 4976.925346119811, 5014.0, 5019.84940451787, 5035.269885866529, 5068.847934561969, 5075.720835354054, 5079.288899554065, 5105.978804239167, 5401.675899446046, 5439.075357189287, 5459.454054594509, 5466.535344495723, 5491.697956612385, 5795.3045634523705, 5944.989351810203, 5992.460678538909, 6008.729607167688, 6032.517398608101, 5933.910991335133, 5946.821595352147, 6025.367336429699, 6050.969745151246, 6070.696465176724, 5570.6657600544095, 5581.469760372758, 5586.553075753971, 5599.216109744639, 5629.380768115504, 6010.398960103955, 6172.259328880473, 6172.722227499773, 6189.659568723763, 6200.689965501707, 5664.8867022659315, 5743.994800909824, 5744.167095771089, 5803.042497987784, 5883.0, 5975.820725378246, 6017.729202185912, 6066.150738896558, 6082.057281121433, 6091.299500557493, 5740.425957404226, 5795.362510123968, 5805.0, 5857.355690874, 5936.7922122725495, 5053.620978426633, 5946.43508866657, 5962.880742385128, 5987.700614969234, 6032.607880487754, 4724.196886529229, 4738.981119059419, 4740.502247264057, 4742.288656701453, 4780.784864681098, 4100.077482566398, 4103.097318589965, 4150.584941505886, 4201.45381100455, 4227.577242275748, 5402.459754024566, 5413.83758487246, 5422.593305502103, 5432.239486471897, 5459.454054594509, 4760.833370832004, 5260.552853007461, 5510.862228444321, 5698.0598201296325, 5711.571632127607, 5388.110961691355, 5388.191771234347, 5420.83737487876, 5473.370562385377, 5481.177823326533, 5137.640365174477, 5145.8936328689515, 5171.4052883918075, 5172.991266703032, 5221.477852214748, 5168.224766285088, 6212.937870621417, 6213.440790328892, 6217.6891155442045, 6276.592021518495, 5820.621659592282, 6000.399960003964, 6044.697765111639, 6049.153118563493, 6050.062240352752, 6158.661273629993, 6168.475679567287, 6182.876342473215, 6204.3795620439405, 6206.06908963641, 5882.5293976480925, 5893.233879595625, 5894.056950887837, 5901.232839730807, 5914.260717410324, 5784.21913041734, 5836.970815146067, 5920.970395147997, 5968.671723055274, 5999.1901093351835, 5635.0702134147405, 5664.348599911063, 5687.544996400361, 5713.514351280021, 5743.138529220483, 5665.631733937281, 5699.0, 5700.059490184239, 5763.308402991668, 5782.855428614318, 5574.721263936868, 5611.859703507445, 5621.690807005654, 5650.971745141413, 5654.123610840326, 4878.512148785093, 5696.715164241854, 5751.511121554715, 5818.94181058184, 5821.79623713168, 5453.700046497481, 5502.8349149525575, 5503.009458297545, 5540.030494663499, 5649.661020338793], "value": 5428.051311741498, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.6770494506317, 486.1613716339315, 504.8662104542295, 523.7800123947942, 531.7128750474744, 30377.84811075944, 38919.0, 40655.796721016355, 40711.18577628446, 40825.59174408255, 41616.167676646524, 42060.57939420613, 42143.78928105355, 42189.57810421903, 42197.0, 9147.131022552847, 9359.0, 9366.110219529133, 9368.297377696666, 9382.967873533906, 8395.748127556182, 8658.437201581908, 10929.672109836718, 11229.989300962912, 11253.943730281337, 11232.0, 11278.54885804571, 11300.886991130066, 11306.095512358992, 11309.773804523946, 8369.49783013015, 11140.108791296718, 11226.494807733672, 11232.943835280854, 11247.100231981503, 8404.453710508797, 8551.144885511461, 8569.22876941072, 8602.440841345355, 9295.62961444834, 32555.0, 39073.60926390728, 39127.804360978014, 39131.41302880459, 39167.41248881269, 3886.8339498150726, 3918.5885482024264, 3936.5079365079364, 3961.841526338972, 4073.9388909166387, 27113.59329610038, 29285.121446356643, 30375.848120759474, 30536.236594085105, 30627.928022519103, 3113.7353324967403, 3133.122725636825, 3145.182252614337, 3260.4294248506417, 3286.4413049781356, 464552.67723661504, 465055.0, 465537.0, 467506.0, 468556.0, 50862.491375086516, 60334.0, 61210.387896121356, 61251.38748612546, 61337.693311533156, 63782.72434551283, 63984.0, 64036.67981660062, 64075.679621602525, 64084.67957660182, 15154.469593564172, 15681.607959801097, 15688.372465101496, 15689.764653530206, 15702.371905123895, 10697.732556686145, 10959.561617535368, 11931.403429828473, 12015.33915634631, 12016.579419720269, 15431.537053888176, 17275.3953611623, 17566.824331756776, 17635.294588216584, 17916.731249031276, 6433.453156481658, 6538.803835884931, 6560.311167327457, 6570.5400621956005, 6589.967050164719, 846.7629063862005, 858.6007506509378, 863.0980625246632, 865.2947847504264, 867.1545243387646, 24800.37999050002, 28278.717212827607, 28907.0, 29258.33544924554, 29475.968341107957, 1020.260311274335, 1027.7893031928436, 1059.7986382587437, 1063.5267306048734, 1081.567373050786, 14227.146371217554, 14894.404223831141, 14948.252587370587, 15096.641302282847, 15315.46395876139, 494639.0, 497412.0, 499876.0, 506600.0, 524086.37956809974, 56938.715306424034, 57204.0, 57271.713641431525, 57312.0, 57359.71320143456, 1359.2931675528669, 1383.1493631416606, 1405.220102842891, 1405.5221224783418, 1408.7393832141086, 27133.457330852878, 28313.575296370982, 28460.49175343492, 28502.42995140044, 28787.0, 49389.25916111189, 49742.0, 49835.501644984535, 49839.50160498494, 49888.50111498839, 465397.0, 474593.0, 476205.61897190294, 476445.0, 477396.0, 33062.66937330596, 37212.44181337228, 38043.42934855924, 38073.23853522969, 38393.692635435924, 36004.91985240447, 36846.70667555774, 46565.53434465612, 46916.0, 48706.75646621744, 36081.458778118875, 37656.43515347217, 37797.05507362338, 39598.0, 39749.403758944216, 37259.254814904096, 38086.23827523379, 38832.0, 39370.212595747354, 39471.802640986614, 31564.842175788974, 36573.0, 36935.0, 37691.43462848114, 38196.61803381931, 30686.92595759138, 31908.0, 31946.227250564658, 33953.0, 34137.0, 24236.1517346892, 24320.148794792098, 24443.755562444858, 24819.751802481744, 25237.621435678113], "value": 19497.192775117193, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.12668033379725, 372.37441098953764, 378.6119227791513, 393.091957577995, 402.8267844826725, 18355.632887342246, 19165.904170479127, 19421.611567768654, 19810.40568782937, 19941.40175794725, 19338.613227735437, 19469.415917522496, 19561.0, 19882.403527894152, 20215.787052776806, 4075.6331930126285, 5560.86097847553, 5817.0, 5824.883502329951, 5835.387284335147, 5697.8575535611735, 5729.799457019005, 5783.566232532556, 5784.826455206329, 5827.621204621707, 5473.562115030808, 5672.06410942194, 5682.431756824326, 5737.9384813809575, 5754.942450575503, 5661.5187709044785, 5676.205331253607, 5714.857128571777, 5743.684097374643, 5758.0, 4799.832005879813, 4962.925556116661, 5015.448300686958, 5031.52200540948, 5051.368578927634, 24336.201336846312, 24398.39004024896, 24613.50772984548, 24678.0, 24686.876565617058, 2412.7345991940874, 2605.2575016120504, 2616.2674451153707, 2622.93442663933, 2731.6995130535825, 9555.426674399558, 9647.517624118766, 9695.70912872615, 10052.59789608422, 10114.544845481898, 1735.9913200433916, 1781.2429717370067, 1795.721663142215, 1796.1109250920747, 1811.1759149586906, 103656.48171759168, 108349.99999999921, 108498.45750771275, 108740.36889446665, 109354.0, 9738.659146929824, 11103.167262455348, 11310.94344528272, 11329.263597866113, 11536.134789890792, 14185.929070354789, 14200.786988195185, 14213.78679319811, 14216.43134274638, 14230.359633816503, 7943.0, 8048.678052877936, 8101.311388532041, 8190.918090819016, 8212.917870821335, 7817.491863028885, 7834.334081603013, 7853.0, 7984.84030319402, 7994.720184793504, 4350.760708161019, 4353.1293741251875, 4370.672199585044, 4533.659975501851, 4548.0, 1767.487428645704, 1841.8526517878538, 1888.376835644234, 1892.3565987564293, 1900.6958886578082, 966.4925913895121, 973.9172170365457, 987.116530705015, 987.6740675577042, 1038.6053299746047, 20278.69581956272, 20574.69137962932, 20879.582408351747, 20892.58214835695, 20988.580228395345, 1005.4470041477158, 1016.588281745894, 1039.3140527251978, 1039.880413752413, 1042.7497400623795, 10642.946785266024, 10786.220273654915, 11013.394263315595, 11036.558537658564, 11106.722331941766, 533432.0, 546136.0, 550525.0, 558209.0, 558295.0, 88867.0, 92287.0, 96775.0, 119582.0, 128716.0, 896.2471523919938, 949.3544389814992, 1006.7281833904805, 1010.2372708604956, 1014.5789497358513, 18491.722624160917, 19253.09046193212, 19573.0, 19599.412017639494, 19637.429005679227, 50995.745021274655, 52161.0, 53611.463885360645, 53998.550253704656, 55818.88362232814, 75893.0, 76587.0, 77142.0, 77249.0, 78014.60992695, 11170.553177872795, 11325.603603873826, 11561.421928903354, 11807.704807379885, 11870.109741769229, 8743.34424918121, 8892.86660700103, 8923.41997770143, 9342.57093155798, 13317.001224908365, 7751.6124193788955, 7828.256315650005, 8076.71731489395, 8203.917960820316, 8358.996920369595, 7059.435245180476, 7127.752643287507, 7767.495112817649, 8082.19178082187, 8208.876866847122, 8026.036875574969, 8275.048369437593, 8328.125546817497, 8407.2433480987, 8407.90697209359, 11392.658220253406, 11888.465019074336, 12037.75924481523, 12136.514539418678, 12271.57049503263, 11494.712632184262, 11654.88345116538, 12016.579419720269, 12087.335196563921, 12207.206531575419], "value": 9256.010694877248, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1228.5147366790118, 1393.400837639815, 1409.9929500352496, 1414.7948547460612, 1420.7284480390051, 7937.44437889348, 8414.495130292187, 8431.747047588568, 8435.198656127674, 8447.873281900776, 7411.740589079383, 7662.923370766304, 7687.346575541086, 7975.880361794563, 8048.8390232195325, 2230.397792595998, 2252.9098836046537, 2276.7723227677184, 2277.3054218463362, 2280.327303445479, 2312.2947501012186, 2333.323336232499, 2348.9530209395803, 2349.7767712067325, 2351.400392899817, 2026.898655067248, 2104.4212841468566, 2232.1406258590478, 2307.204014614955, 2310.780475854791, 2298.9310320690406, 2303.021215983217, 2304.112916527149, 2310.3646497213317, 2312.537492501506, 2245.8989345479495, 2255.22194842779, 2257.0407576779803, 2264.7735226477384, 2273.8517048890203, 8117.553534555597, 8326.04250511187, 8342.16578342167, 8342.582870856493, 8343.45767525115, 4992.725400102993, 5156.690598564098, 5161.225816127573, 5183.792648294101, 5186.377634683824, 3259.6251431085257, 3430.1939044324563, 3511.3504001759757, 3516.507688923553, 3527.682508574215, 1126.8985791278824, 1211.4487908001838, 1212.69076385522, 1692.5683950592643, 1739.0695977652022, 10072.841623213351, 10119.190464762802, 10719.410432426213, 10774.0, 10912.34525928439, 3749.5687995880276, 4151.605597468236, 4327.913441731148, 4388.473383193973, 4399.1641588098155, 8600.612972416257, 8607.311415086779, 8614.91385086141, 8624.698135565224, 8633.69782057625, 4420.756858372756, 5035.144025515671, 5039.966806804597, 5042.546170844642, 5060.5698515625845, 4120.052387950788, 4257.638100761408, 4396.010897548026, 4604.58558729716, 4682.1338052460405, 3276.3774882772195, 3445.9655403446145, 3572.4105522589, 3680.343845269586, 3690.6862916652385, 996.446971930571, 1113.3820729495208, 1129.4522156754133, 1144.5364627325946, 1153.2734377342222, 835.8913341265596, 847.3729440214197, 858.2318824651902, 909.4725059465494, 915.2540679346312, 5421.376541697677, 5422.186671999231, 5615.943840561624, 5772.634174471561, 5834.824955251349, 858.5664239559108, 909.9135582119688, 925.7639301977953, 943.0522325063405, 992.533509250651, 3293.2919422324317, 3380.577427821522, 3501.089716673683, 3644.7061293240517, 3703.962960370362, 9664.951675241578, 9919.057689519484, 9988.850167247498, 10063.345882517759, 10067.647632332833, 9538.046195380546, 9881.604735810488, 9998.750031249278, 10000.24998125129, 10114.848277275847, 7685.846283074307, 7873.25204105609, 7875.251851074138, 7891.289783919475, 7917.3270272026375, 5260.684358938476, 5434.483724046209, 5448.700321482198, 5488.313960754906, 5573.164025396223, 8871.379003469696, 9229.907700922904, 9371.437713737198, 9385.483798391155, 9387.624495020124, 8926.553672316488, 9001.234895033995, 9276.860847087164, 9409.811803764023, 9412.95293523528, 6576.210854697467, 6805.0, 7076.2923707628825, 7110.217876033529, 7236.20401755817, 7455.366293865082, 7624.313811756969, 7753.806154846174, 7882.132965373919, 7949.801254968441, 6986.0, 7117.288271172842, 7121.572705637678, 7185.173705023989, 7265.673044712895, 6964.965175174092, 6966.024756533988, 6989.30106989297, 7032.542884712682, 7044.6125463100025, 5316.229146773619, 6088.4215999479975, 6279.277883043508, 6292.402221788922, 6308.0, 4620.330052142353, 4680.742559159279, 4696.765161741968, 4722.173619616622, 4862.319275301531, 4257.808398622132, 4776.95223047779, 4798.016406636561, 4808.134535783593, 4834.564889159852], "value": 4317.022385791501, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.58649409790877, 348.67776689520076, 356.410141216287, 367.20683324020087, 370.9391141335779, 4956.686478083311, 5026.396832380119, 5029.8179927717, 5034.2494841343205, 5035.723035233061, 4712.622990160788, 4807.663463557552, 4886.218205087187, 4893.241547560125, 4996.525330093636, 3354.664533546638, 3631.7276204284785, 3867.845286188556, 3952.9604703953023, 4015.859444919414, 3613.7831730096277, 3992.5608183099835, 4011.4785077939828, 4027.5166979962446, 4037.636612704856, 3626.6997032700247, 3827.7129215308823, 3891.6497515223623, 3967.2660557796758, 4010.899727506821, 3029.8485075746235, 3831.540215174169, 3845.5000849889334, 3849.4217370877805, 3931.0761970936805, 3332.8500217490273, 3337.0989832745267, 3341.3794309759746, 3348.7823291485975, 3355.8825441109443, 3930.213957208541, 4318.568143185687, 4530.3431002504785, 4760.643481476691, 4916.975415122902, 3854.4411060396365, 3925.8429662813455, 4214.145775858643, 4341.5441378655105, 4374.715643483163, 4475.9705267788595, 4817.4219093708625, 5253.343332083489, 5261.657992230493, 5279.155335146396, 3635.056323400174, 3644.890653280406, 3653.6894363979095, 3655.1958569114754, 3659.505966694489, 5079.949200508022, 5354.705491198021, 5457.454254574551, 5541.778328866881, 5544.667319960775, 3049.0243122201127, 3621.9796026410663, 3726.1988672435014, 3733.5519737631657, 3733.842508822274, 3301.4552598821406, 3677.6506231907924, 3746.7189960752507, 3787.1289603390824, 3795.1081495848453, 3927.8625248116177, 4366.6506679465565, 4376.934345984812, 4405.45808966859, 4425.822967081345, 5023.673461225008, 5323.653962492425, 5334.759935802898, 5344.518993290545, 5357.330333708286, 1161.6108603617884, 1164.5865717670274, 1211.4003568233782, 1215.854097508287, 1217.8856346443083, 752.0, 770.6686124966207, 792.8731402975611, 803.8472690188845, 841.8526757817358, 3968.983572484932, 4246.320588705854, 4416.580424859633, 4653.511381304914, 4851.854444366674, 4158.9376159357635, 4419.867403977886, 4892.412910450768, 5055.368078990126, 5097.8725531861255, 3848.13416981177, 4122.484689413824, 4259.552746961586, 4706.10583989041, 4762.571368576845, 2693.1785805329564, 2698.446818402223, 2699.4196247806817, 2704.17522655588, 2730.2901245676267, 4891.241857512091, 5056.797728090835, 5112.028714544224, 5377.865553361197, 5432.511074003359, 4551.407007547398, 4759.190937540627, 4905.945221777335, 5169.974150129226, 5426.511613954763, 5101.413337466239, 5130.33305670268, 5189.50699683537, 5357.267793425303, 5894.351621321651, 4839.782209800638, 5472.261244731911, 5504.2018907257425, 5507.366652834815, 5533.833984980457, 4823.686460380064, 5348.465153484621, 5401.8919621606565, 5464.9070185963765, 5526.309211348582, 5751.827445176651, 5764.625299355528, 5767.452092051249, 5771.0, 5813.505852002627, 3591.2941352857183, 3711.3505136601525, 3714.888553343404, 3728.5339332583426, 3746.6815320698047, 5192.948070519397, 5457.290552228185, 5504.7522861470525, 5507.284053073236, 5509.47659972302, 4444.82220711168, 4583.587477127075, 5131.4868513148385, 5359.365393555003, 5364.463553644604, 4373.741562535931, 4560.110778398181, 4735.242361222188, 5077.213031980049, 5337.626366154332, 4899.3010277240455, 5148.459411761711, 5214.921776173436, 5258.263843061898, 5262.421133675216, 5035.949640503694, 5077.365329333833, 5216.817411390583, 5240.170308033619, 5248.973755131353, 4252.170826688766, 4591.678582499193, 5211.218317252443, 5226.660267082628, 5230.5801597649925], "value": 3840.5528957573197, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.1060897355874, 736.1313649893124, 759.4000739415861, 786.135251223654, 810.1210186947162, 14560.126392416449, 15520.844791552081, 15560.377584896605, 15585.0, 15599.1420471874, 12372.938135309334, 12383.814242786366, 12400.689982750435, 12416.441260143292, 12430.067744919144, 581.2850194261061, 590.5187272373003, 707.0666719929695, 864.740577826651, 883.218351758695, 853.4623187391966, 878.1964502480223, 879.0813599788237, 896.3143195455482, 900.2527901841456, 919.9724008279729, 940.3370623710268, 942.8727121838533, 943.7263193673826, 947.2658689515646, 728.9927100728978, 936.2089034765612, 936.7564433247337, 937.236152535688, 939.6664184214608, 553.510143522981, 590.8670549126455, 686.2656957055902, 690.5304393012749, 696.5437638346891, 14042.017058805888, 14528.055676381002, 14556.0, 14566.0, 14590.270486475634, 11245.55017799287, 12957.676058098528, 13062.150960187557, 13152.0, 13248.536301229411, 12232.812250757686, 12288.631341059783, 12323.568675096418, 12362.75274494514, 12526.248425094524, 4132.380142978547, 4275.91448171038, 4402.273624851896, 4490.7530085845265, 4562.77186140695, 101485.4925725374, 103438.48280758623, 105271.0, 105905.0, 106184.0, 10422.687319380431, 10533.9473302633, 10555.788884222273, 10695.0, 10710.303830251009, 19272.710909336372, 20442.28452004173, 20608.38174854727, 20674.379768606967, 20678.793212067685, 7297.671604777798, 7640.656170472342, 7654.540727556365, 7664.348530374869, 7672.884906726404, 4081.0205550667624, 4144.772037537964, 4204.159168166379, 4207.221663992172, 4277.016286254179, 2917.416516696669, 3238.0933338665213, 3280.4095262852916, 3344.6822551857535, 3349.7655164138278, 658.2758965138405, 669.1301308299192, 674.4777996771234, 684.4387602166175, 685.9676187338024, 616.2789536242607, 662.6548107342103, 665.1552528289046, 668.8361351468824, 692.7956252905406, 8677.783055423664, 9264.536773161315, 9290.670205969554, 9422.717318480456, 9476.763080923032, 624.6033768556999, 626.2547568393597, 661.1945977732909, 679.1646275081678, 689.6207086102623, 4452.265376212888, 4534.886627834265, 4538.500764915857, 4558.720086205652, 4594.471635761864, 95814.0, 96475.51762241144, 96933.0, 97138.0286197129, 98942.5793065523, 70954.64522677353, 85403.0, 86200.56899715586, 86481.0, 89062.0, 73897.0, 74803.62598187083, 74903.62548187224, 74943.0, 78608.0, 8702.736473822317, 8773.166549177819, 8778.297736181217, 8853.69012084574, 8914.197722205032, 49235.75382123067, 50547.74726126493, 50977.490225097274, 51557.74221128871, 52300.21549676834, 64590.0, 64821.35178648153, 66280.0, 66395.66802165959, 68804.65597671979, 9530.618775248913, 11212.383318917538, 11570.19008669385, 11697.0, 12189.573364932185, 12464.626061217816, 12835.679108022374, 12942.676433089247, 13114.868851311365, 13116.672083197614, 11808.527658893549, 11898.98445223339, 11907.76184476323, 12150.331731754839, 12311.322877241837, 11377.658670239905, 11759.882401175879, 11789.941050294694, 11797.29216247028, 11802.468888900192, 11857.762844743229, 11974.341411222467, 12073.939630302144, 12182.390880455765, 12318.753624927273, 9876.456794876063, 9929.801403971736, 10022.3485473444, 10222.897771022195, 10611.469426528489, 8165.836683266421, 9592.390360991607, 9778.951105244429, 9870.161036311994, 9921.69699622905], "value": 7162.8468641070895, "unit": "score"}], "name": "dromaeo_css", "value": 6987.025998360573, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 116.0, 115.0, 113.0, 115.0, 113.0, 114.0, 115.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 105.0, 104.0, 146.0, 111.0, 109.0, 110.0, 109.0, 130.0, 109.0], "value": 117.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 187.0, 185.0, 186.0, 187.0, 182.0, 181.0, 192.0, 265.0], "value": 193.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 87.0, 61.0, 60.0, 58.0, 75.0, 97.0, 73.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 84.0, 69.0, 68.0, 67.0, 82.0, 69.0, 67.0, 83.0], "value": 74.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 86.0, 85.0, 88.0, 86.0, 87.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 125.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 86.0, 89.0, 88.0, 84.0, 87.0, 85.0, 86.0, 87.0], "value": 86.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 55.0, 54.0, 55.0, 56.0, 56.0, 55.0, 55.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 83.0, 74.0, 73.0, 72.0, 69.0, 69.0, 68.0, 68.0, 73.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 87.0, 87.0, 88.0, 85.0, 90.0, 87.0, 87.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 141.0, 145.0, 147.0, 153.0, 139.0, 141.0, 183.0, 152.0], "value": 148.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 175.0, 173.0, 229.0, 184.0, 175.0, 174.0, 177.0, 174.0, 175.0], "value": 180.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 66.0, 71.0, 83.0, 82.0, 82.0, 83.0, 77.0], "value": 74.7, "unit": "score"}], "name": "kraken", "value": 1458.9, "alertThreshold": 2.0}]} 08:29:20 INFO - Return code: 0 08:29:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:29:20 INFO - # TBPL SUCCESS # 08:29:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:29:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:29:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:29:20 INFO - 2016-12-28 08:29:20.094 screenresolution[1924:16289] starting screenresolution argv=screenresolution get 08:29:20 INFO - 2016-12-28 08:29:20.098 screenresolution[1924:16289] Display 0: 1600x1200x32@60 08:29:20 INFO - 2016-12-28 08:29:20.104 screenresolution[1925:16292] starting screenresolution argv=screenresolution list 08:29:20 INFO - Available Modes on Display 0 08:29:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:29:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:29:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:29:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:29:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:29:20 INFO - Intel Iris: 08:29:20 INFO - Chipset Model: Intel Iris 08:29:20 INFO - Type: GPU 08:29:20 INFO - Bus: Built-In 08:29:20 INFO - VRAM (Dynamic, Max): 1536 MB 08:29:20 INFO - Vendor: Intel (0x8086) 08:29:20 INFO - Device ID: 0x0a2e 08:29:20 INFO - Revision ID: 0x0009 08:29:20 INFO - Displays: 08:29:20 INFO - Display: 08:29:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:29:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:29:20 INFO - Display Serial Number: 1600x1200 60 08:29:20 INFO - Main Display: Yes 08:29:20 INFO - Mirror: Off 08:29:20 INFO - Online: Yes 08:29:20 INFO - Rotation: Supported 08:29:20 INFO - Return code: 0 08:29:20 INFO - Running post-action listener: _resource_record_post_action 08:29:20 INFO - [mozharness: 2016-12-28 16:29:20.339122Z] Finished run-tests step (success) 08:29:20 INFO - Running post-run listener: _resource_record_post_run 08:29:20 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 700416; Write bytes: 549714432; Read time: 6; Write time: 1970 08:29:20 INFO - TinderboxPrint: CPU usage
53.6% 08:29:20 INFO - TinderboxPrint: I/O read bytes / time
700,416 / 6 08:29:20 INFO - TinderboxPrint: I/O write bytes / time
549,714,432 / 1,970 08:29:20 INFO - TinderboxPrint: CPU idle
2,231.8 (46.4%) 08:29:20 INFO - TinderboxPrint: CPU system
960.9 (20.0%) 08:29:20 INFO - TinderboxPrint: CPU user
1,617.0 (33.6%) 08:29:20 INFO - TinderboxPrint: Swap in / out
249,880,576 / 0 08:29:20 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 241345024; Write bytes: 236951552; Read time: 17606; Write time: 481 08:29:20 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 700416; Write bytes: 295334400; Read time: 6; Write time: 1445 08:29:20 INFO - Running post-run listener: _upload_blobber_files 08:29:20 INFO - Blob upload gear active. 08:29:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:29:20 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 08:29:20 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'] 08:29:20 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 08:29:21 INFO - (blobuploader) - INFO - Open directory for files ... 08:29:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:29:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:29:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:22 INFO - (blobuploader) - INFO - Done attempting. 08:29:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:29:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:29:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:23 INFO - (blobuploader) - INFO - Done attempting. 08:29:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:29:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:29:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:24 INFO - (blobuploader) - INFO - Done attempting. 08:29:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:29:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:29:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:29:26 INFO - (blobuploader) - INFO - Done attempting. 08:29:26 INFO - (blobuploader) - INFO - Iteration through files over. 08:29:26 INFO - Return code: 0 08:29:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:29:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:29:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/eea59839eff685d8c4bb81c6260e7d9b6d85a14a0654367f873deef12c2c80742f2886d75b2e241aca2b1ca3ac323f2b765da8ff98617a2588f668175de41031", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/33d1a8d224b95b80238ccf64a29b1978c510dd1f812079e8d228d49dbc56d80750b08b7763517949ade194e5bfd6f84430e4595869572e79bc71f6717fee303b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ac7bba8668315a36efb4fd7d4aac245a35bf60d7bbd2b6e81b62027fc2abc08a51db0593dfe81a589cf7bd138dfda539f01ad257d24ace161da254bd57244bf2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d07a445848a2fc1b94f7bc5fdd662b899317b3e7d8c6beab67d1de5ede1115a8ea988de354a1f3a0e214e4b6edea1e144b1e752623d931fe36c46886e4412721"} 08:29:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:29:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:29:26 INFO - Contents: 08:29:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/eea59839eff685d8c4bb81c6260e7d9b6d85a14a0654367f873deef12c2c80742f2886d75b2e241aca2b1ca3ac323f2b765da8ff98617a2588f668175de41031", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/33d1a8d224b95b80238ccf64a29b1978c510dd1f812079e8d228d49dbc56d80750b08b7763517949ade194e5bfd6f84430e4595869572e79bc71f6717fee303b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ac7bba8668315a36efb4fd7d4aac245a35bf60d7bbd2b6e81b62027fc2abc08a51db0593dfe81a589cf7bd138dfda539f01ad257d24ace161da254bd57244bf2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d07a445848a2fc1b94f7bc5fdd662b899317b3e7d8c6beab67d1de5ede1115a8ea988de354a1f3a0e214e4b6edea1e144b1e752623d931fe36c46886e4412721"} 08:29:26 INFO - Running post-run listener: copy_logs_to_upload_dir 08:29:26 INFO - Copying logs to upload dir... 08:29:26 INFO - mkdir: /builds/slave/test/build/upload/logs 08:29:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.513416 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-28 08:29:26.683675) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 08:29:26.686724) ========= 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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/eea59839eff685d8c4bb81c6260e7d9b6d85a14a0654367f873deef12c2c80742f2886d75b2e241aca2b1ca3ac323f2b765da8ff98617a2588f668175de41031", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/33d1a8d224b95b80238ccf64a29b1978c510dd1f812079e8d228d49dbc56d80750b08b7763517949ade194e5bfd6f84430e4595869572e79bc71f6717fee303b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ac7bba8668315a36efb4fd7d4aac245a35bf60d7bbd2b6e81b62027fc2abc08a51db0593dfe81a589cf7bd138dfda539f01ad257d24ace161da254bd57244bf2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d07a445848a2fc1b94f7bc5fdd662b899317b3e7d8c6beab67d1de5ede1115a8ea988de354a1f3a0e214e4b6edea1e144b1e752623d931fe36c46886e4412721"} build_url:https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011078 build_url: 'https://queue.taskcluster.net/v1/task/NQeaIdx5SlSaA8fref7Ivg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/eea59839eff685d8c4bb81c6260e7d9b6d85a14a0654367f873deef12c2c80742f2886d75b2e241aca2b1ca3ac323f2b765da8ff98617a2588f668175de41031", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/33d1a8d224b95b80238ccf64a29b1978c510dd1f812079e8d228d49dbc56d80750b08b7763517949ade194e5bfd6f84430e4595869572e79bc71f6717fee303b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ac7bba8668315a36efb4fd7d4aac245a35bf60d7bbd2b6e81b62027fc2abc08a51db0593dfe81a589cf7bd138dfda539f01ad257d24ace161da254bd57244bf2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d07a445848a2fc1b94f7bc5fdd662b899317b3e7d8c6beab67d1de5ede1115a8ea988de354a1f3a0e214e4b6edea1e144b1e752623d931fe36c46886e4412721"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-28 08:29:26.721498) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 08:29:26.721838) ========= 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.qhBX0A5tEb/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.4fv4I6Yhji/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.005149 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-28 08:29:26.770294) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 08:29:26.770613) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-28 08:29:26.770931) ========= ========= Total master_lag: 0.18 =========