builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0127 starttime: 1471978391.19 results: success (0) buildid: 20160823095646 builduid: a40991f51bbe48dcbdce81f89b787128 revision: 85c9a094f1275a1f1e2d2bce07abda30cd90d8b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:11.188361) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:11.188992) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:11.189554) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:11.213127) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:11.213453) ========= 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.podzd2Fsal/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.xTv7FeXWod/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-08-23 11:53:11-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-08-23 11:53:11 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130487 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:11.367840) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:11.368153) ========= 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.podzd2Fsal/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.xTv7FeXWod/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.020996 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:11.418717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 11:53:11.419421) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 85c9a094f1275a1f1e2d2bce07abda30cd90d8b9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 85c9a094f1275a1f1e2d2bce07abda30cd90d8b9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.podzd2Fsal/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.xTv7FeXWod/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-08-23 11:53:11,493 truncating revision to first 12 chars 2016-08-23 11:53:11,493 Setting DEBUG logging. 2016-08-23 11:53:11,493 attempt 1/10 2016-08-23 11:53:11,493 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/85c9a094f127?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 11:53:12,365 unpacking tar archive at: autoland-85c9a094f127/testing/mozharness/ program finished with exit code 0 elapsedTime=1.136514 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 11:53:12.579031) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:12.579361) ========= script_repo_revision: 85c9a094f1275a1f1e2d2bce07abda30cd90d8b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:12.579753) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:12.580015) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 11:53:12.598219) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-23 11:53:12.598486) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.podzd2Fsal/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.xTv7FeXWod/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:53:12 INFO - MultiFileLogger online at 20160823 11:53:12 in /builds/slave/test 11:53:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 11:53:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:53:12 INFO - {'append_to_log': False, 11:53:12 INFO - 'base_work_dir': '/builds/slave/test', 11:53:12 INFO - 'blob_upload_branch': 'Autoland', 11:53:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:53:12 INFO - 'branch': 'Autoland', 11:53:12 INFO - 'buildbot_json_path': 'buildprops.json', 11:53:12 INFO - 'config_files': ('talos/mac_config.py',), 11:53:12 INFO - 'default_actions': ('clobber', 11:53:12 INFO - 'read-buildbot-config', 11:53:12 INFO - 'download-and-extract', 11:53:12 INFO - 'populate-webroot', 11:53:12 INFO - 'create-virtualenv', 11:53:12 INFO - 'install', 11:53:12 INFO - 'run-tests'), 11:53:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:53:12 INFO - 'download_minidump_stackwalk': True, 11:53:12 INFO - 'download_symbols': 'ondemand', 11:53:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:53:12 INFO - 'tooltool.py': '/tools/tooltool.py', 11:53:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:53:12 INFO - '/tools/misc-python/virtualenv.py')}, 11:53:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:53:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:53:12 INFO - 'installer_path': 'installer.exe', 11:53:12 INFO - 'log_level': 'info', 11:53:12 INFO - 'log_name': 'talos', 11:53:12 INFO - 'log_to_console': True, 11:53:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:53:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:53:12 INFO - 'opt_config_files': (), 11:53:12 INFO - 'pip_index': False, 11:53:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:53:12 INFO - 'cmd': ('bash', 11:53:12 INFO - '-c', 11:53:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:53:12 INFO - 'enabled': True, 11:53:12 INFO - 'halt_on_failure': False, 11:53:12 INFO - 'name': 'check_screen_resolution'},), 11:53:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:53:12 INFO - 'cmd': ('bash', 11:53:12 INFO - '-c', 11:53:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:53:12 INFO - 'enabled': True, 11:53:12 INFO - 'halt_on_failure': False, 11:53:12 INFO - 'name': 'check_screen_resolution'},), 11:53:12 INFO - 'run_cmd_checks_enabled': True, 11:53:12 INFO - 'sps_profile': False, 11:53:12 INFO - 'sps_profile_interval': 0, 11:53:12 INFO - 'suite': 'dromaeojs', 11:53:12 INFO - 'system_bits': '32', 11:53:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:53:12 INFO - 'title': 't-yosemite-r7-0127', 11:53:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:53:12 INFO - 'use_talos_json': True, 11:53:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:53:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:53:12 INFO - 'work_dir': 'build'} 11:53:12 INFO - [mozharness: 2016-08-23 18:53:12.795344Z] Running clobber step. 11:53:12 INFO - Running pre-action listener: _resource_record_pre_action 11:53:12 INFO - Running main action method: clobber 11:53:12 INFO - rmtree: /builds/slave/test/build 11:53:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:53:13 INFO - Running post-action listener: _resource_record_post_action 11:53:13 INFO - [mozharness: 2016-08-23 18:53:13.252311Z] Finished clobber step (success) 11:53:13 INFO - [mozharness: 2016-08-23 18:53:13.252454Z] Running read-buildbot-config step. 11:53:13 INFO - Running pre-action listener: _resource_record_pre_action 11:53:13 INFO - Running main action method: read_buildbot_config 11:53:13 INFO - Using buildbot properties: 11:53:13 INFO - { 11:53:13 INFO - "project": "", 11:53:13 INFO - "product": "firefox", 11:53:13 INFO - "script_repo_revision": "production", 11:53:13 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 11:53:13 INFO - "repository": "", 11:53:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 11:53:13 INFO - "buildid": "20160823095646", 11:53:13 INFO - "pgo_build": "False", 11:53:13 INFO - "basedir": "/builds/slave/test", 11:53:13 INFO - "buildnumber": 129, 11:53:13 INFO - "slavename": "t-yosemite-r7-0127", 11:53:13 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:53:13 INFO - "platform": "yosemite_r7", 11:53:13 INFO - "scheduler": "tests-autoland-macosx64-talos", 11:53:13 INFO - "branch": "autoland", 11:53:13 INFO - "repo_path": "integration/autoland", 11:53:13 INFO - "revision": "85c9a094f1275a1f1e2d2bce07abda30cd90d8b9", 11:53:13 INFO - "stage_platform": "macosx64", 11:53:13 INFO - "builduid": "a40991f51bbe48dcbdce81f89b787128", 11:53:13 INFO - "slavebuilddir": "test" 11:53:13 INFO - } 11:53:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:53:13 INFO - Running post-action listener: _resource_record_post_action 11:53:13 INFO - [mozharness: 2016-08-23 18:53:13.257023Z] Finished read-buildbot-config step (success) 11:53:13 INFO - [mozharness: 2016-08-23 18:53:13.257447Z] Running download-and-extract step. 11:53:13 INFO - Running pre-action listener: _resource_record_pre_action 11:53:13 INFO - Running main action method: download_and_extract 11:53:13 INFO - mkdir: /builds/slave/test/build/tests 11:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:13 INFO - trying https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:53:13 INFO - Downloading https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:53:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 11:53:14 INFO - Downloaded 1199 bytes. 11:53:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:53:14 INFO - Using the following test package requirements: 11:53:14 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:53:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:53:14 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:53:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:53:14 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:53:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:53:14 INFO - u'jsshell-mac.zip'], 11:53:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:53:14 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:53:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:53:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:53:14 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:53:14 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:53:14 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:53:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:53:14 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:53:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:53:14 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:53:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:53:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:14 INFO - trying https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:53:14 INFO - Downloading https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:53:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:53:15 INFO - Downloaded 16346205 bytes. 11:53:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:53:22 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:22 INFO - trying https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:53:22 INFO - Downloading https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:53:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:53:23 INFO - Downloaded 16346205 bytes. 11:53:23 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:53:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:29 INFO - trying https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:53:29 INFO - Downloading https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:53:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:53:31 INFO - Downloaded 14413981 bytes. 11:53:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:32 INFO - trying https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:53:32 INFO - Downloading https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:53:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 11:53:35 INFO - Downloaded 111510134 bytes. 11:53:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:53:35 INFO - mkdir: /builds/slave/test/properties 11:53:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:53:35 INFO - Writing to file /builds/slave/test/properties/build_url 11:53:35 INFO - Contents: 11:53:35 INFO - build_url:https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:53:36 INFO - Running post-action listener: _resource_record_post_action 11:53:36 INFO - Running post-action listener: set_extra_try_arguments 11:53:36 INFO - [mozharness: 2016-08-23 18:53:36.099521Z] Finished download-and-extract step (success) 11:53:36 INFO - [mozharness: 2016-08-23 18:53:36.099678Z] Running populate-webroot step. 11:53:36 INFO - Running pre-action listener: _resource_record_pre_action 11:53:36 INFO - Running main action method: populate_webroot 11:53:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:53:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:53:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:53:36 INFO - '--tppagecycles', 11:53:36 INFO - '7'], 11:53:36 INFO - 'tests': ['tsvgm']}}, 11:53:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:53:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:53:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:53:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:53:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:36 INFO - 'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:53:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:53:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:36 INFO - 'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['damp', 'tps']}, 11:53:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:36 INFO - 'tests': ['damp', 'tps']}, 11:53:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['dromaeo_dom']}, 11:53:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:53:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['basic_compositor_video']}, 11:53:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:53:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['a11yr', 11:53:36 INFO - 'ts_paint', 11:53:36 INFO - 'tpaint', 11:53:36 INFO - 'sessionrestore', 11:53:36 INFO - 'sessionrestore_no_auto_restore', 11:53:36 INFO - 'tabpaint']}, 11:53:36 INFO - 'other-e10s': {'tests': ['a11yr', 11:53:36 INFO - 'ts_paint', 11:53:36 INFO - 'tpaint', 11:53:36 INFO - 'sessionrestore', 11:53:36 INFO - 'sessionrestore_no_auto_restore', 11:53:36 INFO - 'tabpaint']}, 11:53:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:53:36 INFO - 'ts_paint', 11:53:36 INFO - 'tpaint', 11:53:36 INFO - 'sessionrestore', 11:53:36 INFO - 'sessionrestore_no_auto_restore', 11:53:36 INFO - 'tabpaint']}, 11:53:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:53:36 INFO - 'ts_paint', 11:53:36 INFO - 'tpaint', 11:53:36 INFO - 'sessionrestore', 11:53:36 INFO - 'sessionrestore_no_auto_restore', 11:53:36 INFO - 'tabpaint']}, 11:53:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['a11yr', 11:53:36 INFO - 'ts_paint', 11:53:36 INFO - 'tpaint', 11:53:36 INFO - 'sessionrestore', 11:53:36 INFO - 'sessionrestore_no_auto_restore', 11:53:36 INFO - 'tabpaint']}, 11:53:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['a11yr', 11:53:36 INFO - 'ts_paint', 11:53:36 INFO - 'tpaint', 11:53:36 INFO - 'sessionrestore', 11:53:36 INFO - 'sessionrestore_no_auto_restore', 11:53:36 INFO - 'tabpaint']}, 11:53:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['tsvgx', 11:53:36 INFO - 'tsvgr_opacity', 11:53:36 INFO - 'tart', 11:53:36 INFO - 'tscrollx', 11:53:36 INFO - 'cart']}, 11:53:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:53:36 INFO - 'tsvgr_opacity', 11:53:36 INFO - 'tart', 11:53:36 INFO - 'tscrollx', 11:53:36 INFO - 'cart']}, 11:53:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:36 INFO - 'talos_options': ['--disable-e10s'], 11:53:36 INFO - 'tests': ['tp5o']}, 11:53:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:36 INFO - 'tests': ['tp5o']}, 11:53:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:36 INFO - 'talos_options': ['--disable-e10s', 11:53:36 INFO - '--xperf_path', 11:53:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:36 INFO - 'tests': ['tp5n']}, 11:53:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:53:36 INFO - 'talos_options': ['--xperf_path', 11:53:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:36 INFO - 'tests': ['tp5n']}}, 11:53:36 INFO - 'talos.zip': {'path': '', 11:53:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:53:36 INFO - Running post-action listener: _resource_record_post_action 11:53:36 INFO - [mozharness: 2016-08-23 18:53:36.118324Z] Finished populate-webroot step (success) 11:53:36 INFO - [mozharness: 2016-08-23 18:53:36.118465Z] Running create-virtualenv step. 11:53:36 INFO - Running pre-action listener: _resource_record_pre_action 11:53:36 INFO - Running main action method: create_virtualenv 11:53:36 INFO - Creating virtualenv /builds/slave/test/build/venv 11:53:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:53:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:53:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:53:36 INFO - Using real prefix '/tools/python27' 11:53:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:53:36 INFO - Installing distribute.............................................................................................................................................................................................done. 11:53:39 INFO - Installing pip.................done. 11:53:39 INFO - Return code: 0 11:53:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:39 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 0x105f3da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fd2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdea5be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, '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.podzd2Fsal/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.xTv7FeXWod/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:53:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:53:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:39 INFO - 'HOME': '/Users/cltbld', 11:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:39 INFO - 'LOGNAME': 'cltbld', 11:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:39 INFO - 'MOZ_NO_REMOTE': '1', 11:53:39 INFO - 'NO_EM_RESTART': '1', 11:53:39 INFO - 'PAGER': '/bin/cat', 11:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:39 INFO - 'PWD': '/builds/slave/test', 11:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:39 INFO - 'SHELL': '/bin/bash', 11:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:53:39 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:39 INFO - 'USER': 'cltbld', 11:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:39 INFO - 'XPC_FLAGS': '0x0', 11:53:39 INFO - 'XPC_SERVICE_NAME': '0', 11:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:53:40 INFO - Cleaning up... 11:53:40 INFO - Return code: 0 11:53:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:53: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')]} 11:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53: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')]} 11:53:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:40 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 0x105f3da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fd2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdea5be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, '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.podzd2Fsal/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.xTv7FeXWod/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:53:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:53:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:53:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:53:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:40 INFO - 'HOME': '/Users/cltbld', 11:53:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:40 INFO - 'LOGNAME': 'cltbld', 11:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:40 INFO - 'MOZ_NO_REMOTE': '1', 11:53:40 INFO - 'NO_EM_RESTART': '1', 11:53:40 INFO - 'PAGER': '/bin/cat', 11:53:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:40 INFO - 'PWD': '/builds/slave/test', 11:53:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:40 INFO - 'SHELL': '/bin/bash', 11:53:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:53:40 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:53:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:40 INFO - 'USER': 'cltbld', 11:53:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:40 INFO - 'XPC_FLAGS': '0x0', 11:53:40 INFO - 'XPC_SERVICE_NAME': '0', 11:53:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:40 INFO - Downloading/unpacking psutil>=3.1.1 11:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:53:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:53:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:53:43 INFO - Installing collected packages: psutil 11:53:43 INFO - Running setup.py install for psutil 11:53:43 INFO - building 'psutil._psutil_osx' extension 11:53:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:53:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:53:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:53:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:53:43 INFO - building 'psutil._psutil_posix' extension 11:53:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:53:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:53:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:53:43 INFO - ^ 11:53:44 INFO - 1 warning generated. 11:53:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:53:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:53:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:53:44 INFO - Successfully installed psutil 11:53:44 INFO - Cleaning up... 11:53:44 INFO - Return code: 0 11:53:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:53:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:44 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 0x105f3da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fd2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdea5be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, '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.podzd2Fsal/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.xTv7FeXWod/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:53:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:44 INFO - 'HOME': '/Users/cltbld', 11:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:44 INFO - 'LOGNAME': 'cltbld', 11:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:44 INFO - 'MOZ_NO_REMOTE': '1', 11:53:44 INFO - 'NO_EM_RESTART': '1', 11:53:44 INFO - 'PAGER': '/bin/cat', 11:53:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:44 INFO - 'PWD': '/builds/slave/test', 11:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:44 INFO - 'SHELL': '/bin/bash', 11:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:53:44 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:44 INFO - 'USER': 'cltbld', 11:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:44 INFO - 'XPC_FLAGS': '0x0', 11:53:44 INFO - 'XPC_SERVICE_NAME': '0', 11:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:53:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:53:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:53:47 INFO - Installing collected packages: mozsystemmonitor 11:53:47 INFO - Running setup.py install for mozsystemmonitor 11:53:47 INFO - Successfully installed mozsystemmonitor 11:53:47 INFO - Cleaning up... 11:53:47 INFO - Return code: 0 11:53:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105f3da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fd2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdea5be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, '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.podzd2Fsal/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.xTv7FeXWod/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:53:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:53:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:53:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:53:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:47 INFO - 'HOME': '/Users/cltbld', 11:53:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:47 INFO - 'LOGNAME': 'cltbld', 11:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:47 INFO - 'MOZ_NO_REMOTE': '1', 11:53:47 INFO - 'NO_EM_RESTART': '1', 11:53:47 INFO - 'PAGER': '/bin/cat', 11:53:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:47 INFO - 'PWD': '/builds/slave/test', 11:53:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:47 INFO - 'SHELL': '/bin/bash', 11:53:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:53:47 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:53:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:47 INFO - 'USER': 'cltbld', 11:53:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:47 INFO - 'XPC_FLAGS': '0x0', 11:53:47 INFO - 'XPC_SERVICE_NAME': '0', 11:53:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:47 INFO - Downloading/unpacking jsonschema==2.5.1 11:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:53:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:53: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 11:53: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 11:53: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 11:53: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 11:53: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 11:53: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 11:53:50 INFO - Downloading functools32-3.2.3-2.tar.gz 11:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:53:50 INFO - warning: no files found matching '*.txt' 11:53:50 INFO - no previously-included directories found matching 'build' 11:53:50 INFO - no previously-included directories found matching 'dist' 11:53:50 INFO - no previously-included directories found matching '.git*' 11:53:50 INFO - Installing collected packages: jsonschema, functools32 11:53:50 INFO - Running setup.py install for functools32 11:53:50 INFO - warning: no files found matching '*.txt' 11:53:50 INFO - no previously-included directories found matching 'build' 11:53:50 INFO - no previously-included directories found matching 'dist' 11:53:50 INFO - no previously-included directories found matching '.git*' 11:53:50 INFO - Successfully installed jsonschema functools32 11:53:50 INFO - Cleaning up... 11:53:50 INFO - Return code: 0 11:53:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:50 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 0x105f3da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fd2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdea5be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, '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.podzd2Fsal/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.xTv7FeXWod/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:53:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:53:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:50 INFO - 'HOME': '/Users/cltbld', 11:53:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:50 INFO - 'LOGNAME': 'cltbld', 11:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:50 INFO - 'MOZ_NO_REMOTE': '1', 11:53:50 INFO - 'NO_EM_RESTART': '1', 11:53:50 INFO - 'PAGER': '/bin/cat', 11:53:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:50 INFO - 'PWD': '/builds/slave/test', 11:53:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:50 INFO - 'SHELL': '/bin/bash', 11:53:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:53:50 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:53:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:50 INFO - 'USER': 'cltbld', 11:53:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:50 INFO - 'XPC_FLAGS': '0x0', 11:53:50 INFO - 'XPC_SERVICE_NAME': '0', 11:53:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:53:50 INFO - Cleaning up... 11:53:50 INFO - Return code: 0 11:53:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:50 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 0x105f3da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fd2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdea5be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, '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.podzd2Fsal/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.xTv7FeXWod/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:53:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:53:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:50 INFO - 'HOME': '/Users/cltbld', 11:53:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:50 INFO - 'LOGNAME': 'cltbld', 11:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:50 INFO - 'MOZ_NO_REMOTE': '1', 11:53:50 INFO - 'NO_EM_RESTART': '1', 11:53:50 INFO - 'PAGER': '/bin/cat', 11:53:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:50 INFO - 'PWD': '/builds/slave/test', 11:53:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:50 INFO - 'SHELL': '/bin/bash', 11:53:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:53:50 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:53:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:50 INFO - 'USER': 'cltbld', 11:53:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:50 INFO - 'XPC_FLAGS': '0x0', 11:53:50 INFO - 'XPC_SERVICE_NAME': '0', 11:53:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:51 INFO - Downloading/unpacking blobuploader==1.2.4 11:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:53 INFO - Downloading blobuploader-1.2.4.tar.gz 11:53:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:53:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:53:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:53: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 11:53: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 11:53: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 11:53: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 11:53: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 11:53: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 11:53:54 INFO - Downloading docopt-0.6.1.tar.gz 11:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:53:54 INFO - Installing collected packages: blobuploader, requests, docopt 11:53:54 INFO - Running setup.py install for blobuploader 11:53:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:53:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:53:55 INFO - Running setup.py install for requests 11:53:55 INFO - Running setup.py install for docopt 11:53:55 INFO - Successfully installed blobuploader requests docopt 11:53:55 INFO - Cleaning up... 11:53:55 INFO - Return code: 0 11:53:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:53:55 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 0x105f3da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fd2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdea5be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, '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.podzd2Fsal/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.xTv7FeXWod/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:53:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:53:55 INFO - 'HOME': '/Users/cltbld', 11:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:53:55 INFO - 'LOGNAME': 'cltbld', 11:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:55 INFO - 'MOZ_NO_REMOTE': '1', 11:53:55 INFO - 'NO_EM_RESTART': '1', 11:53:55 INFO - 'PAGER': '/bin/cat', 11:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:55 INFO - 'PWD': '/builds/slave/test', 11:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:53:55 INFO - 'SHELL': '/bin/bash', 11:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:53:55 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:53:55 INFO - 'USER': 'cltbld', 11:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:55 INFO - 'XPC_FLAGS': '0x0', 11:53:55 INFO - 'XPC_SERVICE_NAME': '0', 11:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:53:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-nnUIMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:53:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-E3JRy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:53:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Np9qte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:53:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-xd7OoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:53:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-BRbykF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:53:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-wMzrtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:53:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-9em5lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:53:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-RparHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:53:56 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HJBN8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:53:57 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-7sw8oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:53:57 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-aUwj2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:53:57 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-C5Ptu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:53:57 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HTdrof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:53:57 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-pSnwOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:57 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-yoVeko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:53:57 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-h35DTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:53:57 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-rYWcWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:53:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:53:57 INFO - Running setup.py install for manifestparser 11:53:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:53:58 INFO - Running setup.py install for mozcrash 11:53:58 INFO - Running setup.py install for mozdebug 11:53:58 INFO - Running setup.py install for mozdevice 11:53:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:53:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:53:58 INFO - Running setup.py install for mozfile 11:53:58 INFO - Running setup.py install for mozhttpd 11:53:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:53:58 INFO - Running setup.py install for mozinfo 11:53:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:53:58 INFO - Running setup.py install for mozInstall 11:53:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:53:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:53:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:53:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:53:59 INFO - Running setup.py install for mozleak 11:53:59 INFO - Running setup.py install for mozlog 11:53:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:53:59 INFO - Running setup.py install for moznetwork 11:53:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:53:59 INFO - Running setup.py install for mozprocess 11:53:59 INFO - Running setup.py install for mozprofile 11:53:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:53:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:53:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:53:59 INFO - Running setup.py install for mozrunner 11:54:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Running setup.py install for mozscreenshot 11:54:00 INFO - Running setup.py install for moztest 11:54:00 INFO - Running setup.py install for mozversion 11:54:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:54:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:54:00 INFO - Cleaning up... 11:54:00 INFO - Return code: 0 11:54:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54: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')]} 11:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54: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')]} 11:54:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:00 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 0x105f3da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fd2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdea5be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, '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.podzd2Fsal/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.xTv7FeXWod/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:54:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:54:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:54:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:54:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:54:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:54:00 INFO - 'HOME': '/Users/cltbld', 11:54:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:54:00 INFO - 'LOGNAME': 'cltbld', 11:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:00 INFO - 'MOZ_NO_REMOTE': '1', 11:54:00 INFO - 'NO_EM_RESTART': '1', 11:54:00 INFO - 'PAGER': '/bin/cat', 11:54:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:54:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:00 INFO - 'PWD': '/builds/slave/test', 11:54:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:54:00 INFO - 'SHELL': '/bin/bash', 11:54:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:54:00 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:54:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:54:00 INFO - 'USER': 'cltbld', 11:54:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:54:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:00 INFO - 'XPC_FLAGS': '0x0', 11:54:00 INFO - 'XPC_SERVICE_NAME': '0', 11:54:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:54:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:54:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-iIBV90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:54:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-oxUyxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:54:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:54:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:54:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-G0gBxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:54:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-BQSO9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:54:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-vjwvZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:54:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Pd3D3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:54:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-mHRN8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:54:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-RHZQDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:54:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-hvfaOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:54:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-XXjnx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:54:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-3Ej0mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:54:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:54:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ESCMYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:54:02 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ML6USP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:54:02 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-_tT6QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:02 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-horcYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:54:02 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-FbM_zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:54:02 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)) 11:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:54:02 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-jfqjvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:54:02 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:54:02 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:54:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:04 INFO - Downloading blessings-1.6.tar.gz 11:54:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:54:05 INFO - Installing collected packages: blessings 11:54:05 INFO - Running setup.py install for blessings 11:54:05 INFO - Successfully installed blessings 11:54:05 INFO - Cleaning up... 11:54:05 INFO - Return code: 0 11:54:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:54:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:05 INFO - Reading from file tmpfile_stdout 11:54:05 INFO - Current package versions: 11:54:05 INFO - blessings == 1.6 11:54:05 INFO - blobuploader == 1.2.4 11:54:05 INFO - docopt == 0.6.1 11:54:05 INFO - functools32 == 3.2.3-2 11:54:05 INFO - jsonschema == 2.5.1 11:54:05 INFO - manifestparser == 1.1 11:54:05 INFO - mozInstall == 1.12 11:54:05 INFO - mozcrash == 0.17 11:54:05 INFO - mozdebug == 0.1 11:54:05 INFO - mozdevice == 0.48 11:54:05 INFO - mozfile == 1.2 11:54:05 INFO - mozhttpd == 0.7 11:54:05 INFO - mozinfo == 0.9 11:54:05 INFO - mozleak == 0.1 11:54:05 INFO - mozlog == 3.3 11:54:05 INFO - moznetwork == 0.27 11:54:05 INFO - mozprocess == 0.23 11:54:05 INFO - mozprofile == 0.28 11:54:05 INFO - mozrunner == 6.12 11:54:05 INFO - mozscreenshot == 0.1 11:54:05 INFO - mozsystemmonitor == 0.3 11:54:05 INFO - moztest == 0.7 11:54:05 INFO - mozversion == 1.4 11:54:05 INFO - psutil == 3.1.1 11:54:05 INFO - requests == 1.2.3 11:54:05 INFO - wsgiref == 0.1.2 11:54:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54: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')]} 11:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54: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')]} 11:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:05 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 0x105f3da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fd2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdea5be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, '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.podzd2Fsal/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.xTv7FeXWod/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:54:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:54:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:54:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:54:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:54:05 INFO - 'HOME': '/Users/cltbld', 11:54:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:54:05 INFO - 'LOGNAME': 'cltbld', 11:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:05 INFO - 'MOZ_NO_REMOTE': '1', 11:54:05 INFO - 'NO_EM_RESTART': '1', 11:54:05 INFO - 'PAGER': '/bin/cat', 11:54:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:05 INFO - 'PWD': '/builds/slave/test', 11:54:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:54:05 INFO - 'SHELL': '/bin/bash', 11:54:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:54:05 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:54:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:54:05 INFO - 'USER': 'cltbld', 11:54:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:54:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:05 INFO - 'XPC_FLAGS': '0x0', 11:54:05 INFO - 'XPC_SERVICE_NAME': '0', 11:54:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:54:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:54:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:54:05 INFO - Cleaning up... 11:54:05 INFO - Return code: 0 11:54:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:54: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')]} 11:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54: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')]} 11:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:54:05 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 0x105f3da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fd2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdea5be350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, '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.podzd2Fsal/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.xTv7FeXWod/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:54:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:54:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:54:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:54:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:54:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:54:05 INFO - 'HOME': '/Users/cltbld', 11:54:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:54:05 INFO - 'LOGNAME': 'cltbld', 11:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:05 INFO - 'MOZ_NO_REMOTE': '1', 11:54:05 INFO - 'NO_EM_RESTART': '1', 11:54:05 INFO - 'PAGER': '/bin/cat', 11:54:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:05 INFO - 'PWD': '/builds/slave/test', 11:54:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:54:05 INFO - 'SHELL': '/bin/bash', 11:54:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:54:05 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:54:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:54:05 INFO - 'USER': 'cltbld', 11:54:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:54:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:05 INFO - 'XPC_FLAGS': '0x0', 11:54:05 INFO - 'XPC_SERVICE_NAME': '0', 11:54:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:54:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:54:06 INFO - Cleaning up... 11:54:06 INFO - Return code: 0 11:54:06 INFO - Running post-action listener: _resource_record_post_action 11:54:06 INFO - Running post-action listener: _start_resource_monitoring 11:54:06 INFO - Starting resource monitoring. 11:54:06 INFO - [mozharness: 2016-08-23 18:54:06.187373Z] Finished create-virtualenv step (success) 11:54:06 INFO - [mozharness: 2016-08-23 18:54:06.188392Z] Running install step. 11:54:06 INFO - Running pre-action listener: _resource_record_pre_action 11:54:06 INFO - Running main action method: install 11:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:06 INFO - Reading from file tmpfile_stdout 11:54:06 INFO - Detecting whether we're running mozinstall >=1.0... 11:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:54:06 INFO - Reading from file tmpfile_stdout 11:54:06 INFO - Output received: 11:54:06 INFO - Usage: mozinstall [options] installer 11:54:06 INFO - Options: 11:54:06 INFO - -h, --help show this help message and exit 11:54:06 INFO - -d DEST, --destination=DEST 11:54:06 INFO - Directory to install application into. [default: 11:54:06 INFO - "/builds/slave/test"] 11:54:06 INFO - --app=APP Application being installed. [default: firefox] 11:54:06 INFO - mkdir: /builds/slave/test/build/application 11:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:54:40 INFO - Reading from file tmpfile_stdout 11:54:40 INFO - Output received: 11:54:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:54:40 INFO - Running post-action listener: _resource_record_post_action 11:54:40 INFO - [mozharness: 2016-08-23 18:54:40.536346Z] Finished install step (success) 11:54:40 INFO - [mozharness: 2016-08-23 18:54:40.536512Z] Running run-tests step. 11:54:40 INFO - Running pre-action listener: _resource_record_pre_action 11:54:40 INFO - Running main action method: run_tests 11:54:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:54:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:54:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:54:40 INFO - 2016-08-23 11:54:40.551 screenresolution[6593:119474] starting screenresolution argv=screenresolution get 11:54:40 INFO - 2016-08-23 11:54:40.554 screenresolution[6593:119474] Display 0: 1600x1200x32@60 11:54:40 INFO - 2016-08-23 11:54:40.560 screenresolution[6594:119476] starting screenresolution argv=screenresolution list 11:54:40 INFO - Available Modes on Display 0 11:54:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:54:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:54:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:54:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:54:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:54:40 INFO - Intel Iris: 11:54:40 INFO - Chipset Model: Intel Iris 11:54:40 INFO - Type: GPU 11:54:40 INFO - Bus: Built-In 11:54:40 INFO - VRAM (Dynamic, Max): 1536 MB 11:54:40 INFO - Vendor: Intel (0x8086) 11:54:40 INFO - Device ID: 0x0a2e 11:54:40 INFO - Revision ID: 0x0009 11:54:40 INFO - Displays: 11:54:40 INFO - Display: 11:54:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:54:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:54:40 INFO - Display Serial Number: 1600x1200 60 11:54:40 INFO - Main Display: Yes 11:54:40 INFO - Mirror: Off 11:54:40 INFO - Online: Yes 11:54:40 INFO - Rotation: Supported 11:54:40 INFO - Return code: 0 11:54:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:54:40 INFO - Python 2.7.3 11:54:40 INFO - Return code: 0 11:54:40 INFO - grabbing minidump binary from tooltool 11:54: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')]} 11:54: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 0x105ea1d30>, '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 0x105c06db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e99b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:54: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 11:54: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 11:54: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 11:54:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:54:40 INFO - Return code: 0 11:54:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:54:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:54:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:54:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:54:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:54:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:54:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.podzd2Fsal/Render', 11:54:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:54:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:54:40 INFO - 'HOME': '/Users/cltbld', 11:54:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:54:40 INFO - 'LOGNAME': 'cltbld', 11:54:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:54:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:40 INFO - 'MOZ_NO_REMOTE': '1', 11:54:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:54:40 INFO - 'NO_EM_RESTART': '1', 11:54:40 INFO - 'PAGER': '/bin/cat', 11:54:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:40 INFO - 'PWD': '/builds/slave/test', 11:54:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:54:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:54:40 INFO - 'SHELL': '/bin/bash', 11:54:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xTv7FeXWod/Listeners', 11:54:40 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 11:54:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:54:40 INFO - 'USER': 'cltbld', 11:54:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:54:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:40 INFO - 'XPC_FLAGS': '0x0', 11:54:40 INFO - 'XPC_SERVICE_NAME': '0', 11:54:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:54:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:54:41 INFO - mozversion application_buildid: 20160823095646 11:54:41 INFO - mozversion application_changeset: 85c9a094f1275a1f1e2d2bce07abda30cd90d8b9 11:54:41 INFO - mozversion application_display_name: Nightly 11:54:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:54:41 INFO - mozversion application_name: Firefox 11:54:41 INFO - mozversion application_remotingname: firefox 11:54:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:54:41 INFO - mozversion application_vendor: Mozilla 11:54:41 INFO - mozversion application_version: 51.0a1 11:54:41 INFO - mozversion platform_buildid: 20160823095646 11:54:41 INFO - mozversion platform_changeset: 85c9a094f1275a1f1e2d2bce07abda30cd90d8b9 11:54:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:54:41 INFO - mozversion platform_version: 51.0a1 11:54:41 INFO - using testdate: 1471978481 11:54:41 INFO - actual date: 1471978481 11:54:41 INFO - starting webserver on 'localhost:64999' 11:54:41 INFO - SUITE-START | Running 2 tests 11:54:41 INFO - TEST-START | dromaeo_css 11:54:41 INFO - operating with platform_type : mac_ 11:54:41 INFO - Initialising browser for dromaeo_css test... 11:54:41 INFO - TEST-INFO | started process 6604 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpBAEdMn/profile http://localhost:64999/getInfo.html) 11:54:43 INFO - PROCESS | 6604 | __metrics Screen width/height:1600/1200 11:54:43 INFO - PROCESS | 6604 | colorDepth:24 11:54:43 INFO - PROCESS | 6604 | Browser inner width/height: 1024/689 11:54:43 INFO - PROCESS | 6604 | __metrics 11:54:43 INFO - PROCESS | 6604 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:54:43 INFO - PROCESS | 6604 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:54:44 INFO - TEST-INFO | 6604: exit 0 11:54:44 INFO - Browser initialized. 11:54:44 INFO - Running cycle 1/1 for dromaeo_css test... 11:54:44 INFO - TEST-INFO | started process 6606 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpBAEdMn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:54:56 INFO - PROCESS | 6606 | RSS: Main: 234835968 11:54:56 INFO - PROCESS | 6606 | 11:55:47 INFO - PROCESS | 6606 | 1471978547813 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:57:59 INFO - PROCESS | 6606 | Cycle 1(1): loaded http://localhost:64999/tests/dromaeo/cssquery-dojo.html (next: http://localhost:64999/tests/dromaeo/cssquery-ext.html) 11:58:00 INFO - PROCESS | 6606 | RSS: Main: 377016320 11:58:00 INFO - PROCESS | 6606 | 12:01:03 INFO - PROCESS | 6606 | Cycle 1(1): loaded http://localhost:64999/tests/dromaeo/cssquery-ext.html (next: http://localhost:64999/tests/dromaeo/cssquery-jquery.html) 12:01:04 INFO - PROCESS | 6606 | RSS: Main: 371306496 12:01:04 INFO - PROCESS | 6606 | 12:04:06 INFO - PROCESS | 6606 | Cycle 1(1): loaded http://localhost:64999/tests/dromaeo/cssquery-jquery.html (next: http://localhost:64999/tests/dromaeo/cssquery-mootools.html) 12:04:07 INFO - PROCESS | 6606 | RSS: Main: 336756736 12:04:07 INFO - PROCESS | 6606 | 12:07:10 INFO - PROCESS | 6606 | Cycle 1(1): loaded http://localhost:64999/tests/dromaeo/cssquery-mootools.html (next: http://localhost:64999/tests/dromaeo/cssquery-prototype.html) 12:07:10 INFO - PROCESS | 6606 | RSS: Main: 345083904 12:07:10 INFO - PROCESS | 6606 | 12:10:13 INFO - PROCESS | 6606 | Cycle 1(1): loaded http://localhost:64999/tests/dromaeo/cssquery-prototype.html (next: http://localhost:64999/tests/dromaeo/cssquery-yui.html) 12:10:14 INFO - PROCESS | 6606 | RSS: Main: 330027008 12:10:14 INFO - PROCESS | 6606 | 12:13:18 INFO - PROCESS | 6606 | Cycle 1(1): loaded http://localhost:64999/tests/dromaeo/cssquery-yui.html (next: http://localhost:64999/tests/dromaeo/cssquery-dojo.html) 12:13:18 INFO - PROCESS | 6606 | RSS: Main: 435519488 12:13:18 INFO - PROCESS | 6606 | 12:13:18 INFO - PROCESS | 6606 | __start_tp_report 12:13:18 INFO - PROCESS | 6606 | _x_x_mozilla_page_load 12:13:18 INFO - PROCESS | 6606 | _x_x_mozilla_page_load_details 12:13:18 INFO - PROCESS | 6606 | |i|pagename|runs| 12:13:18 INFO - PROCESS | 6606 | |0;dojo.html;2874.4107457971113;3249.853756580953;3293.291942232421;3319.336132773446;3356;5505.587080968923;5590.692511911849;5641.041023026085;5644.350899646537;5705.229793977812;5623.0721930881455;5683.403242659523;5769.336526299477;5783.161441590967;5803.216565763621;4469.888252793674;5073.822416215433;5164.096283150452;5195.324607800981;5218.347706536683;5291.788328466857;5304.496072873071;5308.973455132719;5319.946800532003;5336.119540275849;5129.461406552333;5204.9739751301;5207.6354655174155;5241.42344342122;5245.711485868275;5173.637845350827;5220.2430647556275;5240.161574148118;5248.343957005374;5258.55302299305;5129.204973229155;5140.306058682105;5165.457626949154;5217.791288348461;5257.553107985826;5680.772769089232;5693.430656934315;5695.14572814077;5695.572832037572;5705.115707065412;6068;6239.2824825145235;6251.343608921089;6251.906221406683;6264.87470250597;6082.704400945001;6173.969130154365;6177.69111544421;6263.96868015657;6277.7802776903045;5801.361850196475;5835.387284335084;5863.472287494146;5870.119482077723;5870.823875283749;6179.938200617937;6272.247330320391;6276.843078923063;6297.370262973757;6299.0866324382705;6015.939840601625;6056.727447264883;6110.511159107261;6140.324564297924;6149.907751383733;6213;6237.656928868956;6238.844028899223;6242.313345531988;6261;5937.7921772737745;6105.603135796159;6183.505319574423;6191.690415479298;6200.56596038282;6001.579889407787;6088.228730131248;6099.146119543268;6131.233595800525;6177.660228687465;4930.482299358588;4931.2849636803185;4943.530364615356;4971.925421118686;4976.601871850244;4404.4494438195225;4409.316555933771;4410.669199810027;4432.667549933768;4440.156370289635;5487.42382049887;5504.669719816744;5592.972035139798;5667.4615911488345;5671.347795003545;4839.588634965997;6209.96895015522;6229.595076320115;6271.4669253114;6282.623042617458;5634.549236061105;5664.801731939362;5675.6594604323045;5676.8864622707315;5680.204771332017;5281;5325.813596524181;5374.516293533523;5420.268263784339;5426.8371948841595;6247;6290.433860952537;6297.874042519215;6308.463780578702;6319.336469670802;6192.473639740672;6267.185265915402;6272.153259309935;6272.843178920563;6288.905666415099;6211.192544969125;6219.067139928866;6296.5592408530965;6300.306966233619;6301.306856245901;6108.419700128481;6134.693265336626;6137.039921714347;6155.753769849335;6186.690665466798;6046.54650901193;6227.034809604518;6245.313015568375;6248.4064013918605;6260.092286618324;5843.883122337615;6075.149479072845;6079;6098.024316109401;6114.327423983447;4414.4702635683925;5935.970320148372;5953.523718102628;5995.790147344822;6123.877522449437;5856.912146317723;5882.382349853204;5899.646021238739;5899.675517846388;5901.704914754331;6029.487493563096;6048.516118710405;6054.061620448838;6065.939340606714;6083.969580152071;5658.943410565842;5784.768609255751;5843.152742852178;5898.262717160355;5911.586188966731 12:13:18 INFO - PROCESS | 6606 | |1;ext.html;457.533316017662;473.42951743149507;490.8478371704774;518.282179181833;522.9529342359189;24240.393990150256;33787.32425351495;42511.57488425119;43558.911027224334;43649.34525982113;45344.77327613366;45637.31544026834;45646;45710.314345284845;45721.85695357618;9859.211263098934;10077.042680945298;10081.09270165685;10083.04211099948;10087;8883.866741998876;9113.908860911406;12050.397480126003;12348.93825530871;12351.32067736274;12244.755104897897;12329.938350308235;12335.383230838466;12348.691282717915;12351.382430878464;10428.270021098526;12231.38843057848;12336.198147120409;12349.135560510767;12372.876271237263;12207.375414285882;12294.938525307316;12297.93851030748;12309.44607492656;12309.753804923941;42414.57585424137;42522.149557009;42657.786711066554;42729.57270427318;42947;3537.8584856605703;3634.27314537091;3659.158393569494;3679.9217080429103;3900.478107074076;29570.556441653393;32239.677603224136;33196.6680333196;33316.00051998444;33771.66228337734;3238.2228265216413;3316.2206881382854;3336.065901547546;3379.053864917827;3443.6212016678146;395830;502918;503174;504913;511836.9999999963;34098.829505852314;62800;63234.36765632285;63305;63306.820511025646;34385;34554.65445345514;34559.4816077759;34594.02168750575;34629.480557791656;14866.959312847997;15045.398184072514;15046.172460514557;15058.849411505746;15080.773788393182;11362.318260904207;11612;12705.983521318272;12797.936010319889;12811.743765124646;7824.21757824213;7961.323287520626;7963.721269755531;7973.488351169363;8043.959780201179;3253.3330667213163;3270.5584746059453;3271.313024264931;3275.3449310138067;3311.403947289511;921.4425272710048;956.5647630328184;974.2449601558849;1021.9984415273074;1024.6208902706046;30964;30993.225169370948;31013;31522.211944701565;31859.203519911724;1078.8166011778017;1130.8812574679541;1136.8067428537167;1160.976780464386;1163.3078318400537;15794.526164215093;16124.596885077966;16183.514494565183;16274.26765795542;16415.475180892707;688865.5577720109;694656;695673;696978;701916;59740.10389844156;60106.69946650239;60157.796844062876;60266.09600855991;60309.39690603125;1251.5682089679046;1291.4059532614915;1335.7194989052407;1389.7637401641746;1400.516821696513;30757;31116.68883311138;31691.683083168875;32253;32276.677233228307;52095.73952130215;52806;52830.735846320524;52843;52890;538671;539272;539902;540631;540648;27405.58891616667;27500.724992750616;27910.30224244329;27936;27970.860145699142;49563.760905976196;49638.25542616791;49679;50532.747336263084;50534;40071.243072207864;40372.59627403806;40778.79610601928;41779.74660760182;42524.362134568626;40519;40553.59446405498;40645.78062658125;42178.789106054275;42470.362944555236;38864;39448.802755986035;39783.403248951865;39952.800235998635;40168.59831401648;30597.929072482355;32104;34851.825740871136;35910.8204458976;36014;23103.075876964733;24858;26122.738772612032;26168.214953551425;26635.600465992637 12:13:18 INFO - PROCESS | 6606 | |2;jquery.html;351.7520148295452;368.7345111519706;377.5771136327312;381.78810760028193;402.4084595644402;15702.52892413228;15901.204939753025;16046;20549.691754623673;21439.785602143973;20169.697454538193;20173.293934712252;20350.491237719063;20640.69038964413;20931.790682093175;5839.824805255849;6187.4431301182885;6188.907166392508;6191.690415479231;6197.318294987548;3557.964420355789;5506.339239291291;6061.66660833654;6850.7602233921825;6854.931450685504;6841.2132604750595;6847.383735463807;6848.14398200225;6853.7943861684225;6859.074025006635;6547.705353259114;6743.190817101955;6799.762008329685;6802.829929251759;6813.454923606099;5205.479452054764;5483.2049352844015;5753.395893431171;5882;5888.617239879437;13553.796693049513;13764.449422023106;13801.723965520634;13806.964477664114;23718.644220336708;2151.193302511558;2222.555488902226;2279.623862062758;2377.667126602277;2611.2557920992426;9904.257180711475;9928.602855885829;10300.639477618322;10368.637097701545;10392.844107338396;1845.6862333403353;1857.7956424793263;1908.2176307713771;1909.9427017189507;1915.8850468971907;97280;119059;128657.99999999907;131380;132010.33994829963;10074.546645400975;11221.495032723547;11459.541618335339;11580.76838463226;11738.823917641243;13248.072634915656;13588.524401645895;13609.11540749848;13609.931950340186;13615.183089014492;7524.586147761816;7740.225977402328;8263.710770123138;8361.623726932303;8542.231199192103;7967.2431119043595;8170.591470426455;8259.95870020646;8344.791380215422;8391.706290279812;4424.911501769947;4523.819047238139;4530.569595888385;4607.193741095295;4617.468991066004;1586.000819483741;1828.9908550457162;1838.1177035023263;1944.6305202011572;1947.3184385464974;841.6128580852876;906.3881879731155;908.3913777768911;970.9660161894441;1046.2676126711333;18573.168540223436;20476.38570842877;21481.785182147978;21532;21563.353099407042;856.6573370651789;912.8402529557301;931.3620170183447;964.8456247000446;1027.1833892055834;8265;8808.427452215585;11404.144689148347;11520.366379849189;11523.250988685708;525361;539597;563937;574821;585201;95912.06422544758;109180.36229456405;126685.4184745196;132748.33625832002;138016.30991845176;915.2494954137543;920.2270093121676;943.1134733350628;943.4905151218403;944.2351695126969;18439.53901152482;18679.533011674815;18891.338803141825;18973.525661858563;19031.333903313316;45957.540424595325;52204.47795521996;54768;55510.612234694454;57162.998590098294;77348.61325693561;78159.60920195363;78613.21386786058;80584;82962;8028.197180281925;8044.074931382732;8298.294644955005;8366;8552.102029286836;12967.870321297043;13128.934355328543;13201.537946171838;13549.458021679417;13800.86199138036;5353.437889021597;5377.677339359651;11199.776004480027;11438.256513326607;11707.824382634097;10665.57337706514;11376.146788991024;11385.601503947322;11558.826617600911;11803.940980295043;11594;11840;12052.758944820878;12061.51753929833;12080.697982550506;8111.837763244584;8278.586070696321;8313.251807337369;8361.916380836114;8385.538795366314;8834.293256539333;11332.263402878787;11423.428828558373;11838.763224735285;11922.4038798058 12:13:18 INFO - PROCESS | 6606 | |3;mootools.html;1298;1358.3208395802098;1524.7789070584765;1630.9592260193501;1857.2756624916276;7648.120466146394;8460.91539084609;8802.119788021202;8804.295656347493;8879.822403551925;8657.480551166918;8770.07914169011;8770.386072974894;8771.035186129537;8779.517126558037;2342.109998200686;2343.1915988983774;2349.0251545608553;2359.4691194481275;2359.716833979925;2355.5642206191824;2395.2933884504027;2409.229046705055;2409.783119519243;2413.8913748881255;2377.1917548033666;2393;2401.1955994741784;2409.7469765674614;2415.9516809663796;2348.978194485387;2375.192434572245;2382.559226543095;2388.1402695029774;2397.988010059956;2349.283468542094;2371.0438887917535;2377.833551651368;2380.4286971126976;2381.7380088190284;8666.39335246533;8759.124087591254;8766.60550275239;8866.601002954883;8872.423292486032;5235.110031294689;5409.594280428944;5419.187121931703;5425.891482170374;5435.891282174374;3568.6074531801482;3577.624349443323;3636.327279453283;3646.3072016316814;3658.743887927846;1072.7264547540328;1083.9729006774814;1086.2070688397434;1098.730810951314;1107.6676996900978;11606.593769218036;11653.70865728355;11701.063914886703;11751.294922304689;11755.05959523236;3745.4569087482164;4380.364847097153;4643.4660014098135;4670.089332580114;4728.007118505152;9193.172614464596;9195.540222988824;9207.677731279373;9209.677661281958;9232.630694772268;5293.444188360244;5294.49702278283;5297.020051290524;5300.072487314705;5304;4401.515833258339;4594.540545945379;4766.666333356701;4897.975510122498;4967.155583550805;3251.4960181171955;3714.758540694846;3760.4735337052834;3836.9232615347537;3864;1168.0655475619471;1171.9941400292944;1183.526589364244;1192.3740036480917;1194.9761004779855;792.1880072925297;846.7459762071354;930.5114814722325;948.5494390164755;953.1413743200786;5771.740271687784;5782.624129431573;5991;6149.815505534841;6292.748290068346;440.7972332726982;450.21437591403213;462.1150496798593;468.27885057012503;470.1372980580673;3141.4031334046913;3561.8397172127316;3910.276598829226;3943.38877473992;4082.4692789937117;10332;10760;10824.350538967687;10830.620928267474;10830.67507974762;10739.301945373525;10748.570057197625;10777.568897244022;10794.892051079545;10797.46012699362;7527.397808175333;7848.725294614661;8048.476849004913;8066.919330806734;8142.715004974797;5631.070873305859;5908.261467316585;6039.969800150971;6096.1465394845645;6214.2853571838;9053.2304754094;10129.392236465836;10167.288289819642;10225.999999999702;10231.43727095017;9878.1603563695;10255.025772551595;10392.844107338246;10398.324108932895;10425.687229383131;7005.404540614105;7218.097737782778;7444.962775186089;7594.544327340377;7745.806354841174;8294.751157465285;8561.357898157788;8597.828043439222;8598;8602.440841345293;6308.33762454954;7549.0941087069905;7568.129665088585;7701.229877012254;7702.191269916578;7278.708851645875;7434.029982054868;7456;7473.92526074754;7566.167721550515;6949.096617439903;7127.823909054948;7129.39400150993;7129.714811407485;7132.322429369202;5042.9243561345875;5153.4073581538605;5208.557272631869;5226.595765947706;5318.840434786963;4724.976375118103;5054.444011158696;5099.158638824552;5293.841184764463;5472.261244731911 12:13:18 INFO - PROCESS | 6606 | |4;prototype.html;300.7158235467484;305.2308183377888;313.3513626792539;314.4371574880964;317.7442159061954;5360.061989151897;5375.247465354849;5427.674339539631;5456.945430545693;5467.371252305986;4987.700737955725;5079.2635067915135;5086.872828179297;5318.202269659553;5328.653637513559;3715.3869611514137;4101.856435024774;4129.4012368206595;4154.605312495308;4193.706440549147;4148.377743338493;4149.211649786531;4163.979180104096;4166.437530933315;4185.162967406531;3608.8015159166234;3810.9047273818237;4023.718339716221;4064.817083231247;4195.181939521794;3529.2588556403057;4030.3802528356655;4046.089629833264;4086.427900093989;4087.4686290782306;3486.477028445728;3534.1694701745328;3537.3809583323073;3552.786832790015;3554.2180720241504;4114.938275925863;5016.44819069902;5234.0482935383925;5234.241035049935;5247.449017853147;4572.553683779162;4590.8163673453355;4621.491635920046;4635.304704294349;4642.373279607277;5578.63738856977;5670.404607516192;5676.290463692038;5684.788094726467;5691.288588926384;3856.421536769479;3858.3633700439395;3870.632289932452;3894.3184942635207;3903.746256493347;4633;4800.543948324938;5534.501894829485;5613.326400831886;5979.641221526722;3840.731148819612;3909.902252443712;3911.6675082618294;3916.8433262669173;3945.270125026879;3996.640302372742;3996.800159991989;4011.3180759270995;4024.335984562573;4036.0515278909434;3881.4954055972544;3913.686905047646;4340.370646256339;4506.977465112654;4661.95338046622;5669.971650141805;5700.971495142498;5761.769529218868;5803.883922321615;5812.651240925558;1273.3315009620023;1330.3636527071756;1335.8530561638213;1359.9728005439836;1361.8433880103717;757.7650928212272;763.7479631721519;844.2654890245526;912.115248209236;917.788908551037;5205.55752761012;5213.3222681051975;5226.477352264743;5246;5259.948620038188;4893.510648935149;5693.686847223443;5742.913856292159;5765.625234359837;5775.054722091763;4759.95281038171;4978.4274808397495;5181.740912954338;5214.400343960418;5233.1365324721755;2537.6701028866128;2946.6905974872757;3010.6236720409947;3062.555929390226;3074.6317888539834;5238.302274328724;5592.86017849557;5703.74333155009;5816.621919575214;6065.787697430569;5212.50481204285;5487.2317875497465;5798.449147330997;5818.650880947157;5864.70676466175;5043.672161309503;5261.605379596622;5761.205815179635;5956.55325850563;6079.817605471843;5532.944670553404;5643.435656434323;5796.9420305798085;5813.459433250277;5823.7379317929945;4829.66192366531;5544.861378465571;5572.247746554163;5609.793894312742;5611.26850644317;4766.475687674284;5614.915776263441;5772.855678608068;5927.229460170151;5943.346231914573;3417.53863228461;3841.8079096045644;3852.556955950102;3854.325493038651;3856.633619806114;4350.695451318377;5491.670499770026;5681.829545113653;5698.430156984268;5841.357450680507;4731.432228132646;4985.027919555652;5228.294180285765;5240.790368385222;5592.029658942197;4248.9787551062045;4587.42657167854;5513.228148059355;5525.530329922001;5582.637128586629;5112.71880046601;5177.844664660066;5345.625806193529;5382.30030096085;5403.5136837684795;4738.834140805193;5399.730013499231;5617.634853734494;5645.9153112702525;5661.207430959751;5497.185928643458;5507.862303442446;5514.503694667499;5537.086380747292;5541.889162216814 12:13:18 INFO - PROCESS | 6606 | |5;yui.html;716.1692436773344;718.6981467783531;733.2154594583794;771.4522688890886;822.1285437436314;16415;17071.317147314123;17113.315467381304;17114.5721356966;17127.57181070474;15180.468683596077;15281.388744450236;15301.311440985155;15307;15334.616634584141;685.5749435350065;708.3695510995218;711.3562226185285;856.4475913036073;877.5305211711735;709.7693249693845;717.4762423430899;717.5443593318255;814.4868732698392;842.4313588327885;863.3481721300441;920.8894932608097;923.9445633261993;937.0020927712;939.9483028433433;924.1636319131197;930.3255140023497;930.3905941608258;931.2270815223416;932.2961164320928;687.4294335701356;690.1545606631886;690.9965450172717;691.9169699636026;693.8993845892368;11678.416079196006;12105.878941210563;12617.810732839014;12629.873701263054;14178.14931104137;14321.57035288943;15074.849251507563;15085.3211605478;15136.924315378463;15139.243037848064;12034.097442691833;12067.57763478274;12180.512779488898;12197.463795153384;12274.877251227552;3430.6054803697643;3701.222743223926;3948.6643635291034;3974.066094467798;4044.4539987101657;113504;113583.43208283988;115226;117632.82367176305;121053.39473302665;11469.197156198987;11557.595484158182;11654.76690466186;11709.063274937984;12017.471608635966;23930.64104038441;24023.03907843667;24026.51946961051;24039.639405408932;24114;7985.960070199611;8069.435139540294;8146.389020823462;8167.305779008732;8313.792155196168;4248.277792775236;4324.589164029412;4354.760488173181;4469.664775141878;4484.215262329145;3521.894343169709;3601.567811862541;3634.83643236055;3636.290923269937;3641.8725344612812;566.9971650141723;619.3094699410125;648.9740410383627;651.8077167235676;655.7475371981822;634.9174607301021;647.5785650497207;680.5270337115658;686.1012074182836;694.5971336624746;7269.963650181821;7663.3103020727285;7840.568768717661;9121.543922803834;9342.719718408458;649.9512536559777;652.7454292825802;671.2616122265468;683.4122654517112;691.4295706042434;4544.636429085665;4675.764518378352;4677.719336839732;4685.226937555265;4749.64377671676;131936;132903;137202.3139884294;137359.31320343335;147246;114350.42824785823;114637.42681286707;117328;121835.17247241299;125403.3729831345;105017.19724843989;106045;109274.45362773295;111111;114669;9568.665096721581;9874.916404527949;9928.508393570908;9951.353162044445;9962;52116.478835211165;59007.81984360203;61988;63069;66384.00423993547;88099.11900881166;89885.27007773744;95057.52471237599;98352;99113.50443247738;11384.601538946097;13194.472221111047;13324.333783310989;13534.79697804514;13841.723165536432;15121.395144194548;15312.464063757716;15333.310001049756;15403.61490962735;16094.436694715629;14020.929895350459;14274.643133921734;14349.282535872955;14848.183349915858;14935.17856517902;9497.620095196115;13595.92191968171;13631.591052268448;13797.448102075805;13868.93065534666;12906;13906.443742250196;13917.373718182504;14103.647408814863;14155.292235388395;9798.65704700332;11171.38557379352;11298;11492.310461372344;11670.591529296435;11223.775524489627;11524.539018439169;11631.418429078343;11766.705832354259;11819.881801181878 12:13:18 INFO - PROCESS | 6606 | __end_tp_report 12:13:18 INFO - PROCESS | 6606 | __start_cc_report 12:13:18 INFO - PROCESS | 6606 | _x_x_mozilla_cycle_collect,1376 12:13:18 INFO - PROCESS | 6606 | __end_cc_report 12:13:18 INFO - PROCESS | 6606 | __startTimestamp1471979598305__endTimestamp 12:13:18 INFO - PROCESS | 6606 | 12:13:18 INFO - PROCESS | 6606 | ------- Summary: start ------- 12:13:18 INFO - PROCESS | 6606 | Number of tests: 6 12:13:18 INFO - PROCESS | 6606 | 12:13:18 INFO - PROCESS | 6606 | [#0] dojo.html Cycles:165 Average:5694.14 Median:5882.38 stddev:657.77 (11.2%) stddev-sans-first:621.48 12:13:18 INFO - PROCESS | 6606 | Values: 2874.4 3249.9 3293.3 3319.3 3356.0 5505.6 5590.7 5641.0 5644.4 5705.2 5623.1 5683.4 5769.3 5783.2 5803.2 4469.9 5073.8 5164.1 5195.3 5218.3 5291.8 5304.5 5309.0 5319.9 5336.1 5129.5 5205.0 5207.6 5241.4 5245.7 5173.6 5220.2 5240.2 5248.3 5258.6 5129.2 5140.3 5165.5 5217.8 5257.6 5680.8 5693.4 5695.1 5695.6 5705.1 6068.0 6239.3 6251.3 6251.9 6264.9 6082.7 6174.0 6177.7 6264.0 6277.8 5801.4 5835.4 5863.5 5870.1 5870.8 6179.9 6272.2 6276.8 6297.4 6299.1 6015.9 6056.7 6110.5 6140.3 6149.9 6213.0 6237.7 6238.8 6242.3 6261.0 5937.8 6105.6 6183.5 6191.7 6200.6 6001.6 6088.2 6099.1 6131.2 6177.7 4930.5 4931.3 4943.5 4971.9 4976.6 4404.4 4409.3 4410.7 4432.7 4440.2 5487.4 5504.7 5593.0 5667.5 5671.3 4839.6 6210.0 6229.6 6271.5 6282.6 5634.5 5664.8 5675.7 5676.9 5680.2 5281.0 5325.8 5374.5 5420.3 5426.8 6247.0 6290.4 6297.9 6308.5 6319.3 6192.5 6267.2 6272.2 6272.8 6288.9 6211.2 6219.1 6296.6 6300.3 6301.3 6108.4 6134.7 6137.0 6155.8 6186.7 6046.5 6227.0 6245.3 6248.4 6260.1 5843.9 6075.1 6079.0 6098.0 6114.3 4414.5 5936.0 5953.5 5995.8 6123.9 5856.9 5882.4 5899.6 5899.7 5901.7 6029.5 6048.5 6054.1 6065.9 6084.0 5658.9 5784.8 5843.2 5898.3 5911.6 12:13:18 INFO - PROCESS | 6606 | 12:13:18 INFO - PROCESS | 6606 | [#1] ext.html Cycles:180 Average:70200.03 Median:34557.07 stddev:155414.04 (449.7%) stddev-sans-first:155761.80 12:13:18 INFO - PROCESS | 6606 | Values: 457.5 473.4 490.8 518.3 523.0 24240.4 33787.3 42511.6 43558.9 43649.3 45344.8 45637.3 45646.0 45710.3 45721.9 9859.2 10077.0 10081.1 10083.0 10087.0 8883.9 9113.9 12050.4 12348.9 12351.3 12244.8 12329.9 12335.4 12348.7 12351.4 10428.3 12231.4 12336.2 12349.1 12372.9 12207.4 12294.9 12297.9 12309.4 12309.8 42414.6 42522.1 42657.8 42729.6 42947.0 3537.9 3634.3 3659.2 3679.9 3900.5 29570.6 32239.7 33196.7 33316.0 33771.7 3238.2 3316.2 3336.1 3379.1 3443.6 395830.0 502918.0 503174.0 504913.0 511837.0 34098.8 62800.0 63234.4 63305.0 63306.8 34385.0 34554.7 34559.5 34594.0 34629.5 14867.0 15045.4 15046.2 15058.8 15080.8 11362.3 11612.0 12706.0 12797.9 12811.7 7824.2 7961.3 7963.7 7973.5 8044.0 3253.3 3270.6 3271.3 3275.3 3311.4 921.4 956.6 974.2 1022.0 1024.6 30964.0 30993.2 31013.0 31522.2 31859.2 1078.8 1130.9 1136.8 1161.0 1163.3 15794.5 16124.6 16183.5 16274.3 16415.5 688865.6 694656.0 695673.0 696978.0 701916.0 59740.1 60106.7 60157.8 60266.1 60309.4 1251.6 1291.4 1335.7 1389.8 1400.5 30757.0 31116.7 31691.7 32253.0 32276.7 52095.7 52806.0 52830.7 52843.0 52890.0 538671.0 539272.0 539902.0 540631.0 540648.0 27405.6 27500.7 27910.3 27936.0 27970.9 49563.8 49638.3 49679.0 50532.7 50534.0 40071.2 40372.6 40778.8 41779.7 42524.4 40519.0 40553.6 40645.8 42178.8 42470.4 38864.0 39448.8 39783.4 39952.8 40168.6 30597.9 32104.0 34851.8 35910.8 36014.0 23103.1 24858.0 26122.7 26168.2 26635.6 12:13:18 INFO - PROCESS | 6606 | 12:13:18 INFO - PROCESS | 6606 | [#2] jquery.html Cycles:180 Average:33498.47 Median:373.16 stddev:93595.00 (25082.0%) stddev-sans-first:93824.46 12:13:18 INFO - PROCESS | 6606 | Values: 351.8 368.7 377.6 381.8 402.4 15702.5 15901.2 16046.0 20549.7 21439.8 20169.7 20173.3 20350.5 20640.7 20931.8 5839.8 6187.4 6188.9 6191.7 6197.3 3558.0 5506.3 6061.7 6850.8 6854.9 6841.2 6847.4 6848.1 6853.8 6859.1 6547.7 6743.2 6799.8 6802.8 6813.5 5205.5 5483.2 5753.4 5882.0 5888.6 13553.8 13764.4 13801.7 13807.0 23718.6 2151.2 2222.6 2279.6 2377.7 2611.3 9904.3 9928.6 10300.6 10368.6 10392.8 1845.7 1857.8 1908.2 1909.9 1915.9 97280.0 119059.0 128658.0 131380.0 132010.3 10074.5 11221.5 11459.5 11580.8 11738.8 13248.1 13588.5 13609.1 13609.9 13615.2 7524.6 7740.2 8263.7 8361.6 8542.2 7967.2 8170.6 8260.0 8344.8 8391.7 4424.9 4523.8 4530.6 4607.2 4617.5 1586.0 1829.0 1838.1 1944.6 1947.3 841.6 906.4 908.4 971.0 1046.3 18573.2 20476.4 21481.8 21532.0 21563.4 856.7 912.8 931.4 964.8 1027.2 8265.0 8808.4 11404.1 11520.4 11523.3 525361.0 539597.0 563937.0 574821.0 585201.0 95912.1 109180.4 126685.4 132748.3 138016.3 915.2 920.2 943.1 943.5 944.2 18439.5 18679.5 18891.3 18973.5 19031.3 45957.5 52204.5 54768.0 55510.6 57163.0 77348.6 78159.6 78613.2 80584.0 82962.0 8028.2 8044.1 8298.3 8366.0 8552.1 12967.9 13128.9 13201.5 13549.5 13800.9 5353.4 5377.7 11199.8 11438.3 11707.8 10665.6 11376.1 11385.6 11558.8 11803.9 11594.0 11840.0 12052.8 12061.5 12080.7 8111.8 8278.6 8313.3 8361.9 8385.5 8834.3 11332.3 11423.4 11838.8 11922.4 12:13:18 INFO - PROCESS | 6606 | 12:13:18 INFO - PROCESS | 6606 | [#3] mootools.html Cycles:180 Average:5684.97 Median:4747.34 stddev:3217.40 (67.8%) stddev-sans-first:3209.54 12:13:18 INFO - PROCESS | 6606 | Values: 1298.0 1358.3 1524.8 1631.0 1857.3 7648.1 8460.9 8802.1 8804.3 8879.8 8657.5 8770.1 8770.4 8771.0 8779.5 2342.1 2343.2 2349.0 2359.5 2359.7 2355.6 2395.3 2409.2 2409.8 2413.9 2377.2 2393.0 2401.2 2409.7 2416.0 2349.0 2375.2 2382.6 2388.1 2398.0 2349.3 2371.0 2377.8 2380.4 2381.7 8666.4 8759.1 8766.6 8866.6 8872.4 5235.1 5409.6 5419.2 5425.9 5435.9 3568.6 3577.6 3636.3 3646.3 3658.7 1072.7 1084.0 1086.2 1098.7 1107.7 11606.6 11653.7 11701.1 11751.3 11755.1 3745.5 4380.4 4643.5 4670.1 4728.0 9193.2 9195.5 9207.7 9209.7 9232.6 5293.4 5294.5 5297.0 5300.1 5304.0 4401.5 4594.5 4766.7 4898.0 4967.2 3251.5 3714.8 3760.5 3836.9 3864.0 1168.1 1172.0 1183.5 1192.4 1195.0 792.2 846.7 930.5 948.5 953.1 5771.7 5782.6 5991.0 6149.8 6292.7 440.8 450.2 462.1 468.3 470.1 3141.4 3561.8 3910.3 3943.4 4082.5 10332.0 10760.0 10824.4 10830.6 10830.7 10739.3 10748.6 10777.6 10794.9 10797.5 7527.4 7848.7 8048.5 8066.9 8142.7 5631.1 5908.3 6040.0 6096.1 6214.3 9053.2 10129.4 10167.3 10226.0 10231.4 9878.2 10255.0 10392.8 10398.3 10425.7 7005.4 7218.1 7445.0 7594.5 7745.8 8294.8 8561.4 8597.8 8598.0 8602.4 6308.3 7549.1 7568.1 7701.2 7702.2 7278.7 7434.0 7456.0 7473.9 7566.2 6949.1 7127.8 7129.4 7129.7 7132.3 5042.9 5153.4 5208.6 5226.6 5318.8 4725.0 5054.4 5099.2 5293.8 5472.3 12:13:18 INFO - PROCESS | 6606 | 12:13:18 INFO - PROCESS | 6606 | [#4] prototype.html Cycles:180 Average:4533.80 Median:5179.79 stddev:1377.48 (26.6%) stddev-sans-first:1344.20 12:13:18 INFO - PROCESS | 6606 | Values: 300.7 305.2 313.4 314.4 317.7 5360.1 5375.2 5427.7 5456.9 5467.4 4987.7 5079.3 5086.9 5318.2 5328.7 3715.4 4101.9 4129.4 4154.6 4193.7 4148.4 4149.2 4164.0 4166.4 4185.2 3608.8 3810.9 4023.7 4064.8 4195.2 3529.3 4030.4 4046.1 4086.4 4087.5 3486.5 3534.2 3537.4 3552.8 3554.2 4114.9 5016.4 5234.0 5234.2 5247.4 4572.6 4590.8 4621.5 4635.3 4642.4 5578.6 5670.4 5676.3 5684.8 5691.3 3856.4 3858.4 3870.6 3894.3 3903.7 4633.0 4800.5 5534.5 5613.3 5979.6 3840.7 3909.9 3911.7 3916.8 3945.3 3996.6 3996.8 4011.3 4024.3 4036.1 3881.5 3913.7 4340.4 4507.0 4662.0 5670.0 5701.0 5761.8 5803.9 5812.7 1273.3 1330.4 1335.9 1360.0 1361.8 757.8 763.7 844.3 912.1 917.8 5205.6 5213.3 5226.5 5246.0 5259.9 4893.5 5693.7 5742.9 5765.6 5775.1 4760.0 4978.4 5181.7 5214.4 5233.1 2537.7 2946.7 3010.6 3062.6 3074.6 5238.3 5592.9 5703.7 5816.6 6065.8 5212.5 5487.2 5798.4 5818.7 5864.7 5043.7 5261.6 5761.2 5956.6 6079.8 5532.9 5643.4 5796.9 5813.5 5823.7 4829.7 5544.9 5572.2 5609.8 5611.3 4766.5 5614.9 5772.9 5927.2 5943.3 3417.5 3841.8 3852.6 3854.3 3856.6 4350.7 5491.7 5681.8 5698.4 5841.4 4731.4 4985.0 5228.3 5240.8 5592.0 4249.0 4587.4 5513.2 5525.5 5582.6 5112.7 5177.8 5345.6 5382.3 5403.5 4738.8 5399.7 5617.6 5645.9 5661.2 5497.2 5507.9 5514.5 5537.1 5541.9 12:13:18 INFO - PROCESS | 6606 | 12:13:18 INFO - PROCESS | 6606 | [#5] yui.html Cycles:180 Average:24600.75 Median:3618.20 stddev:38307.33 (1058.7%) stddev-sans-first:38372.82 12:13:18 INFO - PROCESS | 6606 | Values: 716.2 718.7 733.2 771.5 822.1 16415.0 17071.3 17113.3 17114.6 17127.6 15180.5 15281.4 15301.3 15307.0 15334.6 685.6 708.4 711.4 856.4 877.5 709.8 717.5 717.5 814.5 842.4 863.3 920.9 923.9 937.0 939.9 924.2 930.3 930.4 931.2 932.3 687.4 690.2 691.0 691.9 693.9 11678.4 12105.9 12617.8 12629.9 14178.1 14321.6 15074.8 15085.3 15136.9 15139.2 12034.1 12067.6 12180.5 12197.5 12274.9 3430.6 3701.2 3948.7 3974.1 4044.5 113504.0 113583.4 115226.0 117632.8 121053.4 11469.2 11557.6 11654.8 11709.1 12017.5 23930.6 24023.0 24026.5 24039.6 24114.0 7986.0 8069.4 8146.4 8167.3 8313.8 4248.3 4324.6 4354.8 4469.7 4484.2 3521.9 3601.6 3634.8 3636.3 3641.9 567.0 619.3 649.0 651.8 655.7 634.9 647.6 680.5 686.1 694.6 7270.0 7663.3 7840.6 9121.5 9342.7 650.0 652.7 671.3 683.4 691.4 4544.6 4675.8 4677.7 4685.2 4749.6 131936.0 132903.0 137202.3 137359.3 147246.0 114350.4 114637.4 117328.0 121835.2 125403.4 105017.2 106045.0 109274.5 111111.0 114669.0 9568.7 9874.9 9928.5 9951.4 9962.0 52116.5 59007.8 61988.0 63069.0 66384.0 88099.1 89885.3 95057.5 98352.0 99113.5 11384.6 13194.5 13324.3 13534.8 13841.7 15121.4 15312.5 15333.3 15403.6 16094.4 14020.9 14274.6 14349.3 14848.2 14935.2 9497.6 13595.9 13631.6 13797.4 13868.9 12906.0 13906.4 13917.4 14103.6 14155.3 9798.7 11171.4 11298.0 11492.3 11670.6 11223.8 11524.5 11631.4 11766.7 11819.9 12:13:18 INFO - PROCESS | 6606 | -------- Summary: end -------- 12:13:18 INFO - PROCESS | 6606 | 12:13:19 INFO - TEST-INFO | 6606: exit 0 12:13:19 INFO - TEST-OK | dromaeo_css | took 1117885ms 12:13:19 INFO - TEST-START | kraken 12:13:19 INFO - operating with platform_type : mac_ 12:13:19 INFO - Initialising browser for kraken test... 12:13:19 INFO - TEST-INFO | started process 6609 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpcXQ8p9/profile http://localhost:64999/getInfo.html) 12:13:21 INFO - PROCESS | 6609 | __metrics Screen width/height:1600/1200 12:13:21 INFO - PROCESS | 6609 | colorDepth:24 12:13:21 INFO - PROCESS | 6609 | Browser inner width/height: 1024/689 12:13:21 INFO - PROCESS | 6609 | __metrics 12:13:21 INFO - PROCESS | 6609 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:13:21 INFO - PROCESS | 6609 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:13:21 INFO - TEST-INFO | 6609: exit 0 12:13:21 INFO - Browser initialized. 12:13:21 INFO - Running cycle 1/1 for kraken test... 12:13:21 INFO - TEST-INFO | started process 6610 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpcXQ8p9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:13:33 INFO - PROCESS | 6610 | RSS: Main: 234872832 12:13:33 INFO - PROCESS | 6610 | 12:13:36 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:64999/tests/kraken/driver.html?testName=audio-beat-detection) 12:13:37 INFO - PROCESS | 6610 | RSS: Main: 293949440 12:13:37 INFO - PROCESS | 6610 | 12:13:39 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:64999/tests/kraken/driver.html?testName=audio-dft) 12:13:40 INFO - PROCESS | 6610 | RSS: Main: 292098048 12:13:40 INFO - PROCESS | 6610 | 12:13:44 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:64999/tests/kraken/driver.html?testName=audio-fft) 12:13:44 INFO - PROCESS | 6610 | RSS: Main: 379797504 12:13:44 INFO - PROCESS | 6610 | 12:13:47 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:64999/tests/kraken/driver.html?testName=audio-oscillator) 12:13:47 INFO - PROCESS | 6610 | RSS: Main: 280858624 12:13:47 INFO - PROCESS | 6610 | 12:13:49 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:64999/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:13:50 INFO - PROCESS | 6610 | RSS: Main: 284565504 12:13:50 INFO - PROCESS | 6610 | 12:13:53 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:64999/tests/kraken/driver.html?testName=imaging-darkroom) 12:13:53 INFO - PROCESS | 6610 | RSS: Main: 325361664 12:13:53 INFO - PROCESS | 6610 | 12:13:56 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:64999/tests/kraken/driver.html?testName=imaging-desaturate) 12:13:57 INFO - PROCESS | 6610 | RSS: Main: 324898816 12:13:57 INFO - PROCESS | 6610 | 12:14:00 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:64999/tests/kraken/driver.html?testName=json-parse-financial) 12:14:00 INFO - PROCESS | 6610 | RSS: Main: 323923968 12:14:00 INFO - PROCESS | 6610 | 12:14:02 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:64999/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:14:02 INFO - PROCESS | 6610 | RSS: Main: 300093440 12:14:02 INFO - PROCESS | 6610 | 12:14:04 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:64999/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:14:05 INFO - PROCESS | 6610 | RSS: Main: 306630656 12:14:05 INFO - PROCESS | 6610 | 12:14:07 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:64999/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:14:08 INFO - PROCESS | 6610 | RSS: Main: 309604352 12:14:08 INFO - PROCESS | 6610 | 12:14:11 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:64999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:14:11 INFO - PROCESS | 6610 | RSS: Main: 315531264 12:14:11 INFO - PROCESS | 6610 | 12:14:14 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:64999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:14:15 INFO - PROCESS | 6610 | RSS: Main: 316989440 12:14:15 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | Cycle 1(1): loaded http://localhost:64999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:64999/tests/kraken/driver.html?testName=ai-astar) 12:14:17 INFO - PROCESS | 6610 | RSS: Main: 360378368 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | __start_tp_report 12:14:17 INFO - PROCESS | 6610 | _x_x_mozilla_page_load 12:14:17 INFO - PROCESS | 6610 | _x_x_mozilla_page_load_details 12:14:17 INFO - PROCESS | 6610 | |i|pagename|runs| 12:14:17 INFO - PROCESS | 6610 | |0;ai-astar;113;114;114;112;114;113;113;113;111;123 12:14:17 INFO - PROCESS | 6610 | |1;audio-beat-detection;147;109;132;163;108;129;127;106;159;109 12:14:17 INFO - PROCESS | 6610 | |2;audio-dft;189;185;186;187;199;258;185;204;183;185 12:14:17 INFO - PROCESS | 6610 | |3;audio-fft;75;77;77;153;63;81;81;91;143;59 12:14:17 INFO - PROCESS | 6610 | |4;audio-oscillator;84;84;142;70;73;88;122;70;69;120 12:14:17 INFO - PROCESS | 6610 | |5;imaging-gaussian-blur;88;84;88;86;87;84;88;85;107;107 12:14:17 INFO - PROCESS | 6610 | |6;imaging-darkroom;98;92;93;94;93;93;94;98;95;93 12:14:17 INFO - PROCESS | 6610 | |7;imaging-desaturate;86;85;86;83;85;85;82;81;138;130 12:14:17 INFO - PROCESS | 6610 | |8;json-parse-financial;52;53;55;54;53;55;56;54;53;54 12:14:17 INFO - PROCESS | 6610 | |9;json-stringify-tinderbox;66;66;66;75;74;79;73;70;72;72 12:14:17 INFO - PROCESS | 6610 | |10;stanford-crypto-aes;85;85;85;87;84;83;86;85;89;89 12:14:17 INFO - PROCESS | 6610 | |11;stanford-crypto-ccm;144;147;148;140;144;148;147;147;159;140 12:14:17 INFO - PROCESS | 6610 | |12;stanford-crypto-pbkdf2;165;166;174;166;168;163;167;167;166;166 12:14:17 INFO - PROCESS | 6610 | |13;stanford-crypto-sha256-iterative;67;65;67;68;67;66;67;67;64;66 12:14:17 INFO - PROCESS | 6610 | __end_tp_report 12:14:17 INFO - PROCESS | 6610 | __start_cc_report 12:14:17 INFO - PROCESS | 6610 | _x_x_mozilla_cycle_collect,2065 12:14:17 INFO - PROCESS | 6610 | __end_cc_report 12:14:17 INFO - PROCESS | 6610 | __startTimestamp1471979657328__endTimestamp 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | ------- Summary: start ------- 12:14:17 INFO - PROCESS | 6610 | Number of tests: 14 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#0] ai-astar Cycles:10 Average:114.00 Median:113.50 stddev:3.30 (2.9%) stddev-sans-first:3.48 12:14:17 INFO - PROCESS | 6610 | Values: 113.0 114.0 114.0 112.0 114.0 113.0 113.0 113.0 111.0 123.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:130.50 stddev:21.51 (16.5%) stddev-sans-first:21.79 12:14:17 INFO - PROCESS | 6610 | Values: 147.0 109.0 132.0 163.0 108.0 129.0 127.0 106.0 159.0 109.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#2] audio-dft Cycles:10 Average:196.10 Median:188.00 stddev:22.80 (12.1%) stddev-sans-first:24.04 12:14:17 INFO - PROCESS | 6610 | Values: 189.0 185.0 186.0 187.0 199.0 258.0 185.0 204.0 183.0 185.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#3] audio-fft Cycles:10 Average:90.00 Median:77.00 stddev:31.96 (41.5%) stddev-sans-first:33.44 12:14:17 INFO - PROCESS | 6610 | Values: 75.0 77.0 77.0 153.0 63.0 81.0 81.0 91.0 143.0 59.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:71.50 stddev:26.22 (36.7%) stddev-sans-first:27.64 12:14:17 INFO - PROCESS | 6610 | Values: 84.0 84.0 142.0 70.0 73.0 88.0 122.0 70.0 69.0 120.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:86.50 stddev:8.88 (10.3%) stddev-sans-first:9.38 12:14:17 INFO - PROCESS | 6610 | Values: 88.0 84.0 88.0 86.0 87.0 84.0 88.0 85.0 107.0 107.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.00 stddev:2.11 (2.2%) stddev-sans-first:1.76 12:14:17 INFO - PROCESS | 6610 | Values: 98.0 92.0 93.0 94.0 93.0 93.0 94.0 98.0 95.0 93.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:85.00 stddev:21.18 (24.9%) stddev-sans-first:22.26 12:14:17 INFO - PROCESS | 6610 | Values: 86.0 85.0 86.0 83.0 85.0 85.0 82.0 81.0 138.0 130.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.20 (2.2%) stddev-sans-first:1.05 12:14:17 INFO - PROCESS | 6610 | Values: 52.0 53.0 55.0 54.0 53.0 55.0 56.0 54.0 53.0 54.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.50 stddev:4.35 (6.0%) stddev-sans-first:4.17 12:14:17 INFO - PROCESS | 6610 | Values: 66.0 66.0 66.0 75.0 74.0 79.0 73.0 70.0 72.0 72.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:85.50 stddev:1.99 (2.3%) stddev-sans-first:2.09 12:14:17 INFO - PROCESS | 6610 | Values: 85.0 85.0 85.0 87.0 84.0 83.0 86.0 85.0 89.0 89.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#11] stanford-crypto-ccm Cycles:10 Average:146.40 Median:147.00 stddev:5.36 (3.6%) stddev-sans-first:5.61 12:14:17 INFO - PROCESS | 6610 | Values: 144.0 147.0 148.0 140.0 144.0 148.0 147.0 147.0 159.0 140.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:166.50 stddev:2.86 (1.7%) stddev-sans-first:2.96 12:14:17 INFO - PROCESS | 6610 | Values: 165.0 166.0 174.0 166.0 168.0 163.0 167.0 167.0 166.0 166.0 12:14:17 INFO - PROCESS | 6610 | 12:14:17 INFO - PROCESS | 6610 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.17 (1.8%) stddev-sans-first:1.22 12:14:17 INFO - PROCESS | 6610 | Values: 67.0 65.0 67.0 68.0 67.0 66.0 67.0 67.0 64.0 66.0 12:14:17 INFO - PROCESS | 6610 | -------- Summary: end -------- 12:14:17 INFO - PROCESS | 6610 | 12:14:18 INFO - TEST-INFO | 6610: exit 0 12:14:18 INFO - TEST-OK | kraken | took 59152ms 12:14:18 INFO - SUITE-END | took 1177s 12:14:18 INFO - Completed test suite (00:19:37) 12:14:18 INFO - javascript benchmark 12:14:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2874.4107457971113, 3249.853756580953, 3293.291942232421, 3319.336132773446, 3356.0, 5505.587080968923, 5590.692511911849, 5641.041023026085, 5644.350899646537, 5705.229793977812, 5623.0721930881455, 5683.403242659523, 5769.336526299477, 5783.161441590967, 5803.216565763621, 4469.888252793674, 5073.822416215433, 5164.096283150452, 5195.324607800981, 5218.347706536683, 5291.788328466857, 5304.496072873071, 5308.973455132719, 5319.946800532003, 5336.119540275849, 5129.461406552333, 5204.9739751301, 5207.6354655174155, 5241.42344342122, 5245.711485868275, 5173.637845350827, 5220.2430647556275, 5240.161574148118, 5248.343957005374, 5258.55302299305, 5129.204973229155, 5140.306058682105, 5165.457626949154, 5217.791288348461, 5257.553107985826, 5680.772769089232, 5693.430656934315, 5695.14572814077, 5695.572832037572, 5705.115707065412, 6068.0, 6239.2824825145235, 6251.343608921089, 6251.906221406683, 6264.87470250597, 6082.704400945001, 6173.969130154365, 6177.69111544421, 6263.96868015657, 6277.7802776903045, 5801.361850196475, 5835.387284335084, 5863.472287494146, 5870.119482077723, 5870.823875283749, 6179.938200617937, 6272.247330320391, 6276.843078923063, 6297.370262973757, 6299.0866324382705, 6015.939840601625, 6056.727447264883, 6110.511159107261, 6140.324564297924, 6149.907751383733, 6213.0, 6237.656928868956, 6238.844028899223, 6242.313345531988, 6261.0, 5937.7921772737745, 6105.603135796159, 6183.505319574423, 6191.690415479298, 6200.56596038282, 6001.579889407787, 6088.228730131248, 6099.146119543268, 6131.233595800525, 6177.660228687465, 4930.482299358588, 4931.2849636803185, 4943.530364615356, 4971.925421118686, 4976.601871850244, 4404.4494438195225, 4409.316555933771, 4410.669199810027, 4432.667549933768, 4440.156370289635, 5487.42382049887, 5504.669719816744, 5592.972035139798, 5667.4615911488345, 5671.347795003545, 4839.588634965997, 6209.96895015522, 6229.595076320115, 6271.4669253114, 6282.623042617458, 5634.549236061105, 5664.801731939362, 5675.6594604323045, 5676.8864622707315, 5680.204771332017, 5281.0, 5325.813596524181, 5374.516293533523, 5420.268263784339, 5426.8371948841595, 6247.0, 6290.433860952537, 6297.874042519215, 6308.463780578702, 6319.336469670802, 6192.473639740672, 6267.185265915402, 6272.153259309935, 6272.843178920563, 6288.905666415099, 6211.192544969125, 6219.067139928866, 6296.5592408530965, 6300.306966233619, 6301.306856245901, 6108.419700128481, 6134.693265336626, 6137.039921714347, 6155.753769849335, 6186.690665466798, 6046.54650901193, 6227.034809604518, 6245.313015568375, 6248.4064013918605, 6260.092286618324, 5843.883122337615, 6075.149479072845, 6079.0, 6098.024316109401, 6114.327423983447, 4414.4702635683925, 5935.970320148372, 5953.523718102628, 5995.790147344822, 6123.877522449437, 5856.912146317723, 5882.382349853204, 5899.646021238739, 5899.675517846388, 5901.704914754331, 6029.487493563096, 6048.516118710405, 6054.061620448838, 6065.939340606714, 6083.969580152071, 5658.943410565842, 5784.768609255751, 5843.152742852178, 5898.262717160355, 5911.586188966731], "value": 5651.226413473438, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.533316017662, 473.42951743149507, 490.8478371704774, 518.282179181833, 522.9529342359189, 24240.393990150256, 33787.32425351495, 42511.57488425119, 43558.911027224334, 43649.34525982113, 45344.77327613366, 45637.31544026834, 45646.0, 45710.314345284845, 45721.85695357618, 9859.211263098934, 10077.042680945298, 10081.09270165685, 10083.04211099948, 10087.0, 8883.866741998876, 9113.908860911406, 12050.397480126003, 12348.93825530871, 12351.32067736274, 12244.755104897897, 12329.938350308235, 12335.383230838466, 12348.691282717915, 12351.382430878464, 10428.270021098526, 12231.38843057848, 12336.198147120409, 12349.135560510767, 12372.876271237263, 12207.375414285882, 12294.938525307316, 12297.93851030748, 12309.44607492656, 12309.753804923941, 42414.57585424137, 42522.149557009, 42657.786711066554, 42729.57270427318, 42947.0, 3537.8584856605703, 3634.27314537091, 3659.158393569494, 3679.9217080429103, 3900.478107074076, 29570.556441653393, 32239.677603224136, 33196.6680333196, 33316.00051998444, 33771.66228337734, 3238.2228265216413, 3316.2206881382854, 3336.065901547546, 3379.053864917827, 3443.6212016678146, 395830.0, 502918.0, 503174.0, 504913.0, 511836.9999999963, 34098.829505852314, 62800.0, 63234.36765632285, 63305.0, 63306.820511025646, 34385.0, 34554.65445345514, 34559.4816077759, 34594.02168750575, 34629.480557791656, 14866.959312847997, 15045.398184072514, 15046.172460514557, 15058.849411505746, 15080.773788393182, 11362.318260904207, 11612.0, 12705.983521318272, 12797.936010319889, 12811.743765124646, 7824.21757824213, 7961.323287520626, 7963.721269755531, 7973.488351169363, 8043.959780201179, 3253.3330667213163, 3270.5584746059453, 3271.313024264931, 3275.3449310138067, 3311.403947289511, 921.4425272710048, 956.5647630328184, 974.2449601558849, 1021.9984415273074, 1024.6208902706046, 30964.0, 30993.225169370948, 31013.0, 31522.211944701565, 31859.203519911724, 1078.8166011778017, 1130.8812574679541, 1136.8067428537167, 1160.976780464386, 1163.3078318400537, 15794.526164215093, 16124.596885077966, 16183.514494565183, 16274.26765795542, 16415.475180892707, 688865.5577720109, 694656.0, 695673.0, 696978.0, 701916.0, 59740.10389844156, 60106.69946650239, 60157.796844062876, 60266.09600855991, 60309.39690603125, 1251.5682089679046, 1291.4059532614915, 1335.7194989052407, 1389.7637401641746, 1400.516821696513, 30757.0, 31116.68883311138, 31691.683083168875, 32253.0, 32276.677233228307, 52095.73952130215, 52806.0, 52830.735846320524, 52843.0, 52890.0, 538671.0, 539272.0, 539902.0, 540631.0, 540648.0, 27405.58891616667, 27500.724992750616, 27910.30224244329, 27936.0, 27970.860145699142, 49563.760905976196, 49638.25542616791, 49679.0, 50532.747336263084, 50534.0, 40071.243072207864, 40372.59627403806, 40778.79610601928, 41779.74660760182, 42524.362134568626, 40519.0, 40553.59446405498, 40645.78062658125, 42178.789106054275, 42470.362944555236, 38864.0, 39448.802755986035, 39783.403248951865, 39952.800235998635, 40168.59831401648, 30597.929072482355, 32104.0, 34851.825740871136, 35910.8204458976, 36014.0, 23103.075876964733, 24858.0, 26122.738772612032, 26168.214953551425, 26635.600465992637], "value": 19541.08393425766, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.7520148295452, 368.7345111519706, 377.5771136327312, 381.78810760028193, 402.4084595644402, 15702.52892413228, 15901.204939753025, 16046.0, 20549.691754623673, 21439.785602143973, 20169.697454538193, 20173.293934712252, 20350.491237719063, 20640.69038964413, 20931.790682093175, 5839.824805255849, 6187.4431301182885, 6188.907166392508, 6191.690415479231, 6197.318294987548, 3557.964420355789, 5506.339239291291, 6061.66660833654, 6850.7602233921825, 6854.931450685504, 6841.2132604750595, 6847.383735463807, 6848.14398200225, 6853.7943861684225, 6859.074025006635, 6547.705353259114, 6743.190817101955, 6799.762008329685, 6802.829929251759, 6813.454923606099, 5205.479452054764, 5483.2049352844015, 5753.395893431171, 5882.0, 5888.617239879437, 13553.796693049513, 13764.449422023106, 13801.723965520634, 13806.964477664114, 23718.644220336708, 2151.193302511558, 2222.555488902226, 2279.623862062758, 2377.667126602277, 2611.2557920992426, 9904.257180711475, 9928.602855885829, 10300.639477618322, 10368.637097701545, 10392.844107338396, 1845.6862333403353, 1857.7956424793263, 1908.2176307713771, 1909.9427017189507, 1915.8850468971907, 97280.0, 119059.0, 128657.99999999907, 131380.0, 132010.33994829963, 10074.546645400975, 11221.495032723547, 11459.541618335339, 11580.76838463226, 11738.823917641243, 13248.072634915656, 13588.524401645895, 13609.11540749848, 13609.931950340186, 13615.183089014492, 7524.586147761816, 7740.225977402328, 8263.710770123138, 8361.623726932303, 8542.231199192103, 7967.2431119043595, 8170.591470426455, 8259.95870020646, 8344.791380215422, 8391.706290279812, 4424.911501769947, 4523.819047238139, 4530.569595888385, 4607.193741095295, 4617.468991066004, 1586.000819483741, 1828.9908550457162, 1838.1177035023263, 1944.6305202011572, 1947.3184385464974, 841.6128580852876, 906.3881879731155, 908.3913777768911, 970.9660161894441, 1046.2676126711333, 18573.168540223436, 20476.38570842877, 21481.785182147978, 21532.0, 21563.353099407042, 856.6573370651789, 912.8402529557301, 931.3620170183447, 964.8456247000446, 1027.1833892055834, 8265.0, 8808.427452215585, 11404.144689148347, 11520.366379849189, 11523.250988685708, 525361.0, 539597.0, 563937.0, 574821.0, 585201.0, 95912.06422544758, 109180.36229456405, 126685.4184745196, 132748.33625832002, 138016.30991845176, 915.2494954137543, 920.2270093121676, 943.1134733350628, 943.4905151218403, 944.2351695126969, 18439.53901152482, 18679.533011674815, 18891.338803141825, 18973.525661858563, 19031.333903313316, 45957.540424595325, 52204.47795521996, 54768.0, 55510.612234694454, 57162.998590098294, 77348.61325693561, 78159.60920195363, 78613.21386786058, 80584.0, 82962.0, 8028.197180281925, 8044.074931382732, 8298.294644955005, 8366.0, 8552.102029286836, 12967.870321297043, 13128.934355328543, 13201.537946171838, 13549.458021679417, 13800.86199138036, 5353.437889021597, 5377.677339359651, 11199.776004480027, 11438.256513326607, 11707.824382634097, 10665.57337706514, 11376.146788991024, 11385.601503947322, 11558.826617600911, 11803.940980295043, 11594.0, 11840.0, 12052.758944820878, 12061.51753929833, 12080.697982550506, 8111.837763244584, 8278.586070696321, 8313.251807337369, 8361.916380836114, 8385.538795366314, 8834.293256539333, 11332.263402878787, 11423.428828558373, 11838.763224735285, 11922.4038798058], "value": 9394.736205437504, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1298.0, 1358.3208395802098, 1524.7789070584765, 1630.9592260193501, 1857.2756624916276, 7648.120466146394, 8460.91539084609, 8802.119788021202, 8804.295656347493, 8879.822403551925, 8657.480551166918, 8770.07914169011, 8770.386072974894, 8771.035186129537, 8779.517126558037, 2342.109998200686, 2343.1915988983774, 2349.0251545608553, 2359.4691194481275, 2359.716833979925, 2355.5642206191824, 2395.2933884504027, 2409.229046705055, 2409.783119519243, 2413.8913748881255, 2377.1917548033666, 2393.0, 2401.1955994741784, 2409.7469765674614, 2415.9516809663796, 2348.978194485387, 2375.192434572245, 2382.559226543095, 2388.1402695029774, 2397.988010059956, 2349.283468542094, 2371.0438887917535, 2377.833551651368, 2380.4286971126976, 2381.7380088190284, 8666.39335246533, 8759.124087591254, 8766.60550275239, 8866.601002954883, 8872.423292486032, 5235.110031294689, 5409.594280428944, 5419.187121931703, 5425.891482170374, 5435.891282174374, 3568.6074531801482, 3577.624349443323, 3636.327279453283, 3646.3072016316814, 3658.743887927846, 1072.7264547540328, 1083.9729006774814, 1086.2070688397434, 1098.730810951314, 1107.6676996900978, 11606.593769218036, 11653.70865728355, 11701.063914886703, 11751.294922304689, 11755.05959523236, 3745.4569087482164, 4380.364847097153, 4643.4660014098135, 4670.089332580114, 4728.007118505152, 9193.172614464596, 9195.540222988824, 9207.677731279373, 9209.677661281958, 9232.630694772268, 5293.444188360244, 5294.49702278283, 5297.020051290524, 5300.072487314705, 5304.0, 4401.515833258339, 4594.540545945379, 4766.666333356701, 4897.975510122498, 4967.155583550805, 3251.4960181171955, 3714.758540694846, 3760.4735337052834, 3836.9232615347537, 3864.0, 1168.0655475619471, 1171.9941400292944, 1183.526589364244, 1192.3740036480917, 1194.9761004779855, 792.1880072925297, 846.7459762071354, 930.5114814722325, 948.5494390164755, 953.1413743200786, 5771.740271687784, 5782.624129431573, 5991.0, 6149.815505534841, 6292.748290068346, 440.7972332726982, 450.21437591403213, 462.1150496798593, 468.27885057012503, 470.1372980580673, 3141.4031334046913, 3561.8397172127316, 3910.276598829226, 3943.38877473992, 4082.4692789937117, 10332.0, 10760.0, 10824.350538967687, 10830.620928267474, 10830.67507974762, 10739.301945373525, 10748.570057197625, 10777.568897244022, 10794.892051079545, 10797.46012699362, 7527.397808175333, 7848.725294614661, 8048.476849004913, 8066.919330806734, 8142.715004974797, 5631.070873305859, 5908.261467316585, 6039.969800150971, 6096.1465394845645, 6214.2853571838, 9053.2304754094, 10129.392236465836, 10167.288289819642, 10225.999999999702, 10231.43727095017, 9878.1603563695, 10255.025772551595, 10392.844107338246, 10398.324108932895, 10425.687229383131, 7005.404540614105, 7218.097737782778, 7444.962775186089, 7594.544327340377, 7745.806354841174, 8294.751157465285, 8561.357898157788, 8597.828043439222, 8598.0, 8602.440841345293, 6308.33762454954, 7549.0941087069905, 7568.129665088585, 7701.229877012254, 7702.191269916578, 7278.708851645875, 7434.029982054868, 7456.0, 7473.92526074754, 7566.167721550515, 6949.096617439903, 7127.823909054948, 7129.39400150993, 7129.714811407485, 7132.322429369202, 5042.9243561345875, 5153.4073581538605, 5208.557272631869, 5226.595765947706, 5318.840434786963, 4724.976375118103, 5054.444011158696, 5099.158638824552, 5293.841184764463, 5472.261244731911], "value": 4482.505889550411, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.7158235467484, 305.2308183377888, 313.3513626792539, 314.4371574880964, 317.7442159061954, 5360.061989151897, 5375.247465354849, 5427.674339539631, 5456.945430545693, 5467.371252305986, 4987.700737955725, 5079.2635067915135, 5086.872828179297, 5318.202269659553, 5328.653637513559, 3715.3869611514137, 4101.856435024774, 4129.4012368206595, 4154.605312495308, 4193.706440549147, 4148.377743338493, 4149.211649786531, 4163.979180104096, 4166.437530933315, 4185.162967406531, 3608.8015159166234, 3810.9047273818237, 4023.718339716221, 4064.817083231247, 4195.181939521794, 3529.2588556403057, 4030.3802528356655, 4046.089629833264, 4086.427900093989, 4087.4686290782306, 3486.477028445728, 3534.1694701745328, 3537.3809583323073, 3552.786832790015, 3554.2180720241504, 4114.938275925863, 5016.44819069902, 5234.0482935383925, 5234.241035049935, 5247.449017853147, 4572.553683779162, 4590.8163673453355, 4621.491635920046, 4635.304704294349, 4642.373279607277, 5578.63738856977, 5670.404607516192, 5676.290463692038, 5684.788094726467, 5691.288588926384, 3856.421536769479, 3858.3633700439395, 3870.632289932452, 3894.3184942635207, 3903.746256493347, 4633.0, 4800.543948324938, 5534.501894829485, 5613.326400831886, 5979.641221526722, 3840.731148819612, 3909.902252443712, 3911.6675082618294, 3916.8433262669173, 3945.270125026879, 3996.640302372742, 3996.800159991989, 4011.3180759270995, 4024.335984562573, 4036.0515278909434, 3881.4954055972544, 3913.686905047646, 4340.370646256339, 4506.977465112654, 4661.95338046622, 5669.971650141805, 5700.971495142498, 5761.769529218868, 5803.883922321615, 5812.651240925558, 1273.3315009620023, 1330.3636527071756, 1335.8530561638213, 1359.9728005439836, 1361.8433880103717, 757.7650928212272, 763.7479631721519, 844.2654890245526, 912.115248209236, 917.788908551037, 5205.55752761012, 5213.3222681051975, 5226.477352264743, 5246.0, 5259.948620038188, 4893.510648935149, 5693.686847223443, 5742.913856292159, 5765.625234359837, 5775.054722091763, 4759.95281038171, 4978.4274808397495, 5181.740912954338, 5214.400343960418, 5233.1365324721755, 2537.6701028866128, 2946.6905974872757, 3010.6236720409947, 3062.555929390226, 3074.6317888539834, 5238.302274328724, 5592.86017849557, 5703.74333155009, 5816.621919575214, 6065.787697430569, 5212.50481204285, 5487.2317875497465, 5798.449147330997, 5818.650880947157, 5864.70676466175, 5043.672161309503, 5261.605379596622, 5761.205815179635, 5956.55325850563, 6079.817605471843, 5532.944670553404, 5643.435656434323, 5796.9420305798085, 5813.459433250277, 5823.7379317929945, 4829.66192366531, 5544.861378465571, 5572.247746554163, 5609.793894312742, 5611.26850644317, 4766.475687674284, 5614.915776263441, 5772.855678608068, 5927.229460170151, 5943.346231914573, 3417.53863228461, 3841.8079096045644, 3852.556955950102, 3854.325493038651, 3856.633619806114, 4350.695451318377, 5491.670499770026, 5681.829545113653, 5698.430156984268, 5841.357450680507, 4731.432228132646, 4985.027919555652, 5228.294180285765, 5240.790368385222, 5592.029658942197, 4248.9787551062045, 4587.42657167854, 5513.228148059355, 5525.530329922001, 5582.637128586629, 5112.71880046601, 5177.844664660066, 5345.625806193529, 5382.30030096085, 5403.5136837684795, 4738.834140805193, 5399.730013499231, 5617.634853734494, 5645.9153112702525, 5661.207430959751, 5497.185928643458, 5507.862303442446, 5514.503694667499, 5537.086380747292, 5541.889162216814], "value": 4091.416719443451, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.1692436773344, 718.6981467783531, 733.2154594583794, 771.4522688890886, 822.1285437436314, 16415.0, 17071.317147314123, 17113.315467381304, 17114.5721356966, 17127.57181070474, 15180.468683596077, 15281.388744450236, 15301.311440985155, 15307.0, 15334.616634584141, 685.5749435350065, 708.3695510995218, 711.3562226185285, 856.4475913036073, 877.5305211711735, 709.7693249693845, 717.4762423430899, 717.5443593318255, 814.4868732698392, 842.4313588327885, 863.3481721300441, 920.8894932608097, 923.9445633261993, 937.0020927712, 939.9483028433433, 924.1636319131197, 930.3255140023497, 930.3905941608258, 931.2270815223416, 932.2961164320928, 687.4294335701356, 690.1545606631886, 690.9965450172717, 691.9169699636026, 693.8993845892368, 11678.416079196006, 12105.878941210563, 12617.810732839014, 12629.873701263054, 14178.14931104137, 14321.57035288943, 15074.849251507563, 15085.3211605478, 15136.924315378463, 15139.243037848064, 12034.097442691833, 12067.57763478274, 12180.512779488898, 12197.463795153384, 12274.877251227552, 3430.6054803697643, 3701.222743223926, 3948.6643635291034, 3974.066094467798, 4044.4539987101657, 113504.0, 113583.43208283988, 115226.0, 117632.82367176305, 121053.39473302665, 11469.197156198987, 11557.595484158182, 11654.76690466186, 11709.063274937984, 12017.471608635966, 23930.64104038441, 24023.03907843667, 24026.51946961051, 24039.639405408932, 24114.0, 7985.960070199611, 8069.435139540294, 8146.389020823462, 8167.305779008732, 8313.792155196168, 4248.277792775236, 4324.589164029412, 4354.760488173181, 4469.664775141878, 4484.215262329145, 3521.894343169709, 3601.567811862541, 3634.83643236055, 3636.290923269937, 3641.8725344612812, 566.9971650141723, 619.3094699410125, 648.9740410383627, 651.8077167235676, 655.7475371981822, 634.9174607301021, 647.5785650497207, 680.5270337115658, 686.1012074182836, 694.5971336624746, 7269.963650181821, 7663.3103020727285, 7840.568768717661, 9121.543922803834, 9342.719718408458, 649.9512536559777, 652.7454292825802, 671.2616122265468, 683.4122654517112, 691.4295706042434, 4544.636429085665, 4675.764518378352, 4677.719336839732, 4685.226937555265, 4749.64377671676, 131936.0, 132903.0, 137202.3139884294, 137359.31320343335, 147246.0, 114350.42824785823, 114637.42681286707, 117328.0, 121835.17247241299, 125403.3729831345, 105017.19724843989, 106045.0, 109274.45362773295, 111111.0, 114669.0, 9568.665096721581, 9874.916404527949, 9928.508393570908, 9951.353162044445, 9962.0, 52116.478835211165, 59007.81984360203, 61988.0, 63069.0, 66384.00423993547, 88099.11900881166, 89885.27007773744, 95057.52471237599, 98352.0, 99113.50443247738, 11384.601538946097, 13194.472221111047, 13324.333783310989, 13534.79697804514, 13841.723165536432, 15121.395144194548, 15312.464063757716, 15333.310001049756, 15403.61490962735, 16094.436694715629, 14020.929895350459, 14274.643133921734, 14349.282535872955, 14848.183349915858, 14935.17856517902, 9497.620095196115, 13595.92191968171, 13631.591052268448, 13797.448102075805, 13868.93065534666, 12906.0, 13906.443742250196, 13917.373718182504, 14103.647408814863, 14155.292235388395, 9798.65704700332, 11171.38557379352, 11298.0, 11492.310461372344, 11670.591529296435, 11223.775524489627, 11524.539018439169, 11631.418429078343, 11766.705832354259, 11819.881801181878], "value": 7870.08586933433, "unit": "score"}], "name": "dromaeo_css", "value": 7287.288925443727, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 112.0, 114.0, 113.0, 113.0, 113.0, 111.0, 123.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 132.0, 163.0, 108.0, 129.0, 127.0, 106.0, 159.0, 109.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 185.0, 186.0, 187.0, 199.0, 258.0, 185.0, 204.0, 183.0, 185.0], "value": 196.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 153.0, 63.0, 81.0, 81.0, 91.0, 143.0, 59.0], "value": 90.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 142.0, 70.0, 73.0, 88.0, 122.0, 70.0, 69.0, 120.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 88.0, 86.0, 87.0, 84.0, 88.0, 85.0, 107.0, 107.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 92.0, 93.0, 94.0, 93.0, 93.0, 94.0, 98.0, 95.0, 93.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 83.0, 85.0, 85.0, 82.0, 81.0, 138.0, 130.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 55.0, 54.0, 53.0, 55.0, 56.0, 54.0, 53.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 75.0, 74.0, 79.0, 73.0, 70.0, 72.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 87.0, 84.0, 83.0, 86.0, 85.0, 89.0, 89.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 147.0, 148.0, 140.0, 144.0, 148.0, 147.0, 147.0, 159.0, 140.0], "value": 146.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 174.0, 166.0, 168.0, 163.0, 167.0, 167.0, 166.0, 166.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 67.0, 68.0, 67.0, 66.0, 67.0, 67.0, 64.0, 66.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1490.6000000000001, "alertThreshold": 2.0}]} 12:14:18 INFO - Return code: 0 12:14:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:14:18 INFO - # TBPL SUCCESS # 12:14:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:14:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:14:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:14:18 INFO - 2016-08-23 12:14:18.672 screenresolution[6614:121540] starting screenresolution argv=screenresolution get 12:14:18 INFO - 2016-08-23 12:14:18.674 screenresolution[6614:121540] Display 0: 1600x1200x32@60 12:14:18 INFO - 2016-08-23 12:14:18.680 screenresolution[6615:121543] starting screenresolution argv=screenresolution list 12:14:18 INFO - Available Modes on Display 0 12:14:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:14:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:14:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:14:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:14:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:14:18 INFO - Intel Iris: 12:14:18 INFO - Chipset Model: Intel Iris 12:14:18 INFO - Type: GPU 12:14:18 INFO - Bus: Built-In 12:14:18 INFO - VRAM (Dynamic, Max): 1536 MB 12:14:18 INFO - Vendor: Intel (0x8086) 12:14:18 INFO - Device ID: 0x0a2e 12:14:18 INFO - Revision ID: 0x0009 12:14:18 INFO - Displays: 12:14:18 INFO - Display: 12:14:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:14:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:14:18 INFO - Display Serial Number: 1600x1200 60 12:14:18 INFO - Main Display: Yes 12:14:18 INFO - Mirror: Off 12:14:18 INFO - Online: Yes 12:14:18 INFO - Rotation: Supported 12:14:18 INFO - Return code: 0 12:14:18 INFO - Running post-action listener: _resource_record_post_action 12:14:18 INFO - [mozharness: 2016-08-23 19:14:18.914529Z] Finished run-tests step (success) 12:14:18 INFO - Running post-run listener: _resource_record_post_run 12:14:18 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 8704; Write bytes: 705535488; Read time: 1; Write time: 2057 12:14:18 INFO - TinderboxPrint: CPU usage
53.5% 12:14:18 INFO - TinderboxPrint: I/O read bytes / time
8,704 / 1 12:14:18 INFO - TinderboxPrint: I/O write bytes / time
705,535,488 / 2,057 12:14:18 INFO - TinderboxPrint: CPU idle
2,256.5 (46.5%) 12:14:18 INFO - TinderboxPrint: CPU system
969.8 (20.0%) 12:14:18 INFO - TinderboxPrint: CPU user
1,623.4 (33.5%) 12:14:18 INFO - TinderboxPrint: Swap in / out
404,996,096 / 0 12:14:18 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401003008; Write bytes: 394630656; Read time: 29733; Write time: 706 12:14:18 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 8192; Write bytes: 296908800; Read time: 1; Write time: 1313 12:14:19 INFO - Running post-run listener: _upload_blobber_files 12:14:19 INFO - Blob upload gear active. 12:14:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:14:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:14:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:14:19 INFO - (blobuploader) - INFO - Open directory for files ... 12:14:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:14:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:14:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:20 INFO - (blobuploader) - INFO - Done attempting. 12:14:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:14:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:21 INFO - (blobuploader) - INFO - Done attempting. 12:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:14:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:24 INFO - (blobuploader) - INFO - Done attempting. 12:14:24 INFO - (blobuploader) - INFO - Iteration through files over. 12:14:24 INFO - Return code: 0 12:14:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:14:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:14:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/92b881c5cf6367e75a858fc1c83b98f58b445a366ed8413827d48b51dda19a3c8fa9d05555659aafb146308a8af7be53568f625df5817f5e020bd33d7364731f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/41fa49aa93eb930eeec661e766475aa642698a7d331cb73d41370b736caf107d1f1fa7060e052a7c55ce109d75b156255264a80533227d1b32f8912784a486ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/80a675060c7bd75af4612f41dca706d5ee41506ef913b4c57bb650de9aa8716e24ed9e4d51019e3b8530f2406570ee4caab56bc456cc2bb033d9349a8786e5ca"} 12:14:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:14:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:14:24 INFO - Contents: 12:14:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/92b881c5cf6367e75a858fc1c83b98f58b445a366ed8413827d48b51dda19a3c8fa9d05555659aafb146308a8af7be53568f625df5817f5e020bd33d7364731f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/41fa49aa93eb930eeec661e766475aa642698a7d331cb73d41370b736caf107d1f1fa7060e052a7c55ce109d75b156255264a80533227d1b32f8912784a486ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/80a675060c7bd75af4612f41dca706d5ee41506ef913b4c57bb650de9aa8716e24ed9e4d51019e3b8530f2406570ee4caab56bc456cc2bb033d9349a8786e5ca"} 12:14:24 INFO - Running post-run listener: copy_logs_to_upload_dir 12:14:24 INFO - Copying logs to upload dir... 12:14:24 INFO - mkdir: /builds/slave/test/build/upload/logs 12:14:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.615482 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-23 12:14:24.254440) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 12:14:24.258205) ========= 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.podzd2Fsal/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.xTv7FeXWod/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/Autoland/sha512/92b881c5cf6367e75a858fc1c83b98f58b445a366ed8413827d48b51dda19a3c8fa9d05555659aafb146308a8af7be53568f625df5817f5e020bd33d7364731f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/41fa49aa93eb930eeec661e766475aa642698a7d331cb73d41370b736caf107d1f1fa7060e052a7c55ce109d75b156255264a80533227d1b32f8912784a486ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/80a675060c7bd75af4612f41dca706d5ee41506ef913b4c57bb650de9aa8716e24ed9e4d51019e3b8530f2406570ee4caab56bc456cc2bb033d9349a8786e5ca"} build_url:https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011129 build_url: 'https://queue.taskcluster.net/v1/task/QM8PUJBQQNiJTbwAiAWeiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/92b881c5cf6367e75a858fc1c83b98f58b445a366ed8413827d48b51dda19a3c8fa9d05555659aafb146308a8af7be53568f625df5817f5e020bd33d7364731f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/41fa49aa93eb930eeec661e766475aa642698a7d331cb73d41370b736caf107d1f1fa7060e052a7c55ce109d75b156255264a80533227d1b32f8912784a486ec", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/80a675060c7bd75af4612f41dca706d5ee41506ef913b4c57bb650de9aa8716e24ed9e4d51019e3b8530f2406570ee4caab56bc456cc2bb033d9349a8786e5ca"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 12:14:24.291966) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 12:14:24.292299) ========= 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.podzd2Fsal/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.xTv7FeXWod/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005503 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 12:14:24.341248) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 12:14:24.341569) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 12:14:24.341870) ========= ========= Total master_lag: 0.18 =========