builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0231 starttime: 1463186605.93 results: success (0) buildid: 20160513165348 builduid: 11feeaf77154451cb21915446376703d revision: 1956edae83a4be83b828fcd4986e1403d007d5bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:25.934203) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:25.935309) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:25.937494) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:25.974400) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:25.974707) ========= 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.nvNpJEfMce/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.pcoawy6Yi8/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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-05-13 17:43:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-05-13 17:43:26 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.204007 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:26.259910) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:26.260272) ========= 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.nvNpJEfMce/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.pcoawy6Yi8/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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.025031 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:26.356064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:26.356477) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1956edae83a4be83b828fcd4986e1403d007d5bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1956edae83a4be83b828fcd4986e1403d007d5bc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nvNpJEfMce/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.pcoawy6Yi8/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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-05-13 17:43:26,554 truncating revision to first 12 chars 2016-05-13 17:43:26,554 Setting DEBUG logging. 2016-05-13 17:43:26,554 attempt 1/10 2016-05-13 17:43:26,554 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1956edae83a4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 17:43:27,079 unpacking tar archive at: mozilla-aurora-1956edae83a4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.782722 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:27.183158) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:27.186517) ========= script_repo_revision: 1956edae83a4be83b828fcd4986e1403d007d5bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:27.186971) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:27.187275) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 17:43:27.202554) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 39 secs) (at 2016-05-13 17:43:27.202888) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nvNpJEfMce/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.pcoawy6Yi8/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 17:43:27 INFO - MultiFileLogger online at 20160513 17:43:27 in /builds/slave/test 17:43:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:43:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:43:27 INFO - {'append_to_log': False, 17:43:27 INFO - 'base_work_dir': '/builds/slave/test', 17:43:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:43:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:43:27 INFO - 'branch': 'Mozilla-Aurora', 17:43:27 INFO - 'buildbot_json_path': 'buildprops.json', 17:43:27 INFO - 'config_files': ('talos/mac_config.py',), 17:43:27 INFO - 'default_actions': ('clobber', 17:43:27 INFO - 'read-buildbot-config', 17:43:27 INFO - 'download-and-extract', 17:43:27 INFO - 'populate-webroot', 17:43:27 INFO - 'create-virtualenv', 17:43:27 INFO - 'install', 17:43:27 INFO - 'run-tests'), 17:43:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:43:27 INFO - 'download_minidump_stackwalk': True, 17:43:27 INFO - 'download_symbols': 'ondemand', 17:43:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:43:27 INFO - 'tooltool.py': '/tools/tooltool.py', 17:43:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:43:27 INFO - '/tools/misc-python/virtualenv.py')}, 17:43:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:43:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:43:27 INFO - 'installer_path': 'installer.exe', 17:43:27 INFO - 'log_level': 'info', 17:43:27 INFO - 'log_name': 'talos', 17:43:27 INFO - 'log_to_console': True, 17:43:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:43:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:43:27 INFO - 'opt_config_files': (), 17:43:27 INFO - 'pip_index': False, 17:43:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:43:27 INFO - 'cmd': ('bash', 17:43:27 INFO - '-c', 17:43:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:43:27 INFO - 'enabled': True, 17:43:27 INFO - 'halt_on_failure': False, 17:43:27 INFO - 'name': 'check_screen_resolution'},), 17:43:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:43:27 INFO - 'cmd': ('bash', 17:43:27 INFO - '-c', 17:43:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:43:27 INFO - 'enabled': True, 17:43:27 INFO - 'halt_on_failure': False, 17:43:27 INFO - 'name': 'check_screen_resolution'},), 17:43:27 INFO - 'run_cmd_checks_enabled': True, 17:43:27 INFO - 'sps_profile': False, 17:43:27 INFO - 'sps_profile_interval': 0, 17:43:27 INFO - 'suite': 'dromaeojs', 17:43:27 INFO - 'system_bits': '32', 17:43:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:43:27 INFO - 'title': 't-yosemite-r7-0231', 17:43:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:43:27 INFO - 'use_talos_json': True, 17:43:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:43:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:43:27 INFO - 'work_dir': 'build'} 17:43:27 INFO - ##### 17:43:27 INFO - ##### Running clobber step. 17:43:27 INFO - ##### 17:43:27 INFO - Running pre-action listener: _resource_record_pre_action 17:43:27 INFO - Running main action method: clobber 17:43:27 INFO - rmtree: /builds/slave/test/build 17:43:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:43:29 INFO - Running post-action listener: _resource_record_post_action 17:43:29 INFO - ##### 17:43:29 INFO - ##### Running read-buildbot-config step. 17:43:29 INFO - ##### 17:43:29 INFO - Running pre-action listener: _resource_record_pre_action 17:43:29 INFO - Running main action method: read_buildbot_config 17:43:29 INFO - Using buildbot properties: 17:43:29 INFO - { 17:43:29 INFO - "project": "", 17:43:29 INFO - "product": "firefox", 17:43:29 INFO - "script_repo_revision": "production", 17:43:29 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 17:43:29 INFO - "repository": "", 17:43:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 17:43:29 INFO - "buildid": "20160513165348", 17:43:29 INFO - "pgo_build": "False", 17:43:29 INFO - "basedir": "/builds/slave/test", 17:43:29 INFO - "buildnumber": 320, 17:43:29 INFO - "slavename": "t-yosemite-r7-0231", 17:43:29 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 17:43:29 INFO - "platform": "yosemite_r7", 17:43:29 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:43:29 INFO - "branch": "mozilla-aurora", 17:43:29 INFO - "repo_path": "releases/mozilla-aurora", 17:43:29 INFO - "revision": "1956edae83a4be83b828fcd4986e1403d007d5bc", 17:43:29 INFO - "stage_platform": "macosx64", 17:43:29 INFO - "builduid": "11feeaf77154451cb21915446376703d", 17:43:29 INFO - "slavebuilddir": "test" 17:43:29 INFO - } 17:43:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 17:43:29 INFO - Running post-action listener: _resource_record_post_action 17:43:29 INFO - ##### 17:43:29 INFO - ##### Running download-and-extract step. 17:43:29 INFO - ##### 17:43:29 INFO - Running pre-action listener: _resource_record_pre_action 17:43:29 INFO - Running main action method: download_and_extract 17:43:29 INFO - mkdir: /builds/slave/test/build/tests 17:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:43:29 INFO - https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:43:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:43:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:43:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 17:43:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 17:43:30 INFO - Downloaded 1199 bytes. 17:43:30 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 17:43:30 INFO - Using the following test package requirements: 17:43:30 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:43:30 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:43:30 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 17:43:30 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:43:30 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 17:43:30 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:43:30 INFO - u'jsshell-mac.zip'], 17:43:30 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:43:30 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 17:43:30 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:43:30 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:43:30 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 17:43:30 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:43:30 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 17:43:30 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:43:30 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 17:43:30 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:43:30 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 17:43:30 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:43:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:43:30 INFO - https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:43:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:43:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:43:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:43:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:43:33 INFO - Downloaded 18101621 bytes. 17:43:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:43:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:43:35 INFO - Return code: 0 17:43:35 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 17:43:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:43:35 INFO - https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:43:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:43:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:43:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:43:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:44:05 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip: timed out 17:44:05 INFO - retry: attempt #1 caught exception: timed out 17:44:05 INFO - retry: Failed, sleeping 30 seconds before retrying 17:44:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #2 17:44:37 INFO - Downloaded 18101621 bytes. 17:44:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:44:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:44:40 INFO - Return code: 0 17:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:40 INFO - https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:44:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:44:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:44:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:44:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 17:44:41 INFO - Downloaded 10840145 bytes. 17:44:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:44:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:44:42 INFO - Return code: 0 17:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:42 INFO - https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 17:44:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:44:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:44:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 17:44:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 17:44:45 INFO - Downloaded 114119381 bytes. 17:44:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:44:45 INFO - mkdir: /builds/slave/test/properties 17:44:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:44:45 INFO - Writing to file /builds/slave/test/properties/build_url 17:44:45 INFO - Contents: 17:44:45 INFO - build_url:https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:44:45 INFO - Running post-action listener: _resource_record_post_action 17:44:45 INFO - Running post-action listener: set_extra_try_arguments 17:44:45 INFO - ##### 17:44:45 INFO - ##### Running populate-webroot step. 17:44:45 INFO - ##### 17:44:45 INFO - Running pre-action listener: _resource_record_pre_action 17:44:45 INFO - Running main action method: populate_webroot 17:44:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:44:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:44:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:44:45 INFO - '--tppagecycles', 17:44:45 INFO - '7'], 17:44:45 INFO - 'tests': ['tsvgm']}}, 17:44:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:44:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:44:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:44:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:44:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:44:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:44:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:45 INFO - 'talos_options': ['--disable-e10s'], 17:44:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:44:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:45 INFO - 'talos_options': ['--disable-e10s'], 17:44:45 INFO - 'tests': ['damp', 'tps']}, 17:44:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:45 INFO - 'tests': ['damp', 'tps']}, 17:44:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:44:45 INFO - 'tests': ['dromaeo_dom']}, 17:44:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:44:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:44:45 INFO - 'tests': ['a11yr', 17:44:45 INFO - 'ts_paint', 17:44:45 INFO - 'tpaint', 17:44:45 INFO - 'sessionrestore', 17:44:45 INFO - 'sessionrestore_no_auto_restore', 17:44:45 INFO - 'tabpaint']}, 17:44:45 INFO - 'other-e10s': {'tests': ['a11yr', 17:44:45 INFO - 'ts_paint', 17:44:45 INFO - 'tpaint', 17:44:45 INFO - 'sessionrestore', 17:44:45 INFO - 'sessionrestore_no_auto_restore', 17:44:45 INFO - 'tabpaint']}, 17:44:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:44:45 INFO - 'ts_paint', 17:44:45 INFO - 'tpaint', 17:44:45 INFO - 'sessionrestore', 17:44:45 INFO - 'sessionrestore_no_auto_restore', 17:44:45 INFO - 'tabpaint']}, 17:44:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:44:45 INFO - 'ts_paint', 17:44:45 INFO - 'tpaint', 17:44:45 INFO - 'sessionrestore', 17:44:45 INFO - 'sessionrestore_no_auto_restore', 17:44:45 INFO - 'tabpaint']}, 17:44:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:44:45 INFO - 'tests': ['a11yr', 17:44:45 INFO - 'ts_paint', 17:44:45 INFO - 'tpaint', 17:44:45 INFO - 'sessionrestore', 17:44:45 INFO - 'sessionrestore_no_auto_restore', 17:44:45 INFO - 'tabpaint']}, 17:44:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:44:45 INFO - 'tests': ['a11yr', 17:44:45 INFO - 'ts_paint', 17:44:45 INFO - 'tpaint', 17:44:45 INFO - 'sessionrestore', 17:44:45 INFO - 'sessionrestore_no_auto_restore', 17:44:45 INFO - 'tabpaint']}, 17:44:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:44:45 INFO - 'tests': ['tsvgx', 17:44:45 INFO - 'tsvgr_opacity', 17:44:45 INFO - 'tart', 17:44:45 INFO - 'tscrollx', 17:44:45 INFO - 'cart']}, 17:44:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:44:45 INFO - 'tsvgr_opacity', 17:44:45 INFO - 'tart', 17:44:45 INFO - 'tscrollx', 17:44:45 INFO - 'cart']}, 17:44:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:45 INFO - 'talos_options': ['--disable-e10s'], 17:44:45 INFO - 'tests': ['tp5o']}, 17:44:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:45 INFO - 'tests': ['tp5o']}, 17:44:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:45 INFO - 'talos_options': ['--disable-e10s', 17:44:45 INFO - '--xperf_path', 17:44:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:45 INFO - 'tests': ['tp5n']}, 17:44:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:44:45 INFO - 'talos_options': ['--xperf_path', 17:44:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:44:45 INFO - 'tests': ['tp5n']}}, 17:44:45 INFO - 'talos.zip': {'path': '', 17:44:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:44:45 INFO - Running post-action listener: _resource_record_post_action 17:44:45 INFO - ##### 17:44:45 INFO - ##### Running create-virtualenv step. 17:44:45 INFO - ##### 17:44:45 INFO - Running pre-action listener: _resource_record_pre_action 17:44:45 INFO - Running main action method: create_virtualenv 17:44:45 INFO - Creating virtualenv /builds/slave/test/build/venv 17:44:45 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 17:44:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:44:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:44:46 INFO - Using real prefix '/tools/python27' 17:44:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:44:46 INFO - Installing distribute.............................................................................................................................................................................................done. 17:44:49 INFO - Installing pip.................done. 17:44:49 INFO - Return code: 0 17:44:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e687a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0c85350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e526e00>, '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 0x10e38fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e521b20>, '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.nvNpJEfMce/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.pcoawy6Yi8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 17:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvNpJEfMce/Render', 17:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:44:49 INFO - 'HOME': '/Users/cltbld', 17:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:44:49 INFO - 'LOGNAME': 'cltbld', 17:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:49 INFO - 'MOZ_NO_REMOTE': '1', 17:44:49 INFO - 'NO_EM_RESTART': '1', 17:44:49 INFO - 'PAGER': '/bin/cat', 17:44:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:49 INFO - 'PWD': '/builds/slave/test', 17:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:44:49 INFO - 'SHELL': '/bin/bash', 17:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pcoawy6Yi8/Listeners', 17:44:49 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 17:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:44:49 INFO - 'USER': 'cltbld', 17:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:49 INFO - 'XPC_FLAGS': '0x0', 17:44:49 INFO - 'XPC_SERVICE_NAME': '0', 17:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:44:49 INFO - Cleaning up... 17:44:49 INFO - Return code: 0 17:44:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e687a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0c85350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e526e00>, '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 0x10e38fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e521b20>, '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.nvNpJEfMce/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.pcoawy6Yi8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 17:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 17:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:44:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvNpJEfMce/Render', 17:44:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:44:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:44:49 INFO - 'HOME': '/Users/cltbld', 17:44:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:44:49 INFO - 'LOGNAME': 'cltbld', 17:44:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:49 INFO - 'MOZ_NO_REMOTE': '1', 17:44:49 INFO - 'NO_EM_RESTART': '1', 17:44:49 INFO - 'PAGER': '/bin/cat', 17:44:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:44:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:49 INFO - 'PWD': '/builds/slave/test', 17:44:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:44:49 INFO - 'SHELL': '/bin/bash', 17:44:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pcoawy6Yi8/Listeners', 17:44:49 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 17:44:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:44:49 INFO - 'USER': 'cltbld', 17:44:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:44:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:44:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:49 INFO - 'XPC_FLAGS': '0x0', 17:44:49 INFO - 'XPC_SERVICE_NAME': '0', 17:44:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:50 INFO - Downloading/unpacking psutil>=0.7.1 17:44:50 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 17:44:50 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 17:44:50 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 17:44:50 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 17:44:50 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 17:44:50 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 17:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:44:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:44:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:44:53 INFO - Installing collected packages: psutil 17:44:53 INFO - Running setup.py install for psutil 17:44:53 INFO - building 'psutil._psutil_osx' extension 17:44:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:44:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:44:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:44:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:44:53 INFO - building 'psutil._psutil_posix' extension 17:44:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:44:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:44:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:44:53 INFO - ^ 17:44:53 INFO - 1 warning generated. 17:44:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:44:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:44:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:44:54 INFO - Successfully installed psutil 17:44:54 INFO - Cleaning up... 17:44:54 INFO - Return code: 0 17:44:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e687a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0c85350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e526e00>, '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 0x10e38fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e521b20>, '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.nvNpJEfMce/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.pcoawy6Yi8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 17:44:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 17:44:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 17:44:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvNpJEfMce/Render', 17:44:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:44:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:44:54 INFO - 'HOME': '/Users/cltbld', 17:44:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:44:54 INFO - 'LOGNAME': 'cltbld', 17:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:54 INFO - 'MOZ_NO_REMOTE': '1', 17:44:54 INFO - 'NO_EM_RESTART': '1', 17:44:54 INFO - 'PAGER': '/bin/cat', 17:44:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:54 INFO - 'PWD': '/builds/slave/test', 17:44:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:44:54 INFO - 'SHELL': '/bin/bash', 17:44:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pcoawy6Yi8/Listeners', 17:44:54 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 17:44:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:44:54 INFO - 'USER': 'cltbld', 17:44:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:44:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:54 INFO - 'XPC_FLAGS': '0x0', 17:44:54 INFO - 'XPC_SERVICE_NAME': '0', 17:44:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:44:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:44:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:44:56 INFO - Installing collected packages: mozsystemmonitor 17:44:56 INFO - Running setup.py install for mozsystemmonitor 17:44:57 INFO - Successfully installed mozsystemmonitor 17:44:57 INFO - Cleaning up... 17:44:57 INFO - Return code: 0 17:44:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:44:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e687a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0c85350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e526e00>, '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 0x10e38fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e521b20>, '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.nvNpJEfMce/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.pcoawy6Yi8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 17:44:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:44:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:44:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvNpJEfMce/Render', 17:44:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:44:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:44:57 INFO - 'HOME': '/Users/cltbld', 17:44:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:44:57 INFO - 'LOGNAME': 'cltbld', 17:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:44:57 INFO - 'MOZ_NO_REMOTE': '1', 17:44:57 INFO - 'NO_EM_RESTART': '1', 17:44:57 INFO - 'PAGER': '/bin/cat', 17:44:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:44:57 INFO - 'PWD': '/builds/slave/test', 17:44:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:44:57 INFO - 'SHELL': '/bin/bash', 17:44:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pcoawy6Yi8/Listeners', 17:44:57 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 17:44:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:44:57 INFO - 'USER': 'cltbld', 17:44:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:44:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:44:57 INFO - 'XPC_FLAGS': '0x0', 17:44:57 INFO - 'XPC_SERVICE_NAME': '0', 17:44:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:44:57 INFO - Downloading/unpacking blobuploader==1.2.4 17:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:44:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:59 INFO - Downloading blobuploader-1.2.4.tar.gz 17:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:44:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:44:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:44:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:44:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:45:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:00 INFO - Downloading docopt-0.6.1.tar.gz 17:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:45:01 INFO - Installing collected packages: blobuploader, requests, docopt 17:45:01 INFO - Running setup.py install for blobuploader 17:45:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:45:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:45:01 INFO - Running setup.py install for requests 17:45:01 INFO - Running setup.py install for docopt 17:45:01 INFO - Successfully installed blobuploader requests docopt 17:45:01 INFO - Cleaning up... 17:45:02 INFO - Return code: 0 17:45:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:45:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:02 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 0x10e687a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0c85350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e526e00>, '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 0x10e38fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e521b20>, '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.nvNpJEfMce/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.pcoawy6Yi8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 17:45:02 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 17:45:02 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 17:45:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvNpJEfMce/Render', 17:45:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:02 INFO - 'HOME': '/Users/cltbld', 17:45:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:02 INFO - 'LOGNAME': 'cltbld', 17:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:02 INFO - 'MOZ_NO_REMOTE': '1', 17:45:02 INFO - 'NO_EM_RESTART': '1', 17:45:02 INFO - 'PAGER': '/bin/cat', 17:45:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:02 INFO - 'PWD': '/builds/slave/test', 17:45:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:02 INFO - 'SHELL': '/bin/bash', 17:45:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pcoawy6Yi8/Listeners', 17:45:02 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 17:45:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:02 INFO - 'USER': 'cltbld', 17:45:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:02 INFO - 'XPC_FLAGS': '0x0', 17:45:02 INFO - 'XPC_SERVICE_NAME': '0', 17:45:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:45:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-oMsKBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:45:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-jbO9GO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:45:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-VKddBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:45:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-NdGLKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:45:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-xCNjjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:45:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Ik17Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:45:02 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-YkengB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:45:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-U31xVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:45:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-ER3M1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:45:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-OFfBOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:45:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-VgYSyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:45:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-R4Hwfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:45:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-mBMqAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:45:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-EWohtl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Jt8vek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:45:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-TqmfaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:45:03 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-PCgL9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:45:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:45:04 INFO - Running setup.py install for manifestparser 17:45:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:45:04 INFO - Running setup.py install for mozcrash 17:45:04 INFO - Running setup.py install for mozdebug 17:45:04 INFO - Running setup.py install for mozdevice 17:45:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:45:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:45:04 INFO - Running setup.py install for mozfile 17:45:04 INFO - Running setup.py install for mozhttpd 17:45:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:45:04 INFO - Running setup.py install for mozinfo 17:45:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:45:05 INFO - Running setup.py install for mozInstall 17:45:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:45:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:45:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:45:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:45:05 INFO - Running setup.py install for mozleak 17:45:05 INFO - Running setup.py install for mozlog 17:45:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:45:05 INFO - Running setup.py install for moznetwork 17:45:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:45:05 INFO - Running setup.py install for mozprocess 17:45:05 INFO - Running setup.py install for mozprofile 17:45:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:45:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:45:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:45:05 INFO - Running setup.py install for mozrunner 17:45:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:45:06 INFO - Running setup.py install for mozscreenshot 17:45:06 INFO - Running setup.py install for moztest 17:45:06 INFO - Running setup.py install for mozversion 17:45:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:45:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:45:06 INFO - Cleaning up... 17:45:06 INFO - Return code: 0 17:45:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:06 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 0x10e687a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0c85350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e526e00>, '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 0x10e38fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e521b20>, '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.nvNpJEfMce/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.pcoawy6Yi8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 17:45:06 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 17:45:06 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 17:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvNpJEfMce/Render', 17:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:06 INFO - 'HOME': '/Users/cltbld', 17:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:06 INFO - 'LOGNAME': 'cltbld', 17:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:06 INFO - 'MOZ_NO_REMOTE': '1', 17:45:06 INFO - 'NO_EM_RESTART': '1', 17:45:06 INFO - 'PAGER': '/bin/cat', 17:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:06 INFO - 'PWD': '/builds/slave/test', 17:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:06 INFO - 'SHELL': '/bin/bash', 17:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pcoawy6Yi8/Listeners', 17:45:06 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 17:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:06 INFO - 'USER': 'cltbld', 17:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:06 INFO - 'XPC_FLAGS': '0x0', 17:45:06 INFO - 'XPC_SERVICE_NAME': '0', 17:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:45:06 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-BKMb87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:45:06 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)) 17:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:45:06 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-A7TCxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:45:07 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)) 17:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:45:07 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Q6yISD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:45:07 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)) 17:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:45:07 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-FRQVGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:45:07 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)) 17:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:45:07 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-0OINh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:45:07 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)) 17:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:45:07 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-TRePOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:45:07 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)) 17:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:45:07 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-b9Uxrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:45:07 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)) 17:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:45:07 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-TCtK7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:45:07 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)) 17:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:45:07 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-O6DFtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:45:07 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)) 17:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:45:07 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-fOGRdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:45:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 17:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:45:07 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-EEZuYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:45:08 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)) 17:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:45:08 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-6FcKma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 17:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:45:08 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-xFuOro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:45:08 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)) 17:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:45:08 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-kSW8zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 17:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:08 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-0v7td8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:45:08 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)) 17:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:45:08 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-_Rky1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:45:08 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)) 17:45:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:45:08 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-xLdjlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:45:08 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)) 17:45:08 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)) 17:45:08 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)) 17:45:08 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)) 17:45:08 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)) 17:45:08 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)) 17:45:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:45:08 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 17:45:08 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 17:45:08 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 17:45:08 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 17:45:08 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 17:45:08 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 17:45:11 INFO - Downloading blessings-1.6.tar.gz 17:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:45:11 INFO - Installing collected packages: blessings 17:45:11 INFO - Running setup.py install for blessings 17:45:11 INFO - Successfully installed blessings 17:45:11 INFO - Cleaning up... 17:45:11 INFO - Return code: 0 17:45:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:45:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:45:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:45:11 INFO - Reading from file tmpfile_stdout 17:45:11 INFO - Current package versions: 17:45:11 INFO - blessings == 1.6 17:45:11 INFO - blobuploader == 1.2.4 17:45:11 INFO - docopt == 0.6.1 17:45:11 INFO - manifestparser == 1.1 17:45:11 INFO - mozInstall == 1.12 17:45:11 INFO - mozcrash == 0.17 17:45:11 INFO - mozdebug == 0.1 17:45:11 INFO - mozdevice == 0.48 17:45:11 INFO - mozfile == 1.2 17:45:11 INFO - mozhttpd == 0.7 17:45:11 INFO - mozinfo == 0.9 17:45:11 INFO - mozleak == 0.1 17:45:11 INFO - mozlog == 3.1 17:45:11 INFO - moznetwork == 0.27 17:45:11 INFO - mozprocess == 0.22 17:45:11 INFO - mozprofile == 0.28 17:45:11 INFO - mozrunner == 6.11 17:45:11 INFO - mozscreenshot == 0.1 17:45:11 INFO - mozsystemmonitor == 0.0 17:45:11 INFO - moztest == 0.7 17:45:11 INFO - mozversion == 1.4 17:45:11 INFO - psutil == 3.1.1 17:45:11 INFO - requests == 1.2.3 17:45:11 INFO - wsgiref == 0.1.2 17:45:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:45:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:11 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 0x10e687a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0c85350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e526e00>, '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 0x10e38fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e521b20>, '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.nvNpJEfMce/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.pcoawy6Yi8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 17:45:11 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 17:45:11 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 17:45:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvNpJEfMce/Render', 17:45:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:11 INFO - 'HOME': '/Users/cltbld', 17:45:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:11 INFO - 'LOGNAME': 'cltbld', 17:45:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:11 INFO - 'MOZ_NO_REMOTE': '1', 17:45:11 INFO - 'NO_EM_RESTART': '1', 17:45:11 INFO - 'PAGER': '/bin/cat', 17:45:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:11 INFO - 'PWD': '/builds/slave/test', 17:45:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:11 INFO - 'SHELL': '/bin/bash', 17:45:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pcoawy6Yi8/Listeners', 17:45:11 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 17:45:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:11 INFO - 'USER': 'cltbld', 17:45:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:11 INFO - 'XPC_FLAGS': '0x0', 17:45:11 INFO - 'XPC_SERVICE_NAME': '0', 17:45:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:11 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)) 17:45:11 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)) 17:45:11 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)) 17:45:11 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)) 17:45:11 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)) 17:45:11 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)) 17:45:11 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)) 17:45:11 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)) 17:45:11 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)) 17:45:11 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)) 17:45:11 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)) 17:45:11 INFO - Cleaning up... 17:45:11 INFO - Return code: 0 17:45:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:45:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45:12 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 0x10e687a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0c85350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e526e00>, '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 0x10e38fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e521b20>, '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.nvNpJEfMce/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.pcoawy6Yi8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 17:45:12 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 17:45:12 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 17:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvNpJEfMce/Render', 17:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:12 INFO - 'HOME': '/Users/cltbld', 17:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:12 INFO - 'LOGNAME': 'cltbld', 17:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:12 INFO - 'MOZ_NO_REMOTE': '1', 17:45:12 INFO - 'NO_EM_RESTART': '1', 17:45:12 INFO - 'PAGER': '/bin/cat', 17:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:12 INFO - 'PWD': '/builds/slave/test', 17:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:12 INFO - 'SHELL': '/bin/bash', 17:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pcoawy6Yi8/Listeners', 17:45:12 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 17:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:12 INFO - 'USER': 'cltbld', 17:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:12 INFO - 'XPC_FLAGS': '0x0', 17:45:12 INFO - 'XPC_SERVICE_NAME': '0', 17:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:12 INFO - Downloading/unpacking jsonschema 17:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:45:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:45:14 INFO - Downloading/unpacking functools32 (from jsonschema) 17:45:14 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 17:45:14 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 17:45:14 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 17:45:14 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 17:45:14 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 17:45:14 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 17:45:15 INFO - Downloading functools32-3.2.3-2.tar.gz 17:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:45:15 INFO - warning: no files found matching '*.txt' 17:45:15 INFO - no previously-included directories found matching 'build' 17:45:15 INFO - no previously-included directories found matching 'dist' 17:45:15 INFO - no previously-included directories found matching '.git*' 17:45:15 INFO - Installing collected packages: jsonschema, functools32 17:45:15 INFO - Running setup.py install for functools32 17:45:15 INFO - warning: no files found matching '*.txt' 17:45:15 INFO - no previously-included directories found matching 'build' 17:45:15 INFO - no previously-included directories found matching 'dist' 17:45:15 INFO - no previously-included directories found matching '.git*' 17:45:15 INFO - Successfully installed jsonschema functools32 17:45:15 INFO - Cleaning up... 17:45:15 INFO - Return code: 0 17:45:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:45: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e687a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e697420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86e0c85350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e526e00>, '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 0x10e38fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e521b20>, '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.nvNpJEfMce/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.pcoawy6Yi8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 17:45: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', 'flake8'] in /builds/slave/test/build 17:45: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 flake8 17:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvNpJEfMce/Render', 17:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:15 INFO - 'HOME': '/Users/cltbld', 17:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:15 INFO - 'LOGNAME': 'cltbld', 17:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:15 INFO - 'MOZ_NO_REMOTE': '1', 17:45:15 INFO - 'NO_EM_RESTART': '1', 17:45:15 INFO - 'PAGER': '/bin/cat', 17:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:15 INFO - 'PWD': '/builds/slave/test', 17:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:15 INFO - 'SHELL': '/bin/bash', 17:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pcoawy6Yi8/Listeners', 17:45:15 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 17:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:15 INFO - 'USER': 'cltbld', 17:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:15 INFO - 'XPC_FLAGS': '0x0', 17:45:15 INFO - 'XPC_SERVICE_NAME': '0', 17:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:45:15 INFO - Downloading/unpacking flake8 17:45: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 17:45: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 17:45: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 17:45: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 17:45: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 17:45: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 17:45:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:45:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:45:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 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 17:45:18 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 17:45:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:45:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:45:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:45:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:45:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:45:19 INFO - no previously-included directories found matching 'docs/_build' 17:45:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:45:19 INFO - Running setup.py install for pep8 17:45:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:45:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:45:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:45:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:45:19 INFO - no previously-included directories found matching 'docs/_build' 17:45:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:45:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:45:19 INFO - Cleaning up... 17:45:19 INFO - Return code: 0 17:45:19 INFO - Running post-action listener: _resource_record_post_action 17:45:19 INFO - Running post-action listener: _start_resource_monitoring 17:45:19 INFO - Starting resource monitoring. 17:45:19 INFO - ##### 17:45:19 INFO - ##### Running install step. 17:45:19 INFO - ##### 17:45:19 INFO - Running pre-action listener: _resource_record_pre_action 17:45:19 INFO - Running main action method: install 17:45:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:45:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:45:20 INFO - Reading from file tmpfile_stdout 17:45:20 INFO - Detecting whether we're running mozinstall >=1.0... 17:45:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:45:20 INFO - Reading from file tmpfile_stdout 17:45:20 INFO - Output received: 17:45:20 INFO - Usage: mozinstall [options] installer 17:45:20 INFO - Options: 17:45:20 INFO - -h, --help show this help message and exit 17:45:20 INFO - -d DEST, --destination=DEST 17:45:20 INFO - Directory to install application into. [default: 17:45:20 INFO - "/builds/slave/test"] 17:45:20 INFO - --app=APP Application being installed. [default: firefox] 17:45:20 INFO - mkdir: /builds/slave/test/build/application 17:45:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 17:45:51 INFO - Reading from file tmpfile_stdout 17:45:51 INFO - Output received: 17:45:51 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:45:51 INFO - Running post-action listener: _resource_record_post_action 17:45:51 INFO - ##### 17:45:51 INFO - ##### Running run-tests step. 17:45:51 INFO - ##### 17:45:51 INFO - Running pre-action listener: _resource_record_pre_action 17:45:51 INFO - Running main action method: run_tests 17:45:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:45:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:45:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:45:51 INFO - 2016-05-13 17:45:51.891 screenresolution[1649:8203] starting screenresolution argv=screenresolution get 17:45:51 INFO - 2016-05-13 17:45:51.894 screenresolution[1649:8203] Display 0: 1600x1200x32@60 17:45:51 INFO - 2016-05-13 17:45:51.899 screenresolution[1650:8207] starting screenresolution argv=screenresolution list 17:45:51 INFO - Available Modes on Display 0 17:45:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:45:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:45:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:45:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:45:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:45:52 INFO - Intel Iris: 17:45:52 INFO - Chipset Model: Intel Iris 17:45:52 INFO - Type: GPU 17:45:52 INFO - Bus: Built-In 17:45:52 INFO - VRAM (Dynamic, Max): 1536 MB 17:45:52 INFO - Vendor: Intel (0x8086) 17:45:52 INFO - Device ID: 0x0a2e 17:45:52 INFO - Revision ID: 0x0009 17:45:52 INFO - Displays: 17:45:52 INFO - Display: 17:45:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:45:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:45:52 INFO - Display Serial Number: 1600x1200 60 17:45:52 INFO - Main Display: Yes 17:45:52 INFO - Mirror: Off 17:45:52 INFO - Online: Yes 17:45:52 INFO - Rotation: Supported 17:45:52 INFO - Return code: 0 17:45:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:45:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:45:52 INFO - Python 2.7.3 17:45:52 INFO - Return code: 0 17:45:52 INFO - grabbing minidump binary from tooltool 17:45:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:45:52 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e526e00>, '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 0x10e38fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e521b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:45:52 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 17:45:52 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 17:45:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:45:52 INFO - Return code: 0 17:45:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:45:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:45:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:45:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:45:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:45:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:45:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:45:53 INFO - Return code: 0 17:45:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0231', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:45:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0231 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:45:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvNpJEfMce/Render', 17:45:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:45:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:45:53 INFO - 'HOME': '/Users/cltbld', 17:45:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:45:53 INFO - 'LOGNAME': 'cltbld', 17:45:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:45:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:45:53 INFO - 'MOZ_NO_REMOTE': '1', 17:45:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:45:53 INFO - 'NO_EM_RESTART': '1', 17:45:53 INFO - 'PAGER': '/bin/cat', 17:45:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:45:53 INFO - 'PWD': '/builds/slave/test', 17:45:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:45:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:45:53 INFO - 'SHELL': '/bin/bash', 17:45:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pcoawy6Yi8/Listeners', 17:45:53 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 17:45:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:45:53 INFO - 'USER': 'cltbld', 17:45:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:45:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:45:53 INFO - 'XPC_FLAGS': '0x0', 17:45:53 INFO - 'XPC_SERVICE_NAME': '0', 17:45:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:45:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0231', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:45:53 INFO - mozversion application_buildid: 20160513165348 17:45:53 INFO - mozversion application_changeset: 1956edae83a4be83b828fcd4986e1403d007d5bc 17:45:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:45:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:45:53 INFO - mozversion application_name: Firefox 17:45:53 INFO - mozversion application_remotingname: firefox-dev 17:45:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:45:53 INFO - mozversion application_vendor: Mozilla 17:45:53 INFO - mozversion application_version: 48.0a2 17:45:53 INFO - mozversion platform_buildid: 20160513165348 17:45:53 INFO - mozversion platform_changeset: 1956edae83a4be83b828fcd4986e1403d007d5bc 17:45:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:45:53 INFO - mozversion platform_version: 48.0a2 17:45:53 INFO - using testdate: 1463186753 17:45:53 INFO - actual date: 1463186753 17:45:53 INFO - starting webserver on 'localhost:49273' 17:45:53 INFO - SUITE-START | Running 2 tests 17:45:53 INFO - TEST-START | dromaeo_css 17:45:53 INFO - operating with platform_type : mac_ 17:45:53 INFO - Initialising browser for dromaeo_css test... 17:45:53 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpqtnZdr/profile http://localhost:49273/getInfo.html) 17:45:55 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 17:45:55 INFO - PROCESS | 1668 | colorDepth:24 17:45:55 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 17:45:55 INFO - PROCESS | 1668 | __metrics 17:45:56 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:45:56 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:45:56 INFO - TEST-INFO | 1668: exit 0 17:45:56 INFO - Browser initialized. 17:45:56 INFO - Running cycle 1/1 for dromaeo_css test... 17:45:56 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpqtnZdr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:46:08 INFO - PROCESS | 1671 | RSS: Main: 211607552 17:46:08 INFO - PROCESS | 1671 | 17:47:00 INFO - PROCESS | 1671 | 1463186820249 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] 17:49:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 17:49:12 INFO - PROCESS | 1671 | RSS: Main: 351186944 17:49:12 INFO - PROCESS | 1671 | 17:52:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 17:52:15 INFO - PROCESS | 1671 | RSS: Main: 360550400 17:52:15 INFO - PROCESS | 1671 | 17:55:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 17:55:18 INFO - PROCESS | 1671 | RSS: Main: 362213376 17:55:18 INFO - PROCESS | 1671 | 17:58:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 17:58:21 INFO - PROCESS | 1671 | RSS: Main: 356311040 17:58:21 INFO - PROCESS | 1671 | 18:01:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 18:01:25 INFO - PROCESS | 1671 | RSS: Main: 324435968 18:01:25 INFO - PROCESS | 1671 | 18:04:27 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 18:04:28 INFO - PROCESS | 1671 | RSS: Main: 422309888 18:04:28 INFO - PROCESS | 1671 | 18:04:28 INFO - PROCESS | 1671 | __start_tp_report 18:04:28 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 18:04:28 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 18:04:28 INFO - PROCESS | 1671 | |i|pagename|runs| 18:04:28 INFO - PROCESS | 1671 | |0;dojo.html;2906.69479704631;3468.393031219536;3474.4093504104308;3488.59881113672;3550.982245088774;5766.1639062335935;5789.334226563947;5803.274590676166;5821.708914554266;5826.242588463494;5807.73865176067;5854.034084376083;5858.238429004234;5864.149698293745;5870.911936320958;5127.154019586782;5200.843974680765;5233.790648374061;5291.550218231455;5398.811041613544;5273.551748101416;5287.814926477574;5290.312259406291;5294.338207724035;5299.60252981026;5159.097157997354;5209.505097015777;5225.6864588124645;5245.160774276116;5272.051030814452;5289.841304760844;5339.41266460689;5342.385625653061;5346.946530534684;5382.9730851345885;5188.429272779991;5207.140821764404;5209.114450543417;5261.579073674096;5273.604479664041;5613.522850557708;5633.380328163939;5656;5663;5688.658680479143;5560.77756889724;6259.874802503924;6298.433141017331;6315.968420157916;6325.399087086719;6073.177298882116;6188.628682279078;6259.248890133168;6293.30773614902;6326.715297811609;5799.159121927297;5824.068149096124;5834.445727655866;5835.299764028301;5872.060470324727;6238.469730072995;6277.937220627827;6283.214598175228;6287.182666253358;6295.653739044396;5999.100134979701;6127.693615319216;6137.539684523678;6171.6297022178815;6183.907241391383;6276.46650034743;6284.937150628527;6298.937010629835;6305.117283580303;6306.369363063749;6219.906701399483;6235.688215589294;6248.4064013918605;6249.250089989139;6286.4342209201395;6063.454289114001;6122.571420000646;6142.836439233709;6151.815445536641;6261.968690156611;4886.584640305543;4913.164761990471;4933.432655244621;4956.553910148032;4975.129352363322;4443.155800397914;4450.3991961085485;4459.308807134899;4485.349624304523;4491.101779644085;5709.714514274269;5731.627444216113;5742.712864356766;5746.856328591735;5749.137629355547;6133.395871484054;6194.628322300587;6292.842678933063;6293;6305.5901366411035;5666.9716651416475;5718.999175144333;5720.3421606515785;5724.112762521816;5736.42635736423;5412.134058550613;5424.783008679608;5442.047641662693;5445.673259604358;5446.183072539072;6228.844278893064;6341.587796793194;6405.967970160119;6408.647524386017;6411.743530258737;6241.8751624966335;6296.307406185224;6303.338149494237;6312.021636646327;6331.936680633319;6258.874822503615;6328.746850125944;6334;6366.840828979312;6376.649284289409;6181.505479561534;6283.96858015707;6296.937030629635;6299.08663243818;6314.968425157845;5324.866878328073;6247.468965137832;6283.717232724447;6291.716872740829;6326.114343991937;5985.1022346646605;6069.817905462844;6090.573659843769;6100.267967843887;6102.725377358119;6008.068749343859;6036.728347224298;6045.002574575145;6052.757889684363;6082.178905847831;5901.173835662925;5910.940890591039;5911.290645122613;5933.851653708692;5936.495397891227;5949.91075133882;6059.030555111161;6065.484433823174;6111.561351782825;6127;5626.887462250814;5899.321578018583;5907.645541267366;5956;6014.72933717975 18:04:28 INFO - PROCESS | 1671 | |1;ext.html;508.7456271864066;541.8374487653704;564.8700798816269;573.3864764701768;587.0313981929816;30888.84555577222;31499.68500314996;31524.054278371685;31638.52542211863;31693.683063169392;31688.524672129937;38648.61351386485;39411;39484.40773388394;39529.40705889407;9556.474393908333;9746.561404736804;9752.756181095458;9753.512324383752;9763.463009534473;8575.699850505234;9521.571529281198;9645.469499177543;9662.661806836762;9671.129598336149;8629.180227878342;8702.086280940504;8703.782405439852;8706.216440520351;11478.598249061284;11363;11462.083033357312;11462.656120316404;11473;11473.655790326304;11355.602553910574;11466.770664586746;11473.5984240552;11487.540498380054;11491.195616306863;32636.6736332636;37964.62035379638;40488.39267410991;40940;40972;3194.073718621597;3279.5572597699243;3293.357795229931;3602.6937710294656;3661.487391765155;26621.06826261091;27013.864930675416;27039.32401689954;27185.320366990785;27443;2737.288305040684;2750.8074434789573;2751.793615478847;2789.4002789400374;2829.858507074638;332451;333118;335007;335292;335306.8815871031;36823.815880920425;61960.380396196364;63086;63135.36864631295;63295;35669.46495802565;35766.821165894005;35772.82113589467;35920.46119308213;36030;15613.609659758597;15850.365985360457;15858.44495442677;15876.52370428889;15909.443169489012;13313.534026309033;13329.266890320932;13332.800007999887;13366.599002029954;13392;8171.83656326882;8280.50316980983;8311.95844020776;8314;8364.414490985573;3137.262743255333;3185.665505121975;3208.16587687203;3211.213252753044;3225.3388055449045;949.4493193947494;1024.2471783239314;1030.1861529391697;1084.7505073832904;1153.181241318673;28362.290942726184;28711.995080172095;28927;29023.564646529896;29183.270418239288;1006.5369929832212;1133.6939026462808;1141.178351586856;1159.3565571107947;1194.952201911931;12846.42191101384;13666.316684165751;14527.273636318354;14724.116553006854;15060.472883449027;606256.6871656488;615064;617428;619294;620203.7979620236;59623.70188149031;61551.692241538505;61560.76878462406;61689.691551542855;61849.69075154595;1357.7488164689564;1384.4393020826783;1538.699953509055;1540.5917431880337;1552.1773460066183;23001.53996920019;23619.29142125739;27643;28307.716922830507;28521.42957140804;50560.955123590764;51099.74450127726;51108.977820444125;51150.74424627853;51294.230586540485;434231;438632;438818;438890;439451;26730.331741706614;34905.8254708735;35322.64677353194;41246;42198;42737.57262427336;42902.14195716131;43022.784886075366;43776.12447751091;44203;33224;35384.46923296204;36352.81823590971;36636.81681591575;36726.816365918;35382;35607.64392356147;35634.10914727153;36383.45424818577;36415.90752277436;36886.26227475489;37376.62623373731;37424.438633421065;37780.2443951114;37796.43305350367;32185.839070804497;33003.339933201685;33026.83486582552;33056.00831975044;33098.338033239685;24345.39136521601;24863.005479780608;25027.716186253;25105.49789004246;25288.494230114928 18:04:28 INFO - PROCESS | 1671 | |2;jquery.html;409.6538425030849;410.38032570818064;413.0438035946781;418.0248326656548;420.3232795199728;18768.436946891616;19744.605107897834;20323.898380508097;20329.796702032992;20330.593388132227;17545.56136096595;19910.10404531796;20201.696974545357;20928.372148835522;20977;5780.421957804207;5901.852453688649;5945.88108237835;5967.254093238345;6006.489448396892;5847.5614328925285;6051.334353221142;6091.634501929876;6094.695265236743;6121.173641558399;5709.486146246837;5945.851353716149;5945.989181839076;6063.4239747224165;6070.6661133637635;5175.482451754832;5761.423857614226;5763;5808.506276966463;5818.185454036438;5106.259592359124;5148.021875843578;5155.74221288934;5204.1673332266655;5207.297014903015;25570.232893013053;25880.741192588022;26230.344241393926;26303;26316.342091447867;2197.6593627987686;2232.944176395603;2306.7693230676964;2530.569803133472;2876.137158852336;14468.131912085308;14558.417663293561;14781.408743650241;14964.251787410585;15333.233338333039;1717.407494414425;1731.1603872122012;1740.5213566269251;1756.1043867627475;1846.5014446099613;67668.6616566914;108090;108239;108757;118265.40867295694;11407.48666310018;11498.425078746197;11606.593769218036;11644.825327619923;11751.529938802352;14612.415503379745;14701.779473307908;14708.33812478441;14737.926310368379;14770.630734231516;7368.557886526718;8518.105598912149;8907.688230911886;8978.685745998859;8995.370324077383;8840.24857887074;8841.469511829311;8857.867131993025;8905.910940890508;8925.419847709878;4681.391419115494;4710.29345598163;4729.716217026989;4730.692504987233;4776.832810851603;1974.634692581877;2040.9693854592192;2058.6500294949897;2069.6264443440164;2086.8017538333834;796.307212724933;910.3354551177678;946.5456580841235;986.9703908882746;1015.5531566110888;20090.395368105652;20806.79193208079;20908.268210612554;21028.053737581846;21158.788412115682;976.8046390721742;1003.5333569890037;1006.4615430744517;1019.8419245017034;1036.3781730961364;9014.278857691368;11125.777484450427;11221.821639396227;11295.94352028251;11321.547138114547;497198;502424;503006;530692;598926;69251;70789;94429.52785236167;132354.33822830825;136710;878.6353663229816;1067.4555976451911;1068.4711068021224;1104.1718710966775;1105.7346236903086;16347.509574712778;16400.835991640408;18201.544961376072;18886.811131888506;18926.76975996556;48174.554763357155;49359.50640493549;49749.50751477462;51676;53475.663108421046;51813;69149.61700766058;69276.96084558837;69477.30522694708;69935;8091.919080809116;8337.082920878578;8460.703875364332;8570.971483422029;9026.413283136575;11966.341851198267;12884.935575322063;12989.545365912525;13203.405846736729;13367.933160334525;10240.129588985019;11260.718482038015;11527;11632.941835290769;11640.88359116398;10402.73993150147;10608.681739547517;11048.779024419628;11301.830472542753;11611.5355385787;11139.665810025712;11311.377874216669;11720.765584688428;11762.470688819194;11894.464749086139;8385.538795366314;8502.82994340122;8556.957215213884;8582.78543036429;8595;10575.788484230427;11377.829332560184;11595.304281743123;11606.593769218036;11684.532618695157 18:04:28 INFO - PROCESS | 1671 | |3;mootools.html;1373.8145350377697;1568.9686206275874;1594.1471312847625;1671.0976072920623;1729.403355842234;7840.7255746048895;8521.91478085219;8652.351073669484;8655.91344086559;8664.43681160725;8373.748787536368;8632.913670863289;8646.481211127337;8652.524111173883;8657.004444488886;2438.9268321950326;2457.2751038443703;2460.5817011108156;2468.4322605800676;2474.8762561871836;2403.5793736096202;2457.913973010945;2461.655368248447;2467.9753202468014;2471.752824717532;2266.7166604174477;2341.0401735288533;2423.7939775119135;2451.3626457121186;2472.3819045238693;2425.38152771044;2444.608862581987;2457.619069044301;2458.8032957363366;2470.0243403855557;2427.6722642443397;2444.278937713379;2456.680631517909;2460.3972026853535;2460.667809845666;8411.53736544496;8720.8691869622;8774.122587741174;8784;8791.296696264284;5261.500157485033;5412.431694672082;5419.83740487786;5426.348838139448;5449.700266485342;3484;3560.8041557714573;3583.0325812030865;3613.1509095362567;3624.4200927851416;1060.1359891688326;1071.8251764892545;1077.084478193537;1080.0603474976742;1081.2269227502295;11391.316521008766;11483.885161148366;11682.065434765283;11687.824682629767;11691.824622630667;4518.441137807451;4519.638428925678;4655.95344046562;4658.998315362213;4661.277501987197;9336.346455748033;9338.95330523343;9339.626414943463;9344.205742511827;9372.250219982385;5263.973680131651;5267.841964741064;5305.469453054664;5309.946900530946;5326.334208223972;4238.406623072772;4342.088161486124;4734.692245004064;4892.046051020088;4916.188828843201;4032.697547683935;4042.3532234842282;4106.425100485934;4134.338505839051;4155.916881662411;1352.1211212711619;1379.7930310453512;1379.8256537982566;1385.6535866033491;1397.343248673122;710.996445017782;816.4081041245169;836.431226765793;922.5894476957824;935.9517340578459;5394.9460505394445;5627.155926611041;5671.461211184931;5777.913331300034;5813.982553053199;969.5685419988038;989.1048601016092;998.6604554451376;1012.4178597306578;1034.591336422109;3675.9448608270895;3767.321882061255;3771.151490914577;3796.7152463565344;3848.8845334640005;10230.897691023143;10315.432651204255;10622.415767132727;10652.57389704403;10677.252592318619;10547.894521054845;10553.208509361828;10669;10676.199285053652;10681.572737090584;7707.499012564165;8196.098429172785;8319.126491718282;8321.500709957301;8389.454685445426;4566.657500687395;5515.503604675438;5595.384507704068;5730.799422020209;5824.068149096124;9182.908170918472;9279.350445468752;9289.442633442015;9438.95280523593;9439.292053095907;9183.989761126404;9394.67118650844;9432.245420366216;9521.000294968928;9530.666426675032;7354.411647068115;7418;7486.8876966844455;7488.138864030489;7491.325780679765;6957.686904089227;7999.84000320002;8037.638306276114;8095.635696393794;8109.7161599343735;7403.777886663409;7438.181800002104;7456.366208872307;7551.660175292015;7560.9621951889885;7034.050403195503;7199.1001124859395;7220.638968051681;7268.0914885639295;7422;6810.489213309121;6821.965890170517;6886.035954966209;6886.276940921131;6909.067275917888;4112.506499220112;4583.1062942727185;4608.723476591281;4641.605463535638;4726;4661.930071048869;4782.37829082231;4875.975620121877;4915.696984089222;4952.925706114339 18:04:28 INFO - PROCESS | 1671 | |4;prototype.html;316.76875880607156;319.92001999500127;328.2925295987148;332.6158287178307;333.6296710651177;6378.489720822335;6436.935630643692;6475.255345635247;6481.870362592757;6496.992966090252;5620.690862002588;6005.738794853086;6116.9388306116925;6360.936390636104;6419.679016049202;4758.595519380857;4793.257045157989;4801.615870730342;4809.567138957493;4836.081144582518;4800.543948324904;4810.39870016248;4814.277858321245;4824.2040063389495;4826.879328016793;4556.498785133633;4636.907261854778;4719.598834099105;4770.761461926907;4778.617710583145;4716.174669432836;4728.834490792806;4729.432468103815;4742.217534106868;4758.595519380857;4066.146109317047;4095.9180816383805;4109.589041095896;4114.526829414625;4135.7518548886865;4988.800447982077;6103.29812071613;6128.233970753656;6153.692315384213;6175.320714721377;5553.527950124244;5560.499555040025;5565.666060036411;5583.078791999315;5594.0769772987405;6537.444317233041;6788.660566971632;6825.590464572141;6827.556208846409;6831.2144103428245;4531.614812740921;4589.79345929434;4598.609118224955;4616.884577885546;4630.027694184226;7181.102362204672;7187.38907192884;7191.137063552407;7195.172555156171;7217.206107328242;4636.235021221528;4643;4647.95352046482;4657.231556793091;4660.440747110369;4702.88242793926;4708.340832283483;4709.62323014158;4714.727023703581;4725.267583524491;5272.077386457355;5537.889242215133;5572.164175373645;5577.2191893135;5587.329520457572;6857.794266172023;6860.279670634612;6867.931320686829;6894.345037221456;6897.7585784497305;1549.697808927271;1619.004312347921;1634.3380930723076;1643.9671206575802;1645.5968287769333;1012.1851909213154;1037.273908264218;1037.994810025945;1039.7608550033535;1041.4896700616796;5532.336119665666;6245.656488893063;6407.167068281094;6419.903701444482;6484.157059582224;7153.998440218374;7162;7164.462665300123;7165.6775445105095;7183.604901730455;6408.166938297992;6440.54916155874;6464.93328600785;6468.741250349934;6472.611643301417;3641.672249497558;3723.925521489555;3726.4969229153717;3783.1109689223013;3787.507624008861;7414.925850741423;7462.067241594801;7474.140473845578;7490.737824176236;7502.437317201232;6087.512998960073;7314.780556583311;7320.414366850639;7358.889616655754;7363.558186508827;6342.4926005919415;7229.819254518679;7434.665440055211;7437.107547094383;7458.291462310964;6129.570930034854;6850.794476165723;6851.760188393382;6976.883698608199;7096.716131354688;6970.546914450747;7024.508284420246;7027.8945815811785;7052.118485189351;7064.364207221375;6116;7459.36595389398;7477.775666730006;7485.251474852471;7492.887606685795;4658.208104622155;4670.859874203779;4672.696274742131;4677.158111539956;4685.273782563571;5881.705914704333;7017.333353331425;7068.257832927674;7073.71705131789;7079.610621415665;6544.6727663615675;6826.975953606999;6883.105196324445;6884.1050663415435;6917.584944903322;6868.656567171522;6886.621235832077;6890.621015844176;6904.102466679299;6906.102206713095;6726.529142959946;6733.865322693617;6741.191057072986;6743.494237932076;6743.66281685904;6116.541259405473;6868.793936181923;6878.174619045546;6884.621345826027;6902.068220790129;4962.478939711423;6658.866822663616;6666.633335166613;6704.262531121671;6718.428933540508 18:04:28 INFO - PROCESS | 1671 | |5;yui.html;686.7047169717022;717.7667258141103;745.1356426545206;748.7978245873611;760.22077370695;15231.162286074261;15397.153156576398;15408.691826163484;15925.124118173524;16292;16027.91986040071;16053.197340133005;16127.516174514754;16156.919215403906;16220.675586488263;738.0626604212647;749.9062617172854;917.4862077236752;939.1500691873865;940.5908429833011;920.0569416348229;938.9389689670184;940.2712897504421;940.4780346907463;940.9576569054375;977.8337682593941;988.8170688422629;988.9406635601887;989.2333441582759;989.6684610655439;765.9808504787369;937.7561833923161;955.0926619711302;981.9509024548744;984.7488890332955;719.8308397526577;720.1538192623647;720.646883027318;721.6752461392342;722.1622917415825;14974.62563435912;15060.32228549718;15071.020383675026;15076.773848392282;15101.848981510155;12463.688407789787;12575.93712031434;12597.748045039049;12794.552190673281;12871.292078935654;10074.294799364048;10213.79572408555;10271.229657775697;10383.221258405574;11121.999020088231;3441.3117376524797;3484.6341134181052;3494;3545.6099829018785;3582.946255806165;86549.26901461935;88670.556647217;89236.6614500783;89487.55256223677;89588.55205723995;9202.677906273382;9296.163345298955;9400.623975041059;9452.054794520493;10057.195424366033;21351.786482135292;21434.24980125688;21437.249696260867;21438.78561214399;21462.892685536473;6625.933740662628;6790.456763458911;6825.69284382204;6845.520813543104;6907.585544867224;3931.4495970564144;3989.519060096378;3992.1017771001293;4080.367543030835;4240.787960601958;3589.174489867345;3652.7990960496895;3653.963460365362;3662.377395842713;3669.889903302905;631.8167731357855;644.2650946731063;645.9709313080923;646.9061986012002;662.0400419391901;525.8632755483602;561.6461629173594;575.5136909311625;579.901416759152;581.5231510161711;8000.399970002273;8067.515949043076;8075.030996280484;8169.30560902318;8203.507789532528;505.7799857062116;513.8818071843497;596.7881402102278;603.4116736182187;618.2704408797572;3407.7955322680223;3636.7999760013454;3658.0123366690846;3782.791946442972;3837.9424308634834;94575.52712236533;97942;98746;98817;99095.50452247693;29442;36181;37119.814400928364;37363;46055.76972115118;62560.687196563726;67888;70164;71466;73138.63430682813;7950.880736788837;8153.1846815317995;8160.387970902325;8250.958745206235;8290.502569845828;49857;50049.74975125101;50122.248166276804;50178;50842.491575083775;63117;63184;67309;68357.3164268351;70624.64687676528;10834.891651083704;10904.509297081491;11121.666350009513;11408.372539510407;11476.254043487146;11728.41357932083;11818.82271765907;11893.762124757282;11987.64037078889;12158.635240942785;10956.726081847699;11100.222984391015;11437.142214333791;11531.250468719507;11586;11352.262102963281;11662.825057624312;11670.183087183816;11717.589884354007;11915.880841191823;10144.898551014396;11082.833757493805;11534.365609891536;11750.88249117532;11895.464704088165;9418.293627978066;9513.619455221991;9623.326367154232;9716.22270218395;9981.800363992534;8980.910190898006;9348.25213982866;9684.32209745311;9741.318107732674;9798 18:04:28 INFO - PROCESS | 1671 | __end_tp_report 18:04:28 INFO - PROCESS | 1671 | __start_cc_report 18:04:28 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1373 18:04:28 INFO - PROCESS | 1671 | __end_cc_report 18:04:28 INFO - PROCESS | 1671 | __startTimestamp1463187868109__endTimestamp 18:04:28 INFO - PROCESS | 1671 | 18:04:28 INFO - PROCESS | 1671 | ------- Summary: start ------- 18:04:28 INFO - PROCESS | 1671 | Number of tests: 6 18:04:28 INFO - PROCESS | 1671 | 18:04:28 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5762.99 Median:5911.29 stddev:632.17 (10.7%) stddev-sans-first:593.07 18:04:28 INFO - PROCESS | 1671 | Values: 2906.7 3468.4 3474.4 3488.6 3551.0 5766.2 5789.3 5803.3 5821.7 5826.2 5807.7 5854.0 5858.2 5864.1 5870.9 5127.2 5200.8 5233.8 5291.6 5398.8 5273.6 5287.8 5290.3 5294.3 5299.6 5159.1 5209.5 5225.7 5245.2 5272.1 5289.8 5339.4 5342.4 5346.9 5383.0 5188.4 5207.1 5209.1 5261.6 5273.6 5613.5 5633.4 5656.0 5663.0 5688.7 5560.8 6259.9 6298.4 6316.0 6325.4 6073.2 6188.6 6259.2 6293.3 6326.7 5799.2 5824.1 5834.4 5835.3 5872.1 6238.5 6277.9 6283.2 6287.2 6295.7 5999.1 6127.7 6137.5 6171.6 6183.9 6276.5 6284.9 6298.9 6305.1 6306.4 6219.9 6235.7 6248.4 6249.3 6286.4 6063.5 6122.6 6142.8 6151.8 6262.0 4886.6 4913.2 4933.4 4956.6 4975.1 4443.2 4450.4 4459.3 4485.3 4491.1 5709.7 5731.6 5742.7 5746.9 5749.1 6133.4 6194.6 6292.8 6293.0 6305.6 5667.0 5719.0 5720.3 5724.1 5736.4 5412.1 5424.8 5442.0 5445.7 5446.2 6228.8 6341.6 6406.0 6408.6 6411.7 6241.9 6296.3 6303.3 6312.0 6331.9 6258.9 6328.7 6334.0 6366.8 6376.6 6181.5 6284.0 6296.9 6299.1 6315.0 5324.9 6247.5 6283.7 6291.7 6326.1 5985.1 6069.8 6090.6 6100.3 6102.7 6008.1 6036.7 6045.0 6052.8 6082.2 5901.2 5910.9 5911.3 5933.9 5936.5 5949.9 6059.0 6065.5 6111.6 6127.0 5626.9 5899.3 5907.6 5956.0 6014.7 18:04:28 INFO - PROCESS | 1671 | 18:04:28 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:59619.09 Median:335299.44 stddev:127508.82 (38.0%) stddev-sans-first:127789.28 18:04:28 INFO - PROCESS | 1671 | Values: 508.7 541.8 564.9 573.4 587.0 30888.8 31499.7 31524.1 31638.5 31693.7 31688.5 38648.6 39411.0 39484.4 39529.4 9556.5 9746.6 9752.8 9753.5 9763.5 8575.7 9521.6 9645.5 9662.7 9671.1 8629.2 8702.1 8703.8 8706.2 11478.6 11363.0 11462.1 11462.7 11473.0 11473.7 11355.6 11466.8 11473.6 11487.5 11491.2 32636.7 37964.6 40488.4 40940.0 40972.0 3194.1 3279.6 3293.4 3602.7 3661.5 26621.1 27013.9 27039.3 27185.3 27443.0 2737.3 2750.8 2751.8 2789.4 2829.9 332451.0 333118.0 335007.0 335292.0 335306.9 36823.8 61960.4 63086.0 63135.4 63295.0 35669.5 35766.8 35772.8 35920.5 36030.0 15613.6 15850.4 15858.4 15876.5 15909.4 13313.5 13329.3 13332.8 13366.6 13392.0 8171.8 8280.5 8312.0 8314.0 8364.4 3137.3 3185.7 3208.2 3211.2 3225.3 949.4 1024.2 1030.2 1084.8 1153.2 28362.3 28712.0 28927.0 29023.6 29183.3 1006.5 1133.7 1141.2 1159.4 1195.0 12846.4 13666.3 14527.3 14724.1 15060.5 606256.7 615064.0 617428.0 619294.0 620203.8 59623.7 61551.7 61560.8 61689.7 61849.7 1357.7 1384.4 1538.7 1540.6 1552.2 23001.5 23619.3 27643.0 28307.7 28521.4 50561.0 51099.7 51109.0 51150.7 51294.2 434231.0 438632.0 438818.0 438890.0 439451.0 26730.3 34905.8 35322.6 41246.0 42198.0 42737.6 42902.1 43022.8 43776.1 44203.0 33224.0 35384.5 36352.8 36636.8 36726.8 35382.0 35607.6 35634.1 36383.5 36415.9 36886.3 37376.6 37424.4 37780.2 37796.4 32185.8 33003.3 33026.8 33056.0 33098.3 24345.4 24863.0 25027.7 25105.5 25288.5 18:04:28 INFO - PROCESS | 1671 | 18:04:28 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:31603.42 Median:2269.86 stddev:87712.59 (3864.2%) stddev-sans-first:87927.37 18:04:28 INFO - PROCESS | 1671 | Values: 409.7 410.4 413.0 418.0 420.3 18768.4 19744.6 20323.9 20329.8 20330.6 17545.6 19910.1 20201.7 20928.4 20977.0 5780.4 5901.9 5945.9 5967.3 6006.5 5847.6 6051.3 6091.6 6094.7 6121.2 5709.5 5945.9 5946.0 6063.4 6070.7 5175.5 5761.4 5763.0 5808.5 5818.2 5106.3 5148.0 5155.7 5204.2 5207.3 25570.2 25880.7 26230.3 26303.0 26316.3 2197.7 2232.9 2306.8 2530.6 2876.1 14468.1 14558.4 14781.4 14964.3 15333.2 1717.4 1731.2 1740.5 1756.1 1846.5 67668.7 108090.0 108239.0 108757.0 118265.4 11407.5 11498.4 11606.6 11644.8 11751.5 14612.4 14701.8 14708.3 14737.9 14770.6 7368.6 8518.1 8907.7 8978.7 8995.4 8840.2 8841.5 8857.9 8905.9 8925.4 4681.4 4710.3 4729.7 4730.7 4776.8 1974.6 2041.0 2058.7 2069.6 2086.8 796.3 910.3 946.5 987.0 1015.6 20090.4 20806.8 20908.3 21028.1 21158.8 976.8 1003.5 1006.5 1019.8 1036.4 9014.3 11125.8 11221.8 11295.9 11321.5 497198.0 502424.0 503006.0 530692.0 598926.0 69251.0 70789.0 94429.5 132354.3 136710.0 878.6 1067.5 1068.5 1104.2 1105.7 16347.5 16400.8 18201.5 18886.8 18926.8 48174.6 49359.5 49749.5 51676.0 53475.7 51813.0 69149.6 69277.0 69477.3 69935.0 8091.9 8337.1 8460.7 8571.0 9026.4 11966.3 12884.9 12989.5 13203.4 13367.9 10240.1 11260.7 11527.0 11632.9 11640.9 10402.7 10608.7 11048.8 11301.8 11611.5 11139.7 11311.4 11720.8 11762.5 11894.5 8385.5 8502.8 8557.0 8582.8 8595.0 10575.8 11377.8 11595.3 11606.6 11684.5 18:04:28 INFO - PROCESS | 1671 | 18:04:28 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5597.89 Median:4915.94 stddev:3080.66 (62.7%) stddev-sans-first:3072.94 18:04:28 INFO - PROCESS | 1671 | Values: 1373.8 1569.0 1594.1 1671.1 1729.4 7840.7 8521.9 8652.4 8655.9 8664.4 8373.7 8632.9 8646.5 8652.5 8657.0 2438.9 2457.3 2460.6 2468.4 2474.9 2403.6 2457.9 2461.7 2468.0 2471.8 2266.7 2341.0 2423.8 2451.4 2472.4 2425.4 2444.6 2457.6 2458.8 2470.0 2427.7 2444.3 2456.7 2460.4 2460.7 8411.5 8720.9 8774.1 8784.0 8791.3 5261.5 5412.4 5419.8 5426.3 5449.7 3484.0 3560.8 3583.0 3613.2 3624.4 1060.1 1071.8 1077.1 1080.1 1081.2 11391.3 11483.9 11682.1 11687.8 11691.8 4518.4 4519.6 4656.0 4659.0 4661.3 9336.3 9339.0 9339.6 9344.2 9372.3 5264.0 5267.8 5305.5 5309.9 5326.3 4238.4 4342.1 4734.7 4892.0 4916.2 4032.7 4042.4 4106.4 4134.3 4155.9 1352.1 1379.8 1379.8 1385.7 1397.3 711.0 816.4 836.4 922.6 936.0 5394.9 5627.2 5671.5 5777.9 5814.0 969.6 989.1 998.7 1012.4 1034.6 3675.9 3767.3 3771.2 3796.7 3848.9 10230.9 10315.4 10622.4 10652.6 10677.3 10547.9 10553.2 10669.0 10676.2 10681.6 7707.5 8196.1 8319.1 8321.5 8389.5 4566.7 5515.5 5595.4 5730.8 5824.1 9182.9 9279.4 9289.4 9439.0 9439.3 9184.0 9394.7 9432.2 9521.0 9530.7 7354.4 7418.0 7486.9 7488.1 7491.3 6957.7 7999.8 8037.6 8095.6 8109.7 7403.8 7438.2 7456.4 7551.7 7561.0 7034.1 7199.1 7220.6 7268.1 7422.0 6810.5 6822.0 6886.0 6886.3 6909.1 4112.5 4583.1 4608.7 4641.6 4726.0 4661.9 4782.4 4876.0 4915.7 4952.9 18:04:28 INFO - PROCESS | 1671 | 18:04:28 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:5605.33 Median:6294.07 stddev:1760.03 (28.0%) stddev-sans-first:1719.62 18:04:28 INFO - PROCESS | 1671 | Values: 316.8 319.9 328.3 332.6 333.6 6378.5 6436.9 6475.3 6481.9 6497.0 5620.7 6005.7 6116.9 6360.9 6419.7 4758.6 4793.3 4801.6 4809.6 4836.1 4800.5 4810.4 4814.3 4824.2 4826.9 4556.5 4636.9 4719.6 4770.8 4778.6 4716.2 4728.8 4729.4 4742.2 4758.6 4066.1 4095.9 4109.6 4114.5 4135.8 4988.8 6103.3 6128.2 6153.7 6175.3 5553.5 5560.5 5565.7 5583.1 5594.1 6537.4 6788.7 6825.6 6827.6 6831.2 4531.6 4589.8 4598.6 4616.9 4630.0 7181.1 7187.4 7191.1 7195.2 7217.2 4636.2 4643.0 4648.0 4657.2 4660.4 4702.9 4708.3 4709.6 4714.7 4725.3 5272.1 5537.9 5572.2 5577.2 5587.3 6857.8 6860.3 6867.9 6894.3 6897.8 1549.7 1619.0 1634.3 1644.0 1645.6 1012.2 1037.3 1038.0 1039.8 1041.5 5532.3 6245.7 6407.2 6419.9 6484.2 7154.0 7162.0 7164.5 7165.7 7183.6 6408.2 6440.5 6464.9 6468.7 6472.6 3641.7 3723.9 3726.5 3783.1 3787.5 7414.9 7462.1 7474.1 7490.7 7502.4 6087.5 7314.8 7320.4 7358.9 7363.6 6342.5 7229.8 7434.7 7437.1 7458.3 6129.6 6850.8 6851.8 6976.9 7096.7 6970.5 7024.5 7027.9 7052.1 7064.4 6116.0 7459.4 7477.8 7485.3 7492.9 4658.2 4670.9 4672.7 4677.2 4685.3 5881.7 7017.3 7068.3 7073.7 7079.6 6544.7 6827.0 6883.1 6884.1 6917.6 6868.7 6886.6 6890.6 6904.1 6906.1 6726.5 6733.9 6741.2 6743.5 6743.7 6116.5 6868.8 6878.2 6884.6 6902.1 4962.5 6658.9 6666.6 6704.3 6718.4 18:04:28 INFO - PROCESS | 1671 | 18:04:28 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17547.59 Median:577.71 stddev:24868.35 (4304.7%) stddev-sans-first:24905.88 18:04:28 INFO - PROCESS | 1671 | Values: 686.7 717.8 745.1 748.8 760.2 15231.2 15397.2 15408.7 15925.1 16292.0 16027.9 16053.2 16127.5 16156.9 16220.7 738.1 749.9 917.5 939.2 940.6 920.1 938.9 940.3 940.5 941.0 977.8 988.8 988.9 989.2 989.7 766.0 937.8 955.1 982.0 984.7 719.8 720.2 720.6 721.7 722.2 14974.6 15060.3 15071.0 15076.8 15101.8 12463.7 12575.9 12597.7 12794.6 12871.3 10074.3 10213.8 10271.2 10383.2 11122.0 3441.3 3484.6 3494.0 3545.6 3582.9 86549.3 88670.6 89236.7 89487.6 89588.6 9202.7 9296.2 9400.6 9452.1 10057.2 21351.8 21434.2 21437.2 21438.8 21462.9 6625.9 6790.5 6825.7 6845.5 6907.6 3931.4 3989.5 3992.1 4080.4 4240.8 3589.2 3652.8 3654.0 3662.4 3669.9 631.8 644.3 646.0 646.9 662.0 525.9 561.6 575.5 579.9 581.5 8000.4 8067.5 8075.0 8169.3 8203.5 505.8 513.9 596.8 603.4 618.3 3407.8 3636.8 3658.0 3782.8 3837.9 94575.5 97942.0 98746.0 98817.0 99095.5 29442.0 36181.0 37119.8 37363.0 46055.8 62560.7 67888.0 70164.0 71466.0 73138.6 7950.9 8153.2 8160.4 8251.0 8290.5 49857.0 50049.7 50122.2 50178.0 50842.5 63117.0 63184.0 67309.0 68357.3 70624.6 10834.9 10904.5 11121.7 11408.4 11476.3 11728.4 11818.8 11893.8 11987.6 12158.6 10956.7 11100.2 11437.1 11531.3 11586.0 11352.3 11662.8 11670.2 11717.6 11915.9 10144.9 11082.8 11534.4 11750.9 11895.5 9418.3 9513.6 9623.3 9716.2 9981.8 8980.9 9348.3 9684.3 9741.3 9798.0 18:04:28 INFO - PROCESS | 1671 | -------- Summary: end -------- 18:04:28 INFO - PROCESS | 1671 | 18:04:28 INFO - TEST-INFO | 1671: exit 0 18:04:28 INFO - TEST-OK | dromaeo_css | took 1115191ms 18:04:28 INFO - TEST-START | kraken 18:04:28 INFO - operating with platform_type : mac_ 18:04:28 INFO - Initialising browser for kraken test... 18:04:28 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpFxvQoU/profile http://localhost:49273/getInfo.html) 18:04:30 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 18:04:30 INFO - PROCESS | 1679 | colorDepth:24 18:04:30 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 18:04:30 INFO - PROCESS | 1679 | __metrics 18:04:31 INFO - PROCESS | 1679 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:04:31 ERROR - PROCESS | 1679 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:04:31 INFO - TEST-INFO | 1679: exit 0 18:04:31 INFO - Browser initialized. 18:04:31 INFO - Running cycle 1/1 for kraken test... 18:04:31 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpFxvQoU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:04:43 INFO - PROCESS | 1681 | RSS: Main: 211218432 18:04:43 INFO - PROCESS | 1681 | 18:04:46 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 18:04:47 INFO - PROCESS | 1681 | RSS: Main: 270991360 18:04:47 INFO - PROCESS | 1681 | 18:04:50 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 18:04:50 INFO - PROCESS | 1681 | RSS: Main: 285970432 18:04:50 INFO - PROCESS | 1681 | 18:04:54 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 18:04:54 INFO - PROCESS | 1681 | RSS: Main: 374562816 18:04:54 INFO - PROCESS | 1681 | 18:04:56 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 18:04:57 INFO - PROCESS | 1681 | RSS: Main: 278167552 18:04:57 INFO - PROCESS | 1681 | 18:04:59 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:05:00 INFO - PROCESS | 1681 | RSS: Main: 278589440 18:05:00 INFO - PROCESS | 1681 | 18:05:02 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 18:05:03 INFO - PROCESS | 1681 | RSS: Main: 317063168 18:05:03 INFO - PROCESS | 1681 | 18:05:06 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 18:05:07 INFO - PROCESS | 1681 | RSS: Main: 319496192 18:05:07 INFO - PROCESS | 1681 | 18:05:09 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 18:05:10 INFO - PROCESS | 1681 | RSS: Main: 316743680 18:05:10 INFO - PROCESS | 1681 | 18:05:12 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:05:12 INFO - PROCESS | 1681 | RSS: Main: 308936704 18:05:12 INFO - PROCESS | 1681 | 18:05:14 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:05:15 INFO - PROCESS | 1681 | RSS: Main: 316080128 18:05:15 INFO - PROCESS | 1681 | 18:05:17 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:05:18 INFO - PROCESS | 1681 | RSS: Main: 318615552 18:05:18 INFO - PROCESS | 1681 | 18:05:20 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:05:21 INFO - PROCESS | 1681 | RSS: Main: 320212992 18:05:21 INFO - PROCESS | 1681 | 18:05:24 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:05:24 INFO - PROCESS | 1681 | RSS: Main: 320425984 18:05:24 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 18:05:26 INFO - PROCESS | 1681 | RSS: Main: 384827392 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | __start_tp_report 18:05:26 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 18:05:26 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 18:05:26 INFO - PROCESS | 1681 | |i|pagename|runs| 18:05:26 INFO - PROCESS | 1681 | |0;ai-astar;114;114;120;114;112;112;114;114;114;133 18:05:26 INFO - PROCESS | 1681 | |1;audio-beat-detection;156;134;184;178;117;141;118;150;155;116 18:05:26 INFO - PROCESS | 1681 | |2;audio-dft;183;185;185;183;185;183;182;185;212;252 18:05:26 INFO - PROCESS | 1681 | |3;audio-fft;74;73;75;154;56;73;82;92;144;55 18:05:26 INFO - PROCESS | 1681 | |4;audio-oscillator;90;91;143;77;80;86;124;79;78;121 18:05:26 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;115;87;88;85;88;85;89;87;88;83 18:05:26 INFO - PROCESS | 1681 | |6;imaging-darkroom;93;95;95;96;94;95;95;95;93;95 18:05:26 INFO - PROCESS | 1681 | |7;imaging-desaturate;123;138;97;85;85;85;86;84;85;85 18:05:26 INFO - PROCESS | 1681 | |8;json-parse-financial;60;64;63;62;63;63;64;84;59;56 18:05:26 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;67;65;75;79;77;72;71;70;67;69 18:05:26 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;79;80;80;80;80;79;79;80;80;85 18:05:26 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;130;132;145;145;124;130;127;129;183;148 18:05:26 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;164;173;203;205;170;150;172;163;157;157 18:05:26 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;68;67;68;67;69;66;67;69;66;67 18:05:26 INFO - PROCESS | 1681 | __end_tp_report 18:05:26 INFO - PROCESS | 1681 | __start_cc_report 18:05:26 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,2012 18:05:26 INFO - PROCESS | 1681 | __end_cc_report 18:05:26 INFO - PROCESS | 1681 | __startTimestamp1463187926890__endTimestamp 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | ------- Summary: start ------- 18:05:26 INFO - PROCESS | 1681 | Number of tests: 14 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:116.10 Median:114.00 stddev:6.33 (5.6%) stddev-sans-first:6.67 18:05:26 INFO - PROCESS | 1681 | Values: 114.0 114.0 120.0 114.0 112.0 112.0 114.0 114.0 114.0 133.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:144.90 Median:152.50 stddev:24.37 (16.0%) stddev-sans-first:25.52 18:05:26 INFO - PROCESS | 1681 | Values: 156.0 134.0 184.0 178.0 117.0 141.0 118.0 150.0 155.0 116.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:193.50 Median:185.00 stddev:22.40 (12.1%) stddev-sans-first:23.44 18:05:26 INFO - PROCESS | 1681 | Values: 183.0 185.0 185.0 183.0 185.0 183.0 182.0 185.0 212.0 252.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:87.80 Median:73.50 stddev:34.11 (46.4%) stddev-sans-first:35.81 18:05:26 INFO - PROCESS | 1681 | Values: 74.0 73.0 75.0 154.0 56.0 73.0 82.0 92.0 144.0 55.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:79.50 stddev:23.57 (29.7%) stddev-sans-first:24.87 18:05:26 INFO - PROCESS | 1681 | Values: 90.0 91.0 143.0 77.0 80.0 86.0 124.0 79.0 78.0 121.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:87.50 stddev:9.14 (10.5%) stddev-sans-first:1.94 18:05:26 INFO - PROCESS | 1681 | Values: 115.0 87.0 88.0 85.0 88.0 85.0 89.0 87.0 88.0 83.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.97 (1.0%) stddev-sans-first:0.83 18:05:26 INFO - PROCESS | 1681 | Values: 93.0 95.0 95.0 96.0 94.0 95.0 95.0 95.0 93.0 95.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:85.00 stddev:19.26 (22.7%) stddev-sans-first:17.63 18:05:26 INFO - PROCESS | 1681 | Values: 123.0 138.0 97.0 85.0 85.0 85.0 86.0 84.0 85.0 85.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:63.00 stddev:7.54 (12.0%) stddev-sans-first:7.87 18:05:26 INFO - PROCESS | 1681 | Values: 60.0 64.0 63.0 62.0 63.0 63.0 64.0 84.0 59.0 56.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.50 stddev:4.59 (6.4%) stddev-sans-first:4.61 18:05:26 INFO - PROCESS | 1681 | Values: 67.0 65.0 75.0 79.0 77.0 72.0 71.0 70.0 67.0 69.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.00 stddev:1.75 (2.2%) stddev-sans-first:1.80 18:05:26 INFO - PROCESS | 1681 | Values: 79.0 80.0 80.0 80.0 80.0 79.0 79.0 80.0 80.0 85.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:139.30 Median:138.50 stddev:17.54 (12.7%) stddev-sans-first:18.28 18:05:26 INFO - PROCESS | 1681 | Values: 130.0 132.0 145.0 145.0 124.0 130.0 127.0 129.0 183.0 148.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:171.00 stddev:18.65 (10.9%) stddev-sans-first:19.59 18:05:26 INFO - PROCESS | 1681 | Values: 164.0 173.0 203.0 205.0 170.0 150.0 172.0 163.0 157.0 157.0 18:05:26 INFO - PROCESS | 1681 | 18:05:26 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.07 (1.6%) stddev-sans-first:1.12 18:05:26 INFO - PROCESS | 1681 | Values: 68.0 67.0 68.0 67.0 69.0 66.0 67.0 69.0 66.0 67.0 18:05:26 INFO - PROCESS | 1681 | -------- Summary: end -------- 18:05:26 INFO - PROCESS | 1681 | 18:05:27 INFO - TEST-INFO | 1681: exit 0 18:05:27 INFO - TEST-OK | kraken | took 58903ms 18:05:27 INFO - SUITE-END | took 1174s 18:05:28 INFO - Completed test suite (00:19:34) 18:05:28 INFO - javascript benchmark 18:05:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2906.69479704631, 3468.393031219536, 3474.4093504104308, 3488.59881113672, 3550.982245088774, 5766.1639062335935, 5789.334226563947, 5803.274590676166, 5821.708914554266, 5826.242588463494, 5807.73865176067, 5854.034084376083, 5858.238429004234, 5864.149698293745, 5870.911936320958, 5127.154019586782, 5200.843974680765, 5233.790648374061, 5291.550218231455, 5398.811041613544, 5273.551748101416, 5287.814926477574, 5290.312259406291, 5294.338207724035, 5299.60252981026, 5159.097157997354, 5209.505097015777, 5225.6864588124645, 5245.160774276116, 5272.051030814452, 5289.841304760844, 5339.41266460689, 5342.385625653061, 5346.946530534684, 5382.9730851345885, 5188.429272779991, 5207.140821764404, 5209.114450543417, 5261.579073674096, 5273.604479664041, 5613.522850557708, 5633.380328163939, 5656.0, 5663.0, 5688.658680479143, 5560.77756889724, 6259.874802503924, 6298.433141017331, 6315.968420157916, 6325.399087086719, 6073.177298882116, 6188.628682279078, 6259.248890133168, 6293.30773614902, 6326.715297811609, 5799.159121927297, 5824.068149096124, 5834.445727655866, 5835.299764028301, 5872.060470324727, 6238.469730072995, 6277.937220627827, 6283.214598175228, 6287.182666253358, 6295.653739044396, 5999.100134979701, 6127.693615319216, 6137.539684523678, 6171.6297022178815, 6183.907241391383, 6276.46650034743, 6284.937150628527, 6298.937010629835, 6305.117283580303, 6306.369363063749, 6219.906701399483, 6235.688215589294, 6248.4064013918605, 6249.250089989139, 6286.4342209201395, 6063.454289114001, 6122.571420000646, 6142.836439233709, 6151.815445536641, 6261.968690156611, 4886.584640305543, 4913.164761990471, 4933.432655244621, 4956.553910148032, 4975.129352363322, 4443.155800397914, 4450.3991961085485, 4459.308807134899, 4485.349624304523, 4491.101779644085, 5709.714514274269, 5731.627444216113, 5742.712864356766, 5746.856328591735, 5749.137629355547, 6133.395871484054, 6194.628322300587, 6292.842678933063, 6293.0, 6305.5901366411035, 5666.9716651416475, 5718.999175144333, 5720.3421606515785, 5724.112762521816, 5736.42635736423, 5412.134058550613, 5424.783008679608, 5442.047641662693, 5445.673259604358, 5446.183072539072, 6228.844278893064, 6341.587796793194, 6405.967970160119, 6408.647524386017, 6411.743530258737, 6241.8751624966335, 6296.307406185224, 6303.338149494237, 6312.021636646327, 6331.936680633319, 6258.874822503615, 6328.746850125944, 6334.0, 6366.840828979312, 6376.649284289409, 6181.505479561534, 6283.96858015707, 6296.937030629635, 6299.08663243818, 6314.968425157845, 5324.866878328073, 6247.468965137832, 6283.717232724447, 6291.716872740829, 6326.114343991937, 5985.1022346646605, 6069.817905462844, 6090.573659843769, 6100.267967843887, 6102.725377358119, 6008.068749343859, 6036.728347224298, 6045.002574575145, 6052.757889684363, 6082.178905847831, 5901.173835662925, 5910.940890591039, 5911.290645122613, 5933.851653708692, 5936.495397891227, 5949.91075133882, 6059.030555111161, 6065.484433823174, 6111.561351782825, 6127.0, 5626.887462250814, 5899.321578018583, 5907.645541267366, 5956.0, 6014.72933717975], "value": 5722.814772055292, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.7456271864066, 541.8374487653704, 564.8700798816269, 573.3864764701768, 587.0313981929816, 30888.84555577222, 31499.68500314996, 31524.054278371685, 31638.52542211863, 31693.683063169392, 31688.524672129937, 38648.61351386485, 39411.0, 39484.40773388394, 39529.40705889407, 9556.474393908333, 9746.561404736804, 9752.756181095458, 9753.512324383752, 9763.463009534473, 8575.699850505234, 9521.571529281198, 9645.469499177543, 9662.661806836762, 9671.129598336149, 8629.180227878342, 8702.086280940504, 8703.782405439852, 8706.216440520351, 11478.598249061284, 11363.0, 11462.083033357312, 11462.656120316404, 11473.0, 11473.655790326304, 11355.602553910574, 11466.770664586746, 11473.5984240552, 11487.540498380054, 11491.195616306863, 32636.6736332636, 37964.62035379638, 40488.39267410991, 40940.0, 40972.0, 3194.073718621597, 3279.5572597699243, 3293.357795229931, 3602.6937710294656, 3661.487391765155, 26621.06826261091, 27013.864930675416, 27039.32401689954, 27185.320366990785, 27443.0, 2737.288305040684, 2750.8074434789573, 2751.793615478847, 2789.4002789400374, 2829.858507074638, 332451.0, 333118.0, 335007.0, 335292.0, 335306.8815871031, 36823.815880920425, 61960.380396196364, 63086.0, 63135.36864631295, 63295.0, 35669.46495802565, 35766.821165894005, 35772.82113589467, 35920.46119308213, 36030.0, 15613.609659758597, 15850.365985360457, 15858.44495442677, 15876.52370428889, 15909.443169489012, 13313.534026309033, 13329.266890320932, 13332.800007999887, 13366.599002029954, 13392.0, 8171.83656326882, 8280.50316980983, 8311.95844020776, 8314.0, 8364.414490985573, 3137.262743255333, 3185.665505121975, 3208.16587687203, 3211.213252753044, 3225.3388055449045, 949.4493193947494, 1024.2471783239314, 1030.1861529391697, 1084.7505073832904, 1153.181241318673, 28362.290942726184, 28711.995080172095, 28927.0, 29023.564646529896, 29183.270418239288, 1006.5369929832212, 1133.6939026462808, 1141.178351586856, 1159.3565571107947, 1194.952201911931, 12846.42191101384, 13666.316684165751, 14527.273636318354, 14724.116553006854, 15060.472883449027, 606256.6871656488, 615064.0, 617428.0, 619294.0, 620203.7979620236, 59623.70188149031, 61551.692241538505, 61560.76878462406, 61689.691551542855, 61849.69075154595, 1357.7488164689564, 1384.4393020826783, 1538.699953509055, 1540.5917431880337, 1552.1773460066183, 23001.53996920019, 23619.29142125739, 27643.0, 28307.716922830507, 28521.42957140804, 50560.955123590764, 51099.74450127726, 51108.977820444125, 51150.74424627853, 51294.230586540485, 434231.0, 438632.0, 438818.0, 438890.0, 439451.0, 26730.331741706614, 34905.8254708735, 35322.64677353194, 41246.0, 42198.0, 42737.57262427336, 42902.14195716131, 43022.784886075366, 43776.12447751091, 44203.0, 33224.0, 35384.46923296204, 36352.81823590971, 36636.81681591575, 36726.816365918, 35382.0, 35607.64392356147, 35634.10914727153, 36383.45424818577, 36415.90752277436, 36886.26227475489, 37376.62623373731, 37424.438633421065, 37780.2443951114, 37796.43305350367, 32185.839070804497, 33003.339933201685, 33026.83486582552, 33056.00831975044, 33098.338033239685, 24345.39136521601, 24863.005479780608, 25027.716186253, 25105.49789004246, 25288.494230114928], "value": 18280.36204731456, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.6538425030849, 410.38032570818064, 413.0438035946781, 418.0248326656548, 420.3232795199728, 18768.436946891616, 19744.605107897834, 20323.898380508097, 20329.796702032992, 20330.593388132227, 17545.56136096595, 19910.10404531796, 20201.696974545357, 20928.372148835522, 20977.0, 5780.421957804207, 5901.852453688649, 5945.88108237835, 5967.254093238345, 6006.489448396892, 5847.5614328925285, 6051.334353221142, 6091.634501929876, 6094.695265236743, 6121.173641558399, 5709.486146246837, 5945.851353716149, 5945.989181839076, 6063.4239747224165, 6070.6661133637635, 5175.482451754832, 5761.423857614226, 5763.0, 5808.506276966463, 5818.185454036438, 5106.259592359124, 5148.021875843578, 5155.74221288934, 5204.1673332266655, 5207.297014903015, 25570.232893013053, 25880.741192588022, 26230.344241393926, 26303.0, 26316.342091447867, 2197.6593627987686, 2232.944176395603, 2306.7693230676964, 2530.569803133472, 2876.137158852336, 14468.131912085308, 14558.417663293561, 14781.408743650241, 14964.251787410585, 15333.233338333039, 1717.407494414425, 1731.1603872122012, 1740.5213566269251, 1756.1043867627475, 1846.5014446099613, 67668.6616566914, 108090.0, 108239.0, 108757.0, 118265.40867295694, 11407.48666310018, 11498.425078746197, 11606.593769218036, 11644.825327619923, 11751.529938802352, 14612.415503379745, 14701.779473307908, 14708.33812478441, 14737.926310368379, 14770.630734231516, 7368.557886526718, 8518.105598912149, 8907.688230911886, 8978.685745998859, 8995.370324077383, 8840.24857887074, 8841.469511829311, 8857.867131993025, 8905.910940890508, 8925.419847709878, 4681.391419115494, 4710.29345598163, 4729.716217026989, 4730.692504987233, 4776.832810851603, 1974.634692581877, 2040.9693854592192, 2058.6500294949897, 2069.6264443440164, 2086.8017538333834, 796.307212724933, 910.3354551177678, 946.5456580841235, 986.9703908882746, 1015.5531566110888, 20090.395368105652, 20806.79193208079, 20908.268210612554, 21028.053737581846, 21158.788412115682, 976.8046390721742, 1003.5333569890037, 1006.4615430744517, 1019.8419245017034, 1036.3781730961364, 9014.278857691368, 11125.777484450427, 11221.821639396227, 11295.94352028251, 11321.547138114547, 497198.0, 502424.0, 503006.0, 530692.0, 598926.0, 69251.0, 70789.0, 94429.52785236167, 132354.33822830825, 136710.0, 878.6353663229816, 1067.4555976451911, 1068.4711068021224, 1104.1718710966775, 1105.7346236903086, 16347.509574712778, 16400.835991640408, 18201.544961376072, 18886.811131888506, 18926.76975996556, 48174.554763357155, 49359.50640493549, 49749.50751477462, 51676.0, 53475.663108421046, 51813.0, 69149.61700766058, 69276.96084558837, 69477.30522694708, 69935.0, 8091.919080809116, 8337.082920878578, 8460.703875364332, 8570.971483422029, 9026.413283136575, 11966.341851198267, 12884.935575322063, 12989.545365912525, 13203.405846736729, 13367.933160334525, 10240.129588985019, 11260.718482038015, 11527.0, 11632.941835290769, 11640.88359116398, 10402.73993150147, 10608.681739547517, 11048.779024419628, 11301.830472542753, 11611.5355385787, 11139.665810025712, 11311.377874216669, 11720.765584688428, 11762.470688819194, 11894.464749086139, 8385.538795366314, 8502.82994340122, 8556.957215213884, 8582.78543036429, 8595.0, 10575.788484230427, 11377.829332560184, 11595.304281743123, 11606.593769218036, 11684.532618695157], "value": 9596.562322779748, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1373.8145350377697, 1568.9686206275874, 1594.1471312847625, 1671.0976072920623, 1729.403355842234, 7840.7255746048895, 8521.91478085219, 8652.351073669484, 8655.91344086559, 8664.43681160725, 8373.748787536368, 8632.913670863289, 8646.481211127337, 8652.524111173883, 8657.004444488886, 2438.9268321950326, 2457.2751038443703, 2460.5817011108156, 2468.4322605800676, 2474.8762561871836, 2403.5793736096202, 2457.913973010945, 2461.655368248447, 2467.9753202468014, 2471.752824717532, 2266.7166604174477, 2341.0401735288533, 2423.7939775119135, 2451.3626457121186, 2472.3819045238693, 2425.38152771044, 2444.608862581987, 2457.619069044301, 2458.8032957363366, 2470.0243403855557, 2427.6722642443397, 2444.278937713379, 2456.680631517909, 2460.3972026853535, 2460.667809845666, 8411.53736544496, 8720.8691869622, 8774.122587741174, 8784.0, 8791.296696264284, 5261.500157485033, 5412.431694672082, 5419.83740487786, 5426.348838139448, 5449.700266485342, 3484.0, 3560.8041557714573, 3583.0325812030865, 3613.1509095362567, 3624.4200927851416, 1060.1359891688326, 1071.8251764892545, 1077.084478193537, 1080.0603474976742, 1081.2269227502295, 11391.316521008766, 11483.885161148366, 11682.065434765283, 11687.824682629767, 11691.824622630667, 4518.441137807451, 4519.638428925678, 4655.95344046562, 4658.998315362213, 4661.277501987197, 9336.346455748033, 9338.95330523343, 9339.626414943463, 9344.205742511827, 9372.250219982385, 5263.973680131651, 5267.841964741064, 5305.469453054664, 5309.946900530946, 5326.334208223972, 4238.406623072772, 4342.088161486124, 4734.692245004064, 4892.046051020088, 4916.188828843201, 4032.697547683935, 4042.3532234842282, 4106.425100485934, 4134.338505839051, 4155.916881662411, 1352.1211212711619, 1379.7930310453512, 1379.8256537982566, 1385.6535866033491, 1397.343248673122, 710.996445017782, 816.4081041245169, 836.431226765793, 922.5894476957824, 935.9517340578459, 5394.9460505394445, 5627.155926611041, 5671.461211184931, 5777.913331300034, 5813.982553053199, 969.5685419988038, 989.1048601016092, 998.6604554451376, 1012.4178597306578, 1034.591336422109, 3675.9448608270895, 3767.321882061255, 3771.151490914577, 3796.7152463565344, 3848.8845334640005, 10230.897691023143, 10315.432651204255, 10622.415767132727, 10652.57389704403, 10677.252592318619, 10547.894521054845, 10553.208509361828, 10669.0, 10676.199285053652, 10681.572737090584, 7707.499012564165, 8196.098429172785, 8319.126491718282, 8321.500709957301, 8389.454685445426, 4566.657500687395, 5515.503604675438, 5595.384507704068, 5730.799422020209, 5824.068149096124, 9182.908170918472, 9279.350445468752, 9289.442633442015, 9438.95280523593, 9439.292053095907, 9183.989761126404, 9394.67118650844, 9432.245420366216, 9521.000294968928, 9530.666426675032, 7354.411647068115, 7418.0, 7486.8876966844455, 7488.138864030489, 7491.325780679765, 6957.686904089227, 7999.84000320002, 8037.638306276114, 8095.635696393794, 8109.7161599343735, 7403.777886663409, 7438.181800002104, 7456.366208872307, 7551.660175292015, 7560.9621951889885, 7034.050403195503, 7199.1001124859395, 7220.638968051681, 7268.0914885639295, 7422.0, 6810.489213309121, 6821.965890170517, 6886.035954966209, 6886.276940921131, 6909.067275917888, 4112.506499220112, 4583.1062942727185, 4608.723476591281, 4641.605463535638, 4726.0, 4661.930071048869, 4782.37829082231, 4875.975620121877, 4915.696984089222, 4952.925706114339], "value": 4544.200320639882, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.76875880607156, 319.92001999500127, 328.2925295987148, 332.6158287178307, 333.6296710651177, 6378.489720822335, 6436.935630643692, 6475.255345635247, 6481.870362592757, 6496.992966090252, 5620.690862002588, 6005.738794853086, 6116.9388306116925, 6360.936390636104, 6419.679016049202, 4758.595519380857, 4793.257045157989, 4801.615870730342, 4809.567138957493, 4836.081144582518, 4800.543948324904, 4810.39870016248, 4814.277858321245, 4824.2040063389495, 4826.879328016793, 4556.498785133633, 4636.907261854778, 4719.598834099105, 4770.761461926907, 4778.617710583145, 4716.174669432836, 4728.834490792806, 4729.432468103815, 4742.217534106868, 4758.595519380857, 4066.146109317047, 4095.9180816383805, 4109.589041095896, 4114.526829414625, 4135.7518548886865, 4988.800447982077, 6103.29812071613, 6128.233970753656, 6153.692315384213, 6175.320714721377, 5553.527950124244, 5560.499555040025, 5565.666060036411, 5583.078791999315, 5594.0769772987405, 6537.444317233041, 6788.660566971632, 6825.590464572141, 6827.556208846409, 6831.2144103428245, 4531.614812740921, 4589.79345929434, 4598.609118224955, 4616.884577885546, 4630.027694184226, 7181.102362204672, 7187.38907192884, 7191.137063552407, 7195.172555156171, 7217.206107328242, 4636.235021221528, 4643.0, 4647.95352046482, 4657.231556793091, 4660.440747110369, 4702.88242793926, 4708.340832283483, 4709.62323014158, 4714.727023703581, 4725.267583524491, 5272.077386457355, 5537.889242215133, 5572.164175373645, 5577.2191893135, 5587.329520457572, 6857.794266172023, 6860.279670634612, 6867.931320686829, 6894.345037221456, 6897.7585784497305, 1549.697808927271, 1619.004312347921, 1634.3380930723076, 1643.9671206575802, 1645.5968287769333, 1012.1851909213154, 1037.273908264218, 1037.994810025945, 1039.7608550033535, 1041.4896700616796, 5532.336119665666, 6245.656488893063, 6407.167068281094, 6419.903701444482, 6484.157059582224, 7153.998440218374, 7162.0, 7164.462665300123, 7165.6775445105095, 7183.604901730455, 6408.166938297992, 6440.54916155874, 6464.93328600785, 6468.741250349934, 6472.611643301417, 3641.672249497558, 3723.925521489555, 3726.4969229153717, 3783.1109689223013, 3787.507624008861, 7414.925850741423, 7462.067241594801, 7474.140473845578, 7490.737824176236, 7502.437317201232, 6087.512998960073, 7314.780556583311, 7320.414366850639, 7358.889616655754, 7363.558186508827, 6342.4926005919415, 7229.819254518679, 7434.665440055211, 7437.107547094383, 7458.291462310964, 6129.570930034854, 6850.794476165723, 6851.760188393382, 6976.883698608199, 7096.716131354688, 6970.546914450747, 7024.508284420246, 7027.8945815811785, 7052.118485189351, 7064.364207221375, 6116.0, 7459.36595389398, 7477.775666730006, 7485.251474852471, 7492.887606685795, 4658.208104622155, 4670.859874203779, 4672.696274742131, 4677.158111539956, 4685.273782563571, 5881.705914704333, 7017.333353331425, 7068.257832927674, 7073.71705131789, 7079.610621415665, 6544.6727663615675, 6826.975953606999, 6883.105196324445, 6884.1050663415435, 6917.584944903322, 6868.656567171522, 6886.621235832077, 6890.621015844176, 6904.102466679299, 6906.102206713095, 6726.529142959946, 6733.865322693617, 6741.191057072986, 6743.494237932076, 6743.66281685904, 6116.541259405473, 6868.793936181923, 6878.174619045546, 6884.621345826027, 6902.068220790129, 4962.478939711423, 6658.866822663616, 6666.633335166613, 6704.262531121671, 6718.428933540508], "value": 5019.506071688795, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.7047169717022, 717.7667258141103, 745.1356426545206, 748.7978245873611, 760.22077370695, 15231.162286074261, 15397.153156576398, 15408.691826163484, 15925.124118173524, 16292.0, 16027.91986040071, 16053.197340133005, 16127.516174514754, 16156.919215403906, 16220.675586488263, 738.0626604212647, 749.9062617172854, 917.4862077236752, 939.1500691873865, 940.5908429833011, 920.0569416348229, 938.9389689670184, 940.2712897504421, 940.4780346907463, 940.9576569054375, 977.8337682593941, 988.8170688422629, 988.9406635601887, 989.2333441582759, 989.6684610655439, 765.9808504787369, 937.7561833923161, 955.0926619711302, 981.9509024548744, 984.7488890332955, 719.8308397526577, 720.1538192623647, 720.646883027318, 721.6752461392342, 722.1622917415825, 14974.62563435912, 15060.32228549718, 15071.020383675026, 15076.773848392282, 15101.848981510155, 12463.688407789787, 12575.93712031434, 12597.748045039049, 12794.552190673281, 12871.292078935654, 10074.294799364048, 10213.79572408555, 10271.229657775697, 10383.221258405574, 11121.999020088231, 3441.3117376524797, 3484.6341134181052, 3494.0, 3545.6099829018785, 3582.946255806165, 86549.26901461935, 88670.556647217, 89236.6614500783, 89487.55256223677, 89588.55205723995, 9202.677906273382, 9296.163345298955, 9400.623975041059, 9452.054794520493, 10057.195424366033, 21351.786482135292, 21434.24980125688, 21437.249696260867, 21438.78561214399, 21462.892685536473, 6625.933740662628, 6790.456763458911, 6825.69284382204, 6845.520813543104, 6907.585544867224, 3931.4495970564144, 3989.519060096378, 3992.1017771001293, 4080.367543030835, 4240.787960601958, 3589.174489867345, 3652.7990960496895, 3653.963460365362, 3662.377395842713, 3669.889903302905, 631.8167731357855, 644.2650946731063, 645.9709313080923, 646.9061986012002, 662.0400419391901, 525.8632755483602, 561.6461629173594, 575.5136909311625, 579.901416759152, 581.5231510161711, 8000.399970002273, 8067.515949043076, 8075.030996280484, 8169.30560902318, 8203.507789532528, 505.7799857062116, 513.8818071843497, 596.7881402102278, 603.4116736182187, 618.2704408797572, 3407.7955322680223, 3636.7999760013454, 3658.0123366690846, 3782.791946442972, 3837.9424308634834, 94575.52712236533, 97942.0, 98746.0, 98817.0, 99095.50452247693, 29442.0, 36181.0, 37119.814400928364, 37363.0, 46055.76972115118, 62560.687196563726, 67888.0, 70164.0, 71466.0, 73138.63430682813, 7950.880736788837, 8153.1846815317995, 8160.387970902325, 8250.958745206235, 8290.502569845828, 49857.0, 50049.74975125101, 50122.248166276804, 50178.0, 50842.491575083775, 63117.0, 63184.0, 67309.0, 68357.3164268351, 70624.64687676528, 10834.891651083704, 10904.509297081491, 11121.666350009513, 11408.372539510407, 11476.254043487146, 11728.41357932083, 11818.82271765907, 11893.762124757282, 11987.64037078889, 12158.635240942785, 10956.726081847699, 11100.222984391015, 11437.142214333791, 11531.250468719507, 11586.0, 11352.262102963281, 11662.825057624312, 11670.183087183816, 11717.589884354007, 11915.880841191823, 10144.898551014396, 11082.833757493805, 11534.365609891536, 11750.88249117532, 11895.464704088165, 9418.293627978066, 9513.619455221991, 9623.326367154232, 9716.22270218395, 9981.800363992534, 8980.910190898006, 9348.25213982866, 9684.32209745311, 9741.318107732674, 9798.0], "value": 6803.164221679131, "unit": "score"}], "name": "dromaeo_css", "value": 7335.491110527256, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 120.0, 114.0, 112.0, 112.0, 114.0, 114.0, 114.0, 133.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 134.0, 184.0, 178.0, 117.0, 141.0, 118.0, 150.0, 155.0, 116.0], "value": 144.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 183.0, 185.0, 183.0, 182.0, 185.0, 212.0, 252.0], "value": 193.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 75.0, 154.0, 56.0, 73.0, 82.0, 92.0, 144.0, 55.0], "value": 87.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 143.0, 77.0, 80.0, 86.0, 124.0, 79.0, 78.0, 121.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 87.0, 88.0, 85.0, 88.0, 85.0, 89.0, 87.0, 88.0, 83.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 93.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 138.0, 97.0, 85.0, 85.0, 85.0, 86.0, 84.0, 85.0, 85.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 63.0, 62.0, 63.0, 63.0, 64.0, 84.0, 59.0, 56.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 75.0, 79.0, 77.0, 72.0, 71.0, 70.0, 67.0, 69.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 80.0, 80.0, 80.0, 79.0, 79.0, 80.0, 80.0, 85.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 132.0, 145.0, 145.0, 124.0, 130.0, 127.0, 129.0, 183.0, 148.0], "value": 139.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 173.0, 203.0, 205.0, 170.0, 150.0, 172.0, 163.0, 157.0, 157.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 67.0, 69.0, 66.0, 67.0, 69.0, 66.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1511.9, "alertThreshold": 2.0}]} 18:05:28 INFO - Return code: 0 18:05:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:05:28 ERROR - # TBPL SUCCESS # 18:05:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:05:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:05:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:05:28 INFO - 2016-05-13 18:05:28.231 screenresolution[1685:10455] starting screenresolution argv=screenresolution get 18:05:28 INFO - 2016-05-13 18:05:28.234 screenresolution[1685:10455] Display 0: 1600x1200x32@60 18:05:28 INFO - 2016-05-13 18:05:28.240 screenresolution[1686:10458] starting screenresolution argv=screenresolution list 18:05:28 INFO - Available Modes on Display 0 18:05:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:05:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:05:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:05:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:05:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:05:28 INFO - Intel Iris: 18:05:28 INFO - Chipset Model: Intel Iris 18:05:28 INFO - Type: GPU 18:05:28 INFO - Bus: Built-In 18:05:28 INFO - VRAM (Dynamic, Max): 1536 MB 18:05:28 INFO - Vendor: Intel (0x8086) 18:05:28 INFO - Device ID: 0x0a2e 18:05:28 INFO - Revision ID: 0x0009 18:05:28 INFO - Displays: 18:05:28 INFO - Display: 18:05:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:05:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:05:28 INFO - Display Serial Number: 1600x1200 60 18:05:28 INFO - Main Display: Yes 18:05:28 INFO - Mirror: Off 18:05:28 INFO - Online: Yes 18:05:28 INFO - Rotation: Supported 18:05:28 INFO - Return code: 0 18:05:28 INFO - Running post-action listener: _resource_record_post_action 18:05:28 INFO - Running post-run listener: _resource_record_post_run 18:05:29 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 31510016; Write bytes: 654610432; Read time: 198; Write time: 1797 18:05:29 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354402816; Write bytes: 356493312; Read time: 27795; Write time: 728 18:05:29 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 29728256; Write bytes: 297109504; Read time: 184; Write time: 1065 18:05:29 INFO - Running post-run listener: _upload_blobber_files 18:05:29 INFO - Blob upload gear active. 18:05:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:05:29 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:05:29 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:05:29 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:05:29 INFO - (blobuploader) - INFO - Open directory for files ... 18:05:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:05:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:05:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:31 INFO - (blobuploader) - INFO - Done attempting. 18:05:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:05:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:05:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:05:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:05:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:05:32 INFO - (blobuploader) - INFO - Done attempting. 18:05:32 INFO - (blobuploader) - INFO - Iteration through files over. 18:05:32 INFO - Return code: 0 18:05:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:05:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:05:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a80dfa7cdde6092072422d8f5917f80a291f22d82b7d4a0e063df09fd10fbbd536be429738dc7fcac26235a09f7f36fd77d25e3011f0cb892caafaaaf176f2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fa4a995f07e02de035163ff157642338a49a190c9f210fc8ab250551bf1196bb372b2e55814fc26a362300ec58e4734466f6d40b1d0f532072f08e29cafb2dc"} 18:05:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:05:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:05:32 INFO - Contents: 18:05:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a80dfa7cdde6092072422d8f5917f80a291f22d82b7d4a0e063df09fd10fbbd536be429738dc7fcac26235a09f7f36fd77d25e3011f0cb892caafaaaf176f2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fa4a995f07e02de035163ff157642338a49a190c9f210fc8ab250551bf1196bb372b2e55814fc26a362300ec58e4734466f6d40b1d0f532072f08e29cafb2dc"} 18:05:32 INFO - Running post-run listener: copy_logs_to_upload_dir 18:05:32 INFO - Copying logs to upload dir... 18:05:32 INFO - mkdir: /builds/slave/test/build/upload/logs 18:05:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1324.984973 ========= master_lag: 94.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 39 secs) (at 2016-05-13 18:07:06.264563) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-13 18:07:06.267724) ========= 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.nvNpJEfMce/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.pcoawy6Yi8/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a80dfa7cdde6092072422d8f5917f80a291f22d82b7d4a0e063df09fd10fbbd536be429738dc7fcac26235a09f7f36fd77d25e3011f0cb892caafaaaf176f2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fa4a995f07e02de035163ff157642338a49a190c9f210fc8ab250551bf1196bb372b2e55814fc26a362300ec58e4734466f6d40b1d0f532072f08e29cafb2dc"} build_url:https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010904 build_url: 'https://queue.taskcluster.net/v1/task/HPtzNdNFSc2bt8Z1D0r3ag/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4a80dfa7cdde6092072422d8f5917f80a291f22d82b7d4a0e063df09fd10fbbd536be429738dc7fcac26235a09f7f36fd77d25e3011f0cb892caafaaaf176f2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1fa4a995f07e02de035163ff157642338a49a190c9f210fc8ab250551bf1196bb372b2e55814fc26a362300ec58e4734466f6d40b1d0f532072f08e29cafb2dc"}' ========= master_lag: 1.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-13 18:07:07.301183) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 mins, 36 secs) (at 2016-05-13 18:07:07.307651) ========= 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.nvNpJEfMce/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.pcoawy6Yi8/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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.005155 ========= master_lag: 96.41 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 mins, 36 secs) (at 2016-05-13 18:08:43.719324) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 18:08:43.721901) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 18:08:43.894608) ========= ========= Total master_lag: 191.70 =========