builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0002 starttime: 1473211786.41 results: success (0) buildid: 20160906170916 builduid: e2ccd903a6c8490f9409482bafa34ca8 revision: a093339f5b6181440201bcf747c9013da11c4bda ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:46.405830) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:46.406264) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:46.406537) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:46.439244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:46.439518) ========= 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.vYqcQoIK24/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.8l1TwXsdcX/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:29:46-- 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% 175M=0s 2016-09-06 18:29:46 (175 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112835 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:46.575253) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:46.575558) ========= 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.vYqcQoIK24/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.8l1TwXsdcX/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.028099 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:46.623938) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 18:29:46.624267) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a093339f5b6181440201bcf747c9013da11c4bda --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a093339f5b6181440201bcf747c9013da11c4bda --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vYqcQoIK24/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.8l1TwXsdcX/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:29:46,703 truncating revision to first 12 chars 2016-09-06 18:29:46,703 Setting DEBUG logging. 2016-09-06 18:29:46,703 attempt 1/10 2016-09-06 18:29:46,703 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a093339f5b61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 18:29:47,758 unpacking tar archive at: autoland-a093339f5b61/testing/mozharness/ program finished with exit code 0 elapsedTime=1.315818 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 18:29:47.962263) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:47.962582) ========= script_repo_revision: a093339f5b6181440201bcf747c9013da11c4bda ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:47.962973) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:47.963237) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 18:29:47.981945) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-06 18:29:47.982364) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vYqcQoIK24/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.8l1TwXsdcX/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:29:48 INFO - MultiFileLogger online at 20160906 18:29:48 in /builds/slave/test 18:29:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 18:29:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:29:48 INFO - {'append_to_log': False, 18:29:48 INFO - 'base_work_dir': '/builds/slave/test', 18:29:48 INFO - 'blob_upload_branch': 'Autoland', 18:29:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:29:48 INFO - 'branch': 'Autoland', 18:29:48 INFO - 'buildbot_json_path': 'buildprops.json', 18:29:48 INFO - 'config_files': ('talos/mac_config.py',), 18:29:48 INFO - 'default_actions': ('clobber', 18:29:48 INFO - 'read-buildbot-config', 18:29:48 INFO - 'download-and-extract', 18:29:48 INFO - 'populate-webroot', 18:29:48 INFO - 'create-virtualenv', 18:29:48 INFO - 'install', 18:29:48 INFO - 'run-tests'), 18:29:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:29:48 INFO - 'download_minidump_stackwalk': True, 18:29:48 INFO - 'download_symbols': 'ondemand', 18:29:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:29:48 INFO - 'tooltool.py': '/tools/tooltool.py', 18:29:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:29:48 INFO - '/tools/misc-python/virtualenv.py')}, 18:29:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:29:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:29:48 INFO - 'installer_path': 'installer.exe', 18:29:48 INFO - 'log_level': 'info', 18:29:48 INFO - 'log_name': 'talos', 18:29:48 INFO - 'log_to_console': True, 18:29:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:29:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:29:48 INFO - 'opt_config_files': (), 18:29:48 INFO - 'pip_index': False, 18:29:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:29:48 INFO - 'cmd': ('bash', 18:29:48 INFO - '-c', 18:29:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:29:48 INFO - 'enabled': True, 18:29:48 INFO - 'halt_on_failure': False, 18:29:48 INFO - 'name': 'check_screen_resolution'},), 18:29:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:29:48 INFO - 'cmd': ('bash', 18:29:48 INFO - '-c', 18:29:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:29:48 INFO - 'enabled': True, 18:29:48 INFO - 'halt_on_failure': False, 18:29:48 INFO - 'name': 'check_screen_resolution'},), 18:29:48 INFO - 'run_cmd_checks_enabled': True, 18:29:48 INFO - 'sps_profile': False, 18:29:48 INFO - 'sps_profile_interval': 0, 18:29:48 INFO - 'suite': 'dromaeojs-e10s', 18:29:48 INFO - 'system_bits': '32', 18:29:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:29:48 INFO - 'title': 't-yosemite-r7-0002', 18:29:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:29:48 INFO - 'use_talos_json': True, 18:29:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:29:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:29:48 INFO - 'work_dir': 'build'} 18:29:48 INFO - [mozharness: 2016-09-07 01:29:48.179606Z] Running clobber step. 18:29:48 INFO - Running pre-action listener: _resource_record_pre_action 18:29:48 INFO - Running main action method: clobber 18:29:48 INFO - rmtree: /builds/slave/test/build 18:29:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:29:50 INFO - Running post-action listener: _resource_record_post_action 18:29:50 INFO - [mozharness: 2016-09-07 01:29:50.403108Z] Finished clobber step (success) 18:29:50 INFO - [mozharness: 2016-09-07 01:29:50.403217Z] Running read-buildbot-config step. 18:29:50 INFO - Running pre-action listener: _resource_record_pre_action 18:29:50 INFO - Running main action method: read_buildbot_config 18:29:50 INFO - Using buildbot properties: 18:29:50 INFO - { 18:29:50 INFO - "project": "", 18:29:50 INFO - "product": "firefox", 18:29:50 INFO - "script_repo_revision": "production", 18:29:50 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 18:29:50 INFO - "repository": "", 18:29:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 18:29:50 INFO - "buildid": "20160906170916", 18:29:50 INFO - "pgo_build": "False", 18:29:50 INFO - "basedir": "/builds/slave/test", 18:29:50 INFO - "buildnumber": 181, 18:29:50 INFO - "slavename": "t-yosemite-r7-0002", 18:29:50 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:29:50 INFO - "platform": "yosemite_r7", 18:29:50 INFO - "scheduler": "tests-autoland-macosx64-talos-7-3600", 18:29:50 INFO - "branch": "autoland", 18:29:50 INFO - "repo_path": "integration/autoland", 18:29:50 INFO - "revision": "a093339f5b6181440201bcf747c9013da11c4bda", 18:29:50 INFO - "stage_platform": "macosx64", 18:29:50 INFO - "builduid": "e2ccd903a6c8490f9409482bafa34ca8", 18:29:50 INFO - "slavebuilddir": "test" 18:29:50 INFO - } 18:29:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:29:50 INFO - Running post-action listener: _resource_record_post_action 18:29:50 INFO - [mozharness: 2016-09-07 01:29:50.406137Z] Finished read-buildbot-config step (success) 18:29:50 INFO - [mozharness: 2016-09-07 01:29:50.406247Z] Running download-and-extract step. 18:29:50 INFO - Running pre-action listener: _resource_record_pre_action 18:29:50 INFO - Running main action method: download_and_extract 18:29:50 INFO - mkdir: /builds/slave/test/build/tests 18:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:29:50 INFO - trying https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:29:50 INFO - Downloading https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/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 18:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/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 18:29:51 INFO - Downloaded 1199 bytes. 18:29:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:29:51 INFO - Using the following test package requirements: 18:29:51 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:29:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:51 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:29:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:51 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:29:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:51 INFO - u'jsshell-mac.zip'], 18:29:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:51 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:29:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:29:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:51 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:29:51 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:51 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:29:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:51 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:29:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:29:51 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:29:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:29:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:29:52 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:29:59 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 18:30:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:30:00 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:30:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:30:07 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 18:30:07 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')]} 18:30:07 INFO - trying https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:30:07 INFO - Downloading https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:30:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/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 18:30:10 INFO - Downloaded 111524313 bytes. 18:30:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:30:10 INFO - mkdir: /builds/slave/test/properties 18:30:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:30:10 INFO - Writing to file /builds/slave/test/properties/build_url 18:30:10 INFO - Contents: 18:30:10 INFO - build_url:https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:30:11 INFO - Running post-action listener: _resource_record_post_action 18:30:11 INFO - Running post-action listener: set_extra_try_arguments 18:30:11 INFO - [mozharness: 2016-09-07 01:30:11.544098Z] Finished download-and-extract step (success) 18:30:11 INFO - [mozharness: 2016-09-07 01:30:11.544243Z] Running populate-webroot step. 18:30:11 INFO - Running pre-action listener: _resource_record_pre_action 18:30:11 INFO - Running main action method: populate_webroot 18:30:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:30:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:30:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:30:11 INFO - '--tppagecycles', 18:30:11 INFO - '7'], 18:30:11 INFO - 'tests': ['tsvgm']}}, 18:30:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:30:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:30:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:30:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:30:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:11 INFO - 'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:30:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:11 INFO - 'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['damp', 'tps']}, 18:30:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:11 INFO - 'tests': ['damp', 'tps']}, 18:30:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['dromaeo_dom']}, 18:30:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:30:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['basic_compositor_video']}, 18:30:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:30:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['a11yr', 18:30:11 INFO - 'ts_paint', 18:30:11 INFO - 'tpaint', 18:30:11 INFO - 'sessionrestore', 18:30:11 INFO - 'sessionrestore_no_auto_restore', 18:30:11 INFO - 'tabpaint']}, 18:30:11 INFO - 'other-e10s': {'tests': ['a11yr', 18:30:11 INFO - 'ts_paint', 18:30:11 INFO - 'tpaint', 18:30:11 INFO - 'sessionrestore', 18:30:11 INFO - 'sessionrestore_no_auto_restore', 18:30:11 INFO - 'tabpaint']}, 18:30:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:30:11 INFO - 'ts_paint', 18:30:11 INFO - 'tpaint', 18:30:11 INFO - 'sessionrestore', 18:30:11 INFO - 'sessionrestore_no_auto_restore', 18:30:11 INFO - 'tabpaint']}, 18:30:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:30:11 INFO - 'ts_paint', 18:30:11 INFO - 'tpaint', 18:30:11 INFO - 'sessionrestore', 18:30:11 INFO - 'sessionrestore_no_auto_restore', 18:30:11 INFO - 'tabpaint']}, 18:30:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['a11yr', 18:30:11 INFO - 'ts_paint', 18:30:11 INFO - 'tpaint', 18:30:11 INFO - 'sessionrestore', 18:30:11 INFO - 'sessionrestore_no_auto_restore', 18:30:11 INFO - 'tabpaint']}, 18:30:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['a11yr', 18:30:11 INFO - 'ts_paint', 18:30:11 INFO - 'tpaint', 18:30:11 INFO - 'sessionrestore', 18:30:11 INFO - 'sessionrestore_no_auto_restore', 18:30:11 INFO - 'tabpaint']}, 18:30:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['tsvgx', 18:30:11 INFO - 'tsvgr_opacity', 18:30:11 INFO - 'tart', 18:30:11 INFO - 'tscrollx', 18:30:11 INFO - 'cart']}, 18:30:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:30:11 INFO - 'tsvgr_opacity', 18:30:11 INFO - 'tart', 18:30:11 INFO - 'tscrollx', 18:30:11 INFO - 'cart']}, 18:30:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:11 INFO - 'talos_options': ['--disable-e10s'], 18:30:11 INFO - 'tests': ['tp5o']}, 18:30:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:11 INFO - 'tests': ['tp5o']}, 18:30:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:11 INFO - 'talos_options': ['--disable-e10s', 18:30:11 INFO - '--xperf_path', 18:30:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:11 INFO - 'tests': ['tp5n']}, 18:30:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:30:11 INFO - 'talos_options': ['--xperf_path', 18:30:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:30:11 INFO - 'tests': ['tp5n']}}, 18:30:11 INFO - 'talos.zip': {'path': '', 18:30:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:30:11 INFO - Running post-action listener: _resource_record_post_action 18:30:11 INFO - [mozharness: 2016-09-07 01:30:11.562692Z] Finished populate-webroot step (success) 18:30:11 INFO - [mozharness: 2016-09-07 01:30:11.562827Z] Running create-virtualenv step. 18:30:11 INFO - Running pre-action listener: _resource_record_pre_action 18:30:11 INFO - Running main action method: create_virtualenv 18:30:11 INFO - Creating virtualenv /builds/slave/test/build/venv 18:30:11 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 18:30:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:30:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:30:11 INFO - Using real prefix '/tools/python27' 18:30:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:30:12 INFO - Installing distribute.............................................................................................................................................................................................done. 18:30:15 INFO - Installing pip.................done. 18:30:15 INFO - Return code: 0 18:30:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91dac74f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, '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.vYqcQoIK24/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.8l1TwXsdcX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:30:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:30:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:30:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:15 INFO - 'HOME': '/Users/cltbld', 18:30:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:15 INFO - 'LOGNAME': 'cltbld', 18:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:15 INFO - 'MOZ_NO_REMOTE': '1', 18:30:15 INFO - 'NO_EM_RESTART': '1', 18:30:15 INFO - 'PAGER': '/bin/cat', 18:30:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:15 INFO - 'PWD': '/builds/slave/test', 18:30:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:15 INFO - 'SHELL': '/bin/bash', 18:30:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:30:15 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:30:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:15 INFO - 'USER': 'cltbld', 18:30:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:15 INFO - 'XPC_FLAGS': '0x0', 18:30:15 INFO - 'XPC_SERVICE_NAME': '0', 18:30:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:15 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 18:30:15 INFO - Cleaning up... 18:30:15 INFO - Return code: 0 18:30:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aa7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91dac74f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, '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.vYqcQoIK24/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.8l1TwXsdcX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:30:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:30:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:30:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:15 INFO - 'HOME': '/Users/cltbld', 18:30:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:15 INFO - 'LOGNAME': 'cltbld', 18:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:15 INFO - 'MOZ_NO_REMOTE': '1', 18:30:15 INFO - 'NO_EM_RESTART': '1', 18:30:15 INFO - 'PAGER': '/bin/cat', 18:30:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:15 INFO - 'PWD': '/builds/slave/test', 18:30:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:15 INFO - 'SHELL': '/bin/bash', 18:30:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:30:15 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:30:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:15 INFO - 'USER': 'cltbld', 18:30:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:15 INFO - 'XPC_FLAGS': '0x0', 18:30:15 INFO - 'XPC_SERVICE_NAME': '0', 18:30:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:15 INFO - Downloading/unpacking psutil>=3.1.1 18:30:15 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 18:30:15 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 18:30:15 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 18:30:15 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 18:30:15 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 18:30:15 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 18:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:30:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:18 INFO - Installing collected packages: psutil 18:30:18 INFO - Running setup.py install for psutil 18:30:18 INFO - building 'psutil._psutil_osx' extension 18:30:18 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 18:30:19 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 18:30:19 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 18:30:19 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 18:30:19 INFO - building 'psutil._psutil_posix' extension 18:30:19 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 18:30:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:30:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:30:19 INFO - ^ 18:30:19 INFO - 1 warning generated. 18:30:19 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 18:30:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:30:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:30:19 INFO - Successfully installed psutil 18:30:19 INFO - Cleaning up... 18:30:20 INFO - Return code: 0 18:30:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:30:20 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')]} 18:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:20 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')]} 18:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:20 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 0x10aa7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91dac74f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, '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.vYqcQoIK24/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.8l1TwXsdcX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:30:20 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 18:30:20 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 18:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:20 INFO - 'HOME': '/Users/cltbld', 18:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:20 INFO - 'LOGNAME': 'cltbld', 18:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:20 INFO - 'MOZ_NO_REMOTE': '1', 18:30:20 INFO - 'NO_EM_RESTART': '1', 18:30:20 INFO - 'PAGER': '/bin/cat', 18:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:20 INFO - 'PWD': '/builds/slave/test', 18:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:20 INFO - 'SHELL': '/bin/bash', 18:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:30:20 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:20 INFO - 'USER': 'cltbld', 18:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:20 INFO - 'XPC_FLAGS': '0x0', 18:30:20 INFO - 'XPC_SERVICE_NAME': '0', 18:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:30:20 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 18:30:20 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 18:30:20 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 18:30:20 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 18:30:20 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 18:30:20 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 18:30:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:30:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:30:22 INFO - Installing collected packages: mozsystemmonitor 18:30:22 INFO - Running setup.py install for mozsystemmonitor 18:30:22 INFO - Successfully installed mozsystemmonitor 18:30:22 INFO - Cleaning up... 18:30:22 INFO - Return code: 0 18:30:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:22 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 0x10aa7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91dac74f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, '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.vYqcQoIK24/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.8l1TwXsdcX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:30:22 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 18:30:22 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 18:30:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:30:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:22 INFO - 'HOME': '/Users/cltbld', 18:30:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:22 INFO - 'LOGNAME': 'cltbld', 18:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:22 INFO - 'MOZ_NO_REMOTE': '1', 18:30:22 INFO - 'NO_EM_RESTART': '1', 18:30:22 INFO - 'PAGER': '/bin/cat', 18:30:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:22 INFO - 'PWD': '/builds/slave/test', 18:30:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:22 INFO - 'SHELL': '/bin/bash', 18:30:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:30:22 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:30:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:22 INFO - 'USER': 'cltbld', 18:30:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:22 INFO - 'XPC_FLAGS': '0x0', 18:30:22 INFO - 'XPC_SERVICE_NAME': '0', 18:30:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:23 INFO - Downloading/unpacking jsonschema==2.5.1 18:30:23 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 18:30:23 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 18:30:23 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 18:30:23 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 18:30:23 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 18:30:23 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 18:30:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:30:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:30:25 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 18:30:25 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 18:30:25 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 18:30:25 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 18:30:25 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 18:30:25 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 18:30:26 INFO - Downloading functools32-3.2.3-2.tar.gz 18:30:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:30:26 INFO - warning: no files found matching '*.txt' 18:30:26 INFO - no previously-included directories found matching 'build' 18:30:26 INFO - no previously-included directories found matching 'dist' 18:30:26 INFO - no previously-included directories found matching '.git*' 18:30:26 INFO - Installing collected packages: jsonschema, functools32 18:30:26 INFO - Running setup.py install for functools32 18:30:26 INFO - warning: no files found matching '*.txt' 18:30:26 INFO - no previously-included directories found matching 'build' 18:30:26 INFO - no previously-included directories found matching 'dist' 18:30:26 INFO - no previously-included directories found matching '.git*' 18:30:26 INFO - Successfully installed jsonschema functools32 18:30:26 INFO - Cleaning up... 18:30:26 INFO - Return code: 0 18:30:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:30:26 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')]} 18:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:26 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')]} 18:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:26 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 0x10aa7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91dac74f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, '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.vYqcQoIK24/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.8l1TwXsdcX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:30:26 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 18:30:26 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 18:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:26 INFO - 'HOME': '/Users/cltbld', 18:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:26 INFO - 'LOGNAME': 'cltbld', 18:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:26 INFO - 'MOZ_NO_REMOTE': '1', 18:30:26 INFO - 'NO_EM_RESTART': '1', 18:30:26 INFO - 'PAGER': '/bin/cat', 18:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:26 INFO - 'PWD': '/builds/slave/test', 18:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:26 INFO - 'SHELL': '/bin/bash', 18:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:30:26 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:26 INFO - 'USER': 'cltbld', 18:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:26 INFO - 'XPC_FLAGS': '0x0', 18:30:26 INFO - 'XPC_SERVICE_NAME': '0', 18:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:30:26 INFO - Cleaning up... 18:30:26 INFO - Return code: 0 18:30:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:30:26 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')]} 18:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:26 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')]} 18:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:26 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 0x10aa7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91dac74f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, '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.vYqcQoIK24/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.8l1TwXsdcX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:30:26 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 18:30:26 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 18:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:26 INFO - 'HOME': '/Users/cltbld', 18:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:26 INFO - 'LOGNAME': 'cltbld', 18:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:26 INFO - 'MOZ_NO_REMOTE': '1', 18:30:26 INFO - 'NO_EM_RESTART': '1', 18:30:26 INFO - 'PAGER': '/bin/cat', 18:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:26 INFO - 'PWD': '/builds/slave/test', 18:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:26 INFO - 'SHELL': '/bin/bash', 18:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:30:26 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:26 INFO - 'USER': 'cltbld', 18:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:26 INFO - 'XPC_FLAGS': '0x0', 18:30:26 INFO - 'XPC_SERVICE_NAME': '0', 18:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:27 INFO - Downloading/unpacking blobuploader==1.2.4 18:30:27 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 18:30:27 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 18:30:27 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 18:30:27 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 18:30:27 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 18:30:27 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 18:30:29 INFO - Downloading blobuploader-1.2.4.tar.gz 18:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:30:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:30:29 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 18:30:29 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 18:30:29 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 18:30:29 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 18:30:29 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 18:30:29 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 18:30:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:30:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:30:30 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 18:30:30 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 18:30:30 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 18:30:30 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 18:30:30 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 18:30:30 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 18:30:30 INFO - Downloading docopt-0.6.1.tar.gz 18:30:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:30:30 INFO - Installing collected packages: blobuploader, requests, docopt 18:30:30 INFO - Running setup.py install for blobuploader 18:30:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:30:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:30:31 INFO - Running setup.py install for requests 18:30:31 INFO - Running setup.py install for docopt 18:30:31 INFO - Successfully installed blobuploader requests docopt 18:30:31 INFO - Cleaning up... 18:30:31 INFO - Return code: 0 18:30:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30:31 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')]} 18:30:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:31 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')]} 18:30:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:31 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 0x10aa7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91dac74f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, '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.vYqcQoIK24/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.8l1TwXsdcX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:30:31 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 18:30:31 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 18:30:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:30:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:31 INFO - 'HOME': '/Users/cltbld', 18:30:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:31 INFO - 'LOGNAME': 'cltbld', 18:30:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:31 INFO - 'MOZ_NO_REMOTE': '1', 18:30:31 INFO - 'NO_EM_RESTART': '1', 18:30:31 INFO - 'PAGER': '/bin/cat', 18:30:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:31 INFO - 'PWD': '/builds/slave/test', 18:30:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:31 INFO - 'SHELL': '/bin/bash', 18:30:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:30:31 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:30:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:31 INFO - 'USER': 'cltbld', 18:30:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:31 INFO - 'XPC_FLAGS': '0x0', 18:30:31 INFO - 'XPC_SERVICE_NAME': '0', 18:30:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Yhw1dU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-yRxR8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-64uTal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-vNj_90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Bw7Ize-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-VLovgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-SvOI9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-NU7Cgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:32 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-u6sKxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:33 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-B40ACh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:33 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-JKdTCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:33 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-J_Nla1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:33 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-gmFBsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:33 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-ClMGKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:33 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-xoFOcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:33 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-r75IUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:33 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-u_X4A4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:30:33 INFO - Running setup.py install for manifestparser 18:30:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:30:34 INFO - Running setup.py install for mozcrash 18:30:34 INFO - Running setup.py install for mozdebug 18:30:34 INFO - Running setup.py install for mozdevice 18:30:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:30:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:30:34 INFO - Running setup.py install for mozfile 18:30:34 INFO - Running setup.py install for mozhttpd 18:30:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:30:34 INFO - Running setup.py install for mozinfo 18:30:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:30:34 INFO - Running setup.py install for mozInstall 18:30:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Running setup.py install for mozleak 18:30:35 INFO - Running setup.py install for mozlog 18:30:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Running setup.py install for moznetwork 18:30:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Running setup.py install for mozprocess 18:30:35 INFO - Running setup.py install for mozprofile 18:30:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Running setup.py install for mozrunner 18:30:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:30:35 INFO - Running setup.py install for mozscreenshot 18:30:36 INFO - Running setup.py install for moztest 18:30:36 INFO - Running setup.py install for mozversion 18:30:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:30:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:30:36 INFO - Cleaning up... 18:30:36 INFO - Return code: 0 18:30:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:36 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 0x10aa7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91dac74f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, '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.vYqcQoIK24/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.8l1TwXsdcX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:30:36 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 18:30:36 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 18:30:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:30:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:36 INFO - 'HOME': '/Users/cltbld', 18:30:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:36 INFO - 'LOGNAME': 'cltbld', 18:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:36 INFO - 'MOZ_NO_REMOTE': '1', 18:30:36 INFO - 'NO_EM_RESTART': '1', 18:30:36 INFO - 'PAGER': '/bin/cat', 18:30:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:36 INFO - 'PWD': '/builds/slave/test', 18:30:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:36 INFO - 'SHELL': '/bin/bash', 18:30:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:30:36 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:30:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:36 INFO - 'USER': 'cltbld', 18:30:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:36 INFO - 'XPC_FLAGS': '0x0', 18:30:36 INFO - 'XPC_SERVICE_NAME': '0', 18:30:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:30:36 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Ful9Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:30:36 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)) 18:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:30:36 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-3YuUhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:30:36 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)) 18:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:30:36 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-DPBSX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:30:37 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)) 18:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:30:37 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-dG7p94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:30:37 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)) 18:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:30:37 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-izvNMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:30:37 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)) 18:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:30:37 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-9NxBeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:30:37 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)) 18:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:30:37 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-NEHf84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:30:37 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)) 18:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:30:37 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-ZPAkFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:30:37 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)) 18:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:30:37 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-sjo79F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:30:37 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)) 18:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:30:37 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-WzTWPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:30:37 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)) 18:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:30:37 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-N4hHRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:30:37 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)) 18:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:30:37 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-drY_4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:30:37 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)) 18:30:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:30:37 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-8G3buC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:30:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-Fmxv2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-6B1PSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:30:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-mOfmqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:30:38 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)) 18:30:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:30:38 INFO - Running setup.py (path:/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/pip-l62cV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:30:38 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)) 18:30:38 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)) 18:30:38 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)) 18:30:38 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)) 18:30:38 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)) 18:30:38 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)) 18:30:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:30:38 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 18:30:38 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 18:30:38 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 18:30:38 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 18:30:38 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 18:30:38 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 18:30:40 INFO - Downloading blessings-1.6.tar.gz 18:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:30:41 INFO - Installing collected packages: blessings 18:30:41 INFO - Running setup.py install for blessings 18:30:41 INFO - Successfully installed blessings 18:30:41 INFO - Cleaning up... 18:30:41 INFO - Return code: 0 18:30:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:30:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:41 INFO - Reading from file tmpfile_stdout 18:30:41 INFO - Current package versions: 18:30:41 INFO - blessings == 1.6 18:30:41 INFO - blobuploader == 1.2.4 18:30:41 INFO - docopt == 0.6.1 18:30:41 INFO - functools32 == 3.2.3-2 18:30:41 INFO - jsonschema == 2.5.1 18:30:41 INFO - manifestparser == 1.1 18:30:41 INFO - mozInstall == 1.12 18:30:41 INFO - mozcrash == 0.17 18:30:41 INFO - mozdebug == 0.1 18:30:41 INFO - mozdevice == 0.48 18:30:41 INFO - mozfile == 1.2 18:30:41 INFO - mozhttpd == 0.7 18:30:41 INFO - mozinfo == 0.9 18:30:41 INFO - mozleak == 0.1 18:30:41 INFO - mozlog == 3.3 18:30:41 INFO - moznetwork == 0.27 18:30:41 INFO - mozprocess == 0.23 18:30:41 INFO - mozprofile == 0.28 18:30:41 INFO - mozrunner == 6.12 18:30:41 INFO - mozscreenshot == 0.1 18:30:41 INFO - mozsystemmonitor == 0.3 18:30:41 INFO - moztest == 0.7 18:30:41 INFO - mozversion == 1.4 18:30:41 INFO - psutil == 3.1.1 18:30:41 INFO - requests == 1.2.3 18:30:41 INFO - wsgiref == 0.1.2 18:30:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30:41 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 0x10aa7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91dac74f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, '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.vYqcQoIK24/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.8l1TwXsdcX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:30:41 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 18:30:41 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 18:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:41 INFO - 'HOME': '/Users/cltbld', 18:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:41 INFO - 'LOGNAME': 'cltbld', 18:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:41 INFO - 'MOZ_NO_REMOTE': '1', 18:30:41 INFO - 'NO_EM_RESTART': '1', 18:30:41 INFO - 'PAGER': '/bin/cat', 18:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:41 INFO - 'PWD': '/builds/slave/test', 18:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:41 INFO - 'SHELL': '/bin/bash', 18:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:30:41 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:41 INFO - 'USER': 'cltbld', 18:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:41 INFO - 'XPC_FLAGS': '0x0', 18:30:41 INFO - 'XPC_SERVICE_NAME': '0', 18:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:41 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)) 18:30:41 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)) 18:30:41 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)) 18:30:41 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)) 18:30:41 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)) 18:30:41 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)) 18:30:41 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)) 18:30:41 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)) 18:30:41 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)) 18:30:41 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)) 18:30:41 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)) 18:30:41 INFO - Cleaning up... 18:30:41 INFO - Return code: 0 18:30:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:30: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')]} 18:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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')]} 18:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:30: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa7aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91dac74f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, '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.vYqcQoIK24/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.8l1TwXsdcX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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 18:30: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', 'jsonschema'] in /builds/slave/test/build 18:30: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 jsonschema 18:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:30:41 INFO - 'HOME': '/Users/cltbld', 18:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:30:41 INFO - 'LOGNAME': 'cltbld', 18:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:30:41 INFO - 'MOZ_NO_REMOTE': '1', 18:30:41 INFO - 'NO_EM_RESTART': '1', 18:30:41 INFO - 'PAGER': '/bin/cat', 18:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:30:41 INFO - 'PWD': '/builds/slave/test', 18:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:30:41 INFO - 'SHELL': '/bin/bash', 18:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:30:41 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:30:41 INFO - 'USER': 'cltbld', 18:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:30:41 INFO - 'XPC_FLAGS': '0x0', 18:30:41 INFO - 'XPC_SERVICE_NAME': '0', 18:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:30:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:30:42 INFO - Cleaning up... 18:30:42 INFO - Return code: 0 18:30:42 INFO - Running post-action listener: _resource_record_post_action 18:30:42 INFO - Running post-action listener: _start_resource_monitoring 18:30:42 INFO - Starting resource monitoring. 18:30:42 INFO - [mozharness: 2016-09-07 01:30:42.205148Z] Finished create-virtualenv step (success) 18:30:42 INFO - [mozharness: 2016-09-07 01:30:42.206109Z] Running install step. 18:30:42 INFO - Running pre-action listener: _resource_record_pre_action 18:30:42 INFO - Running main action method: install 18:30:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:30:42 INFO - Reading from file tmpfile_stdout 18:30:42 INFO - Detecting whether we're running mozinstall >=1.0... 18:30:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:30:42 INFO - Reading from file tmpfile_stdout 18:30:42 INFO - Output received: 18:30:42 INFO - Usage: mozinstall [options] installer 18:30:42 INFO - Options: 18:30:42 INFO - -h, --help show this help message and exit 18:30:42 INFO - -d DEST, --destination=DEST 18:30:42 INFO - Directory to install application into. [default: 18:30:42 INFO - "/builds/slave/test"] 18:30:42 INFO - --app=APP Application being installed. [default: firefox] 18:30:42 INFO - mkdir: /builds/slave/test/build/application 18:30:42 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'] 18:30:42 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 18:31:16 INFO - Reading from file tmpfile_stdout 18:31:16 INFO - Output received: 18:31:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:31:16 INFO - Running post-action listener: _resource_record_post_action 18:31:16 INFO - [mozharness: 2016-09-07 01:31:16.645741Z] Finished install step (success) 18:31:16 INFO - [mozharness: 2016-09-07 01:31:16.645899Z] Running run-tests step. 18:31:16 INFO - Running pre-action listener: _resource_record_pre_action 18:31:16 INFO - Running main action method: run_tests 18:31:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:31:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:31:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:31:16 INFO - 2016-09-06 18:31:16.660 screenresolution[1645:7424] starting screenresolution argv=screenresolution get 18:31:16 INFO - 2016-09-06 18:31:16.662 screenresolution[1645:7424] Display 0: 1600x1200x32@60 18:31:16 INFO - 2016-09-06 18:31:16.668 screenresolution[1646:7426] starting screenresolution argv=screenresolution list 18:31:16 INFO - Available Modes on Display 0 18:31:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:31:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:31:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:31:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:31:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:31:16 INFO - Intel Iris: 18:31:16 INFO - Chipset Model: Intel Iris 18:31:16 INFO - Type: GPU 18:31:16 INFO - Bus: Built-In 18:31:16 INFO - VRAM (Dynamic, Max): 1536 MB 18:31:16 INFO - Vendor: Intel (0x8086) 18:31:16 INFO - Device ID: 0x0a2e 18:31:16 INFO - Revision ID: 0x0009 18:31:16 INFO - Displays: 18:31:16 INFO - Display: 18:31:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:31:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:31:16 INFO - Display Serial Number: 1600x1200 60 18:31:16 INFO - Main Display: Yes 18:31:16 INFO - Mirror: Off 18:31:16 INFO - Online: Yes 18:31:16 INFO - Rotation: Supported 18:31:16 INFO - Return code: 0 18:31:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:31:16 INFO - Python 2.7.3 18:31:16 INFO - Return code: 0 18:31:16 INFO - grabbing minidump binary from tooltool 18:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:31:16 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 0x10a9ded30>, '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 0x10a743db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a9d6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:31:16 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 18:31:16 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 18:31:16 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 18:31:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:31:17 INFO - Return code: 0 18:31:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:31:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:31:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:31:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:31:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:31:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0002 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:31:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vYqcQoIK24/Render', 18:31:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:31:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:31:17 INFO - 'HOME': '/Users/cltbld', 18:31:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:31:17 INFO - 'LOGNAME': 'cltbld', 18:31:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:31:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:31:17 INFO - 'MOZ_NO_REMOTE': '1', 18:31:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:31:17 INFO - 'NO_EM_RESTART': '1', 18:31:17 INFO - 'PAGER': '/bin/cat', 18:31:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:31:17 INFO - 'PWD': '/builds/slave/test', 18:31:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:31:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:31:17 INFO - 'SHELL': '/bin/bash', 18:31:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8l1TwXsdcX/Listeners', 18:31:17 INFO - 'TMPDIR': '/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/', 18:31:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:31:17 INFO - 'USER': 'cltbld', 18:31:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:31:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:31:17 INFO - 'XPC_FLAGS': '0x0', 18:31:17 INFO - 'XPC_SERVICE_NAME': '0', 18:31:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:31:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0002', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:31:17 INFO - mozversion application_buildid: 20160906170916 18:31:17 INFO - mozversion application_changeset: a093339f5b6181440201bcf747c9013da11c4bda 18:31:17 INFO - mozversion application_display_name: Nightly 18:31:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:31:17 INFO - mozversion application_name: Firefox 18:31:17 INFO - mozversion application_remotingname: firefox 18:31:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:31:17 INFO - mozversion application_vendor: Mozilla 18:31:17 INFO - mozversion application_version: 51.0a1 18:31:17 INFO - mozversion platform_buildid: 20160906170916 18:31:17 INFO - mozversion platform_changeset: a093339f5b6181440201bcf747c9013da11c4bda 18:31:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:31:17 INFO - mozversion platform_version: 51.0a1 18:31:17 INFO - using testdate: 1473211877 18:31:17 INFO - actual date: 1473211877 18:31:17 INFO - starting webserver on 'localhost:49283' 18:31:17 INFO - SUITE-START | Running 2 tests 18:31:17 INFO - TEST-START | dromaeo_css 18:31:17 INFO - operating with platform_type : mac_ 18:31:17 INFO - Initialising browser for dromaeo_css test... 18:31:17 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpehmfF_/profile http://localhost:49283/getInfo.html) 18:31:20 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 18:31:20 INFO - PROCESS | 1656 | colorDepth:24 18:31:20 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 18:31:20 INFO - PROCESS | 1656 | __metrics 18:31:20 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 18:31:20 INFO - TEST-INFO | 1656: exit 0 18:31:20 INFO - Browser initialized. 18:31:20 INFO - Running cycle 1/1 for dromaeo_css test... 18:31:20 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpehmfF_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:33 INFO - PROCESS | 1659 | RSS: Main: 243830784 18:31:33 INFO - PROCESS | 1659 | 18:32:22 INFO - PROCESS | 1659 | 1473211942749 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] 18:34:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 18:34:36 INFO - PROCESS | 1659 | RSS: Main: 262238208 18:34:36 INFO - PROCESS | 1659 | 18:37:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 18:37:39 INFO - PROCESS | 1659 | RSS: Main: 270114816 18:37:39 INFO - PROCESS | 1659 | 18:40:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 18:40:41 INFO - PROCESS | 1659 | RSS: Main: 274829312 18:40:41 INFO - PROCESS | 1659 | 18:43:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 18:43:44 INFO - PROCESS | 1659 | RSS: Main: 271192064 18:43:44 INFO - PROCESS | 1659 | 18:46:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 18:46:48 INFO - PROCESS | 1659 | RSS: Main: 276619264 18:46:48 INFO - PROCESS | 1659 | 18:49:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 18:49:51 INFO - PROCESS | 1659 | RSS: Main: 279515136 18:49:51 INFO - PROCESS | 1659 | 18:49:51 INFO - PROCESS | 1659 | __start_tp_report 18:49:51 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 18:49:51 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 18:49:51 INFO - PROCESS | 1659 | |i|pagename|runs| 18:49:51 INFO - PROCESS | 1659 | |0;dojo.html;3124.6250449946006;3418.6923176914074;3421.692047715705;3425.0067480430675;3433.0044287156725;5827.9999999999945;5829.912551311729;5839.153322768196;5862.530997520199;5878.294604647443;5661.264035675366;5803.970980145104;5836.182934389189;5871.647701137935;5874.0042578285065;4375.6499480041675;5287.312649355578;5305.65513241639;5369.973150134244;5433.483819037185;5314.707691076989;5480.369757477882;5499.99999999998;5502.944970550303;5515.834524964257;4432.468103827545;4833.879153021167;5220.347456567929;5345.251664766935;5393.7572809223675;5266.131088370415;5285.075111855448;5289.391719952217;5311.920321195185;5312.3093997780425;5248.107821670287;5259.132243179871;5259.2899958505495;5293.920591191135;5298;5254.15933450646;5385.5960802939935;5781.046127388976;5869.50109240715;5887.911681324784;5061;6221.657808820513;6317.526185536103;6320.71556779946;6395.520335974774;6258.3428739982555;6263.185785847856;6302.212223472066;6307.653079080649;6332.05019247112;5805.303363596396;5897.705114744245;5912.793052243193;5937.376575459601;6004.879902401971;6391.169148010729;6421.2615549211505;6431.903521447182;6432;6440.355964403522;6155.6614386209185;6190.969045154746;6193.628382297076;6197.659128747872;6211.254649442096;6126.303932855801;6159.784407545804;6231.657258850716;6268.561200715997;6269.592476489013;6126.6017708848785;6180.3201647818705;6198.194234749454;6211.65835879031;6267.247930248308;6250.937490625127;6266.686665666698;6301.0863424804065;6306.8423289417215;6348.269948955837;4875.829345972874;4999.375078115236;5009.7495125243595;5011.123053465629;5048.545630893237;4445.133199026159;4456.353828694886;4483.955160448419;4507.2788353863225;4535.795889184995;5372;5969.194158788508;5993.700314984233;6019.969900150471;6034.909476357858;6187.319394866561;6279;6282.151909492251;6289.0880822280515;6309.810705678745;5913.172155898178;5987.850303742442;6013.4888534474185;6041.72812223451;6090.390960903963;5505.03661859173;5532.474414930575;5538.778448862;5541.5289700375115;5565;6256.436920677161;6480.675966201671;6523.217213934358;6537.313582073813;6544.345565443417;5152.922706159486;6229.53278504123;6280.560360774702;6308.243010838729;6308.779192728416;6469.2560355557835;6500.577462464925;6516.6415847128865;6518.608883466817;6524.67376631176;6097.827165393427;6280;6281.497480201481;6284.24589049317;6295.811125666238;6248.218972628421;6286.622802631857;6288.811335659938;6292.496600271876;6297.4017468340435;6141.07883817431;6210.937890621217;6211.689415529115;6232.719527621359;6240.5319601031015;5345.385280692614;6180.7527698891545;6209.96895015522;6234.844128896814;6235.158253635701;4502.302143167814;5533.833984980457;5569.777208911761;5572.4706152915405;6044.365341639064;5058.519440653132;6184.041473571604;6236.126942228001;6252.5935814171935;6278.372162783685;4826.420829500483;6037.275526936795;6109.358517355791;6125.173101631222;6125.418085281891 18:49:51 INFO - PROCESS | 1659 | |1;ext.html;456.5959126173338;458.536877753469;508.7074931914148;552.6515302661505;574.597781552913;38266.61733382669;40558.797206014;40602.79698601506;40610.99999999996;40624.79687601565;41446.79276603613;42022.57977420233;42169.94575135616;42211.57788422115;42442.36336454956;9651.613935442592;9830.70507884761;9843.557039933221;9852.655157069505;9862.605495780195;9610.951945240264;9668.033196680346;9672.661456849011;9696.806063878717;9720.659776907809;9720.513974301291;9749;9756.219502439788;9765.853512197322;9766.706998790047;9666;9691.70924872255;9700.854487182663;9706.077922597342;9714.854277185848;9737.172340351079;9738.123568878835;9804.558794854249;9818.852717209247;9848.704538863845;39383.8184854452;39742.80128599367;39890;40002.19995600101;40006.799965999984;3268.117608245784;3320.8671653133847;3349.145967778201;3502.982485087584;3748.850045998157;30977.535336969962;30991.690083099107;31012.53481197784;31485.842570787;32050.839745801353;3196.872125115016;3197.5683282756763;3210.3579284143266;3293.324868401972;3299.307145499448;504254;504706;505323;508115;518044;58498;58908;59001.70499147477;59026.40973590209;59062.11406828901;33761.493577595866;34889.12777180591;34918.65081349205;61963.6901815488;62181.37818621756;16339.673206536041;16372.83627163713;16504.587385315463;16543.83456165423;16584.17079146038;12675.17611355259;12706.809397859039;12771.744565108645;12808.847203751708;12819;15210.771838422432;18576.349827755963;18844.434666960013;18850.434486965412;18985.715214271797;7035.050268213726;7559.773206803805;7773.727919522789;7951.562664053533;7979.800504987422;842.5913431985482;915.0026471146349;962.2446379591987;1039.589362201926;1078.4931082391263;25073.874630627095;28899.855500722362;29923.551146732818;29947.850260748557;30124.594470871547;944.6599224279254;957.3872721458272;1032.9690109296732;1040.2510192661273;1083.409541799721;14871.256437178097;15516.22418879074;15617.921910390376;15636;15653;188797;189664;189706.05146974177;189851;190082;57158.7142064287;57333;57435.712821436464;57944.71027644835;58018.709906450196;1133.28602980122;1225.9019278457702;1240.8883200511816;1306.9283187786114;1389.8540653231466;22913.770862291163;29359.11922642324;29533.704662953955;30158;30167.547486787276;49895.25157122574;49995.25007124969;50109.24836127388;50141;50176.49823501864;558272;604284;606953;607390;609520;21561.353159405244;25198.74801251964;26028;26361.60457593176;26407.867960660074;38247;46085.76957115327;47371.52628473671;47551.5244847561;47896.04207915892;38489;38559.42160867645;38637.22725545417;39746;40056;33855.830720847225;35088.47367289442;35582.466263006594;37978.43032354572;39676.048592711326;30360.392792143593;37872.81063594665;38420.42369364406;38710;38884.41673374958;33239.83380083181;34254.14364640904;34617;34790;35074.64925350714;25401.87299063493;25434.23697289084;25494.617580736674;25875;26112.477750444505 18:49:51 INFO - PROCESS | 1659 | |2;jquery.html;370.5053753239427;374.8369459285211;376.78711527986684;384.18744355687693;387.78284160869913;19645.803541964575;20518.589628207465;21959.011844466997;22036.779632203674;22036.88981555092;11480.827787583172;11693.649190524276;11875.228110172833;11989.580364687237;12021.218620789643;4351.738895666263;5666.631668941526;5672.801451949183;5696.231008813798;5699.544036477072;5565.860853478675;5668.801591944282;5681.375048744635;6167.136600875881;6207.658578778165;6111.449969502743;6132.049532322475;6156.630602163862;6165.136880836686;6182.4744896683615;5826.417358264182;6101.328853826098;6138.14066030756;6186.412290832363;6204.565680402374;5016.573591244749;5727.513161381287;5990.7903223386975;6013.969930150365;6014.699265036731;12907.870921290854;13156.947444204441;13192.86807131926;13205.933970330183;13214.207147571176;1973.3882496426154;2063.308791554831;2071.2129390831387;2272.9772702273094;2430.647556104355;9532.225051170657;10368.948155259175;10580.52387642558;11891;15476.148811815345;1755.8156393578618;1859.5351162209447;1865.4963159946874;1903.1626084522766;1963.7250784890127;120203.3989830054;123061;123559;123865;124776.75223247833;11180.552777888955;11451.59919402817;11490.023348015346;11542.422878856023;11608.013318867823;14052.102966099446;14242.145471271757;14357.497487587885;14374.85625143756;14427.134371937716;7675.961620191863;7816.7264145754625;8192.221738934912;8472.406931514857;8583.742487725378;7038.225795162528;8313.542755148404;8406.915930840736;8407.45351552147;8588.398812083095;4420.336949457607;4493.483249426293;4493.7977790999485;4502.302143167814;4573.679842410999;1571.4919208175272;1633.69776591331;1951.882887026783;1959.3534133735834;1961.7253584498183;728.4754976416971;755.9055118110236;864.1444969480169;890.8485557455248;895.6596493332491;17522;20275.79724202739;21405;21424.357269281947;21602;764.4572353628874;892.3040028777564;901.9143181397757;943.7970836270235;994.6319861651235;8861.955690221508;10679.78640427187;11104.833427498594;11269.94365028186;11285.984261416512;541020;564454;568393;576024;585755;123474.38262808628;123760.38119809343;127106.09340859894;128841;131045.1372917628;425.5404163503409;447.3022085546558;448.450647956251;450.38297532380847;452.3802390724728;8801.647934082821;11057.336559806437;11138.832917506406;11563.248388854698;11620.418979050844;30508.847455762578;43871.78064109659;50392.22842743537;53240.4675953251;55832.441675582726;80315;82240.58879705802;82788.75816862631;82896;84357.57821210855;7679.2704693054075;7939.166387529226;8010;8095.433319667803;8278.420510564203;9090;10473.900240474944;11596.478158483058;12769.361531923181;12793.232406055666;5463.48096930791;10981.780364392827;11304.83042754343;11319.660410187707;11471.655850324503;10930.508127134139;11158.721031974266;11163.051140653135;11177.049950754277;11436;10234.948825255826;11331.093512518813;11562.306261623993;11688.415579220835;11968.640940771791;8011.198880111942;8086.959565202136;8133.0646975598565;8216.055153657167;8287.41988060854;8172.38707096982;10149.492525373555;11056.89431056888;11312.717182070513;11384.943075284571 18:49:51 INFO - PROCESS | 1659 | |3;mootools.html;1405.810215620891;1519.005051691142;1698.405558054681;1853.7404763333127;1860.795312515623;7938.325242354393;8475.406721529487;8678.305735541157;9027.729168124959;9029;8710.869336959951;8783.12168783123;8847.805546251255;8871.201591856732;8900.91099089009;2011.6479616067204;2368.5736567417903;2402.027178992511;2417.9637305440433;2420.3465064432594;2376.8336216464854;2395.9281221563383;2397.0531640002187;2415.3599296186526;2426.9514609707794;2382.523495300938;2393.964090538643;2396.7962723168553;2415.903363865443;2417.746136655652;2366.751491093428;2372.833901626887;2375.9762402375927;2378.310290015902;2385.1890357278435;2120.257909731597;2364.2268298775916;2379.512199998996;2381.345130089222;2385.105585405473;7006.649667516604;7774.689012439496;8939.061398553189;8989.280857531381;8995.055519170524;4324.545922678105;5313.123334649778;5460.099083651193;5491.588130890199;5494.5054945054735;3047.7866549341556;3593.8562457501666;3655;3693.7229707772026;3700.888973330804;1099.571167244775;1106.623747925709;1111.7554138089608;1113.3208742666966;1115.827046807746;10247.436390998491;11184.944075279653;11656;11715.238509496856;11785.998190153847;4721.6687754201375;4724.551167639069;4799.136155491975;4888.633352498506;4908.337374454474;9921.355111917703;10000.299979001546;10018.448985305733;10069.848952265722;10082.69751907444;4313.503947046067;4492.213862574036;5440.483154100354;5445.128779395278;5450.700211488327;4440.911181776346;4812.927806082912;4959.553640172402;4971;4977.825776097891;3336.2660214752714;3958.2281455115976;4068.613481719232;4082.7550346978683;4130.793460326971;1146.6846617180342;1340.530814215017;1345.5828693104972;1348.952786652482;1348.9595312140652;655.783591414832;903.7243640689521;908.7319240823973;910.6676063236871;941.3114089513612;4419.535948725402;5774.364819869811;5829.504492118217;5991.760329586855;6137.232845894263;441.7857339190554;444.3490286729956;448.62315654850084;452.32829248565855;454.69762607865397;3463.6709512596262;3659.780413175218;3854.1328201154515;4027.093903871664;4030.274550580865;10484.213683973732;10815.67552973412;10902.182336324808;10904.236703430684;10912.399818010086;8059.959700201579;10107.848382274271;10612.097971672494;10880.183986201067;10898.564057437614;8080.555569443737;8171.795705107301;8274.627641756135;8290.875636865452;8336.749897503085;5247.081760691787;5572.885422915352;5988.281406231106;6071.512479442565;6173.351798061139;8551.957240213758;9987.751531058617;10121.493925303557;10153;10168.898311016796;10124.746881327732;10290.228232882713;10395.532201050819;10462.895371046192;10474.476276186313;7001.824954376181;7434.293742094493;7583.2416758325935;7583.582902940391;7680.9231907680205;7402.444816638881;7430.182679905098;8504.149585041447;8594.914050859661;8597.785055373666;7384.556926584422;7496.925030749622;7613.809654758676;7710.074791025113;7794;6177.166082578794;7456.552606843607;7495.475316727777;7509.849803004018;7612.543247405173;6004.789832355847;6199.783007594712;7011.929880701127;7026.981087742351;7114.857702846018;4791.952080479151;4828.975855120702;5044.848654540369;5080.187170052774;5105.18317069282;4563.3839431677625;4914.852554423373;4965.950340496549;4971.875703107451;5198.922016169685 18:49:51 INFO - PROCESS | 1659 | |4;prototype.html;272.6428378823741;296.07329060042065;312.14783640660994;315.4904828701647;316.9540416639586;4856.441509226431;5105.259737338084;5305.700103474649;5757.222774925383;5837.824865254039;4866.051120031595;4920.630952678554;5553.888922221553;5628.634138780976;5725.227094342263;3925;3929.5657085163994;4340.804663790137;4342.696011279212;4352.368906508555;3649.5802982656937;3932.488776459071;4204.810783514749;4308.3537469379535;4348.21732088224;3446.327966046609;4214.704970652056;4223.515599630302;4239.40648309237;4244.97877510612;3439.518467414564;4194.601512856274;4214.136102099063;4225.809838557272;4227.408162857202;3636.9636303637153;3645.1251699591908;3648.1973965727498;3650.34293827111;3652.324320000782;5395.845199196597;5522.834314970557;5549.528290095347;5553.278073850373;5557.749901254453;3855.903602409934;4228.9154216915795;4579.816807327703;4824.1316563018645;4828.927566086511;4947.653664243504;5243.79024839006;5521.950829342452;5751.942480575182;6003.909941350883;3928.4107383892647;3935.5670876203594;3966.226585815767;3972.312671048162;3976.741511801724;5335.813246536352;5578.577462747014;6367.458766004896;6381.766381766391;6419.454346380563;3573.2317551726505;4037;4059;4060.573639767841;4065.898352541151;3523.4714792780774;3762.285165818486;3895.928619629568;3952.3083460394314;4048.149888523384;4797.64017698674;4823.397075365579;4827.444843843003;4830.372051633265;4842.418909730784;4758.405199350082;5610.5231055360755;5645.745941432645;5851.414858514115;6086.330503644595;1308.640123965917;1370.7190026044836;1384.2248340929111;1387.4172847404116;1389.8394840308254;894.3203165594065;900.7477906186278;922.1331947969022;925.3661242049218;926.6525053105086;5041.168207245837;5271.551918087006;5327.600429967768;5338.252644629755;5592.273004509388;5634.887302253932;5822.621529600647;6073.6659483727935;6128.050152226412;6129.2032035835055;4344.826206951687;4431.977840110779;5588.050031494656;5617.606767526234;5639.464250896158;2465.8657017772025;2708.5124677557833;2726.7320695876115;2758.4069425073703;2771.5704065869822;5189.7405129743365;5320.308359913264;5400.4599540046465;5428.5928555358505;5479.917801232985;4684.508126646721;4995.850124496271;5330.653507521999;5331.573474122061;5367.409584945653;4678.812847486062;4717.7497963040505;4785.803549112722;5249.6088536537845;5362.52229388465;4111.8972025699595;4263.627828935501;4422.159789639958;5039.573090298632;5087.847364579068;4741.786619602057;4927.605791536596;5611.31660501856;6102.7253773579405;6170.845728856814;6268.179319306421;6464.870702586016;6530.701995492246;6561.606303621798;6600.966995164993;3943.743656662308;4021.778802165909;4056.59434056592;4079.306517891906;4108.322126849156;5253.3189379399755;5523.6685798852195;6097.390260974044;6175.2589689237575;6179.84550386226;5357.383900851452;5563.774490203789;5635.9436405635415;5885.117232415172;6063.818085457443;5890.7054647266605;5894.764209431746;6051.304100028553;6065.54508411862;6079.3312735598165;4893.363862697826;5151.866589350337;5585.240649195491;5927.940720592739;5938.465538101592;5333.359996800409;5795.82612521625;6115.532272254716;6123.908141377794;6147.754089836357;5599.384067752625;5981.461668449861;5993.190919225849;6004.759809607742;6005.549583781147 18:49:51 INFO - PROCESS | 1659 | |5;yui.html;671.7203726900257;697.2643860726934;704.0495331302742;736.3814395907443;810.1210186947162;12255.754884902297;12718.855303022738;12887.420066097025;14487.710245795079;14902.776458353133;11274.718132046703;13922.651933701662;14082.507112251047;14158;14207.928960355184;844.552387234765;862.6678728689437;867.5055218525443;870.960806763694;874.2699845628875;687.1410736579276;843.1357858195338;854.107457706698;861.8448679237734;863.8013256950906;907.4600612635471;911.0388540090203;917.179124683408;917.7017469322464;917.7567944494714;898.4384759525296;904.0417157812707;905.8369493491169;911.0525053943891;914.7713071732067;662.9237637671681;676.610948704492;678.4301187002892;680.3604611665069;684.428502200663;14441.927790360982;14625.634359141;14674.192919389428;14676.55970320892;14707;13274.66813329665;14342.713145737027;14376.712465750627;14489.203093829836;14552.563423097325;11891.108166887518;11909.166358354918;11967.64097077089;12004.459799308965;12129.69675758113;3778.8299526521373;3791.6777073948747;3817.48463957365;3833.674137698299;3942.467766851467;88523.11476885277;95088.52455737746;101780;108082;114065.28902066476;11485;11497.655070347902;11574;11660.30038197711;11701.531938722526;24053.398665033514;24398.39004024878;24441.51116977686;24616.261512154662;24619.291877893418;8243.629036693363;8268.958655206685;8439.746807595782;8576.313894888393;8599.355048371272;4439.289713645801;4486.730796152241;4520.367148599199;4583.908321833612;4690.155771961012;3653.415453527423;3728.3102626014274;3739.700823934079;3775.867844625425;3783.829727662262;581.2269681323829;665.364576829127;671.3348008406618;674.6592970549841;689.2108535726666;587.465406480101;621.4438077920327;641.9518536109811;663.4692246203018;685.5030103175158;7888.171741967126;9349.766255843659;9360.765980850398;9433.386829856037;9606.183474404754;571.4856629033819;648.2805718725377;651.947844172465;671.8454755406186;673.9932600674028;4489.438820147481;4613.584777369986;4619.792109355087;4638.840289927518;4673.859784206479;91208;91603;91945.54027229821;121280.02079708848;152922;94067;109331.45334273278;126749.36625316815;127838.80402989814;129387.70612293945;52803.47196527985;72372.99999999895;72402.63798681078;72578;83799.743003855;9751.122398984126;9939.850902236616;9941.558151869713;10065.396076235449;10085.243606729671;53330.73334633302;65222;65457.34542654512;65685.67157164184;65777.67111164413;91804.08195917956;95640;97688.51155744176;100267;101882.34000109778;10836.729081773019;11066.28069175501;12720.74558508843;13062.412191451218;13412.195268283935;12762.936185319015;12823.487060517848;12827.743445130858;12843.614691559269;13795.172289662652;13464.663383415102;13542.932285338511;13754.037217394687;13870.514531991736;14225.786613200604;10812.297200681931;11153.609623663133;12321.260724356567;12471.5011399543;13787.448502059806;10072.294939354175;12509.186902851285;13648.454061837416;14196.503122390666;14373.784393234318;11622.767544649229;11661.76676466483;11766.235194712317;11868.58459953932;11996.400179990791;10386.844197336895;10771.784564308826;11660.06719462458;11780.998615117811;11944.581939632071 18:49:51 INFO - PROCESS | 1659 | __end_tp_report 18:49:51 INFO - PROCESS | 1659 | __start_cc_report 18:49:51 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,385 18:49:51 INFO - PROCESS | 1659 | __end_cc_report 18:49:51 INFO - PROCESS | 1659 | __startTimestamp1473212991385__endTimestamp 18:49:51 INFO - PROCESS | 1659 | 18:49:51 INFO - PROCESS | 1659 | ------- Summary: start ------- 18:49:51 INFO - PROCESS | 1659 | Number of tests: 6 18:49:51 INFO - PROCESS | 1659 | 18:49:51 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5779.89 Median:6037.28 stddev:685.07 (11.3%) stddev-sans-first:654.74 18:49:51 INFO - PROCESS | 1659 | Values: 3124.6 3418.7 3421.7 3425.0 3433.0 5828.0 5829.9 5839.2 5862.5 5878.3 5661.3 5804.0 5836.2 5871.6 5874.0 4375.6 5287.3 5305.7 5370.0 5433.5 5314.7 5480.4 5500.0 5502.9 5515.8 4432.5 4833.9 5220.3 5345.3 5393.8 5266.1 5285.1 5289.4 5311.9 5312.3 5248.1 5259.1 5259.3 5293.9 5298.0 5254.2 5385.6 5781.0 5869.5 5887.9 5061.0 6221.7 6317.5 6320.7 6395.5 6258.3 6263.2 6302.2 6307.7 6332.1 5805.3 5897.7 5912.8 5937.4 6004.9 6391.2 6421.3 6431.9 6432.0 6440.4 6155.7 6191.0 6193.6 6197.7 6211.3 6126.3 6159.8 6231.7 6268.6 6269.6 6126.6 6180.3 6198.2 6211.7 6267.2 6250.9 6266.7 6301.1 6306.8 6348.3 4875.8 4999.4 5009.7 5011.1 5048.5 4445.1 4456.4 4484.0 4507.3 4535.8 5372.0 5969.2 5993.7 6020.0 6034.9 6187.3 6279.0 6282.2 6289.1 6309.8 5913.2 5987.9 6013.5 6041.7 6090.4 5505.0 5532.5 5538.8 5541.5 5565.0 6256.4 6480.7 6523.2 6537.3 6544.3 5152.9 6229.5 6280.6 6308.2 6308.8 6469.3 6500.6 6516.6 6518.6 6524.7 6097.8 6280.0 6281.5 6284.2 6295.8 6248.2 6286.6 6288.8 6292.5 6297.4 6141.1 6210.9 6211.7 6232.7 6240.5 5345.4 6180.8 6210.0 6234.8 6235.2 4502.3 5533.8 5569.8 5572.5 6044.4 5058.5 6184.0 6236.1 6252.6 6278.4 4826.4 6037.3 6109.4 6125.2 6125.4 18:49:51 INFO - PROCESS | 1659 | 18:49:51 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:58060.11 Median:38797.21 stddev:125002.63 (322.2%) stddev-sans-first:125278.47 18:49:51 INFO - PROCESS | 1659 | Values: 456.6 458.5 508.7 552.7 574.6 38266.6 40558.8 40602.8 40611.0 40624.8 41446.8 42022.6 42169.9 42211.6 42442.4 9651.6 9830.7 9843.6 9852.7 9862.6 9611.0 9668.0 9672.7 9696.8 9720.7 9720.5 9749.0 9756.2 9765.9 9766.7 9666.0 9691.7 9700.9 9706.1 9714.9 9737.2 9738.1 9804.6 9818.9 9848.7 39383.8 39742.8 39890.0 40002.2 40006.8 3268.1 3320.9 3349.1 3503.0 3748.9 30977.5 30991.7 31012.5 31485.8 32050.8 3196.9 3197.6 3210.4 3293.3 3299.3 504254.0 504706.0 505323.0 508115.0 518044.0 58498.0 58908.0 59001.7 59026.4 59062.1 33761.5 34889.1 34918.7 61963.7 62181.4 16339.7 16372.8 16504.6 16543.8 16584.2 12675.2 12706.8 12771.7 12808.8 12819.0 15210.8 18576.3 18844.4 18850.4 18985.7 7035.1 7559.8 7773.7 7951.6 7979.8 842.6 915.0 962.2 1039.6 1078.5 25073.9 28899.9 29923.6 29947.9 30124.6 944.7 957.4 1033.0 1040.3 1083.4 14871.3 15516.2 15617.9 15636.0 15653.0 188797.0 189664.0 189706.1 189851.0 190082.0 57158.7 57333.0 57435.7 57944.7 58018.7 1133.3 1225.9 1240.9 1306.9 1389.9 22913.8 29359.1 29533.7 30158.0 30167.5 49895.3 49995.3 50109.2 50141.0 50176.5 558272.0 604284.0 606953.0 607390.0 609520.0 21561.4 25198.7 26028.0 26361.6 26407.9 38247.0 46085.8 47371.5 47551.5 47896.0 38489.0 38559.4 38637.2 39746.0 40056.0 33855.8 35088.5 35582.5 37978.4 39676.0 30360.4 37872.8 38420.4 38710.0 38884.4 33239.8 34254.1 34617.0 34790.0 35074.6 25401.9 25434.2 25494.6 25875.0 26112.5 18:49:51 INFO - PROCESS | 1659 | 18:49:51 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33355.26 Median:372.67 stddev:95294.62 (25570.7%) stddev-sans-first:95529.76 18:49:51 INFO - PROCESS | 1659 | Values: 370.5 374.8 376.8 384.2 387.8 19645.8 20518.6 21959.0 22036.8 22036.9 11480.8 11693.6 11875.2 11989.6 12021.2 4351.7 5666.6 5672.8 5696.2 5699.5 5565.9 5668.8 5681.4 6167.1 6207.7 6111.4 6132.0 6156.6 6165.1 6182.5 5826.4 6101.3 6138.1 6186.4 6204.6 5016.6 5727.5 5990.8 6014.0 6014.7 12907.9 13156.9 13192.9 13205.9 13214.2 1973.4 2063.3 2071.2 2273.0 2430.6 9532.2 10368.9 10580.5 11891.0 15476.1 1755.8 1859.5 1865.5 1903.2 1963.7 120203.4 123061.0 123559.0 123865.0 124776.8 11180.6 11451.6 11490.0 11542.4 11608.0 14052.1 14242.1 14357.5 14374.9 14427.1 7676.0 7816.7 8192.2 8472.4 8583.7 7038.2 8313.5 8406.9 8407.5 8588.4 4420.3 4493.5 4493.8 4502.3 4573.7 1571.5 1633.7 1951.9 1959.4 1961.7 728.5 755.9 864.1 890.8 895.7 17522.0 20275.8 21405.0 21424.4 21602.0 764.5 892.3 901.9 943.8 994.6 8862.0 10679.8 11104.8 11269.9 11286.0 541020.0 564454.0 568393.0 576024.0 585755.0 123474.4 123760.4 127106.1 128841.0 131045.1 425.5 447.3 448.5 450.4 452.4 8801.6 11057.3 11138.8 11563.2 11620.4 30508.8 43871.8 50392.2 53240.5 55832.4 80315.0 82240.6 82788.8 82896.0 84357.6 7679.3 7939.2 8010.0 8095.4 8278.4 9090.0 10473.9 11596.5 12769.4 12793.2 5463.5 10981.8 11304.8 11319.7 11471.7 10930.5 11158.7 11163.1 11177.0 11436.0 10234.9 11331.1 11562.3 11688.4 11968.6 8011.2 8087.0 8133.1 8216.1 8287.4 8172.4 10149.5 11056.9 11312.7 11384.9 18:49:51 INFO - PROCESS | 1659 | 18:49:51 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5644.82 Median:2509.04 stddev:3191.26 (127.2%) stddev-sans-first:3184.31 18:49:51 INFO - PROCESS | 1659 | Values: 1405.8 1519.0 1698.4 1853.7 1860.8 7938.3 8475.4 8678.3 9027.7 9029.0 8710.9 8783.1 8847.8 8871.2 8900.9 2011.6 2368.6 2402.0 2418.0 2420.3 2376.8 2395.9 2397.1 2415.4 2427.0 2382.5 2394.0 2396.8 2415.9 2417.7 2366.8 2372.8 2376.0 2378.3 2385.2 2120.3 2364.2 2379.5 2381.3 2385.1 7006.6 7774.7 8939.1 8989.3 8995.1 4324.5 5313.1 5460.1 5491.6 5494.5 3047.8 3593.9 3655.0 3693.7 3700.9 1099.6 1106.6 1111.8 1113.3 1115.8 10247.4 11184.9 11656.0 11715.2 11786.0 4721.7 4724.6 4799.1 4888.6 4908.3 9921.4 10000.3 10018.4 10069.8 10082.7 4313.5 4492.2 5440.5 5445.1 5450.7 4440.9 4812.9 4959.6 4971.0 4977.8 3336.3 3958.2 4068.6 4082.8 4130.8 1146.7 1340.5 1345.6 1349.0 1349.0 655.8 903.7 908.7 910.7 941.3 4419.5 5774.4 5829.5 5991.8 6137.2 441.8 444.3 448.6 452.3 454.7 3463.7 3659.8 3854.1 4027.1 4030.3 10484.2 10815.7 10902.2 10904.2 10912.4 8060.0 10107.8 10612.1 10880.2 10898.6 8080.6 8171.8 8274.6 8290.9 8336.7 5247.1 5572.9 5988.3 6071.5 6173.4 8552.0 9987.8 10121.5 10153.0 10168.9 10124.7 10290.2 10395.5 10462.9 10474.5 7001.8 7434.3 7583.2 7583.6 7680.9 7402.4 7430.2 8504.1 8594.9 8597.8 7384.6 7496.9 7613.8 7710.1 7794.0 6177.2 7456.6 7495.5 7509.8 7612.5 6004.8 6199.8 7011.9 7027.0 7114.9 4792.0 4829.0 5044.8 5080.2 5105.2 4563.4 4914.9 4966.0 4971.9 5198.9 18:49:51 INFO - PROCESS | 1659 | 18:49:51 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4639.80 Median:5096.55 stddev:1452.75 (28.5%) stddev-sans-first:1419.36 18:49:51 INFO - PROCESS | 1659 | Values: 272.6 296.1 312.1 315.5 317.0 4856.4 5105.3 5305.7 5757.2 5837.8 4866.1 4920.6 5553.9 5628.6 5725.2 3925.0 3929.6 4340.8 4342.7 4352.4 3649.6 3932.5 4204.8 4308.4 4348.2 3446.3 4214.7 4223.5 4239.4 4245.0 3439.5 4194.6 4214.1 4225.8 4227.4 3637.0 3645.1 3648.2 3650.3 3652.3 5395.8 5522.8 5549.5 5553.3 5557.7 3855.9 4228.9 4579.8 4824.1 4828.9 4947.7 5243.8 5522.0 5751.9 6003.9 3928.4 3935.6 3966.2 3972.3 3976.7 5335.8 5578.6 6367.5 6381.8 6419.5 3573.2 4037.0 4059.0 4060.6 4065.9 3523.5 3762.3 3895.9 3952.3 4048.1 4797.6 4823.4 4827.4 4830.4 4842.4 4758.4 5610.5 5645.7 5851.4 6086.3 1308.6 1370.7 1384.2 1387.4 1389.8 894.3 900.7 922.1 925.4 926.7 5041.2 5271.6 5327.6 5338.3 5592.3 5634.9 5822.6 6073.7 6128.1 6129.2 4344.8 4432.0 5588.1 5617.6 5639.5 2465.9 2708.5 2726.7 2758.4 2771.6 5189.7 5320.3 5400.5 5428.6 5479.9 4684.5 4995.9 5330.7 5331.6 5367.4 4678.8 4717.7 4785.8 5249.6 5362.5 4111.9 4263.6 4422.2 5039.6 5087.8 4741.8 4927.6 5611.3 6102.7 6170.8 6268.2 6464.9 6530.7 6561.6 6601.0 3943.7 4021.8 4056.6 4079.3 4108.3 5253.3 5523.7 6097.4 6175.3 6179.8 5357.4 5563.8 5635.9 5885.1 6063.8 5890.7 5894.8 6051.3 6065.5 6079.3 4893.4 5151.9 5585.2 5927.9 5938.5 5333.4 5795.8 6115.5 6123.9 6147.8 5599.4 5981.5 5993.2 6004.8 6005.5 18:49:51 INFO - PROCESS | 1659 | 18:49:51 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:22282.20 Median:3825.58 stddev:33784.60 (883.1%) stddev-sans-first:33840.41 18:49:51 INFO - PROCESS | 1659 | Values: 671.7 697.3 704.0 736.4 810.1 12255.8 12718.9 12887.4 14487.7 14902.8 11274.7 13922.7 14082.5 14158.0 14207.9 844.6 862.7 867.5 871.0 874.3 687.1 843.1 854.1 861.8 863.8 907.5 911.0 917.2 917.7 917.8 898.4 904.0 905.8 911.1 914.8 662.9 676.6 678.4 680.4 684.4 14441.9 14625.6 14674.2 14676.6 14707.0 13274.7 14342.7 14376.7 14489.2 14552.6 11891.1 11909.2 11967.6 12004.5 12129.7 3778.8 3791.7 3817.5 3833.7 3942.5 88523.1 95088.5 101780.0 108082.0 114065.3 11485.0 11497.7 11574.0 11660.3 11701.5 24053.4 24398.4 24441.5 24616.3 24619.3 8243.6 8269.0 8439.7 8576.3 8599.4 4439.3 4486.7 4520.4 4583.9 4690.2 3653.4 3728.3 3739.7 3775.9 3783.8 581.2 665.4 671.3 674.7 689.2 587.5 621.4 642.0 663.5 685.5 7888.2 9349.8 9360.8 9433.4 9606.2 571.5 648.3 651.9 671.8 674.0 4489.4 4613.6 4619.8 4638.8 4673.9 91208.0 91603.0 91945.5 121280.0 152922.0 94067.0 109331.5 126749.4 127838.8 129387.7 52803.5 72373.0 72402.6 72578.0 83799.7 9751.1 9939.9 9941.6 10065.4 10085.2 53330.7 65222.0 65457.3 65685.7 65777.7 91804.1 95640.0 97688.5 100267.0 101882.3 10836.7 11066.3 12720.7 13062.4 13412.2 12762.9 12823.5 12827.7 12843.6 13795.2 13464.7 13542.9 13754.0 13870.5 14225.8 10812.3 11153.6 12321.3 12471.5 13787.4 10072.3 12509.2 13648.5 14196.5 14373.8 11622.8 11661.8 11766.2 11868.6 11996.4 10386.8 10771.8 11660.1 11781.0 11944.6 18:49:51 INFO - PROCESS | 1659 | -------- Summary: end -------- 18:49:51 INFO - PROCESS | 1659 | 18:49:52 INFO - TEST-INFO | 1659: exit 0 18:49:52 INFO - TEST-OK | dromaeo_css | took 1114779ms 18:49:52 INFO - TEST-START | kraken 18:49:52 INFO - operating with platform_type : mac_ 18:49:52 INFO - Initialising browser for kraken test... 18:49:52 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpVjzt7K/profile http://localhost:49283/getInfo.html) 18:49:54 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 18:49:54 INFO - PROCESS | 1670 | colorDepth:24 18:49:54 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 18:49:54 INFO - PROCESS | 1670 | __metrics 18:49:54 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 18:49:55 INFO - TEST-INFO | 1670: exit 0 18:49:55 INFO - Browser initialized. 18:49:55 INFO - Running cycle 1/1 for kraken test... 18:49:55 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/tmpVjzt7K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:50:07 INFO - PROCESS | 1672 | RSS: Main: 243998720 18:50:07 INFO - PROCESS | 1672 | 18:50:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 18:50:11 INFO - PROCESS | 1672 | RSS: Main: 256966656 18:50:11 INFO - PROCESS | 1672 | 18:50:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 18:50:14 INFO - PROCESS | 1672 | RSS: Main: 265601024 18:50:14 INFO - PROCESS | 1672 | 18:50:18 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 18:50:19 INFO - PROCESS | 1672 | RSS: Main: 262197248 18:50:19 INFO - PROCESS | 1672 | 18:50:21 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 18:50:21 INFO - PROCESS | 1672 | RSS: Main: 263516160 18:50:21 INFO - PROCESS | 1672 | 18:50:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:50:24 INFO - PROCESS | 1672 | RSS: Main: 263839744 18:50:24 INFO - PROCESS | 1672 | 18:50:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 18:50:27 INFO - PROCESS | 1672 | RSS: Main: 265904128 18:50:27 INFO - PROCESS | 1672 | 18:50:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 18:50:31 INFO - PROCESS | 1672 | RSS: Main: 266067968 18:50:31 INFO - PROCESS | 1672 | 18:50:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 18:50:34 INFO - PROCESS | 1672 | RSS: Main: 266174464 18:50:34 INFO - PROCESS | 1672 | 18:50:36 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:50:37 INFO - PROCESS | 1672 | RSS: Main: 265433088 18:50:37 INFO - PROCESS | 1672 | 18:50:39 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:50:39 INFO - PROCESS | 1672 | RSS: Main: 265404416 18:50:39 INFO - PROCESS | 1672 | 18:50:41 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:50:42 INFO - PROCESS | 1672 | RSS: Main: 265744384 18:50:42 INFO - PROCESS | 1672 | 18:50:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:50:46 INFO - PROCESS | 1672 | RSS: Main: 265949184 18:50:46 INFO - PROCESS | 1672 | 18:50:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:50:49 INFO - PROCESS | 1672 | RSS: Main: 265945088 18:50:49 INFO - PROCESS | 1672 | 18:50:51 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 18:50:51 INFO - PROCESS | 1672 | RSS: Main: 267771904 18:50:51 INFO - PROCESS | 1672 | 18:50:51 INFO - PROCESS | 1672 | __start_tp_report 18:50:51 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 18:50:51 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 18:50:51 INFO - PROCESS | 1672 | |i|pagename|runs| 18:50:51 INFO - PROCESS | 1672 | |0;ai-astar;139;173;117;115;119;119;118;129;174;178 18:50:51 INFO - PROCESS | 1672 | |1;audio-beat-detection;141;115;138;158;101;166;155;126;198;142 18:50:51 INFO - PROCESS | 1672 | |2;audio-dft;224;207;265;279;209;199;195;193;192;194 18:50:51 INFO - PROCESS | 1672 | |3;audio-fft;69;81;76;148;60;58;58;56;134;60 18:50:51 INFO - PROCESS | 1672 | |4;audio-oscillator;69;93;138;70;79;107;112;70;72;114 18:50:51 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;127;133;88;87;89;85;90;86;88;86 18:50:51 INFO - PROCESS | 1672 | |6;imaging-darkroom;98;97;95;96;95;95;95;95;95;96 18:50:51 INFO - PROCESS | 1672 | |7;imaging-desaturate;89;93;91;93;101;90;115;118;91;92 18:50:51 INFO - PROCESS | 1672 | |8;json-parse-financial;53;76;74;75;85;79;49;62;67;55 18:50:51 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;65;64;71;71;73;66;95;90;75;71 18:50:51 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;90;89;82;100;80;95;110;109;112;78 18:50:52 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;150;168;158;132;138;156;157;166;196;138 18:50:52 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;202;202;176;176;179;174;177;177;174;180 18:50:52 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;68;70;71;67;66;65;86;92;91;92 18:50:52 INFO - PROCESS | 1672 | __end_tp_report 18:50:52 INFO - PROCESS | 1672 | __start_cc_report 18:50:52 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,956 18:50:52 INFO - PROCESS | 1672 | __end_cc_report 18:50:52 INFO - PROCESS | 1672 | __startTimestamp1473213051992__endTimestamp 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | ------- Summary: start ------- 18:50:52 INFO - PROCESS | 1672 | Number of tests: 14 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:138.10 Median:134.00 stddev:26.45 (19.7%) stddev-sans-first:28.05 18:50:52 INFO - PROCESS | 1672 | Values: 139.0 173.0 117.0 115.0 119.0 119.0 118.0 129.0 174.0 178.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:144.00 Median:148.50 stddev:27.45 (18.5%) stddev-sans-first:29.09 18:50:52 INFO - PROCESS | 1672 | Values: 141.0 115.0 138.0 158.0 101.0 166.0 155.0 126.0 198.0 142.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:215.70 Median:208.00 stddev:31.41 (15.1%) stddev-sans-first:33.18 18:50:52 INFO - PROCESS | 1672 | Values: 224.0 207.0 265.0 279.0 209.0 199.0 195.0 193.0 192.0 194.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:80.00 Median:60.00 stddev:33.37 (55.6%) stddev-sans-first:35.16 18:50:52 INFO - PROCESS | 1672 | Values: 69.0 81.0 76.0 148.0 60.0 58.0 58.0 56.0 134.0 60.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:92.40 Median:70.00 stddev:24.25 (34.6%) stddev-sans-first:24.19 18:50:52 INFO - PROCESS | 1672 | Values: 69.0 93.0 138.0 70.0 79.0 107.0 112.0 70.0 72.0 114.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:95.90 Median:87.50 stddev:18.09 (20.7%) stddev-sans-first:15.29 18:50:52 INFO - PROCESS | 1672 | Values: 127.0 133.0 88.0 87.0 89.0 85.0 90.0 86.0 88.0 86.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:95.50 stddev:1.06 (1.1%) stddev-sans-first:0.73 18:50:52 INFO - PROCESS | 1672 | Values: 98.0 97.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:91.00 stddev:10.66 (11.7%) stddev-sans-first:10.87 18:50:52 INFO - PROCESS | 1672 | Values: 89.0 93.0 91.0 93.0 101.0 90.0 115.0 118.0 91.0 92.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:67.50 Median:74.50 stddev:12.24 (16.4%) stddev-sans-first:11.81 18:50:52 INFO - PROCESS | 1672 | Values: 53.0 76.0 74.0 75.0 85.0 79.0 49.0 62.0 67.0 55.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:74.10 Median:72.00 stddev:10.39 (14.4%) stddev-sans-first:10.48 18:50:52 INFO - PROCESS | 1672 | Values: 65.0 64.0 71.0 71.0 73.0 66.0 95.0 90.0 75.0 71.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:94.50 Median:81.00 stddev:12.81 (15.8%) stddev-sans-first:13.48 18:50:52 INFO - PROCESS | 1672 | Values: 90.0 89.0 82.0 100.0 80.0 95.0 110.0 109.0 112.0 78.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:155.90 Median:157.50 stddev:18.59 (11.8%) stddev-sans-first:19.59 18:50:52 INFO - PROCESS | 1672 | Values: 150.0 168.0 158.0 132.0 138.0 156.0 157.0 166.0 196.0 138.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.70 Median:178.00 stddev:10.86 (6.1%) stddev-sans-first:8.69 18:50:52 INFO - PROCESS | 1672 | Values: 202.0 202.0 176.0 176.0 179.0 174.0 177.0 177.0 174.0 180.0 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.80 Median:78.50 stddev:11.82 (15.1%) stddev-sans-first:12.10 18:50:52 INFO - PROCESS | 1672 | Values: 68.0 70.0 71.0 67.0 66.0 65.0 86.0 92.0 91.0 92.0 18:50:52 INFO - PROCESS | 1672 | -------- Summary: end -------- 18:50:52 INFO - PROCESS | 1672 | 18:50:52 INFO - TEST-INFO | 1672: exit 0 18:50:52 INFO - TEST-OK | kraken | took 60775ms 18:50:52 INFO - SUITE-END | took 1175s 18:50:53 INFO - Completed test suite (00:19:35) 18:50:53 INFO - javascript benchmark 18:50:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7177.475457066077, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3124.6250449946006, 3418.6923176914074, 3421.692047715705, 3425.0067480430675, 3433.0044287156725, 5827.9999999999945, 5829.912551311729, 5839.153322768196, 5862.530997520199, 5878.294604647443, 5661.264035675366, 5803.970980145104, 5836.182934389189, 5871.647701137935, 5874.0042578285065, 4375.6499480041675, 5287.312649355578, 5305.65513241639, 5369.973150134244, 5433.483819037185, 5314.707691076989, 5480.369757477882, 5499.99999999998, 5502.944970550303, 5515.834524964257, 4432.468103827545, 4833.879153021167, 5220.347456567929, 5345.251664766935, 5393.7572809223675, 5266.131088370415, 5285.075111855448, 5289.391719952217, 5311.920321195185, 5312.3093997780425, 5248.107821670287, 5259.132243179871, 5259.2899958505495, 5293.920591191135, 5298.0, 5254.15933450646, 5385.5960802939935, 5781.046127388976, 5869.50109240715, 5887.911681324784, 5061.0, 6221.657808820513, 6317.526185536103, 6320.71556779946, 6395.520335974774, 6258.3428739982555, 6263.185785847856, 6302.212223472066, 6307.653079080649, 6332.05019247112, 5805.303363596396, 5897.705114744245, 5912.793052243193, 5937.376575459601, 6004.879902401971, 6391.169148010729, 6421.2615549211505, 6431.903521447182, 6432.0, 6440.355964403522, 6155.6614386209185, 6190.969045154746, 6193.628382297076, 6197.659128747872, 6211.254649442096, 6126.303932855801, 6159.784407545804, 6231.657258850716, 6268.561200715997, 6269.592476489013, 6126.6017708848785, 6180.3201647818705, 6198.194234749454, 6211.65835879031, 6267.247930248308, 6250.937490625127, 6266.686665666698, 6301.0863424804065, 6306.8423289417215, 6348.269948955837, 4875.829345972874, 4999.375078115236, 5009.7495125243595, 5011.123053465629, 5048.545630893237, 4445.133199026159, 4456.353828694886, 4483.955160448419, 4507.2788353863225, 4535.795889184995, 5372.0, 5969.194158788508, 5993.700314984233, 6019.969900150471, 6034.909476357858, 6187.319394866561, 6279.0, 6282.151909492251, 6289.0880822280515, 6309.810705678745, 5913.172155898178, 5987.850303742442, 6013.4888534474185, 6041.72812223451, 6090.390960903963, 5505.03661859173, 5532.474414930575, 5538.778448862, 5541.5289700375115, 5565.0, 6256.436920677161, 6480.675966201671, 6523.217213934358, 6537.313582073813, 6544.345565443417, 5152.922706159486, 6229.53278504123, 6280.560360774702, 6308.243010838729, 6308.779192728416, 6469.2560355557835, 6500.577462464925, 6516.6415847128865, 6518.608883466817, 6524.67376631176, 6097.827165393427, 6280.0, 6281.497480201481, 6284.24589049317, 6295.811125666238, 6248.218972628421, 6286.622802631857, 6288.811335659938, 6292.496600271876, 6297.4017468340435, 6141.07883817431, 6210.937890621217, 6211.689415529115, 6232.719527621359, 6240.5319601031015, 5345.385280692614, 6180.7527698891545, 6209.96895015522, 6234.844128896814, 6235.158253635701, 4502.302143167814, 5533.833984980457, 5569.777208911761, 5572.4706152915405, 6044.365341639064, 5058.519440653132, 6184.041473571604, 6236.126942228001, 6252.5935814171935, 6278.372162783685, 4826.420829500483, 6037.275526936795, 6109.358517355791, 6125.173101631222, 6125.418085281891], "value": 5737.44831653391, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.5959126173338, 458.536877753469, 508.7074931914148, 552.6515302661505, 574.597781552913, 38266.61733382669, 40558.797206014, 40602.79698601506, 40610.99999999996, 40624.79687601565, 41446.79276603613, 42022.57977420233, 42169.94575135616, 42211.57788422115, 42442.36336454956, 9651.613935442592, 9830.70507884761, 9843.557039933221, 9852.655157069505, 9862.605495780195, 9610.951945240264, 9668.033196680346, 9672.661456849011, 9696.806063878717, 9720.659776907809, 9720.513974301291, 9749.0, 9756.219502439788, 9765.853512197322, 9766.706998790047, 9666.0, 9691.70924872255, 9700.854487182663, 9706.077922597342, 9714.854277185848, 9737.172340351079, 9738.123568878835, 9804.558794854249, 9818.852717209247, 9848.704538863845, 39383.8184854452, 39742.80128599367, 39890.0, 40002.19995600101, 40006.799965999984, 3268.117608245784, 3320.8671653133847, 3349.145967778201, 3502.982485087584, 3748.850045998157, 30977.535336969962, 30991.690083099107, 31012.53481197784, 31485.842570787, 32050.839745801353, 3196.872125115016, 3197.5683282756763, 3210.3579284143266, 3293.324868401972, 3299.307145499448, 504254.0, 504706.0, 505323.0, 508115.0, 518044.0, 58498.0, 58908.0, 59001.70499147477, 59026.40973590209, 59062.11406828901, 33761.493577595866, 34889.12777180591, 34918.65081349205, 61963.6901815488, 62181.37818621756, 16339.673206536041, 16372.83627163713, 16504.587385315463, 16543.83456165423, 16584.17079146038, 12675.17611355259, 12706.809397859039, 12771.744565108645, 12808.847203751708, 12819.0, 15210.771838422432, 18576.349827755963, 18844.434666960013, 18850.434486965412, 18985.715214271797, 7035.050268213726, 7559.773206803805, 7773.727919522789, 7951.562664053533, 7979.800504987422, 842.5913431985482, 915.0026471146349, 962.2446379591987, 1039.589362201926, 1078.4931082391263, 25073.874630627095, 28899.855500722362, 29923.551146732818, 29947.850260748557, 30124.594470871547, 944.6599224279254, 957.3872721458272, 1032.9690109296732, 1040.2510192661273, 1083.409541799721, 14871.256437178097, 15516.22418879074, 15617.921910390376, 15636.0, 15653.0, 188797.0, 189664.0, 189706.05146974177, 189851.0, 190082.0, 57158.7142064287, 57333.0, 57435.712821436464, 57944.71027644835, 58018.709906450196, 1133.28602980122, 1225.9019278457702, 1240.8883200511816, 1306.9283187786114, 1389.8540653231466, 22913.770862291163, 29359.11922642324, 29533.704662953955, 30158.0, 30167.547486787276, 49895.25157122574, 49995.25007124969, 50109.24836127388, 50141.0, 50176.49823501864, 558272.0, 604284.0, 606953.0, 607390.0, 609520.0, 21561.353159405244, 25198.74801251964, 26028.0, 26361.60457593176, 26407.867960660074, 38247.0, 46085.76957115327, 47371.52628473671, 47551.5244847561, 47896.04207915892, 38489.0, 38559.42160867645, 38637.22725545417, 39746.0, 40056.0, 33855.830720847225, 35088.47367289442, 35582.466263006594, 37978.43032354572, 39676.048592711326, 30360.392792143593, 37872.81063594665, 38420.42369364406, 38710.0, 38884.41673374958, 33239.83380083181, 34254.14364640904, 34617.0, 34790.0, 35074.64925350714, 25401.87299063493, 25434.23697289084, 25494.617580736674, 25875.0, 26112.477750444505], "value": 19020.351546500333, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.5053753239427, 374.8369459285211, 376.78711527986684, 384.18744355687693, 387.78284160869913, 19645.803541964575, 20518.589628207465, 21959.011844466997, 22036.779632203674, 22036.88981555092, 11480.827787583172, 11693.649190524276, 11875.228110172833, 11989.580364687237, 12021.218620789643, 4351.738895666263, 5666.631668941526, 5672.801451949183, 5696.231008813798, 5699.544036477072, 5565.860853478675, 5668.801591944282, 5681.375048744635, 6167.136600875881, 6207.658578778165, 6111.449969502743, 6132.049532322475, 6156.630602163862, 6165.136880836686, 6182.4744896683615, 5826.417358264182, 6101.328853826098, 6138.14066030756, 6186.412290832363, 6204.565680402374, 5016.573591244749, 5727.513161381287, 5990.7903223386975, 6013.969930150365, 6014.699265036731, 12907.870921290854, 13156.947444204441, 13192.86807131926, 13205.933970330183, 13214.207147571176, 1973.3882496426154, 2063.308791554831, 2071.2129390831387, 2272.9772702273094, 2430.647556104355, 9532.225051170657, 10368.948155259175, 10580.52387642558, 11891.0, 15476.148811815345, 1755.8156393578618, 1859.5351162209447, 1865.4963159946874, 1903.1626084522766, 1963.7250784890127, 120203.3989830054, 123061.0, 123559.0, 123865.0, 124776.75223247833, 11180.552777888955, 11451.59919402817, 11490.023348015346, 11542.422878856023, 11608.013318867823, 14052.102966099446, 14242.145471271757, 14357.497487587885, 14374.85625143756, 14427.134371937716, 7675.961620191863, 7816.7264145754625, 8192.221738934912, 8472.406931514857, 8583.742487725378, 7038.225795162528, 8313.542755148404, 8406.915930840736, 8407.45351552147, 8588.398812083095, 4420.336949457607, 4493.483249426293, 4493.7977790999485, 4502.302143167814, 4573.679842410999, 1571.4919208175272, 1633.69776591331, 1951.882887026783, 1959.3534133735834, 1961.7253584498183, 728.4754976416971, 755.9055118110236, 864.1444969480169, 890.8485557455248, 895.6596493332491, 17522.0, 20275.79724202739, 21405.0, 21424.357269281947, 21602.0, 764.4572353628874, 892.3040028777564, 901.9143181397757, 943.7970836270235, 994.6319861651235, 8861.955690221508, 10679.78640427187, 11104.833427498594, 11269.94365028186, 11285.984261416512, 541020.0, 564454.0, 568393.0, 576024.0, 585755.0, 123474.38262808628, 123760.38119809343, 127106.09340859894, 128841.0, 131045.1372917628, 425.5404163503409, 447.3022085546558, 448.450647956251, 450.38297532380847, 452.3802390724728, 8801.647934082821, 11057.336559806437, 11138.832917506406, 11563.248388854698, 11620.418979050844, 30508.847455762578, 43871.78064109659, 50392.22842743537, 53240.4675953251, 55832.441675582726, 80315.0, 82240.58879705802, 82788.75816862631, 82896.0, 84357.57821210855, 7679.2704693054075, 7939.166387529226, 8010.0, 8095.433319667803, 8278.420510564203, 9090.0, 10473.900240474944, 11596.478158483058, 12769.361531923181, 12793.232406055666, 5463.48096930791, 10981.780364392827, 11304.83042754343, 11319.660410187707, 11471.655850324503, 10930.508127134139, 11158.721031974266, 11163.051140653135, 11177.049950754277, 11436.0, 10234.948825255826, 11331.093512518813, 11562.306261623993, 11688.415579220835, 11968.640940771791, 8011.198880111942, 8086.959565202136, 8133.0646975598565, 8216.055153657167, 8287.41988060854, 8172.38707096982, 10149.492525373555, 11056.89431056888, 11312.717182070513, 11384.943075284571], "value": 8784.181626857355, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.810215620891, 1519.005051691142, 1698.405558054681, 1853.7404763333127, 1860.795312515623, 7938.325242354393, 8475.406721529487, 8678.305735541157, 9027.729168124959, 9029.0, 8710.869336959951, 8783.12168783123, 8847.805546251255, 8871.201591856732, 8900.91099089009, 2011.6479616067204, 2368.5736567417903, 2402.027178992511, 2417.9637305440433, 2420.3465064432594, 2376.8336216464854, 2395.9281221563383, 2397.0531640002187, 2415.3599296186526, 2426.9514609707794, 2382.523495300938, 2393.964090538643, 2396.7962723168553, 2415.903363865443, 2417.746136655652, 2366.751491093428, 2372.833901626887, 2375.9762402375927, 2378.310290015902, 2385.1890357278435, 2120.257909731597, 2364.2268298775916, 2379.512199998996, 2381.345130089222, 2385.105585405473, 7006.649667516604, 7774.689012439496, 8939.061398553189, 8989.280857531381, 8995.055519170524, 4324.545922678105, 5313.123334649778, 5460.099083651193, 5491.588130890199, 5494.5054945054735, 3047.7866549341556, 3593.8562457501666, 3655.0, 3693.7229707772026, 3700.888973330804, 1099.571167244775, 1106.623747925709, 1111.7554138089608, 1113.3208742666966, 1115.827046807746, 10247.436390998491, 11184.944075279653, 11656.0, 11715.238509496856, 11785.998190153847, 4721.6687754201375, 4724.551167639069, 4799.136155491975, 4888.633352498506, 4908.337374454474, 9921.355111917703, 10000.299979001546, 10018.448985305733, 10069.848952265722, 10082.69751907444, 4313.503947046067, 4492.213862574036, 5440.483154100354, 5445.128779395278, 5450.700211488327, 4440.911181776346, 4812.927806082912, 4959.553640172402, 4971.0, 4977.825776097891, 3336.2660214752714, 3958.2281455115976, 4068.613481719232, 4082.7550346978683, 4130.793460326971, 1146.6846617180342, 1340.530814215017, 1345.5828693104972, 1348.952786652482, 1348.9595312140652, 655.783591414832, 903.7243640689521, 908.7319240823973, 910.6676063236871, 941.3114089513612, 4419.535948725402, 5774.364819869811, 5829.504492118217, 5991.760329586855, 6137.232845894263, 441.7857339190554, 444.3490286729956, 448.62315654850084, 452.32829248565855, 454.69762607865397, 3463.6709512596262, 3659.780413175218, 3854.1328201154515, 4027.093903871664, 4030.274550580865, 10484.213683973732, 10815.67552973412, 10902.182336324808, 10904.236703430684, 10912.399818010086, 8059.959700201579, 10107.848382274271, 10612.097971672494, 10880.183986201067, 10898.564057437614, 8080.555569443737, 8171.795705107301, 8274.627641756135, 8290.875636865452, 8336.749897503085, 5247.081760691787, 5572.885422915352, 5988.281406231106, 6071.512479442565, 6173.351798061139, 8551.957240213758, 9987.751531058617, 10121.493925303557, 10153.0, 10168.898311016796, 10124.746881327732, 10290.228232882713, 10395.532201050819, 10462.895371046192, 10474.476276186313, 7001.824954376181, 7434.293742094493, 7583.2416758325935, 7583.582902940391, 7680.9231907680205, 7402.444816638881, 7430.182679905098, 8504.149585041447, 8594.914050859661, 8597.785055373666, 7384.556926584422, 7496.925030749622, 7613.809654758676, 7710.074791025113, 7794.0, 6177.166082578794, 7456.552606843607, 7495.475316727777, 7509.849803004018, 7612.543247405173, 6004.789832355847, 6199.783007594712, 7011.929880701127, 7026.981087742351, 7114.857702846018, 4791.952080479151, 4828.975855120702, 5044.848654540369, 5080.187170052774, 5105.18317069282, 4563.3839431677625, 4914.852554423373, 4965.950340496549, 4971.875703107451, 5198.922016169685], "value": 4471.77785647686, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.6428378823741, 296.07329060042065, 312.14783640660994, 315.4904828701647, 316.9540416639586, 4856.441509226431, 5105.259737338084, 5305.700103474649, 5757.222774925383, 5837.824865254039, 4866.051120031595, 4920.630952678554, 5553.888922221553, 5628.634138780976, 5725.227094342263, 3925.0, 3929.5657085163994, 4340.804663790137, 4342.696011279212, 4352.368906508555, 3649.5802982656937, 3932.488776459071, 4204.810783514749, 4308.3537469379535, 4348.21732088224, 3446.327966046609, 4214.704970652056, 4223.515599630302, 4239.40648309237, 4244.97877510612, 3439.518467414564, 4194.601512856274, 4214.136102099063, 4225.809838557272, 4227.408162857202, 3636.9636303637153, 3645.1251699591908, 3648.1973965727498, 3650.34293827111, 3652.324320000782, 5395.845199196597, 5522.834314970557, 5549.528290095347, 5553.278073850373, 5557.749901254453, 3855.903602409934, 4228.9154216915795, 4579.816807327703, 4824.1316563018645, 4828.927566086511, 4947.653664243504, 5243.79024839006, 5521.950829342452, 5751.942480575182, 6003.909941350883, 3928.4107383892647, 3935.5670876203594, 3966.226585815767, 3972.312671048162, 3976.741511801724, 5335.813246536352, 5578.577462747014, 6367.458766004896, 6381.766381766391, 6419.454346380563, 3573.2317551726505, 4037.0, 4059.0, 4060.573639767841, 4065.898352541151, 3523.4714792780774, 3762.285165818486, 3895.928619629568, 3952.3083460394314, 4048.149888523384, 4797.64017698674, 4823.397075365579, 4827.444843843003, 4830.372051633265, 4842.418909730784, 4758.405199350082, 5610.5231055360755, 5645.745941432645, 5851.414858514115, 6086.330503644595, 1308.640123965917, 1370.7190026044836, 1384.2248340929111, 1387.4172847404116, 1389.8394840308254, 894.3203165594065, 900.7477906186278, 922.1331947969022, 925.3661242049218, 926.6525053105086, 5041.168207245837, 5271.551918087006, 5327.600429967768, 5338.252644629755, 5592.273004509388, 5634.887302253932, 5822.621529600647, 6073.6659483727935, 6128.050152226412, 6129.2032035835055, 4344.826206951687, 4431.977840110779, 5588.050031494656, 5617.606767526234, 5639.464250896158, 2465.8657017772025, 2708.5124677557833, 2726.7320695876115, 2758.4069425073703, 2771.5704065869822, 5189.7405129743365, 5320.308359913264, 5400.4599540046465, 5428.5928555358505, 5479.917801232985, 4684.508126646721, 4995.850124496271, 5330.653507521999, 5331.573474122061, 5367.409584945653, 4678.812847486062, 4717.7497963040505, 4785.803549112722, 5249.6088536537845, 5362.52229388465, 4111.8972025699595, 4263.627828935501, 4422.159789639958, 5039.573090298632, 5087.847364579068, 4741.786619602057, 4927.605791536596, 5611.31660501856, 6102.7253773579405, 6170.845728856814, 6268.179319306421, 6464.870702586016, 6530.701995492246, 6561.606303621798, 6600.966995164993, 3943.743656662308, 4021.778802165909, 4056.59434056592, 4079.306517891906, 4108.322126849156, 5253.3189379399755, 5523.6685798852195, 6097.390260974044, 6175.2589689237575, 6179.84550386226, 5357.383900851452, 5563.774490203789, 5635.9436405635415, 5885.117232415172, 6063.818085457443, 5890.7054647266605, 5894.764209431746, 6051.304100028553, 6065.54508411862, 6079.3312735598165, 4893.363862697826, 5151.866589350337, 5585.240649195491, 5927.940720592739, 5938.465538101592, 5333.359996800409, 5795.82612521625, 6115.532272254716, 6123.908141377794, 6147.754089836357, 5599.384067752625, 5981.461668449861, 5993.190919225849, 6004.759809607742, 6005.549583781147], "value": 4176.914305483121, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.7203726900257, 697.2643860726934, 704.0495331302742, 736.3814395907443, 810.1210186947162, 12255.754884902297, 12718.855303022738, 12887.420066097025, 14487.710245795079, 14902.776458353133, 11274.718132046703, 13922.651933701662, 14082.507112251047, 14158.0, 14207.928960355184, 844.552387234765, 862.6678728689437, 867.5055218525443, 870.960806763694, 874.2699845628875, 687.1410736579276, 843.1357858195338, 854.107457706698, 861.8448679237734, 863.8013256950906, 907.4600612635471, 911.0388540090203, 917.179124683408, 917.7017469322464, 917.7567944494714, 898.4384759525296, 904.0417157812707, 905.8369493491169, 911.0525053943891, 914.7713071732067, 662.9237637671681, 676.610948704492, 678.4301187002892, 680.3604611665069, 684.428502200663, 14441.927790360982, 14625.634359141, 14674.192919389428, 14676.55970320892, 14707.0, 13274.66813329665, 14342.713145737027, 14376.712465750627, 14489.203093829836, 14552.563423097325, 11891.108166887518, 11909.166358354918, 11967.64097077089, 12004.459799308965, 12129.69675758113, 3778.8299526521373, 3791.6777073948747, 3817.48463957365, 3833.674137698299, 3942.467766851467, 88523.11476885277, 95088.52455737746, 101780.0, 108082.0, 114065.28902066476, 11485.0, 11497.655070347902, 11574.0, 11660.30038197711, 11701.531938722526, 24053.398665033514, 24398.39004024878, 24441.51116977686, 24616.261512154662, 24619.291877893418, 8243.629036693363, 8268.958655206685, 8439.746807595782, 8576.313894888393, 8599.355048371272, 4439.289713645801, 4486.730796152241, 4520.367148599199, 4583.908321833612, 4690.155771961012, 3653.415453527423, 3728.3102626014274, 3739.700823934079, 3775.867844625425, 3783.829727662262, 581.2269681323829, 665.364576829127, 671.3348008406618, 674.6592970549841, 689.2108535726666, 587.465406480101, 621.4438077920327, 641.9518536109811, 663.4692246203018, 685.5030103175158, 7888.171741967126, 9349.766255843659, 9360.765980850398, 9433.386829856037, 9606.183474404754, 571.4856629033819, 648.2805718725377, 651.947844172465, 671.8454755406186, 673.9932600674028, 4489.438820147481, 4613.584777369986, 4619.792109355087, 4638.840289927518, 4673.859784206479, 91208.0, 91603.0, 91945.54027229821, 121280.02079708848, 152922.0, 94067.0, 109331.45334273278, 126749.36625316815, 127838.80402989814, 129387.70612293945, 52803.47196527985, 72372.99999999895, 72402.63798681078, 72578.0, 83799.743003855, 9751.122398984126, 9939.850902236616, 9941.558151869713, 10065.396076235449, 10085.243606729671, 53330.73334633302, 65222.0, 65457.34542654512, 65685.67157164184, 65777.67111164413, 91804.08195917956, 95640.0, 97688.51155744176, 100267.0, 101882.34000109778, 10836.729081773019, 11066.28069175501, 12720.74558508843, 13062.412191451218, 13412.195268283935, 12762.936185319015, 12823.487060517848, 12827.743445130858, 12843.614691559269, 13795.172289662652, 13464.663383415102, 13542.932285338511, 13754.037217394687, 13870.514531991736, 14225.786613200604, 10812.297200681931, 11153.609623663133, 12321.260724356567, 12471.5011399543, 13787.448502059806, 10072.294939354175, 12509.186902851285, 13648.454061837416, 14196.503122390666, 14373.784393234318, 11622.767544649229, 11661.76676466483, 11766.235194712317, 11868.58459953932, 11996.400179990791, 10386.844197336895, 10771.784564308826, 11660.06719462458, 11780.998615117811, 11944.581939632071], "value": 7635.069475054056, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1609.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 173.0, 117.0, 115.0, 119.0, 119.0, 118.0, 129.0, 174.0, 178.0], "value": 138.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 138.0, 158.0, 101.0, 166.0, 155.0, 126.0, 198.0, 142.0], "value": 144.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 207.0, 265.0, 279.0, 209.0, 199.0, 195.0, 193.0, 192.0, 194.0], "value": 215.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 76.0, 148.0, 60.0, 58.0, 58.0, 56.0, 134.0, 60.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 93.0, 138.0, 70.0, 79.0, 107.0, 112.0, 70.0, 72.0, 114.0], "value": 92.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 133.0, 88.0, 87.0, 89.0, 85.0, 90.0, 86.0, 88.0, 86.0], "value": 95.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 91.0, 93.0, 101.0, 90.0, 115.0, 118.0, 91.0, 92.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 76.0, 74.0, 75.0, 85.0, 79.0, 49.0, 62.0, 67.0, 55.0], "value": 67.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 71.0, 71.0, 73.0, 66.0, 95.0, 90.0, 75.0, 71.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 82.0, 100.0, 80.0, 95.0, 110.0, 109.0, 112.0, 78.0], "value": 94.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 168.0, 158.0, 132.0, 138.0, 156.0, 157.0, 166.0, 196.0, 138.0], "value": 155.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 202.0, 176.0, 176.0, 179.0, 174.0, 177.0, 177.0, 174.0, 180.0], "value": 181.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 71.0, 67.0, 66.0, 65.0, 86.0, 92.0, 91.0, 92.0], "value": 76.8, "unit": "score"}]}]} 18:50:53 INFO - Return code: 0 18:50:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:50:53 ERROR - # TBPL SUCCESS # 18:50:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:50:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:50:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:50:53 INFO - 2016-09-06 18:50:53.291 screenresolution[1677:10377] starting screenresolution argv=screenresolution get 18:50:53 INFO - 2016-09-06 18:50:53.293 screenresolution[1677:10377] Display 0: 1600x1200x32@60 18:50:53 INFO - 2016-09-06 18:50:53.299 screenresolution[1678:10380] starting screenresolution argv=screenresolution list 18:50:53 INFO - Available Modes on Display 0 18:50:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:50:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:50:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:50:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:50:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:50:53 INFO - Intel Iris: 18:50:53 INFO - Chipset Model: Intel Iris 18:50:53 INFO - Type: GPU 18:50:53 INFO - Bus: Built-In 18:50:53 INFO - VRAM (Dynamic, Max): 1536 MB 18:50:53 INFO - Vendor: Intel (0x8086) 18:50:53 INFO - Device ID: 0x0a2e 18:50:53 INFO - Revision ID: 0x0009 18:50:53 INFO - Displays: 18:50:53 INFO - Display: 18:50:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:50:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:50:53 INFO - Display Serial Number: 1600x1200 60 18:50:53 INFO - Main Display: Yes 18:50:53 INFO - Mirror: Off 18:50:53 INFO - Online: Yes 18:50:53 INFO - Rotation: Supported 18:50:53 INFO - Return code: 0 18:50:53 INFO - Running post-action listener: _resource_record_post_action 18:50:53 INFO - [mozharness: 2016-09-07 01:50:53.536417Z] Finished run-tests step (success) 18:50:53 INFO - Running post-run listener: _resource_record_post_run 18:50:53 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 38454272; Write bytes: 703917056; Read time: 500; Write time: 1961 18:50:53 INFO - TinderboxPrint: CPU usage
54.6% 18:50:53 INFO - TinderboxPrint: I/O read bytes / time
38,454,272 / 500 18:50:53 INFO - TinderboxPrint: I/O write bytes / time
703,917,056 / 1,961 18:50:53 INFO - TinderboxPrint: CPU idle
2,197.1 (45.4%) 18:50:53 INFO - TinderboxPrint: CPU system
986.2 (20.4%) 18:50:53 INFO - TinderboxPrint: CPU user
1,658.3 (34.3%) 18:50:53 INFO - TinderboxPrint: Swap in / out
444,067,840 / 0 18:50:53 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398344704; Write bytes: 392320512; Read time: 29546; Write time: 704 18:50:53 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 36389888; Write bytes: 294876672; Read time: 351; Write time: 1218 18:50:54 INFO - Running post-run listener: _upload_blobber_files 18:50:54 INFO - Blob upload gear active. 18:50:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:50:54 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 18:50:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:50:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:50:54 INFO - (blobuploader) - INFO - Open directory for files ... 18:50:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:50:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:50:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:55 INFO - (blobuploader) - INFO - Done attempting. 18:50:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:50:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:50:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:56 INFO - (blobuploader) - INFO - Done attempting. 18:50:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:50:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:50:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:50:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:50:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:50:59 INFO - (blobuploader) - INFO - Done attempting. 18:50:59 INFO - (blobuploader) - INFO - Iteration through files over. 18:50:59 INFO - Return code: 0 18:50:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:50:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:50:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7c23998772b9cc094574dc6aed93da6779e2ea68cf3a6ca0521b9d84b183c43473694768b17c41786574580b8e49f454ea0a3e5afb3972c2ccea25922a032bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c04e0d3f39004c3c08a8d2562484a92af7f416cd1ec8135b8b6a5bb4536e2e7b369bf91afc851216ad949bb7ca00259a8f12e268031489a7af2aa13b0138ee40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5efc06a61dd76dec955117ae5e79681a09485283682dc263a2d59af0d194ded22dd9393172e3e4294e02aec95521e132f674905e7b15bc5ed8c66ff789c08fb4"} 18:50:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:50:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:50:59 INFO - Contents: 18:50:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7c23998772b9cc094574dc6aed93da6779e2ea68cf3a6ca0521b9d84b183c43473694768b17c41786574580b8e49f454ea0a3e5afb3972c2ccea25922a032bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c04e0d3f39004c3c08a8d2562484a92af7f416cd1ec8135b8b6a5bb4536e2e7b369bf91afc851216ad949bb7ca00259a8f12e268031489a7af2aa13b0138ee40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5efc06a61dd76dec955117ae5e79681a09485283682dc263a2d59af0d194ded22dd9393172e3e4294e02aec95521e132f674905e7b15bc5ed8c66ff789c08fb4"} 18:50:59 INFO - Running post-run listener: copy_logs_to_upload_dir 18:50:59 INFO - Copying logs to upload dir... 18:50:59 INFO - mkdir: /builds/slave/test/build/upload/logs 18:50:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.362542 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-06 18:50:59.384003) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 18:50:59.387125) ========= 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.vYqcQoIK24/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.8l1TwXsdcX/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7c23998772b9cc094574dc6aed93da6779e2ea68cf3a6ca0521b9d84b183c43473694768b17c41786574580b8e49f454ea0a3e5afb3972c2ccea25922a032bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c04e0d3f39004c3c08a8d2562484a92af7f416cd1ec8135b8b6a5bb4536e2e7b369bf91afc851216ad949bb7ca00259a8f12e268031489a7af2aa13b0138ee40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5efc06a61dd76dec955117ae5e79681a09485283682dc263a2d59af0d194ded22dd9393172e3e4294e02aec95521e132f674905e7b15bc5ed8c66ff789c08fb4"} build_url:https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010682 build_url: 'https://queue.taskcluster.net/v1/task/QHxu8TgOTQOOrwLfpLPcGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d7c23998772b9cc094574dc6aed93da6779e2ea68cf3a6ca0521b9d84b183c43473694768b17c41786574580b8e49f454ea0a3e5afb3972c2ccea25922a032bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c04e0d3f39004c3c08a8d2562484a92af7f416cd1ec8135b8b6a5bb4536e2e7b369bf91afc851216ad949bb7ca00259a8f12e268031489a7af2aa13b0138ee40", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5efc06a61dd76dec955117ae5e79681a09485283682dc263a2d59af0d194ded22dd9393172e3e4294e02aec95521e132f674905e7b15bc5ed8c66ff789c08fb4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 18:50:59.420391) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:50:59.420734) ========= 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.vYqcQoIK24/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.8l1TwXsdcX/Listeners TMPDIR=/var/folders/zn/cd1kk5_j395_6yk0j171qxfh00000w/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.005043 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 18:50:59.469681) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 18:50:59.470042) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 18:50:59.470379) ========= ========= Total master_lag: 0.17 =========