builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0021 starttime: 1473189664.81 results: success (0) buildid: 20160906100156 builduid: 845d14e888014a219b1751c2f3ce0d00 revision: 27653aa4f14dfd5ebb5a5d044a1308251fad35fe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:04.811949) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:04.812377) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:04.812644) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:04.850964) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:04.851220) ========= 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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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-09-06 12:21:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2016-09-06 12:21:05 (14.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.480759 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:05.355804) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:05.356081) ========= 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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.028485 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:05.407518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:05.407834) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 27653aa4f14dfd5ebb5a5d044a1308251fad35fe --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 27653aa4f14dfd5ebb5a5d044a1308251fad35fe --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KoQSgWGyMj/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.xrGBDdnv7q/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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-09-06 12:21:05,484 truncating revision to first 12 chars 2016-09-06 12:21:05,484 Setting DEBUG logging. 2016-09-06 12:21:05,484 attempt 1/10 2016-09-06 12:21:05,484 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/27653aa4f14d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 12:21:06,162 unpacking tar archive at: larch-27653aa4f14d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.965858 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:06.400060) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:06.400337) ========= script_repo_revision: 27653aa4f14dfd5ebb5a5d044a1308251fad35fe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:06.400682) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:06.400936) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 12:21:06.422933) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-06 12:21:06.423195) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KoQSgWGyMj/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.xrGBDdnv7q/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:21:06 INFO - MultiFileLogger online at 20160906 12:21:06 in /builds/slave/test 12:21:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 12:21:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:21:06 INFO - {'append_to_log': False, 12:21:06 INFO - 'base_work_dir': '/builds/slave/test', 12:21:06 INFO - 'blob_upload_branch': 'Larch', 12:21:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:21:06 INFO - 'branch': 'Larch', 12:21:06 INFO - 'buildbot_json_path': 'buildprops.json', 12:21:06 INFO - 'config_files': ('talos/mac_config.py',), 12:21:06 INFO - 'default_actions': ('clobber', 12:21:06 INFO - 'read-buildbot-config', 12:21:06 INFO - 'download-and-extract', 12:21:06 INFO - 'populate-webroot', 12:21:06 INFO - 'create-virtualenv', 12:21:06 INFO - 'install', 12:21:06 INFO - 'run-tests'), 12:21:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:21:06 INFO - 'download_minidump_stackwalk': True, 12:21:06 INFO - 'download_symbols': 'ondemand', 12:21:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:21:06 INFO - 'tooltool.py': '/tools/tooltool.py', 12:21:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:21:06 INFO - '/tools/misc-python/virtualenv.py')}, 12:21:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:21:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:21:06 INFO - 'installer_path': 'installer.exe', 12:21:06 INFO - 'log_level': 'info', 12:21:06 INFO - 'log_name': 'talos', 12:21:06 INFO - 'log_to_console': True, 12:21:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:21:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:21:06 INFO - 'opt_config_files': (), 12:21:06 INFO - 'pip_index': False, 12:21:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:06 INFO - 'cmd': ('bash', 12:21:06 INFO - '-c', 12:21:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:06 INFO - 'enabled': True, 12:21:06 INFO - 'halt_on_failure': False, 12:21:06 INFO - 'name': 'check_screen_resolution'},), 12:21:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:06 INFO - 'cmd': ('bash', 12:21:06 INFO - '-c', 12:21:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:06 INFO - 'enabled': True, 12:21:06 INFO - 'halt_on_failure': False, 12:21:06 INFO - 'name': 'check_screen_resolution'},), 12:21:06 INFO - 'run_cmd_checks_enabled': True, 12:21:06 INFO - 'sps_profile': False, 12:21:06 INFO - 'sps_profile_interval': 0, 12:21:06 INFO - 'suite': 'dromaeojs', 12:21:06 INFO - 'system_bits': '32', 12:21:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:21:06 INFO - 'title': 't-yosemite-r7-0021', 12:21:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:21:06 INFO - 'use_talos_json': True, 12:21:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:21:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:21:06 INFO - 'work_dir': 'build'} 12:21:06 INFO - [mozharness: 2016-09-06 19:21:06.630904Z] Running clobber step. 12:21:06 INFO - Running pre-action listener: _resource_record_pre_action 12:21:06 INFO - Running main action method: clobber 12:21:06 INFO - rmtree: /builds/slave/test/build 12:21:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:21:08 INFO - Running post-action listener: _resource_record_post_action 12:21:08 INFO - [mozharness: 2016-09-06 19:21:08.832575Z] Finished clobber step (success) 12:21:08 INFO - [mozharness: 2016-09-06 19:21:08.832705Z] Running read-buildbot-config step. 12:21:08 INFO - Running pre-action listener: _resource_record_pre_action 12:21:08 INFO - Running main action method: read_buildbot_config 12:21:08 INFO - Using buildbot properties: 12:21:08 INFO - { 12:21:08 INFO - "project": "", 12:21:08 INFO - "product": "firefox", 12:21:08 INFO - "script_repo_revision": "production", 12:21:08 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 12:21:08 INFO - "repository": "", 12:21:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 12:21:08 INFO - "buildid": "20160906100156", 12:21:08 INFO - "pgo_build": "False", 12:21:08 INFO - "basedir": "/builds/slave/test", 12:21:08 INFO - "buildnumber": 0, 12:21:08 INFO - "slavename": "t-yosemite-r7-0021", 12:21:08 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:21:08 INFO - "platform": "yosemite_r7", 12:21:08 INFO - "scheduler": "tests-larch-macosx64-talos", 12:21:08 INFO - "branch": "larch", 12:21:08 INFO - "repo_path": "projects/larch", 12:21:08 INFO - "revision": "27653aa4f14dfd5ebb5a5d044a1308251fad35fe", 12:21:08 INFO - "stage_platform": "macosx64", 12:21:08 INFO - "builduid": "845d14e888014a219b1751c2f3ce0d00", 12:21:08 INFO - "slavebuilddir": "test" 12:21:08 INFO - } 12:21:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:21:08 INFO - Running post-action listener: _resource_record_post_action 12:21:08 INFO - [mozharness: 2016-09-06 19:21:08.835927Z] Finished read-buildbot-config step (success) 12:21:08 INFO - [mozharness: 2016-09-06 19:21:08.836065Z] Running download-and-extract step. 12:21:08 INFO - Running pre-action listener: _resource_record_pre_action 12:21:08 INFO - Running main action method: download_and_extract 12:21:08 INFO - mkdir: /builds/slave/test/build/tests 12:21:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:08 INFO - trying https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:21:08 INFO - Downloading https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/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 12:21:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/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 12:21:09 INFO - Downloaded 1199 bytes. 12:21:09 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:21:09 INFO - Using the following test package requirements: 12:21:09 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:21:09 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:21:09 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:21:09 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:21:09 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:21:09 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:21:09 INFO - u'jsshell-mac.zip'], 12:21:09 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:21:09 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:21:09 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:21:09 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:21:09 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:21:09 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:21:09 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:21:09 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:21:09 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:21:09 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:21:09 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:21:09 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:21:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:21:10 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 12:21:17 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 12:21:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:21:17 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 12:21:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:21:25 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 12:21:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:27 INFO - trying https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:21:27 INFO - Downloading https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:21:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/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 12:21:31 INFO - Downloaded 111502822 bytes. 12:21:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:21:31 INFO - mkdir: /builds/slave/test/properties 12:21:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:21:31 INFO - Writing to file /builds/slave/test/properties/build_url 12:21:31 INFO - Contents: 12:21:31 INFO - build_url:https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:21:32 INFO - Running post-action listener: _resource_record_post_action 12:21:32 INFO - Running post-action listener: set_extra_try_arguments 12:21:32 INFO - [mozharness: 2016-09-06 19:21:32.587132Z] Finished download-and-extract step (success) 12:21:32 INFO - [mozharness: 2016-09-06 19:21:32.587273Z] Running populate-webroot step. 12:21:32 INFO - Running pre-action listener: _resource_record_pre_action 12:21:32 INFO - Running main action method: populate_webroot 12:21:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:21:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:21:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:21:32 INFO - '--tppagecycles', 12:21:32 INFO - '7'], 12:21:32 INFO - 'tests': ['tsvgm']}}, 12:21:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:21:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:21:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:21:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:21:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:32 INFO - 'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:32 INFO - 'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['damp', 'tps']}, 12:21:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:32 INFO - 'tests': ['damp', 'tps']}, 12:21:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['dromaeo_dom']}, 12:21:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:21:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['basic_compositor_video']}, 12:21:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:21:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['a11yr', 12:21:32 INFO - 'ts_paint', 12:21:32 INFO - 'tpaint', 12:21:32 INFO - 'sessionrestore', 12:21:32 INFO - 'sessionrestore_no_auto_restore', 12:21:32 INFO - 'tabpaint']}, 12:21:32 INFO - 'other-e10s': {'tests': ['a11yr', 12:21:32 INFO - 'ts_paint', 12:21:32 INFO - 'tpaint', 12:21:32 INFO - 'sessionrestore', 12:21:32 INFO - 'sessionrestore_no_auto_restore', 12:21:32 INFO - 'tabpaint']}, 12:21:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:21:32 INFO - 'ts_paint', 12:21:32 INFO - 'tpaint', 12:21:32 INFO - 'sessionrestore', 12:21:32 INFO - 'sessionrestore_no_auto_restore', 12:21:32 INFO - 'tabpaint']}, 12:21:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:21:32 INFO - 'ts_paint', 12:21:32 INFO - 'tpaint', 12:21:32 INFO - 'sessionrestore', 12:21:32 INFO - 'sessionrestore_no_auto_restore', 12:21:32 INFO - 'tabpaint']}, 12:21:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['a11yr', 12:21:32 INFO - 'ts_paint', 12:21:32 INFO - 'tpaint', 12:21:32 INFO - 'sessionrestore', 12:21:32 INFO - 'sessionrestore_no_auto_restore', 12:21:32 INFO - 'tabpaint']}, 12:21:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['a11yr', 12:21:32 INFO - 'ts_paint', 12:21:32 INFO - 'tpaint', 12:21:32 INFO - 'sessionrestore', 12:21:32 INFO - 'sessionrestore_no_auto_restore', 12:21:32 INFO - 'tabpaint']}, 12:21:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['tsvgx', 12:21:32 INFO - 'tsvgr_opacity', 12:21:32 INFO - 'tart', 12:21:32 INFO - 'tscrollx', 12:21:32 INFO - 'cart']}, 12:21:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:21:32 INFO - 'tsvgr_opacity', 12:21:32 INFO - 'tart', 12:21:32 INFO - 'tscrollx', 12:21:32 INFO - 'cart']}, 12:21:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:32 INFO - 'talos_options': ['--disable-e10s'], 12:21:32 INFO - 'tests': ['tp5o']}, 12:21:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:32 INFO - 'tests': ['tp5o']}, 12:21:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:32 INFO - 'talos_options': ['--disable-e10s', 12:21:32 INFO - '--xperf_path', 12:21:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:32 INFO - 'tests': ['tp5n']}, 12:21:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:32 INFO - 'talos_options': ['--xperf_path', 12:21:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:32 INFO - 'tests': ['tp5n']}}, 12:21:32 INFO - 'talos.zip': {'path': '', 12:21:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:21:32 INFO - Running post-action listener: _resource_record_post_action 12:21:32 INFO - [mozharness: 2016-09-06 19:21:32.606617Z] Finished populate-webroot step (success) 12:21:32 INFO - [mozharness: 2016-09-06 19:21:32.606747Z] Running create-virtualenv step. 12:21:32 INFO - Running pre-action listener: _resource_record_pre_action 12:21:32 INFO - Running main action method: create_virtualenv 12:21:32 INFO - Creating virtualenv /builds/slave/test/build/venv 12:21:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:21:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:21:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:21:32 INFO - Using real prefix '/tools/python27' 12:21:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:21:33 INFO - Installing distribute.............................................................................................................................................................................................done. 12:21:36 INFO - Installing pip.................done. 12:21:36 INFO - Return code: 0 12:21:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:36 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 0x10579ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105833618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff453c992d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, '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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:21:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:21:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:21:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:21:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:36 INFO - 'HOME': '/Users/cltbld', 12:21:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:36 INFO - 'LOGNAME': 'cltbld', 12:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:36 INFO - 'MOZ_NO_REMOTE': '1', 12:21:36 INFO - 'NO_EM_RESTART': '1', 12:21:36 INFO - 'PAGER': '/bin/cat', 12:21:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:36 INFO - 'PWD': '/builds/slave/test', 12:21:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:36 INFO - 'SHELL': '/bin/bash', 12:21:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:21:36 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:21:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:36 INFO - 'USER': 'cltbld', 12:21:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:36 INFO - 'XPC_FLAGS': '0x0', 12:21:36 INFO - 'XPC_SERVICE_NAME': '0', 12:21:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:21:36 INFO - Cleaning up... 12:21:36 INFO - Return code: 0 12:21:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:36 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 0x10579ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105833618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff453c992d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, '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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:21:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:21:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:21:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:21:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:36 INFO - 'HOME': '/Users/cltbld', 12:21:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:36 INFO - 'LOGNAME': 'cltbld', 12:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:36 INFO - 'MOZ_NO_REMOTE': '1', 12:21:36 INFO - 'NO_EM_RESTART': '1', 12:21:36 INFO - 'PAGER': '/bin/cat', 12:21:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:36 INFO - 'PWD': '/builds/slave/test', 12:21:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:36 INFO - 'SHELL': '/bin/bash', 12:21:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:21:36 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:21:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:36 INFO - 'USER': 'cltbld', 12:21:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:36 INFO - 'XPC_FLAGS': '0x0', 12:21:36 INFO - 'XPC_SERVICE_NAME': '0', 12:21:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:37 INFO - Downloading/unpacking psutil>=3.1.1 12:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:21:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:21:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:21:40 INFO - Installing collected packages: psutil 12:21:40 INFO - Running setup.py install for psutil 12:21:40 INFO - building 'psutil._psutil_osx' extension 12:21:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:21:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:21:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:21:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:21:41 INFO - building 'psutil._psutil_posix' extension 12:21:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:21:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:21:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:21:41 INFO - ^ 12:21:41 INFO - 1 warning generated. 12:21:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:21:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:21:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:21:41 INFO - Successfully installed psutil 12:21:41 INFO - Cleaning up... 12:21:41 INFO - Return code: 0 12:21:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:41 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 0x10579ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105833618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff453c992d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, '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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:21:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:21:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:21:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:41 INFO - 'HOME': '/Users/cltbld', 12:21:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:41 INFO - 'LOGNAME': 'cltbld', 12:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:41 INFO - 'MOZ_NO_REMOTE': '1', 12:21:41 INFO - 'NO_EM_RESTART': '1', 12:21:41 INFO - 'PAGER': '/bin/cat', 12:21:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:41 INFO - 'PWD': '/builds/slave/test', 12:21:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:41 INFO - 'SHELL': '/bin/bash', 12:21:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:21:41 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:21:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:41 INFO - 'USER': 'cltbld', 12:21:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:41 INFO - 'XPC_FLAGS': '0x0', 12:21:41 INFO - 'XPC_SERVICE_NAME': '0', 12:21:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:21:44 INFO - Installing collected packages: mozsystemmonitor 12:21:44 INFO - Running setup.py install for mozsystemmonitor 12:21:44 INFO - Successfully installed mozsystemmonitor 12:21:44 INFO - Cleaning up... 12:21:44 INFO - Return code: 0 12:21:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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', '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 0x10579ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105833618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff453c992d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, '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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:21: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:21: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 jsonschema==2.5.1 12:21:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:21:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:44 INFO - 'HOME': '/Users/cltbld', 12:21:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:44 INFO - 'LOGNAME': 'cltbld', 12:21:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:44 INFO - 'MOZ_NO_REMOTE': '1', 12:21:44 INFO - 'NO_EM_RESTART': '1', 12:21:44 INFO - 'PAGER': '/bin/cat', 12:21:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:44 INFO - 'PWD': '/builds/slave/test', 12:21:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:44 INFO - 'SHELL': '/bin/bash', 12:21:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:21:44 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:21:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:44 INFO - 'USER': 'cltbld', 12:21:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:44 INFO - 'XPC_FLAGS': '0x0', 12:21:44 INFO - 'XPC_SERVICE_NAME': '0', 12:21:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:45 INFO - Downloading/unpacking jsonschema==2.5.1 12:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:21:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21:48 INFO - Downloading functools32-3.2.3-2.tar.gz 12:21:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:21:48 INFO - warning: no files found matching '*.txt' 12:21:48 INFO - no previously-included directories found matching 'build' 12:21:48 INFO - no previously-included directories found matching 'dist' 12:21:48 INFO - no previously-included directories found matching '.git*' 12:21:48 INFO - Installing collected packages: jsonschema, functools32 12:21:48 INFO - Running setup.py install for functools32 12:21:48 INFO - warning: no files found matching '*.txt' 12:21:48 INFO - no previously-included directories found matching 'build' 12:21:48 INFO - no previously-included directories found matching 'dist' 12:21:48 INFO - no previously-included directories found matching '.git*' 12:21:48 INFO - Successfully installed jsonschema functools32 12:21:48 INFO - Cleaning up... 12:21:48 INFO - Return code: 0 12:21:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10579ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105833618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff453c992d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, '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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:21:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:21:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:48 INFO - 'HOME': '/Users/cltbld', 12:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:48 INFO - 'LOGNAME': 'cltbld', 12:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:48 INFO - 'MOZ_NO_REMOTE': '1', 12:21:48 INFO - 'NO_EM_RESTART': '1', 12:21:48 INFO - 'PAGER': '/bin/cat', 12:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:48 INFO - 'PWD': '/builds/slave/test', 12:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:48 INFO - 'SHELL': '/bin/bash', 12:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:21:48 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:48 INFO - 'USER': 'cltbld', 12:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:48 INFO - 'XPC_FLAGS': '0x0', 12:21:48 INFO - 'XPC_SERVICE_NAME': '0', 12:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:21:48 INFO - Cleaning up... 12:21:48 INFO - Return code: 0 12:21:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10579ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105833618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff453c992d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, '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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:21:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:21:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:48 INFO - 'HOME': '/Users/cltbld', 12:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:48 INFO - 'LOGNAME': 'cltbld', 12:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:48 INFO - 'MOZ_NO_REMOTE': '1', 12:21:48 INFO - 'NO_EM_RESTART': '1', 12:21:48 INFO - 'PAGER': '/bin/cat', 12:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:48 INFO - 'PWD': '/builds/slave/test', 12:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:48 INFO - 'SHELL': '/bin/bash', 12:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:21:48 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:48 INFO - 'USER': 'cltbld', 12:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:48 INFO - 'XPC_FLAGS': '0x0', 12:21:48 INFO - 'XPC_SERVICE_NAME': '0', 12:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:48 INFO - Downloading/unpacking blobuploader==1.2.4 12:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:51 INFO - Downloading blobuploader-1.2.4.tar.gz 12:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:21:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:21:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:52 INFO - Downloading docopt-0.6.1.tar.gz 12:21:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:21:52 INFO - Installing collected packages: blobuploader, requests, docopt 12:21:52 INFO - Running setup.py install for blobuploader 12:21:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:21:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:21:52 INFO - Running setup.py install for requests 12:21:53 INFO - Running setup.py install for docopt 12:21:53 INFO - Successfully installed blobuploader requests docopt 12:21:53 INFO - Cleaning up... 12:21:53 INFO - Return code: 0 12:21:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:53 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 0x10579ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105833618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff453c992d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, '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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:21:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:21:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:21:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:53 INFO - 'HOME': '/Users/cltbld', 12:21:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:53 INFO - 'LOGNAME': 'cltbld', 12:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:53 INFO - 'MOZ_NO_REMOTE': '1', 12:21:53 INFO - 'NO_EM_RESTART': '1', 12:21:53 INFO - 'PAGER': '/bin/cat', 12:21:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:53 INFO - 'PWD': '/builds/slave/test', 12:21:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:53 INFO - 'SHELL': '/bin/bash', 12:21:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:21:53 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:21:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:53 INFO - 'USER': 'cltbld', 12:21:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:53 INFO - 'XPC_FLAGS': '0x0', 12:21:53 INFO - 'XPC_SERVICE_NAME': '0', 12:21:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:21:53 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ppSVZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:21:53 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-DdoVOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:21:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-vo1sPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:21:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-1ucdFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:21:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-qm2iFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:21:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-8jYF8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:21:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-GfhBmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:21:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ZaabMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:21:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-8Cvfiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:21:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-x8Rxd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:21:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-YI10NM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:21:54 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-jQ6lQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:21:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-agtSlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:21:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-gWxOYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-sNzOOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:21:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ytwPNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:21:55 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-WzfZbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:21:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:21:55 INFO - Running setup.py install for manifestparser 12:21:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:21:55 INFO - Running setup.py install for mozcrash 12:21:55 INFO - Running setup.py install for mozdebug 12:21:55 INFO - Running setup.py install for mozdevice 12:21:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:21:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:21:56 INFO - Running setup.py install for mozfile 12:21:56 INFO - Running setup.py install for mozhttpd 12:21:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:21:56 INFO - Running setup.py install for mozinfo 12:21:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:21:56 INFO - Running setup.py install for mozInstall 12:21:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:21:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:21:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:21:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:21:56 INFO - Running setup.py install for mozleak 12:21:56 INFO - Running setup.py install for mozlog 12:21:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:21:57 INFO - Running setup.py install for moznetwork 12:21:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:21:57 INFO - Running setup.py install for mozprocess 12:21:57 INFO - Running setup.py install for mozprofile 12:21:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:21:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:21:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:21:57 INFO - Running setup.py install for mozrunner 12:21:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:21:57 INFO - Running setup.py install for mozscreenshot 12:21:57 INFO - Running setup.py install for moztest 12:21:57 INFO - Running setup.py install for mozversion 12:21:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:21:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:21:58 INFO - Cleaning up... 12:21:58 INFO - Return code: 0 12:21:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:58 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 0x10579ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105833618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff453c992d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, '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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:21:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:21:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:21:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:58 INFO - 'HOME': '/Users/cltbld', 12:21:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:58 INFO - 'LOGNAME': 'cltbld', 12:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:58 INFO - 'MOZ_NO_REMOTE': '1', 12:21:58 INFO - 'NO_EM_RESTART': '1', 12:21:58 INFO - 'PAGER': '/bin/cat', 12:21:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:58 INFO - 'PWD': '/builds/slave/test', 12:21:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:58 INFO - 'SHELL': '/bin/bash', 12:21:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:21:58 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:21:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:58 INFO - 'USER': 'cltbld', 12:21:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:58 INFO - 'XPC_FLAGS': '0x0', 12:21:58 INFO - 'XPC_SERVICE_NAME': '0', 12:21:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:21:58 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-XkSP1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:21:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:21:58 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-woIHma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:21:58 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)) 12:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:21:58 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ZgH9Fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:21:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:21:58 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-2l28an-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:21:58 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)) 12:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:21:58 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-gCRHTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:21:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:21:58 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-BSY0fN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:21:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:21:59 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-GnPTxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:21:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:21:59 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-0ilXvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:21:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:21:59 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-FSxWvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:21:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:21:59 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-lEkcRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:21:59 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)) 12:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:21:59 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-RuxPTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:21:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:21:59 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-YBAk1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:21:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:21:59 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-zh1wBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:21:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:21:59 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-IS5T9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:21:59 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)) 12:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:59 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-2TAZDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:00 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-ZOWxoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:00 INFO - Running setup.py (path:/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/pip-BMtIkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:22:00 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)) 12:22:00 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)) 12:22:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:22:00 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)) 12:22:00 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)) 12:22:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:02 INFO - Downloading blessings-1.6.tar.gz 12:22:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:22:02 INFO - Installing collected packages: blessings 12:22:02 INFO - Running setup.py install for blessings 12:22:02 INFO - Successfully installed blessings 12:22:02 INFO - Cleaning up... 12:22:02 INFO - Return code: 0 12:22:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:22:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:03 INFO - Reading from file tmpfile_stdout 12:22:03 INFO - Current package versions: 12:22:03 INFO - blessings == 1.6 12:22:03 INFO - blobuploader == 1.2.4 12:22:03 INFO - docopt == 0.6.1 12:22:03 INFO - functools32 == 3.2.3-2 12:22:03 INFO - jsonschema == 2.5.1 12:22:03 INFO - manifestparser == 1.1 12:22:03 INFO - mozInstall == 1.12 12:22:03 INFO - mozcrash == 0.17 12:22:03 INFO - mozdebug == 0.1 12:22:03 INFO - mozdevice == 0.48 12:22:03 INFO - mozfile == 1.2 12:22:03 INFO - mozhttpd == 0.7 12:22:03 INFO - mozinfo == 0.9 12:22:03 INFO - mozleak == 0.1 12:22:03 INFO - mozlog == 3.3 12:22:03 INFO - moznetwork == 0.27 12:22:03 INFO - mozprocess == 0.23 12:22:03 INFO - mozprofile == 0.28 12:22:03 INFO - mozrunner == 6.12 12:22:03 INFO - mozscreenshot == 0.1 12:22:03 INFO - mozsystemmonitor == 0.3 12:22:03 INFO - moztest == 0.7 12:22:03 INFO - mozversion == 1.4 12:22:03 INFO - psutil == 3.1.1 12:22:03 INFO - requests == 1.2.3 12:22:03 INFO - wsgiref == 0.1.2 12:22:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:03 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 0x10579ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105833618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff453c992d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, '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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:22:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:22:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:03 INFO - 'HOME': '/Users/cltbld', 12:22:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:03 INFO - 'LOGNAME': 'cltbld', 12:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:03 INFO - 'MOZ_NO_REMOTE': '1', 12:22:03 INFO - 'NO_EM_RESTART': '1', 12:22:03 INFO - 'PAGER': '/bin/cat', 12:22:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:03 INFO - 'PWD': '/builds/slave/test', 12:22:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:03 INFO - 'SHELL': '/bin/bash', 12:22:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:22:03 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:22:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:03 INFO - 'USER': 'cltbld', 12:22:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:03 INFO - 'XPC_FLAGS': '0x0', 12:22:03 INFO - 'XPC_SERVICE_NAME': '0', 12:22:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:22:03 INFO - Cleaning up... 12:22:03 INFO - Return code: 0 12:22:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:03 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 0x10579ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105833618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff453c992d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, '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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:22:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:22:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:03 INFO - 'HOME': '/Users/cltbld', 12:22:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:03 INFO - 'LOGNAME': 'cltbld', 12:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:03 INFO - 'MOZ_NO_REMOTE': '1', 12:22:03 INFO - 'NO_EM_RESTART': '1', 12:22:03 INFO - 'PAGER': '/bin/cat', 12:22:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:03 INFO - 'PWD': '/builds/slave/test', 12:22:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:03 INFO - 'SHELL': '/bin/bash', 12:22:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:22:03 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:22:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:03 INFO - 'USER': 'cltbld', 12:22:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:03 INFO - 'XPC_FLAGS': '0x0', 12:22:03 INFO - 'XPC_SERVICE_NAME': '0', 12:22:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:22:03 INFO - Cleaning up... 12:22:03 INFO - Return code: 0 12:22:03 INFO - Running post-action listener: _resource_record_post_action 12:22:03 INFO - Running post-action listener: _start_resource_monitoring 12:22:03 INFO - Starting resource monitoring. 12:22:03 INFO - [mozharness: 2016-09-06 19:22:03.794121Z] Finished create-virtualenv step (success) 12:22:03 INFO - [mozharness: 2016-09-06 19:22:03.795082Z] Running install step. 12:22:03 INFO - Running pre-action listener: _resource_record_pre_action 12:22:03 INFO - Running main action method: install 12:22:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:04 INFO - Reading from file tmpfile_stdout 12:22:04 INFO - Detecting whether we're running mozinstall >=1.0... 12:22:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:22:04 INFO - Reading from file tmpfile_stdout 12:22:04 INFO - Output received: 12:22:04 INFO - Usage: mozinstall [options] installer 12:22:04 INFO - Options: 12:22:04 INFO - -h, --help show this help message and exit 12:22:04 INFO - -d DEST, --destination=DEST 12:22:04 INFO - Directory to install application into. [default: 12:22:04 INFO - "/builds/slave/test"] 12:22:04 INFO - --app=APP Application being installed. [default: firefox] 12:22:04 INFO - mkdir: /builds/slave/test/build/application 12:22:04 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'] 12:22:04 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 12:22:38 INFO - Reading from file tmpfile_stdout 12:22:38 INFO - Output received: 12:22:38 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:22:38 INFO - Running post-action listener: _resource_record_post_action 12:22:38 INFO - [mozharness: 2016-09-06 19:22:38.268967Z] Finished install step (success) 12:22:38 INFO - [mozharness: 2016-09-06 19:22:38.269118Z] Running run-tests step. 12:22:38 INFO - Running pre-action listener: _resource_record_pre_action 12:22:38 INFO - Running main action method: run_tests 12:22:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:22:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:22:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:22:38 INFO - 2016-09-06 12:22:38.282 screenresolution[1683:7928] starting screenresolution argv=screenresolution get 12:22:38 INFO - 2016-09-06 12:22:38.285 screenresolution[1683:7928] Display 0: 1600x1200x32@60 12:22:38 INFO - 2016-09-06 12:22:38.291 screenresolution[1684:7930] starting screenresolution argv=screenresolution list 12:22:38 INFO - Available Modes on Display 0 12:22:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:22:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:22:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:22:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:22:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:22:38 INFO - Intel Iris: 12:22:38 INFO - Chipset Model: Intel Iris 12:22:38 INFO - Type: GPU 12:22:38 INFO - Bus: Built-In 12:22:38 INFO - VRAM (Dynamic, Max): 1536 MB 12:22:38 INFO - Vendor: Intel (0x8086) 12:22:38 INFO - Device ID: 0x0a2e 12:22:38 INFO - Revision ID: 0x0009 12:22:38 INFO - Displays: 12:22:38 INFO - Display: 12:22:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:22:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:22:38 INFO - Display Serial Number: 1600x1200 60 12:22:38 INFO - Main Display: Yes 12:22:38 INFO - Mirror: Off 12:22:38 INFO - Online: Yes 12:22:38 INFO - Rotation: Supported 12:22:38 INFO - Return code: 0 12:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:22:38 INFO - Python 2.7.3 12:22:38 INFO - Return code: 0 12:22:38 INFO - grabbing minidump binary from tooltool 12:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:38 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 0x105702d30>, '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 0x105467db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1056fab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:22:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:22:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:22:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:22:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:22:38 INFO - Return code: 0 12:22:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:22:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:22:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:22:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:22:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0021', '--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 12:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0021 --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 12:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KoQSgWGyMj/Render', 12:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:38 INFO - 'HOME': '/Users/cltbld', 12:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:38 INFO - 'LOGNAME': 'cltbld', 12:22:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:22:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:38 INFO - 'MOZ_NO_REMOTE': '1', 12:22:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:22:38 INFO - 'NO_EM_RESTART': '1', 12:22:38 INFO - 'PAGER': '/bin/cat', 12:22:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:38 INFO - 'PWD': '/builds/slave/test', 12:22:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:38 INFO - 'SHELL': '/bin/bash', 12:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xrGBDdnv7q/Listeners', 12:22:38 INFO - 'TMPDIR': '/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/', 12:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:38 INFO - 'USER': 'cltbld', 12:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:38 INFO - 'XPC_FLAGS': '0x0', 12:22:38 INFO - 'XPC_SERVICE_NAME': '0', 12:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0021', '--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 12:22:38 INFO - mozversion application_buildid: 20160906100156 12:22:38 INFO - mozversion application_changeset: 27653aa4f14dfd5ebb5a5d044a1308251fad35fe 12:22:38 INFO - mozversion application_display_name: Nightly 12:22:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:22:38 INFO - mozversion application_name: Firefox 12:22:38 INFO - mozversion application_remotingname: firefox 12:22:38 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 12:22:38 INFO - mozversion application_vendor: Mozilla 12:22:38 INFO - mozversion application_version: 51.0a1 12:22:38 INFO - mozversion platform_buildid: 20160906100156 12:22:38 INFO - mozversion platform_changeset: 27653aa4f14dfd5ebb5a5d044a1308251fad35fe 12:22:38 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 12:22:38 INFO - mozversion platform_version: 51.0a1 12:22:38 INFO - using testdate: 1473189758 12:22:38 INFO - actual date: 1473189758 12:22:38 INFO - starting webserver on 'localhost:49271' 12:22:38 INFO - SUITE-START | Running 2 tests 12:22:38 INFO - TEST-START | dromaeo_css 12:22:38 INFO - operating with platform_type : mac_ 12:22:38 INFO - Initialising browser for dromaeo_css test... 12:22:38 INFO - TEST-INFO | started process 1694 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpeHJuyM/profile http://localhost:49271/getInfo.html) 12:22:41 INFO - PROCESS | 1694 | __metrics Screen width/height:1600/1200 12:22:41 INFO - PROCESS | 1694 | colorDepth:24 12:22:41 INFO - PROCESS | 1694 | Browser inner width/height: 1024/689 12:22:41 INFO - PROCESS | 1694 | __metrics 12:22:41 INFO - PROCESS | 1694 | 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:22:41 INFO - PROCESS | 1694 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:22:42 INFO - TEST-INFO | 1694: exit 0 12:22:42 INFO - Browser initialized. 12:22:42 INFO - Running cycle 1/1 for dromaeo_css test... 12:22:42 INFO - TEST-INFO | started process 1697 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmpeHJuyM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:22:53 INFO - PROCESS | 1697 | RSS: Main: 243625984 12:22:53 INFO - PROCESS | 1697 | 12:23:45 INFO - PROCESS | 1697 | 1473189825308 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] 12:25:56 INFO - PROCESS | 1697 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 12:25:58 INFO - PROCESS | 1697 | RSS: Main: 387080192 12:25:58 INFO - PROCESS | 1697 | 12:29:01 INFO - PROCESS | 1697 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 12:29:02 INFO - PROCESS | 1697 | RSS: Main: 373190656 12:29:02 INFO - PROCESS | 1697 | 12:32:04 INFO - PROCESS | 1697 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 12:32:05 INFO - PROCESS | 1697 | RSS: Main: 335663104 12:32:05 INFO - PROCESS | 1697 | 12:35:07 INFO - PROCESS | 1697 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 12:35:08 INFO - PROCESS | 1697 | RSS: Main: 354365440 12:35:08 INFO - PROCESS | 1697 | 12:38:11 INFO - PROCESS | 1697 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 12:38:12 INFO - PROCESS | 1697 | RSS: Main: 332673024 12:38:12 INFO - PROCESS | 1697 | 12:41:15 INFO - PROCESS | 1697 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 12:41:15 INFO - PROCESS | 1697 | RSS: Main: 393641984 12:41:15 INFO - PROCESS | 1697 | 12:41:15 INFO - PROCESS | 1697 | __start_tp_report 12:41:15 INFO - PROCESS | 1697 | _x_x_mozilla_page_load 12:41:15 INFO - PROCESS | 1697 | _x_x_mozilla_page_load_details 12:41:15 INFO - PROCESS | 1697 | |i|pagename|runs| 12:41:15 INFO - PROCESS | 1697 | |0;dojo.html;2690.636764036855;3306.5536152619397;3364.125327414871;3397.6092749333834;3409.249965007699;5586.24585680933;5660.688662123582;5681.517071048961;5713.314402271733;5763.481286684198;4877.756112194394;5667.149927510875;5737.5696822738255;5802.477777000069;5822.4177582241755;5122.846314610549;5187.844364669066;5191.922121168185;5234.607404444663;5280.128778751501;5293.920591191135;5304.708241046741;5305.310309659739;5309.017831701148;5363.383210930754;4276.743395396286;5186.1442861927735;5214.791408343662;5239.5546378557865;5247.317848679666;5225.059489291926;5246.475352464761;5248.501392367757;5249.553787927993;5252.080885844962;4271.423357846713;4325.589069038437;5101.20931255656;5179.482051794828;5205.479452054802;5638.267025286727;5639.436056394327;5683.8010669626365;5713.314402271712;5775.422457754233;6224.066390041485;6226.315105338409;6239.719212635442;6244.656543890084;6249.437550620466;5136.691798492103;6089.580040920871;6100.840840240385;6231.4391704746795;6336.714847831859;5688.829335119953;5708.115242137475;5715.3141623005085;5747.195392645033;5776.075827867605;6343.270523889812;6364.204474440695;6368.267649220399;6379.170707807955;6383.553151279366;5986.102084687332;6019.307779605314;6030.03519436888;6030.638161710223;6098.054801505774;6166.7224974876235;6203.348648391854;6214.161088253028;6218.471429928416;6284.37156284377;6185.567010309325;6189.659568723763;6200;6203.47270482005;6212.627242365382;6134.4172303631085;6136;6229.875402492015;6254.655993920378;6260.373962603703;4838.927416088762;4892.559669629679;4894.804207831647;4926.704397736147;4937.1113199624415;4386.451693538308;4451.310046942729;4459.710118842329;4468.776561171929;4485.25993211123;5736;5744.741486633028;5751.942480575224;5772.769089236467;5806.738696758656;6225.875482490324;6327.335629758901;6335.936640633627;6338;6341.143945567382;5558;5637.802676906371;5645.80239691617;5723.771049157987;5756.194132821408;4575.656825738083;5360.812371566976;5418.485243901823;5447.428020057916;5467.589930755209;6305.211848518935;6329.620222786648;6331.176946996861;6334.1765570475545;6347.238331400262;5667.829965101054;5684.914726279027;6114.633122012693;6129.786714140983;6154.692265386802;6242.500599951902;6274.372562743689;6334.968325158345;6340.080688300263;6372.936270637234;5376.865578360573;6057.909576276313;6178.104174894705;6205.131281620486;6206.72069756853;6176.629402235881;6218.409251121137;6238.719257633508;6254.687265636791;6268.717907694256;5908;5962.4633782959745;6086.878262434638;6091.390860913873;6119.938800612115;5833.912491312549;5995;6053.515718742402;6098.129681996294;6104.06507294887;5873.03094989322;5924.200232968667;5940.8811823764145;5949.315828679757;5955.40445955401;5219.373675159005;5907.49786268172;6045.274567051982;6118.60229085108;6127.877442451037;5830.76676932918;5889.970550147222;5930.52555795544;5933.139694744152;6006.579539432372 12:41:15 INFO - PROCESS | 1697 | |1;ext.html;497.08784380661484;515.7189331814161;527.1776029394144;539.1562205148941;564.9887002259952;26961;30788;31051.844740776294;32593.67406325936;32668.346633067325;42123.57876421235;43166.35250471238;43235.35146972798;45543.77228113855;45673.54326456734;9816.705498835028;9944.900550994505;9986.40081595103;9990.900090999106;10012;8733.956330218372;9742.51287435629;9862.556184971658;9867.111959923606;9876.90123098767;11955.7608847823;12125.151239413244;12140.453679584396;12368.443420046075;12395.814062789066;12086.818697719496;12185.512579496899;12193;12194.085443591766;12205.38973051344;11962.1626486146;12030.63908082759;12064.879351206464;12065.517379304818;12066.638000859988;40747.38878916819;41135.177296453905;41168.176636467106;41182.38226426606;41275.793621032004;3371.106656735967;3403.540676037582;3636.327279453283;4016.8995775105564;4145.17096580685;30853.38293234145;31830.84084579562;31854.522182167286;31972;32070.358592828245;3165.5409965554863;3269.8038117713013;3284.310294838087;3399.9660003399895;3424.503447000196;513065;514223;515914;516407;518464;28711.425771484453;34071;57133.71433142808;61367.693161533905;61718;34407.82796086054;34588;34588.135296617285;34602.48096278558;34640.133996650286;14885.479008234877;15003.474878379204;15009;15021.849781502264;15067.321970511353;12762.489500420064;12860.678483037998;12874.678133046562;12881.613551593467;12892.419841107172;7240.565566066053;7395.001674773836;7703.268189521987;7717.344025757761;7818.061832580013;2724.6866610339666;3248;3287.967120328766;3298.0600528849163;3306.7189288910713;956.4357029352715;968.801395713877;977.0717818072859;1001.7295330260789;1073.1790180511914;28108.859455703;30146.547801782992;31669.841650791597;31676;32563.278324643266;1029;1030.8866024737272;1038.9220808439397;1074.9946250268856;1105.309181761399;13810;15290.923545382424;15489.225538723244;15684;15713.68572628541;659656;666370;666648;668951;670096;49847.252291215664;57019.71490142606;57039.429605703415;57714.13428798571;58082.41917580854;1340.6380277325068;1362.8160198373291;1386.3761307411705;1388.5903658420589;1388.7361401333715;30484.542731859485;31496.685033149377;31744.04767856968;32025.359492809548;32037.160714107973;52335.95328093493;53128.46871531235;53221.66945826385;53272.73363633157;53293.46706532885;448458;459496;461052;461260;546217;40905.59094409137;41851;42091.15817683691;42140.367894480994;42272;46977.2953405706;47097.82255443641;47194;47430.28854567115;48353.274700878814;36310.8184459076;37758.43362349622;38265.23469530649;39443.605563943995;39951.80144595667;37693;37874.81062594779;38252;38697;39385.40921886117;36229.818850905576;36774.44838327374;37142.25715485621;37866.621333787414;38019;31748.36503269968;33708.110576660096;33890.49164262489;33960;34361;20892.164313427293;21180.4704882373;24832.37919051966;25592.360190995376;25735 12:41:15 INFO - PROCESS | 1697 | |2;jquery.html;353.4239190120104;368.8413981987744;373.42306137018306;382.95213098362694;398.4640658314566;15314.846851531482;16078.954867933591;19748.802511974875;20384.388468345936;20541.38375848725;19623.31318403856;19947.608906485897;20225.696614550794;20324.288649897255;20807.895960520214;5645.237892884449;5913.940860591393;6180.351063138373;6184.752609895599;6187.535934804885;6042.516598672096;6086.6348019119;6117.908231376533;6145.60053596517;6170.598911070766;5835.649861008332;6058.242719660043;6109.35851735568;6119.143319935212;6120.449159575637;6058.909116363258;6099.115628233882;6107.389261073901;6107.969460152715;6112.174856394375;5211.661242019257;5472.425395333512;5543.972280138614;5570.164475328733;5576.386597474275;12826.23042617446;13052.15161014531;15526.534203973899;25105.748942510523;25469.363265918317;2049.7745248022707;2080.1679328268815;2157.8273738100916;2308.4113551044543;2779.63864697588;9351.906480935171;9874.851877221847;10118.797624047551;10228.232882533839;10295.948520257425;1684.3936182974119;1763.5150333658219;1827.9543011424687;1839.9264029438807;1863.6861012985812;116487;125841;126971.3651431737;129260;129867.99999999905;9537.712408824858;10670.679879603624;10810.29733067348;11138.944305278585;11233.831492527619;13956.302184890716;13985.160890346611;13989.86010139906;14013.299335033207;14021.929890350688;8222.17778222173;8532.744017679479;8581.828363432696;8632.568371581396;8656.138930130051;8497.957510212409;8510.404271700922;8515.957420212859;8690.522021288889;8715.607797649121;4334.609885110355;4405.867823965222;4484.955150448519;4501.099780044005;4502.369668246448;1674.7571602117625;1851.4630757080297;1909.994505768965;1911.3788018893915;1937.7093435984434;838.9706360277484;847.7033038436621;850.2985037344216;947.9336446448682;976.2336565795958;20556.17775288972;21061.47346316324;21266.78733212679;21391.25130620421;21442.571148576943;754.3361841579375;921.9216366608779;937.2970272295778;941.8728471656376;942.2462030375672;10449.425281609585;10821.458927053616;10898.945505272422;10930.540628358714;10946.397948112772;560138;561003;574310;574357;576095;126680.36659816642;134300;136999.31500342436;137262;137994;935.2658163341744;950.0499500499501;976.5995941663883;977.6675930183629;980.4019548075668;18646.81353186505;18922.3377181798;18977.810221898155;18990.525236869187;18999.145038473027;27532.860821532704;44438;55731.16403254036;55928.88142237214;56553.71723141358;62196.75606487935;62205.75588488296;62544;66280.6685966567;68592;6189.381061893955;7779;7847.686092556234;8037.839243214986;8067.354611631173;9224.354295199271;11127.59570297976;12928.676783080498;13711.520096796565;13824.101433406797;11315.830262545904;11364.43177841088;11535.5962541314;11554.48004839801;11802.94098529502;9652.648629191779;11447.942760286145;11459.427028648368;11566.537338506701;11844.466998984894;11567.074634029426;11722.58970936013;11965.102617303912;12016.158868879094;12098.455569499569;8117.457683040204;8151.877721834058;8171.060328062349;8237.723152911309;8301.6679332826;8176.100628930932;10811.45942702846;11186.161037922026;11570.132240082195;11715.297082175097 12:41:15 INFO - PROCESS | 1697 | |3;mootools.html;1363.5091367107834;1366.753984282829;1557.844215578442;1687.9662406751866;1928.6142771445714;7718.961405192973;8580.828383432328;8811.383203175781;8818.941726992769;8895.644174233039;8640.91359086409;8726.52004139772;8731.694390696326;8740.51927144007;8756.824863502727;2370.798482129017;2398.7481314461993;2403.0868270057354;2403.1949296985445;2404.7955923746417;2344.8944797484155;2364.255259593231;2396.400899775056;2400.6519054737055;2401.2916189724074;2360.4216966843146;2384.654225137354;2388.0925248405583;2397.496525729599;2406.4465173010217;2005.8767090429405;2269.693591365161;2357.4695693468916;2375.406148462884;2377.227401094651;2315.3401280635107;2344.3904584808074;2359.1389142962867;2360.8465449745713;2362.2519335691636;8615;8704.390692651517;8732.170443807829;8804.64781408743;8812.38313318068;5211;5361.651492653004;5373.650712703701;5379.892402151974;5384.138537833927;3607.5310209672575;3628.238070005276;3637.963620363789;3684.5946945836204;3714.1271801126713;1076.5048077884182;1082.5453309609982;1087.0162502934857;1107.19175002248;1107.9556817727282;11395.943020284929;11481.311121332663;11536.653900383002;11562.381266622595;11563.942180289045;4391.473023237232;4447.777611119431;4631.907361852744;4632.62938964882;4649.651276154234;7646.923530764732;9091.499967501852;9101.226395756436;9106.86339704905;9133;5224.947750522446;5227.738613069331;5230.032443997872;5235.65968212065;5236.0575096483;4249.341352090369;4299.763513006815;4419.469663640384;4549.6360291176625;4908.067467181224;3802.049487628093;3810.923781524354;3826.7512611680154;3858.7266202153223;3868.9806550967064;1167.649705088487;1173.6185739634652;1179.8466199394195;1189.1378750406059;1189.2804853063772;857.652650676477;880.5245167609547;882.6954700628273;897.3539051882634;926.0785518409226;5566.944330556724;5675.290588676416;5687.800926967537;5690.886182276414;5812.488752924271;773.6827900560743;925.7454200094893;928.1414691410419;935.8970513243538;961.427950369529;3123.7188653020885;3738.4579236010627;3866.74866133706;3931.76409415436;4004;9971.501424928725;10677.83983240252;10726.034656880918;10827.566897324019;10880.12958963281;10490.842637360445;10831.404272765074;10831.566737330577;10843.295185812896;10849.566017359217;6168.383161683885;8261.297789687944;8268.214519620626;8272.462289951134;8281.37889658278;5635.830925072254;5682.687452190004;5774.9133762994425;5799.391063938226;5829.271341082364;9865.704028879145;9954;10009.74975625586;10030.799384012425;10041.196704263495;9267.212286955684;9777.951110244403;10094;10116.696499105039;10335.483225838527;6293.811185664437;6987.965060174666;7053.153621565445;7129.964350178216;7139.32176443237;8206;8227.177282271725;8255.463394879314;8303.792405189919;8317.875231871405;7502.962485187539;7535.284148005931;7606.505577137468;7607.35337496319;7620.314171724572;6243.500519958483;7159.105111861018;7309.744158954411;7399.630018498946;7438.925610743823;6870.7595234166565;6926.168859736662;6984.546004509691;6987.266337034538;7003.1596208453275;4854.83008094715;4873.610111191168;4875.829345972874;4886.218205087169;5055.090083784954;3739.700823934133;4849.539293767087;4901.656884018084;5042.823501177588;5082.923756143735 12:41:15 INFO - PROCESS | 1697 | |4;prototype.html;297.02723580274585;303.4992262766435;313.74900079936043;316.44463965740124;320.24741856636894;4450.688451808374;4634.841289677581;5223.82463945612;5336.91994620081;5373.140297552392;4823.903521929559;5399.946000539993;5412.404635490103;5412.512873841353;5424.972875135629;4138.813753381105;4166.437530933315;4180.184863951531;4180.728252663567;4194.958050419495;3924.941125883114;4167.166566686675;4173.728707634009;4203.243416185085;4204.831806727742;3787.696984241254;3860.9189426960497;3959.0696186396176;3974.2250261198933;4161.25097482453;3639.3085313790293;3728.5339332583426;3908.4918960534947;4012.739171953828;4164.708470407073;3133.9843300783577;3568.964310356889;3584.62361452049;3610.6208848070837;3615.783053016801;4905.386826646669;5147.6911385317;5200.895982080375;5288.206768984683;5288.788448462057;3914.5498267699295;4211.7472951623;4702.952970470285;4717.905641887143;4776.259679749644;5385.434529374398;5691.544676425876;5726.770929162829;5751.827445176651;5767.1349297605275;3691.7969511676847;3950.170464202521;3955.358526211619;3956.8219430125428;3964.940525892114;4799.448063472675;5593.524550413179;5978.940210597882;5986.401359864022;5998;3526.6297038811067;3950.2692001979726;4023.637872591481;4054.2094291612852;4059.939100913489;4107.67138628909;4109.383592461095;4114.876553703393;4121;4123.8556650517085;4636.342691314086;4710.940038491381;4715.74063426508;4729.226540047483;4729.43246810385;4699.953000469952;5574.247476590689;5634.661920284796;5694.4590263924865;5790.305163380337;1367.924764137982;1378.2419669182111;1380.7997840313299;1392.5404616476742;1396.3576754692751;725.528406535756;794.7933537280348;846.4836449765638;858.7548055319811;859.2610355094614;3885.980570097188;4067.633912947849;4235.322348424237;4517.503074661785;4534.455865296186;4356.324769660708;4612.30815377696;4746.477887432379;4859.781309841065;4934.925976110289;4150.937735933963;4282.935755963663;4288.614024737726;4389.604935555815;4441.489893436247;2174.532475517771;2226.5212979209546;2497.9125730599344;2547.3504256414494;2627.69781475129;4506.526814684476;4591.770411479412;4699.157010008896;4913.557779799764;5065.746712664352;4486;4494.640428765691;4578;4673.38290557289;5055.570276526537;4801.575204521764;4864.813547126638;6153.2616086068165;6160.507159427235;6199.5660303779205;5963.612365196248;5977;6000.8799824004145;6015.578909476295;6029.1559181715475;5086.237064440215;5841.94158058414;5886.911696324473;5907.940920590739;5911.113333000085;5791.131330300489;5880.500157486662;6003.279606447254;6127.632342059491;6146.723397447036;3335.6497567755036;3802.7908465033584;4039.212943069273;4081.9591804082766;4106.891139392287;5848.01751106947;5896.233489646319;5905.852353691193;5905.940940590539;5947.680404642107;4931.914978704679;5134.76893539799;5205.323307969939;5756.251687280626;5765.740541675718;5689.743961521659;5691.78414195978;5705.34388545306;5723.05262789672;5768.855778605568;4821.180399332192;5623.8875222496135;5660.462256085665;5675.262215911905;5676.943230567642;5017.84946451607;5333.43998880112;5739.368669446441;5739.5695322850115;5760.222369980167;4910.582600478856;5188.884389856199;5214.217867319932;5388.515033646991;5734.684592347461 12:41:15 INFO - PROCESS | 1697 | |5;yui.html;704.9144317750664;731.1372580355182;779.9922000779992;817.6157206113124;824.3775949158382;13505.932470337659;15113.546593602196;16265.268062937166;16320.75518867215;16330.18349082544;14696.706065878703;14764.261786910665;14797;14818;14838;697.2817997462622;716.3946465236867;718.3893690363202;719.8776208044619;864.6238886084542;694.4444444444449;695.8817001109799;851.7998270406449;875.9474431534128;880.6521424037502;905.1175104273347;914.6112902016649;917.8485549884261;919.7608621758324;921.3320342751518;913.8583519554413;914.9359544831831;916.6104405627648;917.9403338782957;920.0477505781485;550.1088237055994;672.1195234243157;672.4351544702473;675.4191395399952;676.5839009009424;12392;12492.75014499714;12517.812232816515;12530.122891397607;14474.927625361912;12293.385330733428;14134.36395362211;14894.180820054893;15009.973653556739;15083.4720784772;11713.590024349192;11893.40532973348;12003.819942700866;12134.878651213552;12474.625761227178;3804.9429258561145;3820.4842346283176;3847.634474724897;3882.3788193889127;3999.3001224785835;121552;121659.78340216573;121847;121856;122040.77959220383;10968;11210.775784484265;11322.056265371062;11403.543858245743;11636.53453861836;24187;24215.394615134763;24221;24277.271681849572;24279.757202428103;7732.458727888994;7734.690612375442;7959.960200199078;8020.598970051474;8085.716999904975;4438.844640437569;4443.800028998703;4451.577100175478;4492.393526873896;4669.813207471731;3591.425371940529;3723.069232691827;3724.0875985383755;3726.9440958385644;3728.5712143103347;548.5830768615881;640.3180612647516;661.9801405957829;669.397542212013;670.3196255800367;608.342989571262;646.4569761400446;646.7025168422484;647.6373230990661;664.4219529009792;9123.863142052875;9146.085391460801;9327.860082098774;9390.210992393928;9487.715368538955;467.96490263230396;494.51043466967445;503.74309102358063;570.5920267009109;626.0952923026274;4200.789960502024;4570.9314360284625;4572.74849883253;4580.7938642761155;4655.441347038377;105475;120851;122216.38891805484;127534;156505;86734.1326586726;102194;111966.44016779865;120046;122164;88253.55873220593;103826;106508.9349106499;106537;106874;9338.439693618404;9456.574454149717;9533.475658838553;9554.57004434816;9561.091696288842;60085.699571501864;60206.39793602183;60500.39499604948;61233.38766612277;61704;85449;87381;89330;92371;93628.53185734028;10751.6236931707;12665.240085594894;13111.868881311064;13429.529966451128;13606.523771667944;12303.32331721728;14696.779880068798;14726.852731472549;14983.850161498245;15437.22813859325;13752.381142848795;13800.664816079132;13912.791308130589;13952.232627205602;14208.502702405367;13553;13554.796678050034;13609.863901360859;13662.590122296348;13720.862791372358;13124.278164701032;13282.601521954357;13423.395947182595;13459.932700336436;13537.593872183455;8335.874961875697;10418.062374386342;10783.4414643166;11247.778737429417;11695.941520292345;10940.671779846618;11334.14993875446;11452.94273528627;11632.825507617561;11674.649760507198 12:41:15 INFO - PROCESS | 1697 | __end_tp_report 12:41:15 INFO - PROCESS | 1697 | __start_cc_report 12:41:15 INFO - PROCESS | 1697 | _x_x_mozilla_cycle_collect,1439 12:41:15 INFO - PROCESS | 1697 | __end_cc_report 12:41:15 INFO - PROCESS | 1697 | __startTimestamp1473190875479__endTimestamp 12:41:15 INFO - PROCESS | 1697 | 12:41:15 INFO - PROCESS | 1697 | ------- Summary: start ------- 12:41:15 INFO - PROCESS | 1697 | Number of tests: 6 12:41:15 INFO - PROCESS | 1697 | 12:41:15 INFO - PROCESS | 1697 | [#0] dojo.html Cycles:165 Average:5688.31 Median:5873.03 stddev:678.53 (11.6%) stddev-sans-first:638.56 12:41:15 INFO - PROCESS | 1697 | Values: 2690.6 3306.6 3364.1 3397.6 3409.2 5586.2 5660.7 5681.5 5713.3 5763.5 4877.8 5667.1 5737.6 5802.5 5822.4 5122.8 5187.8 5191.9 5234.6 5280.1 5293.9 5304.7 5305.3 5309.0 5363.4 4276.7 5186.1 5214.8 5239.6 5247.3 5225.1 5246.5 5248.5 5249.6 5252.1 4271.4 4325.6 5101.2 5179.5 5205.5 5638.3 5639.4 5683.8 5713.3 5775.4 6224.1 6226.3 6239.7 6244.7 6249.4 5136.7 6089.6 6100.8 6231.4 6336.7 5688.8 5708.1 5715.3 5747.2 5776.1 6343.3 6364.2 6368.3 6379.2 6383.6 5986.1 6019.3 6030.0 6030.6 6098.1 6166.7 6203.3 6214.2 6218.5 6284.4 6185.6 6189.7 6200.0 6203.5 6212.6 6134.4 6136.0 6229.9 6254.7 6260.4 4838.9 4892.6 4894.8 4926.7 4937.1 4386.5 4451.3 4459.7 4468.8 4485.3 5736.0 5744.7 5751.9 5772.8 5806.7 6225.9 6327.3 6335.9 6338.0 6341.1 5558.0 5637.8 5645.8 5723.8 5756.2 4575.7 5360.8 5418.5 5447.4 5467.6 6305.2 6329.6 6331.2 6334.2 6347.2 5667.8 5684.9 6114.6 6129.8 6154.7 6242.5 6274.4 6335.0 6340.1 6372.9 5376.9 6057.9 6178.1 6205.1 6206.7 6176.6 6218.4 6238.7 6254.7 6268.7 5908.0 5962.5 6086.9 6091.4 6119.9 5833.9 5995.0 6053.5 6098.1 6104.1 5873.0 5924.2 5940.9 5949.3 5955.4 5219.4 5907.5 6045.3 6118.6 6127.9 5830.8 5890.0 5930.5 5933.1 6006.6 12:41:15 INFO - PROCESS | 1697 | 12:41:15 INFO - PROCESS | 1697 | [#1] ext.html Cycles:180 Average:67791.84 Median:34621.31 stddev:149510.01 (431.8%) stddev-sans-first:149844.05 12:41:15 INFO - PROCESS | 1697 | Values: 497.1 515.7 527.2 539.2 565.0 26961.0 30788.0 31051.8 32593.7 32668.3 42123.6 43166.4 43235.4 45543.8 45673.5 9816.7 9944.9 9986.4 9990.9 10012.0 8734.0 9742.5 9862.6 9867.1 9876.9 11955.8 12125.2 12140.5 12368.4 12395.8 12086.8 12185.5 12193.0 12194.1 12205.4 11962.2 12030.6 12064.9 12065.5 12066.6 40747.4 41135.2 41168.2 41182.4 41275.8 3371.1 3403.5 3636.3 4016.9 4145.2 30853.4 31830.8 31854.5 31972.0 32070.4 3165.5 3269.8 3284.3 3400.0 3424.5 513065.0 514223.0 515914.0 516407.0 518464.0 28711.4 34071.0 57133.7 61367.7 61718.0 34407.8 34588.0 34588.1 34602.5 34640.1 14885.5 15003.5 15009.0 15021.8 15067.3 12762.5 12860.7 12874.7 12881.6 12892.4 7240.6 7395.0 7703.3 7717.3 7818.1 2724.7 3248.0 3288.0 3298.1 3306.7 956.4 968.8 977.1 1001.7 1073.2 28108.9 30146.5 31669.8 31676.0 32563.3 1029.0 1030.9 1038.9 1075.0 1105.3 13810.0 15290.9 15489.2 15684.0 15713.7 659656.0 666370.0 666648.0 668951.0 670096.0 49847.3 57019.7 57039.4 57714.1 58082.4 1340.6 1362.8 1386.4 1388.6 1388.7 30484.5 31496.7 31744.0 32025.4 32037.2 52336.0 53128.5 53221.7 53272.7 53293.5 448458.0 459496.0 461052.0 461260.0 546217.0 40905.6 41851.0 42091.2 42140.4 42272.0 46977.3 47097.8 47194.0 47430.3 48353.3 36310.8 37758.4 38265.2 39443.6 39951.8 37693.0 37874.8 38252.0 38697.0 39385.4 36229.8 36774.4 37142.3 37866.6 38019.0 31748.4 33708.1 33890.5 33960.0 34361.0 20892.2 21180.5 24832.4 25592.4 25735.0 12:41:15 INFO - PROCESS | 1697 | 12:41:15 INFO - PROCESS | 1697 | [#2] jquery.html Cycles:180 Average:33833.48 Median:361.13 stddev:95566.71 (26463.0%) stddev-sans-first:95801.73 12:41:15 INFO - PROCESS | 1697 | Values: 353.4 368.8 373.4 383.0 398.5 15314.8 16079.0 19748.8 20384.4 20541.4 19623.3 19947.6 20225.7 20324.3 20807.9 5645.2 5913.9 6180.4 6184.8 6187.5 6042.5 6086.6 6117.9 6145.6 6170.6 5835.6 6058.2 6109.4 6119.1 6120.4 6058.9 6099.1 6107.4 6108.0 6112.2 5211.7 5472.4 5544.0 5570.2 5576.4 12826.2 13052.2 15526.5 25105.7 25469.4 2049.8 2080.2 2157.8 2308.4 2779.6 9351.9 9874.9 10118.8 10228.2 10295.9 1684.4 1763.5 1828.0 1839.9 1863.7 116487.0 125841.0 126971.4 129260.0 129868.0 9537.7 10670.7 10810.3 11138.9 11233.8 13956.3 13985.2 13989.9 14013.3 14021.9 8222.2 8532.7 8581.8 8632.6 8656.1 8498.0 8510.4 8516.0 8690.5 8715.6 4334.6 4405.9 4485.0 4501.1 4502.4 1674.8 1851.5 1910.0 1911.4 1937.7 839.0 847.7 850.3 947.9 976.2 20556.2 21061.5 21266.8 21391.3 21442.6 754.3 921.9 937.3 941.9 942.2 10449.4 10821.5 10898.9 10930.5 10946.4 560138.0 561003.0 574310.0 574357.0 576095.0 126680.4 134300.0 136999.3 137262.0 137994.0 935.3 950.0 976.6 977.7 980.4 18646.8 18922.3 18977.8 18990.5 18999.1 27532.9 44438.0 55731.2 55928.9 56553.7 62196.8 62205.8 62544.0 66280.7 68592.0 6189.4 7779.0 7847.7 8037.8 8067.4 9224.4 11127.6 12928.7 13711.5 13824.1 11315.8 11364.4 11535.6 11554.5 11802.9 9652.6 11447.9 11459.4 11566.5 11844.5 11567.1 11722.6 11965.1 12016.2 12098.5 8117.5 8151.9 8171.1 8237.7 8301.7 8176.1 10811.5 11186.2 11570.1 11715.3 12:41:15 INFO - PROCESS | 1697 | 12:41:15 INFO - PROCESS | 1697 | [#3] mootools.html Cycles:180 Average:5600.83 Median:4904.86 stddev:3147.12 (64.2%) stddev-sans-first:3139.84 12:41:15 INFO - PROCESS | 1697 | Values: 1363.5 1366.8 1557.8 1688.0 1928.6 7719.0 8580.8 8811.4 8818.9 8895.6 8640.9 8726.5 8731.7 8740.5 8756.8 2370.8 2398.7 2403.1 2403.2 2404.8 2344.9 2364.3 2396.4 2400.7 2401.3 2360.4 2384.7 2388.1 2397.5 2406.4 2005.9 2269.7 2357.5 2375.4 2377.2 2315.3 2344.4 2359.1 2360.8 2362.3 8615.0 8704.4 8732.2 8804.6 8812.4 5211.0 5361.7 5373.7 5379.9 5384.1 3607.5 3628.2 3638.0 3684.6 3714.1 1076.5 1082.5 1087.0 1107.2 1108.0 11395.9 11481.3 11536.7 11562.4 11563.9 4391.5 4447.8 4631.9 4632.6 4649.7 7646.9 9091.5 9101.2 9106.9 9133.0 5224.9 5227.7 5230.0 5235.7 5236.1 4249.3 4299.8 4419.5 4549.6 4908.1 3802.0 3810.9 3826.8 3858.7 3869.0 1167.6 1173.6 1179.8 1189.1 1189.3 857.7 880.5 882.7 897.4 926.1 5566.9 5675.3 5687.8 5690.9 5812.5 773.7 925.7 928.1 935.9 961.4 3123.7 3738.5 3866.7 3931.8 4004.0 9971.5 10677.8 10726.0 10827.6 10880.1 10490.8 10831.4 10831.6 10843.3 10849.6 6168.4 8261.3 8268.2 8272.5 8281.4 5635.8 5682.7 5774.9 5799.4 5829.3 9865.7 9954.0 10009.7 10030.8 10041.2 9267.2 9778.0 10094.0 10116.7 10335.5 6293.8 6988.0 7053.2 7130.0 7139.3 8206.0 8227.2 8255.5 8303.8 8317.9 7503.0 7535.3 7606.5 7607.4 7620.3 6243.5 7159.1 7309.7 7399.6 7438.9 6870.8 6926.2 6984.5 6987.3 7003.2 4854.8 4873.6 4875.8 4886.2 5055.1 3739.7 4849.5 4901.7 5042.8 5082.9 12:41:15 INFO - PROCESS | 1697 | 12:41:15 INFO - PROCESS | 1697 | [#4] prototype.html Cycles:180 Average:4475.95 Median:4737.96 stddev:1396.44 (29.5%) stddev-sans-first:1364.67 12:41:15 INFO - PROCESS | 1697 | Values: 297.0 303.5 313.7 316.4 320.2 4450.7 4634.8 5223.8 5336.9 5373.1 4823.9 5399.9 5412.4 5412.5 5425.0 4138.8 4166.4 4180.2 4180.7 4195.0 3924.9 4167.2 4173.7 4203.2 4204.8 3787.7 3860.9 3959.1 3974.2 4161.3 3639.3 3728.5 3908.5 4012.7 4164.7 3134.0 3569.0 3584.6 3610.6 3615.8 4905.4 5147.7 5200.9 5288.2 5288.8 3914.5 4211.7 4703.0 4717.9 4776.3 5385.4 5691.5 5726.8 5751.8 5767.1 3691.8 3950.2 3955.4 3956.8 3964.9 4799.4 5593.5 5978.9 5986.4 5998.0 3526.6 3950.3 4023.6 4054.2 4059.9 4107.7 4109.4 4114.9 4121.0 4123.9 4636.3 4710.9 4715.7 4729.2 4729.4 4700.0 5574.2 5634.7 5694.5 5790.3 1367.9 1378.2 1380.8 1392.5 1396.4 725.5 794.8 846.5 858.8 859.3 3886.0 4067.6 4235.3 4517.5 4534.5 4356.3 4612.3 4746.5 4859.8 4934.9 4150.9 4282.9 4288.6 4389.6 4441.5 2174.5 2226.5 2497.9 2547.4 2627.7 4506.5 4591.8 4699.2 4913.6 5065.7 4486.0 4494.6 4578.0 4673.4 5055.6 4801.6 4864.8 6153.3 6160.5 6199.6 5963.6 5977.0 6000.9 6015.6 6029.2 5086.2 5841.9 5886.9 5907.9 5911.1 5791.1 5880.5 6003.3 6127.6 6146.7 3335.6 3802.8 4039.2 4082.0 4106.9 5848.0 5896.2 5905.9 5905.9 5947.7 4931.9 5134.8 5205.3 5756.3 5765.7 5689.7 5691.8 5705.3 5723.1 5768.9 4821.2 5623.9 5660.5 5675.3 5676.9 5017.8 5333.4 5739.4 5739.6 5760.2 4910.6 5188.9 5214.2 5388.5 5734.7 12:41:15 INFO - PROCESS | 1697 | 12:41:15 INFO - PROCESS | 1697 | [#5] yui.html Cycles:180 Average:23738.65 Median:3727.76 stddev:36710.83 (984.8%) stddev-sans-first:36773.07 12:41:15 INFO - PROCESS | 1697 | Values: 704.9 731.1 780.0 817.6 824.4 13505.9 15113.5 16265.3 16320.8 16330.2 14696.7 14764.3 14797.0 14818.0 14838.0 697.3 716.4 718.4 719.9 864.6 694.4 695.9 851.8 875.9 880.7 905.1 914.6 917.8 919.8 921.3 913.9 914.9 916.6 917.9 920.0 550.1 672.1 672.4 675.4 676.6 12392.0 12492.8 12517.8 12530.1 14474.9 12293.4 14134.4 14894.2 15010.0 15083.5 11713.6 11893.4 12003.8 12134.9 12474.6 3804.9 3820.5 3847.6 3882.4 3999.3 121552.0 121659.8 121847.0 121856.0 122040.8 10968.0 11210.8 11322.1 11403.5 11636.5 24187.0 24215.4 24221.0 24277.3 24279.8 7732.5 7734.7 7960.0 8020.6 8085.7 4438.8 4443.8 4451.6 4492.4 4669.8 3591.4 3723.1 3724.1 3726.9 3728.6 548.6 640.3 662.0 669.4 670.3 608.3 646.5 646.7 647.6 664.4 9123.9 9146.1 9327.9 9390.2 9487.7 468.0 494.5 503.7 570.6 626.1 4200.8 4570.9 4572.7 4580.8 4655.4 105475.0 120851.0 122216.4 127534.0 156505.0 86734.1 102194.0 111966.4 120046.0 122164.0 88253.6 103826.0 106508.9 106537.0 106874.0 9338.4 9456.6 9533.5 9554.6 9561.1 60085.7 60206.4 60500.4 61233.4 61704.0 85449.0 87381.0 89330.0 92371.0 93628.5 10751.6 12665.2 13111.9 13429.5 13606.5 12303.3 14696.8 14726.9 14983.9 15437.2 13752.4 13800.7 13912.8 13952.2 14208.5 13553.0 13554.8 13609.9 13662.6 13720.9 13124.3 13282.6 13423.4 13459.9 13537.6 8335.9 10418.1 10783.4 11247.8 11695.9 10940.7 11334.1 11452.9 11632.8 11674.6 12:41:15 INFO - PROCESS | 1697 | -------- Summary: end -------- 12:41:15 INFO - PROCESS | 1697 | 12:41:16 INFO - TEST-INFO | 1697: exit 0 12:41:16 INFO - TEST-OK | dromaeo_css | took 1117349ms 12:41:16 INFO - TEST-START | kraken 12:41:16 INFO - operating with platform_type : mac_ 12:41:16 INFO - Initialising browser for kraken test... 12:41:16 INFO - TEST-INFO | started process 1815 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmp4CjWkn/profile http://localhost:49271/getInfo.html) 12:41:18 INFO - PROCESS | 1815 | __metrics Screen width/height:1600/1200 12:41:18 INFO - PROCESS | 1815 | colorDepth:24 12:41:18 INFO - PROCESS | 1815 | Browser inner width/height: 1024/689 12:41:18 INFO - PROCESS | 1815 | __metrics 12:41:18 INFO - PROCESS | 1815 | 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:41:18 INFO - PROCESS | 1815 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:41:19 INFO - TEST-INFO | 1815: exit 0 12:41:19 INFO - Browser initialized. 12:41:19 INFO - Running cycle 1/1 for kraken test... 12:41:19 INFO - TEST-INFO | started process 1817 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/T/tmp4CjWkn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:31 INFO - PROCESS | 1817 | RSS: Main: 243363840 12:41:31 INFO - PROCESS | 1817 | 12:41:34 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 12:41:35 INFO - PROCESS | 1817 | RSS: Main: 293367808 12:41:35 INFO - PROCESS | 1817 | 12:41:37 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 12:41:38 INFO - PROCESS | 1817 | RSS: Main: 289488896 12:41:38 INFO - PROCESS | 1817 | 12:41:41 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 12:41:42 INFO - PROCESS | 1817 | RSS: Main: 378535936 12:41:42 INFO - PROCESS | 1817 | 12:41:44 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 12:41:45 INFO - PROCESS | 1817 | RSS: Main: 279601152 12:41:45 INFO - PROCESS | 1817 | 12:41:47 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:41:47 INFO - PROCESS | 1817 | RSS: Main: 283566080 12:41:47 INFO - PROCESS | 1817 | 12:41:50 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 12:41:51 INFO - PROCESS | 1817 | RSS: Main: 322314240 12:41:51 INFO - PROCESS | 1817 | 12:41:54 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 12:41:54 INFO - PROCESS | 1817 | RSS: Main: 323719168 12:41:54 INFO - PROCESS | 1817 | 12:41:57 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 12:41:58 INFO - PROCESS | 1817 | RSS: Main: 321839104 12:41:58 INFO - PROCESS | 1817 | 12:41:59 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:42:00 INFO - PROCESS | 1817 | RSS: Main: 299941888 12:42:00 INFO - PROCESS | 1817 | 12:42:02 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:42:02 INFO - PROCESS | 1817 | RSS: Main: 306233344 12:42:02 INFO - PROCESS | 1817 | 12:42:05 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:42:05 INFO - PROCESS | 1817 | RSS: Main: 312868864 12:42:05 INFO - PROCESS | 1817 | 12:42:08 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:42:09 INFO - PROCESS | 1817 | RSS: Main: 315502592 12:42:09 INFO - PROCESS | 1817 | 12:42:12 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:42:12 INFO - PROCESS | 1817 | RSS: Main: 316866560 12:42:12 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 12:42:14 INFO - PROCESS | 1817 | RSS: Main: 360333312 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | __start_tp_report 12:42:14 INFO - PROCESS | 1817 | _x_x_mozilla_page_load 12:42:14 INFO - PROCESS | 1817 | _x_x_mozilla_page_load_details 12:42:14 INFO - PROCESS | 1817 | |i|pagename|runs| 12:42:14 INFO - PROCESS | 1817 | |0;ai-astar;157;168;153;114;113;112;126;110;109;113 12:42:14 INFO - PROCESS | 1817 | |1;audio-beat-detection;147;111;134;163;111;154;127;108;160;112 12:42:14 INFO - PROCESS | 1817 | |2;audio-dft;191;181;185;183;184;183;180;183;184;182 12:42:14 INFO - PROCESS | 1817 | |3;audio-fft;77;79;98;190;82;48;69;87;160;71 12:42:14 INFO - PROCESS | 1817 | |4;audio-oscillator;81;83;137;70;88;101;150;72;69;115 12:42:14 INFO - PROCESS | 1817 | |5;imaging-gaussian-blur;88;84;90;84;87;85;88;85;88;86 12:42:14 INFO - PROCESS | 1817 | |6;imaging-darkroom;95;96;96;96;96;96;97;96;96;96 12:42:14 INFO - PROCESS | 1817 | |7;imaging-desaturate;83;83;102;139;131;84;85;85;85;83 12:42:14 INFO - PROCESS | 1817 | |8;json-parse-financial;54;53;54;53;53;54;55;53;53;53 12:42:14 INFO - PROCESS | 1817 | |9;json-stringify-tinderbox;66;63;73;72;75;70;69;70;69;73 12:42:14 INFO - PROCESS | 1817 | |10;stanford-crypto-aes;86;89;85;89;89;88;86;88;91;117 12:42:14 INFO - PROCESS | 1817 | |11;stanford-crypto-ccm;142;142;142;140;136;139;138;151;186;152 12:42:14 INFO - PROCESS | 1817 | |12;stanford-crypto-pbkdf2;172;174;170;173;171;171;173;171;171;171 12:42:14 INFO - PROCESS | 1817 | |13;stanford-crypto-sha256-iterative;67;68;67;69;79;86;70;58;84;80 12:42:14 INFO - PROCESS | 1817 | __end_tp_report 12:42:14 INFO - PROCESS | 1817 | __start_cc_report 12:42:14 INFO - PROCESS | 1817 | _x_x_mozilla_cycle_collect,1995 12:42:14 INFO - PROCESS | 1817 | __end_cc_report 12:42:14 INFO - PROCESS | 1817 | __startTimestamp1473190934983__endTimestamp 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | ------- Summary: start ------- 12:42:14 INFO - PROCESS | 1817 | Number of tests: 14 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#0] ai-astar Cycles:10 Average:127.50 Median:120.00 stddev:22.74 (19.0%) stddev-sans-first:21.47 12:42:14 INFO - PROCESS | 1817 | Values: 157.0 168.0 153.0 114.0 113.0 112.0 126.0 110.0 109.0 113.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#1] audio-beat-detection Cycles:10 Average:132.70 Median:140.50 stddev:21.95 (15.6%) stddev-sans-first:22.66 12:42:14 INFO - PROCESS | 1817 | Values: 147.0 111.0 134.0 163.0 111.0 154.0 127.0 108.0 160.0 112.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#2] audio-dft Cycles:10 Average:183.60 Median:183.50 stddev:2.99 (1.6%) stddev-sans-first:1.56 12:42:14 INFO - PROCESS | 1817 | Values: 191.0 181.0 185.0 183.0 184.0 183.0 180.0 183.0 184.0 182.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#3] audio-fft Cycles:10 Average:96.10 Median:78.00 stddev:44.12 (56.6%) stddev-sans-first:46.25 12:42:14 INFO - PROCESS | 1817 | Values: 77.0 79.0 98.0 190.0 82.0 48.0 69.0 87.0 160.0 71.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:71.00 stddev:28.71 (40.4%) stddev-sans-first:29.89 12:42:14 INFO - PROCESS | 1817 | Values: 81.0 83.0 137.0 70.0 88.0 101.0 150.0 72.0 69.0 115.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.50 stddev:2.01 (2.3%) stddev-sans-first:2.06 12:42:14 INFO - PROCESS | 1817 | Values: 88.0 84.0 90.0 84.0 87.0 85.0 88.0 85.0 88.0 86.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 12:42:14 INFO - PROCESS | 1817 | Values: 95.0 96.0 96.0 96.0 96.0 96.0 97.0 96.0 96.0 96.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:83.50 stddev:21.41 (25.6%) stddev-sans-first:22.18 12:42:14 INFO - PROCESS | 1817 | Values: 83.0 83.0 102.0 139.0 131.0 84.0 85.0 85.0 85.0 83.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:53.50 stddev:0.71 (1.3%) stddev-sans-first:0.73 12:42:14 INFO - PROCESS | 1817 | Values: 54.0 53.0 54.0 53.0 53.0 54.0 55.0 53.0 53.0 53.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:71.00 stddev:3.56 (5.0%) stddev-sans-first:3.47 12:42:14 INFO - PROCESS | 1817 | Values: 66.0 63.0 73.0 72.0 75.0 70.0 69.0 70.0 69.0 73.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:88.50 stddev:9.38 (10.6%) stddev-sans-first:9.79 12:42:14 INFO - PROCESS | 1817 | Values: 86.0 89.0 85.0 89.0 89.0 88.0 86.0 88.0 91.0 117.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:142.00 stddev:14.73 (10.4%) stddev-sans-first:15.52 12:42:14 INFO - PROCESS | 1817 | Values: 142.0 142.0 142.0 140.0 136.0 139.0 138.0 151.0 186.0 152.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:171.50 stddev:1.25 (0.7%) stddev-sans-first:1.32 12:42:14 INFO - PROCESS | 1817 | Values: 172.0 174.0 170.0 173.0 171.0 171.0 173.0 171.0 171.0 171.0 12:42:14 INFO - PROCESS | 1817 | 12:42:14 INFO - PROCESS | 1817 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:74.50 stddev:8.95 (12.0%) stddev-sans-first:9.25 12:42:14 INFO - PROCESS | 1817 | Values: 67.0 68.0 67.0 69.0 79.0 86.0 70.0 58.0 84.0 80.0 12:42:14 INFO - PROCESS | 1817 | -------- Summary: end -------- 12:42:14 INFO - PROCESS | 1817 | 12:42:15 INFO - TEST-INFO | 1817: exit 0 12:42:15 INFO - TEST-OK | kraken | took 59615ms 12:42:15 INFO - SUITE-END | took 1176s 12:42:16 INFO - Completed test suite (00:19:37) 12:42:16 INFO - javascript benchmark 12:42:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2690.636764036855, 3306.5536152619397, 3364.125327414871, 3397.6092749333834, 3409.249965007699, 5586.24585680933, 5660.688662123582, 5681.517071048961, 5713.314402271733, 5763.481286684198, 4877.756112194394, 5667.149927510875, 5737.5696822738255, 5802.477777000069, 5822.4177582241755, 5122.846314610549, 5187.844364669066, 5191.922121168185, 5234.607404444663, 5280.128778751501, 5293.920591191135, 5304.708241046741, 5305.310309659739, 5309.017831701148, 5363.383210930754, 4276.743395396286, 5186.1442861927735, 5214.791408343662, 5239.5546378557865, 5247.317848679666, 5225.059489291926, 5246.475352464761, 5248.501392367757, 5249.553787927993, 5252.080885844962, 4271.423357846713, 4325.589069038437, 5101.20931255656, 5179.482051794828, 5205.479452054802, 5638.267025286727, 5639.436056394327, 5683.8010669626365, 5713.314402271712, 5775.422457754233, 6224.066390041485, 6226.315105338409, 6239.719212635442, 6244.656543890084, 6249.437550620466, 5136.691798492103, 6089.580040920871, 6100.840840240385, 6231.4391704746795, 6336.714847831859, 5688.829335119953, 5708.115242137475, 5715.3141623005085, 5747.195392645033, 5776.075827867605, 6343.270523889812, 6364.204474440695, 6368.267649220399, 6379.170707807955, 6383.553151279366, 5986.102084687332, 6019.307779605314, 6030.03519436888, 6030.638161710223, 6098.054801505774, 6166.7224974876235, 6203.348648391854, 6214.161088253028, 6218.471429928416, 6284.37156284377, 6185.567010309325, 6189.659568723763, 6200.0, 6203.47270482005, 6212.627242365382, 6134.4172303631085, 6136.0, 6229.875402492015, 6254.655993920378, 6260.373962603703, 4838.927416088762, 4892.559669629679, 4894.804207831647, 4926.704397736147, 4937.1113199624415, 4386.451693538308, 4451.310046942729, 4459.710118842329, 4468.776561171929, 4485.25993211123, 5736.0, 5744.741486633028, 5751.942480575224, 5772.769089236467, 5806.738696758656, 6225.875482490324, 6327.335629758901, 6335.936640633627, 6338.0, 6341.143945567382, 5558.0, 5637.802676906371, 5645.80239691617, 5723.771049157987, 5756.194132821408, 4575.656825738083, 5360.812371566976, 5418.485243901823, 5447.428020057916, 5467.589930755209, 6305.211848518935, 6329.620222786648, 6331.176946996861, 6334.1765570475545, 6347.238331400262, 5667.829965101054, 5684.914726279027, 6114.633122012693, 6129.786714140983, 6154.692265386802, 6242.500599951902, 6274.372562743689, 6334.968325158345, 6340.080688300263, 6372.936270637234, 5376.865578360573, 6057.909576276313, 6178.104174894705, 6205.131281620486, 6206.72069756853, 6176.629402235881, 6218.409251121137, 6238.719257633508, 6254.687265636791, 6268.717907694256, 5908.0, 5962.4633782959745, 6086.878262434638, 6091.390860913873, 6119.938800612115, 5833.912491312549, 5995.0, 6053.515718742402, 6098.129681996294, 6104.06507294887, 5873.03094989322, 5924.200232968667, 5940.8811823764145, 5949.315828679757, 5955.40445955401, 5219.373675159005, 5907.49786268172, 6045.274567051982, 6118.60229085108, 6127.877442451037, 5830.76676932918, 5889.970550147222, 5930.52555795544, 5933.139694744152, 6006.579539432372], "value": 5643.669704728674, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.08784380661484, 515.7189331814161, 527.1776029394144, 539.1562205148941, 564.9887002259952, 26961.0, 30788.0, 31051.844740776294, 32593.67406325936, 32668.346633067325, 42123.57876421235, 43166.35250471238, 43235.35146972798, 45543.77228113855, 45673.54326456734, 9816.705498835028, 9944.900550994505, 9986.40081595103, 9990.900090999106, 10012.0, 8733.956330218372, 9742.51287435629, 9862.556184971658, 9867.111959923606, 9876.90123098767, 11955.7608847823, 12125.151239413244, 12140.453679584396, 12368.443420046075, 12395.814062789066, 12086.818697719496, 12185.512579496899, 12193.0, 12194.085443591766, 12205.38973051344, 11962.1626486146, 12030.63908082759, 12064.879351206464, 12065.517379304818, 12066.638000859988, 40747.38878916819, 41135.177296453905, 41168.176636467106, 41182.38226426606, 41275.793621032004, 3371.106656735967, 3403.540676037582, 3636.327279453283, 4016.8995775105564, 4145.17096580685, 30853.38293234145, 31830.84084579562, 31854.522182167286, 31972.0, 32070.358592828245, 3165.5409965554863, 3269.8038117713013, 3284.310294838087, 3399.9660003399895, 3424.503447000196, 513065.0, 514223.0, 515914.0, 516407.0, 518464.0, 28711.425771484453, 34071.0, 57133.71433142808, 61367.693161533905, 61718.0, 34407.82796086054, 34588.0, 34588.135296617285, 34602.48096278558, 34640.133996650286, 14885.479008234877, 15003.474878379204, 15009.0, 15021.849781502264, 15067.321970511353, 12762.489500420064, 12860.678483037998, 12874.678133046562, 12881.613551593467, 12892.419841107172, 7240.565566066053, 7395.001674773836, 7703.268189521987, 7717.344025757761, 7818.061832580013, 2724.6866610339666, 3248.0, 3287.967120328766, 3298.0600528849163, 3306.7189288910713, 956.4357029352715, 968.801395713877, 977.0717818072859, 1001.7295330260789, 1073.1790180511914, 28108.859455703, 30146.547801782992, 31669.841650791597, 31676.0, 32563.278324643266, 1029.0, 1030.8866024737272, 1038.9220808439397, 1074.9946250268856, 1105.309181761399, 13810.0, 15290.923545382424, 15489.225538723244, 15684.0, 15713.68572628541, 659656.0, 666370.0, 666648.0, 668951.0, 670096.0, 49847.252291215664, 57019.71490142606, 57039.429605703415, 57714.13428798571, 58082.41917580854, 1340.6380277325068, 1362.8160198373291, 1386.3761307411705, 1388.5903658420589, 1388.7361401333715, 30484.542731859485, 31496.685033149377, 31744.04767856968, 32025.359492809548, 32037.160714107973, 52335.95328093493, 53128.46871531235, 53221.66945826385, 53272.73363633157, 53293.46706532885, 448458.0, 459496.0, 461052.0, 461260.0, 546217.0, 40905.59094409137, 41851.0, 42091.15817683691, 42140.367894480994, 42272.0, 46977.2953405706, 47097.82255443641, 47194.0, 47430.28854567115, 48353.274700878814, 36310.8184459076, 37758.43362349622, 38265.23469530649, 39443.605563943995, 39951.80144595667, 37693.0, 37874.81062594779, 38252.0, 38697.0, 39385.40921886117, 36229.818850905576, 36774.44838327374, 37142.25715485621, 37866.621333787414, 38019.0, 31748.36503269968, 33708.110576660096, 33890.49164262489, 33960.0, 34361.0, 20892.164313427293, 21180.4704882373, 24832.37919051966, 25592.360190995376, 25735.0], "value": 19123.066349191933, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.4239190120104, 368.8413981987744, 373.42306137018306, 382.95213098362694, 398.4640658314566, 15314.846851531482, 16078.954867933591, 19748.802511974875, 20384.388468345936, 20541.38375848725, 19623.31318403856, 19947.608906485897, 20225.696614550794, 20324.288649897255, 20807.895960520214, 5645.237892884449, 5913.940860591393, 6180.351063138373, 6184.752609895599, 6187.535934804885, 6042.516598672096, 6086.6348019119, 6117.908231376533, 6145.60053596517, 6170.598911070766, 5835.649861008332, 6058.242719660043, 6109.35851735568, 6119.143319935212, 6120.449159575637, 6058.909116363258, 6099.115628233882, 6107.389261073901, 6107.969460152715, 6112.174856394375, 5211.661242019257, 5472.425395333512, 5543.972280138614, 5570.164475328733, 5576.386597474275, 12826.23042617446, 13052.15161014531, 15526.534203973899, 25105.748942510523, 25469.363265918317, 2049.7745248022707, 2080.1679328268815, 2157.8273738100916, 2308.4113551044543, 2779.63864697588, 9351.906480935171, 9874.851877221847, 10118.797624047551, 10228.232882533839, 10295.948520257425, 1684.3936182974119, 1763.5150333658219, 1827.9543011424687, 1839.9264029438807, 1863.6861012985812, 116487.0, 125841.0, 126971.3651431737, 129260.0, 129867.99999999905, 9537.712408824858, 10670.679879603624, 10810.29733067348, 11138.944305278585, 11233.831492527619, 13956.302184890716, 13985.160890346611, 13989.86010139906, 14013.299335033207, 14021.929890350688, 8222.17778222173, 8532.744017679479, 8581.828363432696, 8632.568371581396, 8656.138930130051, 8497.957510212409, 8510.404271700922, 8515.957420212859, 8690.522021288889, 8715.607797649121, 4334.609885110355, 4405.867823965222, 4484.955150448519, 4501.099780044005, 4502.369668246448, 1674.7571602117625, 1851.4630757080297, 1909.994505768965, 1911.3788018893915, 1937.7093435984434, 838.9706360277484, 847.7033038436621, 850.2985037344216, 947.9336446448682, 976.2336565795958, 20556.17775288972, 21061.47346316324, 21266.78733212679, 21391.25130620421, 21442.571148576943, 754.3361841579375, 921.9216366608779, 937.2970272295778, 941.8728471656376, 942.2462030375672, 10449.425281609585, 10821.458927053616, 10898.945505272422, 10930.540628358714, 10946.397948112772, 560138.0, 561003.0, 574310.0, 574357.0, 576095.0, 126680.36659816642, 134300.0, 136999.31500342436, 137262.0, 137994.0, 935.2658163341744, 950.0499500499501, 976.5995941663883, 977.6675930183629, 980.4019548075668, 18646.81353186505, 18922.3377181798, 18977.810221898155, 18990.525236869187, 18999.145038473027, 27532.860821532704, 44438.0, 55731.16403254036, 55928.88142237214, 56553.71723141358, 62196.75606487935, 62205.75588488296, 62544.0, 66280.6685966567, 68592.0, 6189.381061893955, 7779.0, 7847.686092556234, 8037.839243214986, 8067.354611631173, 9224.354295199271, 11127.59570297976, 12928.676783080498, 13711.520096796565, 13824.101433406797, 11315.830262545904, 11364.43177841088, 11535.5962541314, 11554.48004839801, 11802.94098529502, 9652.648629191779, 11447.942760286145, 11459.427028648368, 11566.537338506701, 11844.466998984894, 11567.074634029426, 11722.58970936013, 11965.102617303912, 12016.158868879094, 12098.455569499569, 8117.457683040204, 8151.877721834058, 8171.060328062349, 8237.723152911309, 8301.6679332826, 8176.100628930932, 10811.45942702846, 11186.161037922026, 11570.132240082195, 11715.297082175097], "value": 9344.329513209954, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.5091367107834, 1366.753984282829, 1557.844215578442, 1687.9662406751866, 1928.6142771445714, 7718.961405192973, 8580.828383432328, 8811.383203175781, 8818.941726992769, 8895.644174233039, 8640.91359086409, 8726.52004139772, 8731.694390696326, 8740.51927144007, 8756.824863502727, 2370.798482129017, 2398.7481314461993, 2403.0868270057354, 2403.1949296985445, 2404.7955923746417, 2344.8944797484155, 2364.255259593231, 2396.400899775056, 2400.6519054737055, 2401.2916189724074, 2360.4216966843146, 2384.654225137354, 2388.0925248405583, 2397.496525729599, 2406.4465173010217, 2005.8767090429405, 2269.693591365161, 2357.4695693468916, 2375.406148462884, 2377.227401094651, 2315.3401280635107, 2344.3904584808074, 2359.1389142962867, 2360.8465449745713, 2362.2519335691636, 8615.0, 8704.390692651517, 8732.170443807829, 8804.64781408743, 8812.38313318068, 5211.0, 5361.651492653004, 5373.650712703701, 5379.892402151974, 5384.138537833927, 3607.5310209672575, 3628.238070005276, 3637.963620363789, 3684.5946945836204, 3714.1271801126713, 1076.5048077884182, 1082.5453309609982, 1087.0162502934857, 1107.19175002248, 1107.9556817727282, 11395.943020284929, 11481.311121332663, 11536.653900383002, 11562.381266622595, 11563.942180289045, 4391.473023237232, 4447.777611119431, 4631.907361852744, 4632.62938964882, 4649.651276154234, 7646.923530764732, 9091.499967501852, 9101.226395756436, 9106.86339704905, 9133.0, 5224.947750522446, 5227.738613069331, 5230.032443997872, 5235.65968212065, 5236.0575096483, 4249.341352090369, 4299.763513006815, 4419.469663640384, 4549.6360291176625, 4908.067467181224, 3802.049487628093, 3810.923781524354, 3826.7512611680154, 3858.7266202153223, 3868.9806550967064, 1167.649705088487, 1173.6185739634652, 1179.8466199394195, 1189.1378750406059, 1189.2804853063772, 857.652650676477, 880.5245167609547, 882.6954700628273, 897.3539051882634, 926.0785518409226, 5566.944330556724, 5675.290588676416, 5687.800926967537, 5690.886182276414, 5812.488752924271, 773.6827900560743, 925.7454200094893, 928.1414691410419, 935.8970513243538, 961.427950369529, 3123.7188653020885, 3738.4579236010627, 3866.74866133706, 3931.76409415436, 4004.0, 9971.501424928725, 10677.83983240252, 10726.034656880918, 10827.566897324019, 10880.12958963281, 10490.842637360445, 10831.404272765074, 10831.566737330577, 10843.295185812896, 10849.566017359217, 6168.383161683885, 8261.297789687944, 8268.214519620626, 8272.462289951134, 8281.37889658278, 5635.830925072254, 5682.687452190004, 5774.9133762994425, 5799.391063938226, 5829.271341082364, 9865.704028879145, 9954.0, 10009.74975625586, 10030.799384012425, 10041.196704263495, 9267.212286955684, 9777.951110244403, 10094.0, 10116.696499105039, 10335.483225838527, 6293.811185664437, 6987.965060174666, 7053.153621565445, 7129.964350178216, 7139.32176443237, 8206.0, 8227.177282271725, 8255.463394879314, 8303.792405189919, 8317.875231871405, 7502.962485187539, 7535.284148005931, 7606.505577137468, 7607.35337496319, 7620.314171724572, 6243.500519958483, 7159.105111861018, 7309.744158954411, 7399.630018498946, 7438.925610743823, 6870.7595234166565, 6926.168859736662, 6984.546004509691, 6987.266337034538, 7003.1596208453275, 4854.83008094715, 4873.610111191168, 4875.829345972874, 4886.218205087169, 5055.090083784954, 3739.700823934133, 4849.539293767087, 4901.656884018084, 5042.823501177588, 5082.923756143735], "value": 4504.794396123578, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.02723580274585, 303.4992262766435, 313.74900079936043, 316.44463965740124, 320.24741856636894, 4450.688451808374, 4634.841289677581, 5223.82463945612, 5336.91994620081, 5373.140297552392, 4823.903521929559, 5399.946000539993, 5412.404635490103, 5412.512873841353, 5424.972875135629, 4138.813753381105, 4166.437530933315, 4180.184863951531, 4180.728252663567, 4194.958050419495, 3924.941125883114, 4167.166566686675, 4173.728707634009, 4203.243416185085, 4204.831806727742, 3787.696984241254, 3860.9189426960497, 3959.0696186396176, 3974.2250261198933, 4161.25097482453, 3639.3085313790293, 3728.5339332583426, 3908.4918960534947, 4012.739171953828, 4164.708470407073, 3133.9843300783577, 3568.964310356889, 3584.62361452049, 3610.6208848070837, 3615.783053016801, 4905.386826646669, 5147.6911385317, 5200.895982080375, 5288.206768984683, 5288.788448462057, 3914.5498267699295, 4211.7472951623, 4702.952970470285, 4717.905641887143, 4776.259679749644, 5385.434529374398, 5691.544676425876, 5726.770929162829, 5751.827445176651, 5767.1349297605275, 3691.7969511676847, 3950.170464202521, 3955.358526211619, 3956.8219430125428, 3964.940525892114, 4799.448063472675, 5593.524550413179, 5978.940210597882, 5986.401359864022, 5998.0, 3526.6297038811067, 3950.2692001979726, 4023.637872591481, 4054.2094291612852, 4059.939100913489, 4107.67138628909, 4109.383592461095, 4114.876553703393, 4121.0, 4123.8556650517085, 4636.342691314086, 4710.940038491381, 4715.74063426508, 4729.226540047483, 4729.43246810385, 4699.953000469952, 5574.247476590689, 5634.661920284796, 5694.4590263924865, 5790.305163380337, 1367.924764137982, 1378.2419669182111, 1380.7997840313299, 1392.5404616476742, 1396.3576754692751, 725.528406535756, 794.7933537280348, 846.4836449765638, 858.7548055319811, 859.2610355094614, 3885.980570097188, 4067.633912947849, 4235.322348424237, 4517.503074661785, 4534.455865296186, 4356.324769660708, 4612.30815377696, 4746.477887432379, 4859.781309841065, 4934.925976110289, 4150.937735933963, 4282.935755963663, 4288.614024737726, 4389.604935555815, 4441.489893436247, 2174.532475517771, 2226.5212979209546, 2497.9125730599344, 2547.3504256414494, 2627.69781475129, 4506.526814684476, 4591.770411479412, 4699.157010008896, 4913.557779799764, 5065.746712664352, 4486.0, 4494.640428765691, 4578.0, 4673.38290557289, 5055.570276526537, 4801.575204521764, 4864.813547126638, 6153.2616086068165, 6160.507159427235, 6199.5660303779205, 5963.612365196248, 5977.0, 6000.8799824004145, 6015.578909476295, 6029.1559181715475, 5086.237064440215, 5841.94158058414, 5886.911696324473, 5907.940920590739, 5911.113333000085, 5791.131330300489, 5880.500157486662, 6003.279606447254, 6127.632342059491, 6146.723397447036, 3335.6497567755036, 3802.7908465033584, 4039.212943069273, 4081.9591804082766, 4106.891139392287, 5848.01751106947, 5896.233489646319, 5905.852353691193, 5905.940940590539, 5947.680404642107, 4931.914978704679, 5134.76893539799, 5205.323307969939, 5756.251687280626, 5765.740541675718, 5689.743961521659, 5691.78414195978, 5705.34388545306, 5723.05262789672, 5768.855778605568, 4821.180399332192, 5623.8875222496135, 5660.462256085665, 5675.262215911905, 5676.943230567642, 5017.84946451607, 5333.43998880112, 5739.368669446441, 5739.5695322850115, 5760.222369980167, 4910.582600478856, 5188.884389856199, 5214.217867319932, 5388.515033646991, 5734.684592347461], "value": 4031.0068248690077, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.9144317750664, 731.1372580355182, 779.9922000779992, 817.6157206113124, 824.3775949158382, 13505.932470337659, 15113.546593602196, 16265.268062937166, 16320.75518867215, 16330.18349082544, 14696.706065878703, 14764.261786910665, 14797.0, 14818.0, 14838.0, 697.2817997462622, 716.3946465236867, 718.3893690363202, 719.8776208044619, 864.6238886084542, 694.4444444444449, 695.8817001109799, 851.7998270406449, 875.9474431534128, 880.6521424037502, 905.1175104273347, 914.6112902016649, 917.8485549884261, 919.7608621758324, 921.3320342751518, 913.8583519554413, 914.9359544831831, 916.6104405627648, 917.9403338782957, 920.0477505781485, 550.1088237055994, 672.1195234243157, 672.4351544702473, 675.4191395399952, 676.5839009009424, 12392.0, 12492.75014499714, 12517.812232816515, 12530.122891397607, 14474.927625361912, 12293.385330733428, 14134.36395362211, 14894.180820054893, 15009.973653556739, 15083.4720784772, 11713.590024349192, 11893.40532973348, 12003.819942700866, 12134.878651213552, 12474.625761227178, 3804.9429258561145, 3820.4842346283176, 3847.634474724897, 3882.3788193889127, 3999.3001224785835, 121552.0, 121659.78340216573, 121847.0, 121856.0, 122040.77959220383, 10968.0, 11210.775784484265, 11322.056265371062, 11403.543858245743, 11636.53453861836, 24187.0, 24215.394615134763, 24221.0, 24277.271681849572, 24279.757202428103, 7732.458727888994, 7734.690612375442, 7959.960200199078, 8020.598970051474, 8085.716999904975, 4438.844640437569, 4443.800028998703, 4451.577100175478, 4492.393526873896, 4669.813207471731, 3591.425371940529, 3723.069232691827, 3724.0875985383755, 3726.9440958385644, 3728.5712143103347, 548.5830768615881, 640.3180612647516, 661.9801405957829, 669.397542212013, 670.3196255800367, 608.342989571262, 646.4569761400446, 646.7025168422484, 647.6373230990661, 664.4219529009792, 9123.863142052875, 9146.085391460801, 9327.860082098774, 9390.210992393928, 9487.715368538955, 467.96490263230396, 494.51043466967445, 503.74309102358063, 570.5920267009109, 626.0952923026274, 4200.789960502024, 4570.9314360284625, 4572.74849883253, 4580.7938642761155, 4655.441347038377, 105475.0, 120851.0, 122216.38891805484, 127534.0, 156505.0, 86734.1326586726, 102194.0, 111966.44016779865, 120046.0, 122164.0, 88253.55873220593, 103826.0, 106508.9349106499, 106537.0, 106874.0, 9338.439693618404, 9456.574454149717, 9533.475658838553, 9554.57004434816, 9561.091696288842, 60085.699571501864, 60206.39793602183, 60500.39499604948, 61233.38766612277, 61704.0, 85449.0, 87381.0, 89330.0, 92371.0, 93628.53185734028, 10751.6236931707, 12665.240085594894, 13111.868881311064, 13429.529966451128, 13606.523771667944, 12303.32331721728, 14696.779880068798, 14726.852731472549, 14983.850161498245, 15437.22813859325, 13752.381142848795, 13800.664816079132, 13912.791308130589, 13952.232627205602, 14208.502702405367, 13553.0, 13554.796678050034, 13609.863901360859, 13662.590122296348, 13720.862791372358, 13124.278164701032, 13282.601521954357, 13423.395947182595, 13459.932700336436, 13537.593872183455, 8335.874961875697, 10418.062374386342, 10783.4414643166, 11247.778737429417, 11695.941520292345, 10940.671779846618, 11334.14993875446, 11452.94273528627, 11632.825507617561, 11674.649760507198], "value": 7697.392933684247, "unit": "score"}], "name": "dromaeo_css", "value": 7214.243901733056, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 168.0, 153.0, 114.0, 113.0, 112.0, 126.0, 110.0, 109.0, 113.0], "value": 127.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 134.0, 163.0, 111.0, 154.0, 127.0, 108.0, 160.0, 112.0], "value": 132.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 181.0, 185.0, 183.0, 184.0, 183.0, 180.0, 183.0, 184.0, 182.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 98.0, 190.0, 82.0, 48.0, 69.0, 87.0, 160.0, 71.0], "value": 96.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 137.0, 70.0, 88.0, 101.0, 150.0, 72.0, 69.0, 115.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 90.0, 84.0, 87.0, 85.0, 88.0, 85.0, 88.0, 86.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 102.0, 139.0, 131.0, 84.0, 85.0, 85.0, 85.0, 83.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 53.0, 53.0, 54.0, 55.0, 53.0, 53.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 73.0, 72.0, 75.0, 70.0, 69.0, 70.0, 69.0, 73.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 85.0, 89.0, 89.0, 88.0, 86.0, 88.0, 91.0, 117.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 142.0, 142.0, 140.0, 136.0, 139.0, 138.0, 151.0, 186.0, 152.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 170.0, 173.0, 171.0, 171.0, 173.0, 171.0, 171.0, 171.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 69.0, 79.0, 86.0, 70.0, 58.0, 84.0, 80.0], "value": 72.8, "unit": "score"}], "name": "kraken", "value": 1520.6, "alertThreshold": 2.0}]} 12:42:16 INFO - Return code: 0 12:42:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:42:16 INFO - # TBPL SUCCESS # 12:42:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:42:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:42:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:42:16 INFO - 2016-09-06 12:42:16.253 screenresolution[1826:10257] starting screenresolution argv=screenresolution get 12:42:16 INFO - 2016-09-06 12:42:16.257 screenresolution[1826:10257] Display 0: 1600x1200x32@60 12:42:16 INFO - 2016-09-06 12:42:16.263 screenresolution[1827:10260] starting screenresolution argv=screenresolution list 12:42:16 INFO - Available Modes on Display 0 12:42:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:42:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:42:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:42:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:42:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:42:16 INFO - Intel Iris: 12:42:16 INFO - Chipset Model: Intel Iris 12:42:16 INFO - Type: GPU 12:42:16 INFO - Bus: Built-In 12:42:16 INFO - VRAM (Dynamic, Max): 1536 MB 12:42:16 INFO - Vendor: Intel (0x8086) 12:42:16 INFO - Device ID: 0x0a2e 12:42:16 INFO - Revision ID: 0x0009 12:42:16 INFO - Displays: 12:42:16 INFO - Display: 12:42:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:42:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:42:16 INFO - Display Serial Number: 1600x1200 60 12:42:16 INFO - Main Display: Yes 12:42:16 INFO - Mirror: Off 12:42:16 INFO - Online: Yes 12:42:16 INFO - Rotation: Supported 12:42:16 INFO - Return code: 0 12:42:16 INFO - Running post-action listener: _resource_record_post_action 12:42:16 INFO - [mozharness: 2016-09-06 19:42:16.497027Z] Finished run-tests step (success) 12:42:16 INFO - Running post-run listener: _resource_record_post_run 12:42:16 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31759360; Write bytes: 709497856; Read time: 325; Write time: 1938 12:42:16 INFO - TinderboxPrint: CPU usage
53.5% 12:42:16 INFO - TinderboxPrint: I/O read bytes / time
31,759,360 / 325 12:42:16 INFO - TinderboxPrint: I/O write bytes / time
709,497,856 / 1,938 12:42:16 INFO - TinderboxPrint: CPU idle
2,252.8 (46.5%) 12:42:16 INFO - TinderboxPrint: CPU system
968.5 (20.0%) 12:42:16 INFO - TinderboxPrint: CPU user
1,628.4 (33.6%) 12:42:16 INFO - TinderboxPrint: Swap in / out
433,594,368 / 0 12:42:16 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398371328; Write bytes: 393365504; Read time: 29761; Write time: 737 12:42:16 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29772800; Write bytes: 299305984; Read time: 302; Write time: 1164 12:42:16 INFO - Running post-run listener: _upload_blobber_files 12:42:16 INFO - Blob upload gear active. 12:42:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:42:16 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:42:16 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:42:16 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:42:17 INFO - (blobuploader) - INFO - Open directory for files ... 12:42:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:42:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:42:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:18 INFO - (blobuploader) - INFO - Done attempting. 12:42:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:42:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:42:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:19 INFO - (blobuploader) - INFO - Done attempting. 12:42:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:42:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:42:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:21 INFO - (blobuploader) - INFO - Done attempting. 12:42:21 INFO - (blobuploader) - INFO - Iteration through files over. 12:42:21 INFO - Return code: 0 12:42:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:42:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:42:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/35adbdba6e63db6638084f0ad9128a4763fbe675e2c093581a53d07ebead8392baf6c82c1a6058e2b1b76f709f4c921e44a35dcff29e3dc7252ce101f97a5e10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/08c2f2755ead5797f14e55d769d60639b54a230a413ed53f563d882064bff4f896907b062509620376c8669c30d864532a38d576c1ff565b7991099090c832ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6cdc62cdf3bcf8fe2fffdd6cfa655c3ce4cc4ebb285ad6d07a285029406072fca614fb2de92333e0ceedeb648b8e1d45a23f470671f4bef20a89ee0e0eb07791"} 12:42:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:42:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:42:21 INFO - Contents: 12:42:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/35adbdba6e63db6638084f0ad9128a4763fbe675e2c093581a53d07ebead8392baf6c82c1a6058e2b1b76f709f4c921e44a35dcff29e3dc7252ce101f97a5e10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/08c2f2755ead5797f14e55d769d60639b54a230a413ed53f563d882064bff4f896907b062509620376c8669c30d864532a38d576c1ff565b7991099090c832ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6cdc62cdf3bcf8fe2fffdd6cfa655c3ce4cc4ebb285ad6d07a285029406072fca614fb2de92333e0ceedeb648b8e1d45a23f470671f4bef20a89ee0e0eb07791"} 12:42:21 INFO - Running post-run listener: copy_logs_to_upload_dir 12:42:21 INFO - Copying logs to upload dir... 12:42:21 INFO - mkdir: /builds/slave/test/build/upload/logs 12:42:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.469476 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-06 12:42:21.939573) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 12:42:21.942772) ========= 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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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/Larch/sha512/35adbdba6e63db6638084f0ad9128a4763fbe675e2c093581a53d07ebead8392baf6c82c1a6058e2b1b76f709f4c921e44a35dcff29e3dc7252ce101f97a5e10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/08c2f2755ead5797f14e55d769d60639b54a230a413ed53f563d882064bff4f896907b062509620376c8669c30d864532a38d576c1ff565b7991099090c832ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6cdc62cdf3bcf8fe2fffdd6cfa655c3ce4cc4ebb285ad6d07a285029406072fca614fb2de92333e0ceedeb648b8e1d45a23f470671f4bef20a89ee0e0eb07791"} build_url:https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/JaoqNnVwSE6j19aTrPL05w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/35adbdba6e63db6638084f0ad9128a4763fbe675e2c093581a53d07ebead8392baf6c82c1a6058e2b1b76f709f4c921e44a35dcff29e3dc7252ce101f97a5e10", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/08c2f2755ead5797f14e55d769d60639b54a230a413ed53f563d882064bff4f896907b062509620376c8669c30d864532a38d576c1ff565b7991099090c832ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/6cdc62cdf3bcf8fe2fffdd6cfa655c3ce4cc4ebb285ad6d07a285029406072fca614fb2de92333e0ceedeb648b8e1d45a23f470671f4bef20a89ee0e0eb07791"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 12:42:21.975424) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:42:21.975751) ========= 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.KoQSgWGyMj/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.xrGBDdnv7q/Listeners TMPDIR=/var/folders/hz/6vdv9x_s7jxbq2qw6vthh2l000000w/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.005249 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 12:42:22.024481) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 12:42:22.024832) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 12:42:22.025550) ========= ========= Total master_lag: 0.19 =========