builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0009 starttime: 1464405318.08 results: success (0) buildid: 20160527191736 builduid: 62e462e558b34ffd8b45ed8aa5932282 revision: 138121c338c75865135a001fc51f7465e36f4740 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:18.082463) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:18.082995) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:18.083871) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:18.157103) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:18.157506) ========= 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.TUUrEJbQt9/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.xamfJhk0Og/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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-27 20:15:18-- 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% 187M=0s 2016-05-27 20:15:18 (187 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.454286 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:18.633131) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:18.633492) ========= 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.TUUrEJbQt9/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.xamfJhk0Og/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.029190 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:18.682268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:18.682591) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 138121c338c75865135a001fc51f7465e36f4740 --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 138121c338c75865135a001fc51f7465e36f4740 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TUUrEJbQt9/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.xamfJhk0Og/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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-27 20:15:18,757 truncating revision to first 12 chars 2016-05-27 20:15:18,757 Setting DEBUG logging. 2016-05-27 20:15:18,757 attempt 1/10 2016-05-27 20:15:18,757 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/138121c338c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 20:15:19,433 unpacking tar archive at: mozilla-aurora-138121c338c7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.949880 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:19.651646) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:19.651919) ========= script_repo_revision: 138121c338c75865135a001fc51f7465e36f4740 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:19.652271) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:19.652550) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 20:15:19.670678) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 26 secs) (at 2016-05-27 20:15:19.670972) ========= /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.TUUrEJbQt9/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.xamfJhk0Og/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:15:19 INFO - MultiFileLogger online at 20160527 20:15:19 in /builds/slave/test 20:15:19 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 20:15:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:15:19 INFO - {'append_to_log': False, 20:15:19 INFO - 'base_work_dir': '/builds/slave/test', 20:15:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:15:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:15:19 INFO - 'branch': 'Mozilla-Aurora', 20:15:19 INFO - 'buildbot_json_path': 'buildprops.json', 20:15:19 INFO - 'config_files': ('talos/mac_config.py',), 20:15:19 INFO - 'default_actions': ('clobber', 20:15:19 INFO - 'read-buildbot-config', 20:15:19 INFO - 'download-and-extract', 20:15:19 INFO - 'populate-webroot', 20:15:19 INFO - 'create-virtualenv', 20:15:19 INFO - 'install', 20:15:19 INFO - 'run-tests'), 20:15:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:15:19 INFO - 'download_minidump_stackwalk': True, 20:15:19 INFO - 'download_symbols': 'ondemand', 20:15:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:15:19 INFO - 'tooltool.py': '/tools/tooltool.py', 20:15:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:15:19 INFO - '/tools/misc-python/virtualenv.py')}, 20:15:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:15:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:15:19 INFO - 'installer_path': 'installer.exe', 20:15:19 INFO - 'log_level': 'info', 20:15:19 INFO - 'log_name': 'talos', 20:15:19 INFO - 'log_to_console': True, 20:15:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:15:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:15:19 INFO - 'opt_config_files': (), 20:15:19 INFO - 'pip_index': False, 20:15:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:15:19 INFO - 'cmd': ('bash', 20:15:19 INFO - '-c', 20:15:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:15:19 INFO - 'enabled': True, 20:15:19 INFO - 'halt_on_failure': False, 20:15:19 INFO - 'name': 'check_screen_resolution'},), 20:15:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:15:19 INFO - 'cmd': ('bash', 20:15:19 INFO - '-c', 20:15:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:15:19 INFO - 'enabled': True, 20:15:19 INFO - 'halt_on_failure': False, 20:15:19 INFO - 'name': 'check_screen_resolution'},), 20:15:19 INFO - 'run_cmd_checks_enabled': True, 20:15:19 INFO - 'sps_profile': False, 20:15:19 INFO - 'sps_profile_interval': 0, 20:15:19 INFO - 'suite': 'dromaeojs', 20:15:19 INFO - 'system_bits': '32', 20:15:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:15:19 INFO - 'title': 't-yosemite-r7-0009', 20:15:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:15:19 INFO - 'use_talos_json': True, 20:15:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:15:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:15:19 INFO - 'work_dir': 'build'} 20:15:19 INFO - ##### 20:15:19 INFO - ##### Running clobber step. 20:15:19 INFO - ##### 20:15:19 INFO - Running pre-action listener: _resource_record_pre_action 20:15:19 INFO - Running main action method: clobber 20:15:19 INFO - rmtree: /builds/slave/test/build 20:15:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:15:21 INFO - Running post-action listener: _resource_record_post_action 20:15:21 INFO - ##### 20:15:21 INFO - ##### Running read-buildbot-config step. 20:15:21 INFO - ##### 20:15:21 INFO - Running pre-action listener: _resource_record_pre_action 20:15:21 INFO - Running main action method: read_buildbot_config 20:15:21 INFO - Using buildbot properties: 20:15:21 INFO - { 20:15:21 INFO - "project": "", 20:15:21 INFO - "product": "firefox", 20:15:21 INFO - "script_repo_revision": "production", 20:15:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 20:15:21 INFO - "repository": "", 20:15:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 20:15:21 INFO - "buildid": "20160527191736", 20:15:21 INFO - "pgo_build": "False", 20:15:21 INFO - "basedir": "/builds/slave/test", 20:15:21 INFO - "buildnumber": 331, 20:15:21 INFO - "slavename": "t-yosemite-r7-0009", 20:15:21 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 20:15:21 INFO - "platform": "yosemite_r7", 20:15:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:15:21 INFO - "branch": "mozilla-aurora", 20:15:21 INFO - "repo_path": "releases/mozilla-aurora", 20:15:21 INFO - "revision": "138121c338c75865135a001fc51f7465e36f4740", 20:15:21 INFO - "stage_platform": "macosx64", 20:15:21 INFO - "builduid": "62e462e558b34ffd8b45ed8aa5932282", 20:15:21 INFO - "slavebuilddir": "test" 20:15:21 INFO - } 20:15:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 20:15:21 INFO - Running post-action listener: _resource_record_post_action 20:15:21 INFO - ##### 20:15:21 INFO - ##### Running download-and-extract step. 20:15:21 INFO - ##### 20:15:21 INFO - Running pre-action listener: _resource_record_pre_action 20:15:21 INFO - Running main action method: download_and_extract 20:15:21 INFO - mkdir: /builds/slave/test/build/tests 20:15:21 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')]} 20:15:21 INFO - https://queue.taskcluster.net/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:15:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 20:15:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 20:15:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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 20:15:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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 20:15:52 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 20:15:52 INFO - retry: attempt #1 caught exception: timed out 20:15:52 INFO - retry: Failed, sleeping 30 seconds before retrying 20:16:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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 #2 20:16:43 INFO - Downloaded 1199 bytes. 20:16:43 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 20:16:43 INFO - Using the following test package requirements: 20:16:43 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 20:16:43 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:16:43 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 20:16:43 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:16:43 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 20:16:43 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:16:43 INFO - u'jsshell-mac.zip'], 20:16:43 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:16:43 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 20:16:43 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 20:16:43 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:16:43 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 20:16:43 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:16:43 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 20:16:43 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:16:43 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 20:16:43 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:16:43 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 20:16:43 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:16:43 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')]} 20:16:43 INFO - https://queue.taskcluster.net/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:16:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:16:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:16:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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 20:16:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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 20:16:45 INFO - Downloaded 18425540 bytes. 20:16:45 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'] 20:16:45 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 20:16:48 INFO - Return code: 0 20:16:48 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 20:16:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:16:48 INFO - https://queue.taskcluster.net/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:16:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:16:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:16:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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 20:16:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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 20:16:50 INFO - Downloaded 18425540 bytes. 20:16:50 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'] 20:16:50 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 20:16:54 INFO - Return code: 0 20:16: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')]} 20:16:54 INFO - https://queue.taskcluster.net/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:16:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 20:16:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 20:16:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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 20:16:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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 20:16:55 INFO - Downloaded 10840145 bytes. 20:16:55 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'] 20:16:55 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 20:16:55 INFO - Return code: 0 20:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:16:55 INFO - https://queue.taskcluster.net/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 20:16:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:16:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:16:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 20:16:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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 20:16:58 INFO - Downloaded 114144470 bytes. 20:16:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:16:58 INFO - mkdir: /builds/slave/test/properties 20:16:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:16:58 INFO - Writing to file /builds/slave/test/properties/build_url 20:16:58 INFO - Contents: 20:16:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:17:00 INFO - Running post-action listener: _resource_record_post_action 20:17:00 INFO - Running post-action listener: set_extra_try_arguments 20:17:00 INFO - ##### 20:17:00 INFO - ##### Running populate-webroot step. 20:17:00 INFO - ##### 20:17:00 INFO - Running pre-action listener: _resource_record_pre_action 20:17:00 INFO - Running main action method: populate_webroot 20:17:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:17:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:17:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:17:00 INFO - '--tppagecycles', 20:17:00 INFO - '7'], 20:17:00 INFO - 'tests': ['tsvgm']}}, 20:17:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:17:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:17:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:17:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:17:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:17:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:17:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:17:00 INFO - 'talos_options': ['--disable-e10s'], 20:17:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:17:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:17:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:17:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:17:00 INFO - 'talos_options': ['--disable-e10s'], 20:17:00 INFO - 'tests': ['damp', 'tps']}, 20:17:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:17:00 INFO - 'tests': ['damp', 'tps']}, 20:17:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:17:00 INFO - 'tests': ['dromaeo_dom']}, 20:17:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:17:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:17:00 INFO - 'tests': ['a11yr', 20:17:00 INFO - 'ts_paint', 20:17:00 INFO - 'tpaint', 20:17:00 INFO - 'sessionrestore', 20:17:00 INFO - 'sessionrestore_no_auto_restore', 20:17:00 INFO - 'tabpaint']}, 20:17:00 INFO - 'other-e10s': {'tests': ['a11yr', 20:17:00 INFO - 'ts_paint', 20:17:00 INFO - 'tpaint', 20:17:00 INFO - 'sessionrestore', 20:17:00 INFO - 'sessionrestore_no_auto_restore', 20:17:00 INFO - 'tabpaint']}, 20:17:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:17:00 INFO - 'ts_paint', 20:17:00 INFO - 'tpaint', 20:17:00 INFO - 'sessionrestore', 20:17:00 INFO - 'sessionrestore_no_auto_restore', 20:17:00 INFO - 'tabpaint']}, 20:17:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:17:00 INFO - 'ts_paint', 20:17:00 INFO - 'tpaint', 20:17:00 INFO - 'sessionrestore', 20:17:00 INFO - 'sessionrestore_no_auto_restore', 20:17:00 INFO - 'tabpaint']}, 20:17:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:17:00 INFO - 'tests': ['a11yr', 20:17:00 INFO - 'ts_paint', 20:17:00 INFO - 'tpaint', 20:17:00 INFO - 'sessionrestore', 20:17:00 INFO - 'sessionrestore_no_auto_restore', 20:17:00 INFO - 'tabpaint']}, 20:17:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:17:00 INFO - 'tests': ['a11yr', 20:17:00 INFO - 'ts_paint', 20:17:00 INFO - 'tpaint', 20:17:00 INFO - 'sessionrestore', 20:17:00 INFO - 'sessionrestore_no_auto_restore', 20:17:00 INFO - 'tabpaint']}, 20:17:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:17:00 INFO - 'tests': ['tsvgx', 20:17:00 INFO - 'tsvgr_opacity', 20:17:00 INFO - 'tart', 20:17:00 INFO - 'tscrollx', 20:17:00 INFO - 'cart']}, 20:17:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:17:00 INFO - 'tsvgr_opacity', 20:17:00 INFO - 'tart', 20:17:00 INFO - 'tscrollx', 20:17:00 INFO - 'cart']}, 20:17:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:17:00 INFO - 'talos_options': ['--disable-e10s'], 20:17:00 INFO - 'tests': ['tp5o']}, 20:17:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:17:00 INFO - 'tests': ['tp5o']}, 20:17:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:17:00 INFO - 'talos_options': ['--disable-e10s', 20:17:00 INFO - '--xperf_path', 20:17:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:17:00 INFO - 'tests': ['tp5n']}, 20:17:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:17:00 INFO - 'talos_options': ['--xperf_path', 20:17:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:17:00 INFO - 'tests': ['tp5n']}}, 20:17:00 INFO - 'talos.zip': {'path': '', 20:17:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:17:00 INFO - Running post-action listener: _resource_record_post_action 20:17:00 INFO - ##### 20:17:00 INFO - ##### Running create-virtualenv step. 20:17:00 INFO - ##### 20:17:00 INFO - Running pre-action listener: _resource_record_pre_action 20:17:00 INFO - Running main action method: create_virtualenv 20:17:00 INFO - Creating virtualenv /builds/slave/test/build/venv 20:17:00 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 20:17:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:17:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:17:00 INFO - Using real prefix '/tools/python27' 20:17:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:17:01 INFO - Installing distribute.............................................................................................................................................................................................done. 20:17:04 INFO - Installing pip.................done. 20:17:04 INFO - Return code: 0 20:17:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:17:04 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')]} 20:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:04 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')]} 20:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:04 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 0x108af3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb96b4911f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108992e00>, '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 0x1087fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10898db20>, '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.TUUrEJbQt9/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.xamfJhk0Og/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:17:04 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 20:17:04 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 20:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TUUrEJbQt9/Render', 20:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:04 INFO - 'HOME': '/Users/cltbld', 20:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:04 INFO - 'LOGNAME': 'cltbld', 20:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:04 INFO - 'MOZ_NO_REMOTE': '1', 20:17:04 INFO - 'NO_EM_RESTART': '1', 20:17:04 INFO - 'PAGER': '/bin/cat', 20:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:04 INFO - 'PWD': '/builds/slave/test', 20:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:04 INFO - 'SHELL': '/bin/bash', 20:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xamfJhk0Og/Listeners', 20:17:04 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:04 INFO - 'USER': 'cltbld', 20:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:04 INFO - 'XPC_FLAGS': '0x0', 20:17:04 INFO - 'XPC_SERVICE_NAME': '0', 20:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:04 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 20:17:04 INFO - Cleaning up... 20:17:04 INFO - Return code: 0 20:17:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:17:04 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')]} 20:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:04 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')]} 20:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:04 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 0x108af3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb96b4911f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108992e00>, '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 0x1087fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10898db20>, '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.TUUrEJbQt9/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.xamfJhk0Og/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:17:04 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 20:17:04 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 20:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TUUrEJbQt9/Render', 20:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:04 INFO - 'HOME': '/Users/cltbld', 20:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:04 INFO - 'LOGNAME': 'cltbld', 20:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:04 INFO - 'MOZ_NO_REMOTE': '1', 20:17:04 INFO - 'NO_EM_RESTART': '1', 20:17:04 INFO - 'PAGER': '/bin/cat', 20:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:04 INFO - 'PWD': '/builds/slave/test', 20:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:04 INFO - 'SHELL': '/bin/bash', 20:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xamfJhk0Og/Listeners', 20:17:04 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:04 INFO - 'USER': 'cltbld', 20:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:04 INFO - 'XPC_FLAGS': '0x0', 20:17:04 INFO - 'XPC_SERVICE_NAME': '0', 20:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:04 INFO - Downloading/unpacking psutil>=0.7.1 20:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:17:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:17:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:17:07 INFO - Installing collected packages: psutil 20:17:07 INFO - Running setup.py install for psutil 20:17:07 INFO - building 'psutil._psutil_osx' extension 20:17:07 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 20:17:08 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 20:17:08 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 20:17:08 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 20:17:08 INFO - building 'psutil._psutil_posix' extension 20:17:08 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 20:17:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:17:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:17:08 INFO - ^ 20:17:08 INFO - 1 warning generated. 20:17:08 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 20:17:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:17:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:17:08 INFO - Successfully installed psutil 20:17:08 INFO - Cleaning up... 20:17:08 INFO - Return code: 0 20:17:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:08 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 0x108af3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb96b4911f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108992e00>, '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 0x1087fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10898db20>, '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.TUUrEJbQt9/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.xamfJhk0Og/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:17:08 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 20:17:08 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 20:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TUUrEJbQt9/Render', 20:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:08 INFO - 'HOME': '/Users/cltbld', 20:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:08 INFO - 'LOGNAME': 'cltbld', 20:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:08 INFO - 'MOZ_NO_REMOTE': '1', 20:17:08 INFO - 'NO_EM_RESTART': '1', 20:17:08 INFO - 'PAGER': '/bin/cat', 20:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:08 INFO - 'PWD': '/builds/slave/test', 20:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:08 INFO - 'SHELL': '/bin/bash', 20:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xamfJhk0Og/Listeners', 20:17:08 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:08 INFO - 'USER': 'cltbld', 20:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:08 INFO - 'XPC_FLAGS': '0x0', 20:17:08 INFO - 'XPC_SERVICE_NAME': '0', 20:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:17:09 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 20:17:09 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 20:17:09 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 20:17:09 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 20:17:09 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 20:17:09 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 20:17:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:17:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:17:11 INFO - Installing collected packages: mozsystemmonitor 20:17:11 INFO - Running setup.py install for mozsystemmonitor 20:17:11 INFO - Successfully installed mozsystemmonitor 20:17:11 INFO - Cleaning up... 20:17:11 INFO - Return code: 0 20:17:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:11 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 0x108af3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb96b4911f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108992e00>, '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 0x1087fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10898db20>, '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.TUUrEJbQt9/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.xamfJhk0Og/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:17:11 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 20:17:11 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 20:17:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TUUrEJbQt9/Render', 20:17:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:11 INFO - 'HOME': '/Users/cltbld', 20:17:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:11 INFO - 'LOGNAME': 'cltbld', 20:17:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:11 INFO - 'MOZ_NO_REMOTE': '1', 20:17:11 INFO - 'NO_EM_RESTART': '1', 20:17:11 INFO - 'PAGER': '/bin/cat', 20:17:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:11 INFO - 'PWD': '/builds/slave/test', 20:17:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:11 INFO - 'SHELL': '/bin/bash', 20:17:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xamfJhk0Og/Listeners', 20:17:11 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:17:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:11 INFO - 'USER': 'cltbld', 20:17:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:11 INFO - 'XPC_FLAGS': '0x0', 20:17:11 INFO - 'XPC_SERVICE_NAME': '0', 20:17:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:12 INFO - Downloading/unpacking blobuploader==1.2.4 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17:14 INFO - Downloading blobuploader-1.2.4.tar.gz 20:17:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:17:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:17:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17: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 20:17:15 INFO - Downloading docopt-0.6.1.tar.gz 20:17:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:17:15 INFO - Installing collected packages: blobuploader, requests, docopt 20:17:15 INFO - Running setup.py install for blobuploader 20:17:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:17:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:17:16 INFO - Running setup.py install for requests 20:17:16 INFO - Running setup.py install for docopt 20:17:16 INFO - Successfully installed blobuploader requests docopt 20:17:16 INFO - Cleaning up... 20:17:16 INFO - Return code: 0 20:17:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:16 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 0x108af3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb96b4911f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108992e00>, '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 0x1087fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10898db20>, '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.TUUrEJbQt9/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.xamfJhk0Og/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:17:16 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 20:17:16 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 20:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TUUrEJbQt9/Render', 20:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:16 INFO - 'HOME': '/Users/cltbld', 20:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:16 INFO - 'LOGNAME': 'cltbld', 20:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:16 INFO - 'MOZ_NO_REMOTE': '1', 20:17:16 INFO - 'NO_EM_RESTART': '1', 20:17:16 INFO - 'PAGER': '/bin/cat', 20:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:16 INFO - 'PWD': '/builds/slave/test', 20:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:16 INFO - 'SHELL': '/bin/bash', 20:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xamfJhk0Og/Listeners', 20:17:16 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:16 INFO - 'USER': 'cltbld', 20:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:16 INFO - 'XPC_FLAGS': '0x0', 20:17:16 INFO - 'XPC_SERVICE_NAME': '0', 20:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:16 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-dEggUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-i5Eenn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-jUaBl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-faFso9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-X8M1t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-CAsz0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-jYUmkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-I2MkGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-O3wRo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-2Umh6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:18 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-WxPgxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:18 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-LBqSoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:18 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-sUUrx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:18 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Xtz4a3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:18 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-OVB4Oz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:18 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-w_1G4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:18 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-IbAILo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:17:18 INFO - Running setup.py install for manifestparser 20:17:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:17:18 INFO - Running setup.py install for mozcrash 20:17:19 INFO - Running setup.py install for mozdebug 20:17:19 INFO - Running setup.py install for mozdevice 20:17:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:17:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:17:19 INFO - Running setup.py install for mozfile 20:17:19 INFO - Running setup.py install for mozhttpd 20:17:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:17:19 INFO - Running setup.py install for mozinfo 20:17:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:17:19 INFO - Running setup.py install for mozInstall 20:17:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:17:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:17:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:17:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:17:19 INFO - Running setup.py install for mozleak 20:17:20 INFO - Running setup.py install for mozlog 20:17:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:17:20 INFO - Running setup.py install for moznetwork 20:17:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:17:20 INFO - Running setup.py install for mozprocess 20:17:20 INFO - Running setup.py install for mozprofile 20:17:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:17:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:17:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:17:20 INFO - Running setup.py install for mozrunner 20:17:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:17:20 INFO - Running setup.py install for mozscreenshot 20:17:20 INFO - Running setup.py install for moztest 20:17:21 INFO - Running setup.py install for mozversion 20:17:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:17:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:17:21 INFO - Cleaning up... 20:17:21 INFO - Return code: 0 20:17:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:21 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')]} 20:17:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:21 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')]} 20:17:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:21 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 0x108af3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb96b4911f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108992e00>, '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 0x1087fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10898db20>, '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.TUUrEJbQt9/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.xamfJhk0Og/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:17:21 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 20:17:21 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 20:17:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TUUrEJbQt9/Render', 20:17:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:21 INFO - 'HOME': '/Users/cltbld', 20:17:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:21 INFO - 'LOGNAME': 'cltbld', 20:17:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:21 INFO - 'MOZ_NO_REMOTE': '1', 20:17:21 INFO - 'NO_EM_RESTART': '1', 20:17:21 INFO - 'PAGER': '/bin/cat', 20:17:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:21 INFO - 'PWD': '/builds/slave/test', 20:17:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:21 INFO - 'SHELL': '/bin/bash', 20:17:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xamfJhk0Og/Listeners', 20:17:21 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:17:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:21 INFO - 'USER': 'cltbld', 20:17:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:21 INFO - 'XPC_FLAGS': '0x0', 20:17:21 INFO - 'XPC_SERVICE_NAME': '0', 20:17:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:21 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-GZb1Rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:21 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)) 20:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:21 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-6DnLjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:21 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)) 20:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:21 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-hRwgit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:21 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)) 20:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:21 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-AC70GD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:21 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)) 20:17:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:22 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Orq1Zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:22 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)) 20:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:22 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-5Sox1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:22 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)) 20:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:22 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-KpmqGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:22 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)) 20:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:22 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-AtPVsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:22 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)) 20:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:22 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-CyvwLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:22 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)) 20:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:22 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-VDCTvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:22 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)) 20:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:22 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-fa8zO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:22 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)) 20:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:22 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-4jspL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:22 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)) 20:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:22 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-5U3PwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:22 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)) 20:17:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:22 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-zxhtzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:23 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)) 20:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:23 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-McaYI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:23 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)) 20:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:23 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-NUAaxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:23 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)) 20:17:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:23 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-cWPxux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:23 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)) 20:17:23 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)) 20:17:23 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)) 20:17:23 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)) 20:17:23 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)) 20:17:23 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)) 20:17:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:25 INFO - Downloading blessings-1.6.tar.gz 20:17:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:17:25 INFO - Installing collected packages: blessings 20:17:25 INFO - Running setup.py install for blessings 20:17:26 INFO - Successfully installed blessings 20:17:26 INFO - Cleaning up... 20:17:26 INFO - Return code: 0 20:17:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:17:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:26 INFO - Reading from file tmpfile_stdout 20:17:26 INFO - Current package versions: 20:17:26 INFO - blessings == 1.6 20:17:26 INFO - blobuploader == 1.2.4 20:17:26 INFO - docopt == 0.6.1 20:17:26 INFO - manifestparser == 1.1 20:17:26 INFO - mozInstall == 1.12 20:17:26 INFO - mozcrash == 0.17 20:17:26 INFO - mozdebug == 0.1 20:17:26 INFO - mozdevice == 0.48 20:17:26 INFO - mozfile == 1.2 20:17:26 INFO - mozhttpd == 0.7 20:17:26 INFO - mozinfo == 0.9 20:17:26 INFO - mozleak == 0.1 20:17:26 INFO - mozlog == 3.1 20:17:26 INFO - moznetwork == 0.27 20:17:26 INFO - mozprocess == 0.22 20:17:26 INFO - mozprofile == 0.28 20:17:26 INFO - mozrunner == 6.11 20:17:26 INFO - mozscreenshot == 0.1 20:17:26 INFO - mozsystemmonitor == 0.0 20:17:26 INFO - moztest == 0.7 20:17:26 INFO - mozversion == 1.4 20:17:26 INFO - psutil == 3.1.1 20:17:26 INFO - requests == 1.2.3 20:17:26 INFO - wsgiref == 0.1.2 20:17:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:26 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 0x108af3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb96b4911f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108992e00>, '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 0x1087fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10898db20>, '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.TUUrEJbQt9/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.xamfJhk0Og/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:17:26 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 20:17:26 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 20:17:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TUUrEJbQt9/Render', 20:17:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:26 INFO - 'HOME': '/Users/cltbld', 20:17:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:26 INFO - 'LOGNAME': 'cltbld', 20:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:26 INFO - 'MOZ_NO_REMOTE': '1', 20:17:26 INFO - 'NO_EM_RESTART': '1', 20:17:26 INFO - 'PAGER': '/bin/cat', 20:17:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:26 INFO - 'PWD': '/builds/slave/test', 20:17:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:26 INFO - 'SHELL': '/bin/bash', 20:17:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xamfJhk0Og/Listeners', 20:17:26 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:17:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:26 INFO - 'USER': 'cltbld', 20:17:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:26 INFO - 'XPC_FLAGS': '0x0', 20:17:26 INFO - 'XPC_SERVICE_NAME': '0', 20:17:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:26 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)) 20:17:26 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)) 20:17:26 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)) 20:17:26 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)) 20:17:26 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)) 20:17:26 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)) 20:17:26 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)) 20:17:26 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)) 20:17:26 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)) 20:17:26 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)) 20:17:26 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)) 20:17:26 INFO - Cleaning up... 20:17:26 INFO - Return code: 0 20:17:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108af3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb96b4911f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108992e00>, '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 0x1087fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10898db20>, '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.TUUrEJbQt9/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.xamfJhk0Og/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:17:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TUUrEJbQt9/Render', 20:17:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:26 INFO - 'HOME': '/Users/cltbld', 20:17:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:26 INFO - 'LOGNAME': 'cltbld', 20:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:26 INFO - 'MOZ_NO_REMOTE': '1', 20:17:26 INFO - 'NO_EM_RESTART': '1', 20:17:26 INFO - 'PAGER': '/bin/cat', 20:17:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:26 INFO - 'PWD': '/builds/slave/test', 20:17:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:26 INFO - 'SHELL': '/bin/bash', 20:17:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xamfJhk0Og/Listeners', 20:17:26 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:17:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:26 INFO - 'USER': 'cltbld', 20:17:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:26 INFO - 'XPC_FLAGS': '0x0', 20:17:26 INFO - 'XPC_SERVICE_NAME': '0', 20:17:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:26 INFO - Downloading/unpacking jsonschema 20:17:26 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 20:17:26 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 20:17:26 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 20:17:26 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 20:17:26 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 20:17:26 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 20:17:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:17:29 INFO - Downloading/unpacking functools32 (from jsonschema) 20:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:29 INFO - Downloading functools32-3.2.3-2.tar.gz 20:17:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:17:29 INFO - warning: no files found matching '*.txt' 20:17:29 INFO - no previously-included directories found matching 'build' 20:17:29 INFO - no previously-included directories found matching 'dist' 20:17:29 INFO - no previously-included directories found matching '.git*' 20:17:29 INFO - Installing collected packages: jsonschema, functools32 20:17:29 INFO - Running setup.py install for functools32 20:17:29 INFO - warning: no files found matching '*.txt' 20:17:29 INFO - no previously-included directories found matching 'build' 20:17:29 INFO - no previously-included directories found matching 'dist' 20:17:29 INFO - no previously-included directories found matching '.git*' 20:17:30 INFO - Successfully installed jsonschema functools32 20:17:30 INFO - Cleaning up... 20:17:30 INFO - Return code: 0 20:17:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:30 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 0x108af3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb96b4911f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108992e00>, '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 0x1087fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10898db20>, '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.TUUrEJbQt9/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.xamfJhk0Og/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 20:17:30 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 20:17:30 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 20:17:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TUUrEJbQt9/Render', 20:17:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:30 INFO - 'HOME': '/Users/cltbld', 20:17:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:30 INFO - 'LOGNAME': 'cltbld', 20:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:30 INFO - 'MOZ_NO_REMOTE': '1', 20:17:30 INFO - 'NO_EM_RESTART': '1', 20:17:30 INFO - 'PAGER': '/bin/cat', 20:17:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:30 INFO - 'PWD': '/builds/slave/test', 20:17:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:30 INFO - 'SHELL': '/bin/bash', 20:17:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xamfJhk0Og/Listeners', 20:17:30 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:17:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:30 INFO - 'USER': 'cltbld', 20:17:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:30 INFO - 'XPC_FLAGS': '0x0', 20:17:30 INFO - 'XPC_SERVICE_NAME': '0', 20:17:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:30 INFO - Downloading/unpacking flake8 20:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:17:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:17:32 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 20:17:32 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 20:17:32 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 20:17:32 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 20:17:32 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 20:17:32 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 20:17:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:17:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:17:33 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 20:17:33 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 20:17:33 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 20:17:33 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 20:17:33 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 20:17:33 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 20:17:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:17:33 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 20:17:33 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 20:17:33 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 20:17:33 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 20:17:33 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 20:17:33 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 20:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:17:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:17:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:17:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:17:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:17:34 INFO - no previously-included directories found matching 'docs/_build' 20:17:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:17:34 INFO - Running setup.py install for pep8 20:17:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:17:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:17:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:17:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:17:34 INFO - no previously-included directories found matching 'docs/_build' 20:17:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:17:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:17:34 INFO - Cleaning up... 20:17:34 INFO - Return code: 0 20:17:34 INFO - Running post-action listener: _resource_record_post_action 20:17:34 INFO - Running post-action listener: _start_resource_monitoring 20:17:34 INFO - Starting resource monitoring. 20:17:34 INFO - ##### 20:17:34 INFO - ##### Running install step. 20:17:34 INFO - ##### 20:17:34 INFO - Running pre-action listener: _resource_record_pre_action 20:17:34 INFO - Running main action method: install 20:17:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:34 INFO - Reading from file tmpfile_stdout 20:17:34 INFO - Detecting whether we're running mozinstall >=1.0... 20:17:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:17:34 INFO - Reading from file tmpfile_stdout 20:17:34 INFO - Output received: 20:17:34 INFO - Usage: mozinstall [options] installer 20:17:34 INFO - Options: 20:17:34 INFO - -h, --help show this help message and exit 20:17:34 INFO - -d DEST, --destination=DEST 20:17:34 INFO - Directory to install application into. [default: 20:17:34 INFO - "/builds/slave/test"] 20:17:34 INFO - --app=APP Application being installed. [default: firefox] 20:17:34 INFO - mkdir: /builds/slave/test/build/application 20:17:34 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'] 20:17:34 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 20:18:07 INFO - Reading from file tmpfile_stdout 20:18:07 INFO - Output received: 20:18:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:18:07 INFO - Running post-action listener: _resource_record_post_action 20:18:07 INFO - ##### 20:18:07 INFO - ##### Running run-tests step. 20:18:07 INFO - ##### 20:18:07 INFO - Running pre-action listener: _resource_record_pre_action 20:18:07 INFO - Running main action method: run_tests 20:18:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:18:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:18:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:18:07 INFO - 2016-05-27 20:18:07.219 screenresolution[1656:10034] starting screenresolution argv=screenresolution get 20:18:07 INFO - 2016-05-27 20:18:07.222 screenresolution[1656:10034] Display 0: 1600x1200x32@60 20:18:07 INFO - 2016-05-27 20:18:07.228 screenresolution[1657:10037] starting screenresolution argv=screenresolution list 20:18:07 INFO - Available Modes on Display 0 20:18:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:18:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:18:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:18:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:18:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:18:07 INFO - Intel Iris: 20:18:07 INFO - Chipset Model: Intel Iris 20:18:07 INFO - Type: GPU 20:18:07 INFO - Bus: Built-In 20:18:07 INFO - VRAM (Dynamic, Max): 1536 MB 20:18:07 INFO - Vendor: Intel (0x8086) 20:18:07 INFO - Device ID: 0x0a2e 20:18:07 INFO - Revision ID: 0x0009 20:18:07 INFO - Displays: 20:18:07 INFO - Display: 20:18:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:18:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:18:07 INFO - Display Serial Number: 1600x1200 60 20:18:07 INFO - Main Display: Yes 20:18:07 INFO - Mirror: Off 20:18:07 INFO - Online: Yes 20:18:07 INFO - Rotation: Supported 20:18:07 INFO - Return code: 0 20:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:18:07 INFO - Python 2.7.3 20:18:07 INFO - Return code: 0 20:18:07 INFO - grabbing minidump binary from tooltool 20:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:18:07 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 0x108992e00>, '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 0x1087fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10898db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:18:07 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 20:18:07 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 20:18:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:18:07 INFO - Return code: 0 20:18:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:18:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:18:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:18:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:18:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:18:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:18:08 INFO - Return code: 0 20:18:08 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/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--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 20:18:08 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/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0009 --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 20:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TUUrEJbQt9/Render', 20:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:18:08 INFO - 'HOME': '/Users/cltbld', 20:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:18:08 INFO - 'LOGNAME': 'cltbld', 20:18:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:18:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:08 INFO - 'MOZ_NO_REMOTE': '1', 20:18:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:18:08 INFO - 'NO_EM_RESTART': '1', 20:18:08 INFO - 'PAGER': '/bin/cat', 20:18:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:08 INFO - 'PWD': '/builds/slave/test', 20:18:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:18:08 INFO - 'SHELL': '/bin/bash', 20:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xamfJhk0Og/Listeners', 20:18:08 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 20:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:18:08 INFO - 'USER': 'cltbld', 20:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:08 INFO - 'XPC_FLAGS': '0x0', 20:18:08 INFO - 'XPC_SERVICE_NAME': '0', 20:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:18:08 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/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--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 20:18:08 INFO - mozversion application_buildid: 20160527191736 20:18:08 INFO - mozversion application_changeset: 138121c338c75865135a001fc51f7465e36f4740 20:18:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:18:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:18:08 INFO - mozversion application_name: Firefox 20:18:08 INFO - mozversion application_remotingname: firefox-dev 20:18:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:18:08 INFO - mozversion application_vendor: Mozilla 20:18:08 INFO - mozversion application_version: 48.0a2 20:18:08 INFO - mozversion platform_buildid: 20160527191736 20:18:08 INFO - mozversion platform_changeset: 138121c338c75865135a001fc51f7465e36f4740 20:18:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:18:08 INFO - mozversion platform_version: 48.0a2 20:18:08 INFO - using testdate: 1464405488 20:18:08 INFO - actual date: 1464405488 20:18:08 INFO - starting webserver on 'localhost:49276' 20:18:08 INFO - SUITE-START | Running 2 tests 20:18:08 INFO - TEST-START | dromaeo_css 20:18:08 INFO - operating with platform_type : mac_ 20:18:08 INFO - Initialising browser for dromaeo_css test... 20:18:08 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmp4c1Ykc/profile http://localhost:49276/getInfo.html) 20:18:11 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 20:18:11 INFO - PROCESS | 1675 | colorDepth:24 20:18:11 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 20:18:11 INFO - PROCESS | 1675 | __metrics 20:18:11 INFO - PROCESS | 1675 | 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" 20:18:11 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:18:12 INFO - TEST-INFO | 1675: exit 0 20:18:12 INFO - Browser initialized. 20:18:12 INFO - Running cycle 1/1 for dromaeo_css test... 20:18:12 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmp4c1Ykc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:18:23 INFO - PROCESS | 1678 | RSS: Main: 211406848 20:18:23 INFO - PROCESS | 1678 | 20:19:15 INFO - PROCESS | 1678 | 1464405555223 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] 20:21:25 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 20:21:27 INFO - PROCESS | 1678 | RSS: Main: 352354304 20:21:27 INFO - PROCESS | 1678 | 20:24:29 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 20:24:30 INFO - PROCESS | 1678 | RSS: Main: 359542784 20:24:30 INFO - PROCESS | 1678 | 20:27:32 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 20:27:33 INFO - PROCESS | 1678 | RSS: Main: 362336256 20:27:33 INFO - PROCESS | 1678 | 20:30:35 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 20:30:36 INFO - PROCESS | 1678 | RSS: Main: 360841216 20:30:36 INFO - PROCESS | 1678 | 20:33:39 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 20:33:40 INFO - PROCESS | 1678 | RSS: Main: 329203712 20:33:40 INFO - PROCESS | 1678 | 20:36:42 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 20:36:42 INFO - PROCESS | 1678 | RSS: Main: 376451072 20:36:42 INFO - PROCESS | 1678 | 20:36:42 INFO - PROCESS | 1678 | __start_tp_report 20:36:42 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 20:36:42 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 20:36:42 INFO - PROCESS | 1678 | |i|pagename|runs| 20:36:42 INFO - PROCESS | 1678 | |0;dojo.html;2859.885604575817;3389.661033896612;3390.677885600867;3410.7782994105364;3457.464093065574;5512.862178445541;5583.776648934037;5585.832425027245;5597.888042239153;5599.888002239953;5554.611177217596;5618.438156184379;5623.634463759863;5624.690642014688;5680.715964201794;5001.049800537886;5049.924251136245;5094.1085310070775;5150.922736158961;5195.844124676266;5078.898422031557;5159;5160.4323524412475;5164.974175129138;5168.22476628505;5088.058709138802;5111.974440127794;5138;5142.717150556718;5153.433122356538;5120.61595380348;5136.794528218867;5150.768215430315;5163;5190.4550022247495;4955.430125535573;5017.824376146854;5062.898742025176;5065.924011139836;5103.362079740033;5458.699771512565;5468.398476167618;5507.697076660782;5516.979358818631;5526.696031718254;6129.785457508966;6139.017757158877;6146.231721034871;6149.385061493859;6159.722812473449;5449.291592093042;6007.609505382136;6090.939090609126;6147.2930612979635;6242.875142497124;5722.37053924068;5723.8569035774435;5751.051076572402;5755.482006619424;5793.073108302693;6308.148399965946;6335.3981371769605;6354.364563543609;6372.139761132281;6375.585586936834;5835.824925252249;5915.3788852170765;5927.2590926134235;6058.515318774487;6070.696465176724;6188.381161883775;6188.907166392508;6193.84515387119;6202.968985155046;6221.844453888599;6092.68232078519;6094.451499365078;6099.634021958696;6108.08378743192;6113.833133127588;5929.940700592939;5968.074948383008;6108.77496446079;6125.510168486495;6137.560853825989;4814.735189564608;4853.878653033702;4855.053264613366;4856.8785780355065;4871;4357.172137293904;4373.606375426227;4382.2112019836095;4397.252467080604;4409.250427427345;5445.292112025411;5524.58565607573;5576.581756368289;5594.41258667834;5601;6127.80043271483;6208.441240288395;6213.782517611862;6217.191765070512;6251.812445626639;5567.359753628303;5568.805091821848;5572.5263352614675;5578.944210557843;5602.38373778884;5219.321488206509;5292.311999440049;5299.6025298102795;5301;5335.253064570963;6288.52836037299;6339.4611458025665;6344.524160687874;6348.999999999907;6370.872582548416;6255.812325630239;6289.119523266838;6299.905501417574;6324.30432652417;6337.397947195009;6242.968785156045;6318.27339855904;6349.238091429058;6351.364863513611;6365.395287447685;5248.396434409939;5996.70016499182;6127.601705889103;6130.785422510191;6137.41694539018;5136.691798492103;6199.597026193283;6212.627242365473;6216.906746398718;6219.160413344322;5088.567471764942;5319.521243088141;5891.1752354669525;5896.968030594715;6022.307434645072;5807.070868660994;5880.323762767336;5883.117532370179;5921.3190483094995;6010.1585777991995;5815.447532484407;5816.47651711348;5822.592418530662;5837.766489340379;5861.0915308127305;5791.8552036199435;5904.3210030847;5949.881002379841;5955.166276721176;5995.490383317292;4925.408950925912;5572.498475137257;5799.942000580109;5816.331121921034;5842.240508734008 20:36:42 INFO - PROCESS | 1678 | |1;ext.html;525.9631825772195;537.5323468582333;585.9853503662409;602.8124594548738;622.2874808344443;28178.718212817865;33772.4934125988;37723.43414848776;38862.611373886255;38888.22223555527;42631.573684263145;42736.572634273645;42755.572444275545;42780.716578502695;42823.78588107063;8582.313414926823;9511.048895110504;9692.757681057994;9693.127618514332;9698.563564639608;8683.261922736576;11567.710807229803;11743.177977541533;11748.706282342924;11757.294562326288;11463.541458341699;11549.422528873565;11552.942235288812;11559.53761849525;11568.074554035657;8690.565471726388;11482.598109066184;11553.364564948883;11575.594854180063;11651.76696466066;11387.259828111146;11509.712257193554;11518.712032199179;11520.76958460826;11523.942380288128;38278.808605956794;38723.41914871279;38783;38862.611373886466;38885.80557097196;3213.630432500269;3272.4109660261142;3380.4422270325367;3432.9656703433147;3537.8584856605703;27695.723042769518;28581;28695.13914582566;29135.41729165426;29487.70512294871;2708.9729102708916;2711.8372897626205;2751.2159034675024;2795.4688609164195;2843.4739573179027;348572;352477;353158;353288;353407;36423.63576364255;36834.447483287775;36931.81534092312;60689.393106069256;61466.38533614696;35327.823360883034;35440.82279588637;35671.64328356683;35710.46434303487;35746;15608.609784755245;15818.60453488672;15820.76268855968;15835.129067901376;15903.363865445484;12344.506219751289;12512.436940337706;13187.670308242179;13348.065635405428;13489.392977316043;7277.017602623578;7727.343175830118;8011.359091272684;8030.957433528898;8085.070216925129;2942.955855662167;3349.179451034512;3369.359821633882;3370.1406141433777;3411.795292282471;822.4653974916232;1015.6445244164483;1022.8772547294373;1070.2080460459204;1073.8885253762476;28232.435351292857;28366;28726.038796346802;29027.564586531218;29145.27136821554;949.924006079512;1124.3029321820525;1128.1707944660668;1230.7600017996583;1259.5150866916304;15022.849771502364;15171.468998584996;15176.772348414783;15409;15519.37922483088;593757;601984;602613;605317;605536;62029.37970620236;62521.68739156275;62526.37473625205;62542.37457625366;62555.6872215636;1189.5301355964345;1477.7340078785921;1502.737021021295;1524.6569521857493;1533;28963.42073158483;29455;29601.25996850096;29883.402331953675;30285.545716813824;53054.204186936455;53779.19331210113;53904.921901562535;53935;54026.459735402146;492157;505785;505909.4704526454;505962;507607;24190.153344632854;27248.182554523395;27448.725512744615;27669;28068.29829254285;45595.77202113968;45675.54324456713;45692;45820.083598328514;47265.52734472611;31003.68996310008;36086.27827443489;36809.07977300589;37647;37701.24597508089;36485.817570911975;37109;37148.44277335788;37153.442698360086;37917.431238530895;36588;37316.626833731316;38235.044123897125;38296.2340753189;38320;27485.450290993667;32216.35567288688;32761.344773103927;32889.684412623224;32918.341633166725;24813;24864.756762162182;25040.49919001646;25380.873095634404;25631.23106306814 20:36:42 INFO - PROCESS | 1678 | |2;jquery.html;400.1816285695752;400.8476778824047;406.0842799487156;406.2849385082254;417.06161137440756;13988.230647314394;16004.919975400122;16667.499975000737;19979;20436.284730034433;16031.83968160318;16902.49292521223;20007.899960500214;20507.487312817186;20513;5230.9738451307785;5539.446055394468;5540.750666220029;5552.639078459907;5572.498475137237;4036.515618125829;5460.044492213867;5539;5539.224508568804;5540.2243685884005;5901.763929442838;6164.352742961992;6172.105044768506;6189.659568723719;6206.565540412173;6153.507719382439;6158.7228574714245;6158.768246350725;6284.497240220771;6722.66386680664;5895.64626122434;6033.637981721067;6067.484263837579;6073.939260607382;6074.453299203049;13236.669083272995;14319.713605727931;24973.876175572143;25417.110401135873;25698.87150564254;2103.5372218111993;2126.319577735125;2188.7592364839857;2361.8700971446565;2787.6236708044357;9745.90254097457;9826.90173098267;9978.251631127694;10174.982501749766;10216.591336346537;1701.6086300150912;1762.5769815244248;1823.9968017590272;1884.3781552087914;1910.0927059646694;109538.45230773874;112718.43640781744;115343.26985095315;115981;116559;11578.536858525733;11795.52817887292;11829.467673954692;11831.349275789915;11838.171328007129;15069.397224111131;15099.924500377647;15133.243337833064;15140.864435167407;15147.394104235927;6457.192850893638;7682.961585192038;8752.474851508803;8908.73273801787;8998.820023599492;7476.028116344949;8193.79515512105;8406.747797566082;9079.636814527477;9219.677311294072;4720.079584481062;4741.620670346364;4743.051389722069;4752.429708434941;4770.0936822003705;1710.811810700822;1944.397236856579;1994.9800501995085;2004.679251319811;2040.8775473471342;917.4357769971552;1013.6705570689555;1030.577463240068;1033.0495943731635;1039.3763741755038;20394.796052039586;20532;20842.895785520974;20902.268420605207;20921.581568368852;971.2278738402987;980.7695191629962;1016.2581315639625;1049.3441599000626;1051.279873286801;10735.51690173944;11196.272242304223;11201.327920324806;11233.798367465233;11234.606788762354;569520.304796955;574617;582768.0861595665;585910;591397;112865;113730;117237.41381293039;118740.40629796969;119098.8090119105;1050.7635781949;1053.3258714422775;1064.1912146768511;1067.7277294289906;1068.1614932278123;17883.105844707454;17915.73126403131;18171;18371;18600.34898778536;40572.79713601413;50832.237516436544;52814.88740450339;53719.38841834751;54587;52043.73978130085;53308.73345633247;68602.31397685959;70483.9427408579;73050;6362.840928976812;8137.593120344078;8268.214519620626;8299.419040667095;8323.292520135856;8997.82004359896;12312;12390.938045309716;12656.6835829105;12701.4284357206;8415.368847336596;10763.085137763064;11099.778004439704;11321.603743868925;11336.75295717487;10755.677329680122;10827;10893.237473376788;11001.559937602726;11168.720781980515;11100.500477478694;11155.832662509907;11418.371989540656;11477.885221147682;11516;6464.288928217987;8043.436959413019;8073.798155046171;8191.467554608931;8389.244967952913;8301.335893128657;9198.126178013077;10616.89383106159;11261.21171517986;11293.096552275634 20:36:42 INFO - PROCESS | 1678 | |3;mootools.html;1411.1603595860458;1529.9464518741845;1559.5867095219762;1816.900070496123;1989.5424052467931;7901.841963160733;8595.484270943747;8744;8749.650013999433;8757.737267881957;8472.449290796094;8696.34777391695;8709.520976346299;8718.258947989429;8741.519216443094;2134.7118139051186;2386.4153282445736;2430.173740928084;2450.926472205828;2451.4974430241846;2138.1661152150673;2324.1052194904914;2464.2730394533655;2467.284487498623;2467.7902378297867;2360.1503458754833;2408.0728919365997;2428.4171798768257;2431.2706188143575;2432.914847980321;2133.5946170227685;2377.8811059446957;2430.149447693311;2431.5501632197925;2435.1233555919853;2408.915687950931;2443.1937460637946;2446.657467954488;2446.9845014319035;2447.021191523387;8597.441166324168;8665.653373865038;8675.69635062776;8680.131986801332;8680.262177714902;5095.592352611782;5316.12283973144;5345.625806193568;5351.973240133774;5353;3470.9305813883834;3500.457429098494;3516.1561225306;3563.964360356389;3567.1438854674952;965.54619328916;984.4929861121528;1018.1396719771781;1038.68839348196;1054.752133248686;11939.343336116597;11969.94015029928;11988.940055299754;11989.721952828935;11990.100742444352;4046.4537287465814;4778.1399348116975;4850.95149048505;4872.049950259736;4876.268559716;10035.197184225244;10142.695719128438;10160.33957792741;10162.085412312927;10175.542100605491;5485.451454854562;5495.120780675153;5499.615026948155;5506.559475241971;5508.55931525477;4308.978455107705;4455.22033644111;4781.378420805273;4884.804607815648;4904.509549045139;3478.982605087009;4000.2199571083943;4008.6993475489453;4070.75575465473;4117.691173162025;1336.8863646589955;1367.2753440676458;1373.6497193215666;1380.7100508893045;1383.0733408616252;811.1604489353608;823.279630323467;867.6789587852535;909.1999040844147;917.454114801692;5509.449055094458;5528.612997090165;5574.804881829116;5716.999525083093;5894.616849904828;447.2642503082476;454.9567791059844;466.5591016489453;467.9719216847;470.62114997426715;3730.142067324476;3803.2773772983046;3847.0959324558708;3856.537215534154;3860.9034774130873;10225.846612300822;10532;10665.986731260518;10667.73330666724;10679.199060070527;10094.09153176203;10520.684379468628;10522.526486308134;10597.947010265054;10644.467776611138;8183.427160098736;8266.297364724065;8338.624761885609;8358.03882553514;8367.205115513894;5427.755750991136;5457.181422786613;5465.426130256425;5563.58273129525;5662.377138514842;9027.368084234304;9288.256939444964;9318.301127415336;9417.48203848795;9444.905550944677;9204.987451380477;9377.578008989476;9378.202852757318;9522;9558.522073896416;6715.294894036056;6876.965615172092;7188.281171882979;7312.817179570553;7450.403967682681;8093.028836539653;8101.149379315323;8160.714374996846;8168.591570421574;8172.754817355489;7112.395446387115;7223.097112860893;7232.493725439169;7311.817204569928;7410.147832999059;7101.005859179616;7105.786826395217;7131.2155662876;7245.529040612343;7250.093738282715;6723.865522689421;6849.486288528281;6851.417629501549;6851.588904665736;6856.725730970706;4079.8899849572917;4699.953000469952;4700.835470758507;4779.5220477951925;4838.346823178826;4857.441394239708;4892.535209155125;4934.950650493593;4949.183384741477;5037.899242015066 20:36:42 INFO - PROCESS | 1678 | |4;prototype.html;314.89923224568145;316.77667244592567;327.23753653986205;329.8499182871794;332.72550146129436;6279.514869247577;6452.58058226215;6533.216014078312;6558.8688226235445;6564.967175164124;5255.86860328492;6106.938930610704;6369.936300636992;6416.9358306416925;6487.513436492271;4806.351142595758;4845.830395936143;4847.224444088955;4859.441164266119;4867.659263851532;4612.146752850716;4833.081714474256;4862.78117484714;4865.367502224706;4881.633877459187;4581.129585378784;4692.97653511732;4694.29585562165;4696.201645720235;4708.293755936603;3834.827432765518;4653.953460465385;4676.0647870426055;4701.224297990828;4709.92935105972;4064.6951478638925;4066.410370496291;4072.2059198456313;4075;4083.530394004668;6006.519478441714;6054.788082417138;6061.605995610271;6108.694565271763;6114.602550834182;5571.526420254283;5577.135543990688;5582.804601838957;5585.96659617972;5589.469000444951;5400.972995135038;6492.967535162341;6774.322567743236;6801.9594121763275;6835.282295359015;4401.273789824675;4436.866893993153;4437.755923424211;4442.444694413199;4444.2222611042935;7055.400290975222;7148;7295.607542492713;7303.45224108194;7321.26787321269;4482;4571.2000399929875;4571.885702857455;4574;4578.908421831545;4619.792109355087;4642.744649044335;4658.953410465852;4659.347691323217;4670.369500117441;5483.232347471358;5488.1493368527945;5493.670379777226;5497.560195184456;5504.587155963319;6184.041473571604;6373.553851230369;6943.652817359111;6946.583205007716;6953.478489113337;1575.9369625214863;1598.5683865356289;1600.7438809790378;1600.8799340049543;1602.3670650093147;902.7111324376268;958.7219706285239;1009.530568285736;1027.5119318323736;1031.56674196836;6334.5565810393755;6340.904886426707;6397.488200943913;6403.48772098231;6408.166938297992;7149;7175.820604484825;7179.533330333512;7179.533330333512;7196.532225405332;6400.647964361911;6413.551051426449;6416.582922110141;6425.58233714807;6433.228012638419;2923.473774720571;3301.5377847101427;3640.199156185635;3766.472693822867;3830.71269654777;6309.9588567885785;7223.422126229889;7464.962675186589;7501.099868015764;7515.286047825448;7316.487845850739;7376.373008294355;7407.518511296857;7409.703611855573;7435.628218589157;7527.698892044366;7532.17146113927;7533.660985255787;7545.207753185946;7545.434092443089;6990.965045174945;7125.608091555015;7208.315210055036;7222.422206223595;7236.674349654383;5680.914786278292;7012.4390048796995;7049.436045116481;7063.682134304071;7107.822304442224;7446.925530744623;7447.81380465471;7471.290227428385;7479.401647868266;7495.737649182252;4593.28804035375;4598.770061496979;4603.838865639686;4605.286180642006;4606.332081848181;5756.884862302814;7125.714971401086;7134.714611415485;7143.785686429415;7144.67848946809;5609.775608975595;6725.19297684281;6938.791836244921;6940.514164008471;6960.303969602999;6831.795046148623;6912.135983002125;6921.307869213038;6973.58158510491;6995.895061574182;5868.735906884115;6446.580972236977;6795.660216989229;6806.65966701653;6810.514162330614;6704.363085506869;6955.8956615649795;6982.33667801558;6986.790396288119;6989.231184569795;6575.704093315908;6702.162229721285;6711.899321510084;6814.318568143146;6820.863582728219 20:36:42 INFO - PROCESS | 1678 | |5;yui.html;673.168636733634;737.3179808676972;748.5097261293851;755.1919446192572;760.7527553545092;14417.711645767078;14651.560453186408;14657.780133298009;14663.193524356157;14664.266786660652;15117.39530418782;15579.84420155798;15594.844051559481;15613.375464981387;15624.2969066392;736.5323019882386;745.3738859358139;904.7964208053185;913.0732306708696;922.2161163011453;913.6117150211134;914.7210100919217;917.4678686361895;917.7338571814166;917.807260475301;978.1441238915949;978.2907392140712;978.6966040527424;978.8678528398682;979.4955597867104;764.5718397697308;948.1087777489138;969.4813274897966;970.2238209432426;973.8831340239182;723.1105739939851;724.1997592660088;725.021221351177;725.5465334166146;726.5531698005741;14812.925935370362;14884.62788430287;14889.47886823956;14903;14908.18005009724;12029.398530073462;12101.515939362414;12222.022238220921;12306.200096993667;12376.07179461544;9986;10134.493275336205;10228.386296822215;10359.689209323733;10470.947645261802;3336.1492819330915;3339.716124129452;3398.6261511233743;3405.080628230389;3480.2343484433386;83869.58065209696;83891;84453.57773211095;84815;85147.5742621283;8851.982022067416;8928.062553431926;8940.642374304955;8990.370674052885;9271.953640231755;20664.690029649566;20696.068676909574;20715.27496537644;20730.689039664416;20796.168153274004;7214.67533960973;7358.300961408658;7435.25647435263;7457.121551081283;7546.698132074765;4073.735207211522;4084.0606660468256;4170.207660544487;4245.087306229176;4362.192994296065;3452.741044421628;3508.9298214035575;3514.525539052195;3517.7889326640484;3522.225110475691;600.83777380108;610.2402508876477;634.1407392982443;636.8821767972847;641.5252712992352;518.5436815602246;522.4253321346489;574.5719439017959;595.85401576614;608.4888693497395;8045.718399855977;8123.268905798388;8127.268545830903;8263.132371100948;8345.374096942754;531.2880739808675;558.6536347464598;574.4600075928614;581.0876923230572;607.3804719186405;3799.0692280390926;3811.27585758705;3847.0959324558708;3854.383298672199;3898.805059747058;67552;87113.1288687105;88415.55792221126;88780.5560972191;89256;72687.18282042886;73751.63124184452;74157.6292118536;74984;75332.24667753362;66484.66757666181;67216;68348;68627.65686171537;70007.29992700109;7562.848743025219;7682.769516914502;7723.420743444377;7736.806579835549;7774.339181169664;47844.76077619589;48607.27089093737;48749.53751387464;49072.75463622802;49704.01183952746;62235.6888215556;63160.73678526312;67382.66308668621;67807.66096169487;68744;9654;9933.056359645821;10080.798384032132;10125.34185277984;10129.392236465836;10576.629817956335;10689.679309620724;10722.356658600196;10948.178886583697;10968.835467468154;10354.378737275787;10534.315269507457;10553.788924221626;10625.149988000787;11876.228045177037;10895.073918716998;11114.944425277821;11148.108151348035;11313.377764223047;11579.652610421701;10854.240203185702;10960.28758130719;10984.890151098387;11124.888751112385;11972.700682483008;9120;9245.58394872219;9392.577334019848;9441.291903107433;9694;9416.293777966543;9504.382215155993;9519.904800951901;9587.760305992128;9640.662576809778 20:36:42 INFO - PROCESS | 1678 | __end_tp_report 20:36:42 INFO - PROCESS | 1678 | __start_cc_report 20:36:42 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1384 20:36:42 INFO - PROCESS | 1678 | __end_cc_report 20:36:42 INFO - PROCESS | 1678 | __startTimestamp1464406602818__endTimestamp 20:36:42 INFO - PROCESS | 1678 | 20:36:42 INFO - PROCESS | 1678 | ------- Summary: start ------- 20:36:42 INFO - PROCESS | 1678 | Number of tests: 6 20:36:42 INFO - PROCESS | 1678 | 20:36:42 INFO - PROCESS | 1678 | [#0] dojo.html Cycles:165 Average:5628.35 Median:5793.07 stddev:647.68 (11.2%) stddev-sans-first:612.18 20:36:42 INFO - PROCESS | 1678 | Values: 2859.9 3389.7 3390.7 3410.8 3457.5 5512.9 5583.8 5585.8 5597.9 5599.9 5554.6 5618.4 5623.6 5624.7 5680.7 5001.0 5049.9 5094.1 5150.9 5195.8 5078.9 5159.0 5160.4 5165.0 5168.2 5088.1 5112.0 5138.0 5142.7 5153.4 5120.6 5136.8 5150.8 5163.0 5190.5 4955.4 5017.8 5062.9 5065.9 5103.4 5458.7 5468.4 5507.7 5517.0 5526.7 6129.8 6139.0 6146.2 6149.4 6159.7 5449.3 6007.6 6090.9 6147.3 6242.9 5722.4 5723.9 5751.1 5755.5 5793.1 6308.1 6335.4 6354.4 6372.1 6375.6 5835.8 5915.4 5927.3 6058.5 6070.7 6188.4 6188.9 6193.8 6203.0 6221.8 6092.7 6094.5 6099.6 6108.1 6113.8 5929.9 5968.1 6108.8 6125.5 6137.6 4814.7 4853.9 4855.1 4856.9 4871.0 4357.2 4373.6 4382.2 4397.3 4409.3 5445.3 5524.6 5576.6 5594.4 5601.0 6127.8 6208.4 6213.8 6217.2 6251.8 5567.4 5568.8 5572.5 5578.9 5602.4 5219.3 5292.3 5299.6 5301.0 5335.3 6288.5 6339.5 6344.5 6349.0 6370.9 6255.8 6289.1 6299.9 6324.3 6337.4 6243.0 6318.3 6349.2 6351.4 6365.4 5248.4 5996.7 6127.6 6130.8 6137.4 5136.7 6199.6 6212.6 6216.9 6219.2 5088.6 5319.5 5891.2 5897.0 6022.3 5807.1 5880.3 5883.1 5921.3 6010.2 5815.4 5816.5 5822.6 5837.8 5861.1 5791.9 5904.3 5949.9 5955.2 5995.5 4925.4 5572.5 5799.9 5816.3 5842.2 20:36:42 INFO - PROCESS | 1678 | 20:36:42 INFO - PROCESS | 1678 | [#1] ext.html Cycles:180 Average:61672.69 Median:3396.12 stddev:132686.08 (3907.0%) stddev-sans-first:132978.88 20:36:42 INFO - PROCESS | 1678 | Values: 526.0 537.5 586.0 602.8 622.3 28178.7 33772.5 37723.4 38862.6 38888.2 42631.6 42736.6 42755.6 42780.7 42823.8 8582.3 9511.0 9692.8 9693.1 9698.6 8683.3 11567.7 11743.2 11748.7 11757.3 11463.5 11549.4 11552.9 11559.5 11568.1 8690.6 11482.6 11553.4 11575.6 11651.8 11387.3 11509.7 11518.7 11520.8 11523.9 38278.8 38723.4 38783.0 38862.6 38885.8 3213.6 3272.4 3380.4 3433.0 3537.9 27695.7 28581.0 28695.1 29135.4 29487.7 2709.0 2711.8 2751.2 2795.5 2843.5 348572.0 352477.0 353158.0 353288.0 353407.0 36423.6 36834.4 36931.8 60689.4 61466.4 35327.8 35440.8 35671.6 35710.5 35746.0 15608.6 15818.6 15820.8 15835.1 15903.4 12344.5 12512.4 13187.7 13348.1 13489.4 7277.0 7727.3 8011.4 8031.0 8085.1 2943.0 3349.2 3369.4 3370.1 3411.8 822.5 1015.6 1022.9 1070.2 1073.9 28232.4 28366.0 28726.0 29027.6 29145.3 949.9 1124.3 1128.2 1230.8 1259.5 15022.8 15171.5 15176.8 15409.0 15519.4 593757.0 601984.0 602613.0 605317.0 605536.0 62029.4 62521.7 62526.4 62542.4 62555.7 1189.5 1477.7 1502.7 1524.7 1533.0 28963.4 29455.0 29601.3 29883.4 30285.5 53054.2 53779.2 53904.9 53935.0 54026.5 492157.0 505785.0 505909.5 505962.0 507607.0 24190.2 27248.2 27448.7 27669.0 28068.3 45595.8 45675.5 45692.0 45820.1 47265.5 31003.7 36086.3 36809.1 37647.0 37701.2 36485.8 37109.0 37148.4 37153.4 37917.4 36588.0 37316.6 38235.0 38296.2 38320.0 27485.5 32216.4 32761.3 32889.7 32918.3 24813.0 24864.8 25040.5 25380.9 25631.2 20:36:42 INFO - PROCESS | 1678 | 20:36:42 INFO - PROCESS | 1678 | [#2] jquery.html Cycles:180 Average:33323.88 Median:20911.92 stddev:96627.61 (462.1%) stddev-sans-first:96867.05 20:36:42 INFO - PROCESS | 1678 | Values: 400.2 400.8 406.1 406.3 417.1 13988.2 16004.9 16667.5 19979.0 20436.3 16031.8 16902.5 20007.9 20507.5 20513.0 5231.0 5539.4 5540.8 5552.6 5572.5 4036.5 5460.0 5539.0 5539.2 5540.2 5901.8 6164.4 6172.1 6189.7 6206.6 6153.5 6158.7 6158.8 6284.5 6722.7 5895.6 6033.6 6067.5 6073.9 6074.5 13236.7 14319.7 24973.9 25417.1 25698.9 2103.5 2126.3 2188.8 2361.9 2787.6 9745.9 9826.9 9978.3 10175.0 10216.6 1701.6 1762.6 1824.0 1884.4 1910.1 109538.5 112718.4 115343.3 115981.0 116559.0 11578.5 11795.5 11829.5 11831.3 11838.2 15069.4 15099.9 15133.2 15140.9 15147.4 6457.2 7683.0 8752.5 8908.7 8998.8 7476.0 8193.8 8406.7 9079.6 9219.7 4720.1 4741.6 4743.1 4752.4 4770.1 1710.8 1944.4 1995.0 2004.7 2040.9 917.4 1013.7 1030.6 1033.0 1039.4 20394.8 20532.0 20842.9 20902.3 20921.6 971.2 980.8 1016.3 1049.3 1051.3 10735.5 11196.3 11201.3 11233.8 11234.6 569520.3 574617.0 582768.1 585910.0 591397.0 112865.0 113730.0 117237.4 118740.4 119098.8 1050.8 1053.3 1064.2 1067.7 1068.2 17883.1 17915.7 18171.0 18371.0 18600.3 40572.8 50832.2 52814.9 53719.4 54587.0 52043.7 53308.7 68602.3 70483.9 73050.0 6362.8 8137.6 8268.2 8299.4 8323.3 8997.8 12312.0 12390.9 12656.7 12701.4 8415.4 10763.1 11099.8 11321.6 11336.8 10755.7 10827.0 10893.2 11001.6 11168.7 11100.5 11155.8 11418.4 11477.9 11516.0 6464.3 8043.4 8073.8 8191.5 8389.2 8301.3 9198.1 10616.9 11261.2 11293.1 20:36:42 INFO - PROCESS | 1678 | 20:36:42 INFO - PROCESS | 1678 | [#3] mootools.html Cycles:180 Average:5610.79 Median:4874.16 stddev:3157.58 (64.8%) stddev-sans-first:3150.66 20:36:42 INFO - PROCESS | 1678 | Values: 1411.2 1529.9 1559.6 1816.9 1989.5 7901.8 8595.5 8744.0 8749.7 8757.7 8472.4 8696.3 8709.5 8718.3 8741.5 2134.7 2386.4 2430.2 2450.9 2451.5 2138.2 2324.1 2464.3 2467.3 2467.8 2360.2 2408.1 2428.4 2431.3 2432.9 2133.6 2377.9 2430.1 2431.6 2435.1 2408.9 2443.2 2446.7 2447.0 2447.0 8597.4 8665.7 8675.7 8680.1 8680.3 5095.6 5316.1 5345.6 5352.0 5353.0 3470.9 3500.5 3516.2 3564.0 3567.1 965.5 984.5 1018.1 1038.7 1054.8 11939.3 11969.9 11988.9 11989.7 11990.1 4046.5 4778.1 4851.0 4872.0 4876.3 10035.2 10142.7 10160.3 10162.1 10175.5 5485.5 5495.1 5499.6 5506.6 5508.6 4309.0 4455.2 4781.4 4884.8 4904.5 3479.0 4000.2 4008.7 4070.8 4117.7 1336.9 1367.3 1373.6 1380.7 1383.1 811.2 823.3 867.7 909.2 917.5 5509.4 5528.6 5574.8 5717.0 5894.6 447.3 455.0 466.6 468.0 470.6 3730.1 3803.3 3847.1 3856.5 3860.9 10225.8 10532.0 10666.0 10667.7 10679.2 10094.1 10520.7 10522.5 10597.9 10644.5 8183.4 8266.3 8338.6 8358.0 8367.2 5427.8 5457.2 5465.4 5563.6 5662.4 9027.4 9288.3 9318.3 9417.5 9444.9 9205.0 9377.6 9378.2 9522.0 9558.5 6715.3 6877.0 7188.3 7312.8 7450.4 8093.0 8101.1 8160.7 8168.6 8172.8 7112.4 7223.1 7232.5 7311.8 7410.1 7101.0 7105.8 7131.2 7245.5 7250.1 6723.9 6849.5 6851.4 6851.6 6856.7 4079.9 4700.0 4700.8 4779.5 4838.3 4857.4 4892.5 4935.0 4949.2 5037.9 20:36:42 INFO - PROCESS | 1678 | 20:36:42 INFO - PROCESS | 1678 | [#4] prototype.html Cycles:180 Average:5605.48 Median:6371.75 stddev:1803.22 (28.3%) stddev-sans-first:1764.02 20:36:42 INFO - PROCESS | 1678 | Values: 314.9 316.8 327.2 329.8 332.7 6279.5 6452.6 6533.2 6558.9 6565.0 5255.9 6106.9 6369.9 6416.9 6487.5 4806.4 4845.8 4847.2 4859.4 4867.7 4612.1 4833.1 4862.8 4865.4 4881.6 4581.1 4693.0 4694.3 4696.2 4708.3 3834.8 4654.0 4676.1 4701.2 4709.9 4064.7 4066.4 4072.2 4075.0 4083.5 6006.5 6054.8 6061.6 6108.7 6114.6 5571.5 5577.1 5582.8 5586.0 5589.5 5401.0 6493.0 6774.3 6802.0 6835.3 4401.3 4436.9 4437.8 4442.4 4444.2 7055.4 7148.0 7295.6 7303.5 7321.3 4482.0 4571.2 4571.9 4574.0 4578.9 4619.8 4642.7 4659.0 4659.3 4670.4 5483.2 5488.1 5493.7 5497.6 5504.6 6184.0 6373.6 6943.7 6946.6 6953.5 1575.9 1598.6 1600.7 1600.9 1602.4 902.7 958.7 1009.5 1027.5 1031.6 6334.6 6340.9 6397.5 6403.5 6408.2 7149.0 7175.8 7179.5 7179.5 7196.5 6400.6 6413.6 6416.6 6425.6 6433.2 2923.5 3301.5 3640.2 3766.5 3830.7 6310.0 7223.4 7465.0 7501.1 7515.3 7316.5 7376.4 7407.5 7409.7 7435.6 7527.7 7532.2 7533.7 7545.2 7545.4 6991.0 7125.6 7208.3 7222.4 7236.7 5680.9 7012.4 7049.4 7063.7 7107.8 7446.9 7447.8 7471.3 7479.4 7495.7 4593.3 4598.8 4603.8 4605.3 4606.3 5756.9 7125.7 7134.7 7143.8 7144.7 5609.8 6725.2 6938.8 6940.5 6960.3 6831.8 6912.1 6921.3 6973.6 6995.9 5868.7 6446.6 6795.7 6806.7 6810.5 6704.4 6955.9 6982.3 6986.8 6989.2 6575.7 6702.2 6711.9 6814.3 6820.9 20:36:42 INFO - PROCESS | 1678 | 20:36:42 INFO - PROCESS | 1678 | [#5] yui.html Cycles:180 Average:17798.35 Median:31897.44 stddev:24906.67 (78.1%) stddev-sans-first:24943.34 20:36:42 INFO - PROCESS | 1678 | Values: 673.2 737.3 748.5 755.2 760.8 14417.7 14651.6 14657.8 14663.2 14664.3 15117.4 15579.8 15594.8 15613.4 15624.3 736.5 745.4 904.8 913.1 922.2 913.6 914.7 917.5 917.7 917.8 978.1 978.3 978.7 978.9 979.5 764.6 948.1 969.5 970.2 973.9 723.1 724.2 725.0 725.5 726.6 14812.9 14884.6 14889.5 14903.0 14908.2 12029.4 12101.5 12222.0 12306.2 12376.1 9986.0 10134.5 10228.4 10359.7 10470.9 3336.1 3339.7 3398.6 3405.1 3480.2 83869.6 83891.0 84453.6 84815.0 85147.6 8852.0 8928.1 8940.6 8990.4 9272.0 20664.7 20696.1 20715.3 20730.7 20796.2 7214.7 7358.3 7435.3 7457.1 7546.7 4073.7 4084.1 4170.2 4245.1 4362.2 3452.7 3508.9 3514.5 3517.8 3522.2 600.8 610.2 634.1 636.9 641.5 518.5 522.4 574.6 595.9 608.5 8045.7 8123.3 8127.3 8263.1 8345.4 531.3 558.7 574.5 581.1 607.4 3799.1 3811.3 3847.1 3854.4 3898.8 67552.0 87113.1 88415.6 88780.6 89256.0 72687.2 73751.6 74157.6 74984.0 75332.2 66484.7 67216.0 68348.0 68627.7 70007.3 7562.8 7682.8 7723.4 7736.8 7774.3 47844.8 48607.3 48749.5 49072.8 49704.0 62235.7 63160.7 67382.7 67807.7 68744.0 9654.0 9933.1 10080.8 10125.3 10129.4 10576.6 10689.7 10722.4 10948.2 10968.8 10354.4 10534.3 10553.8 10625.1 11876.2 10895.1 11114.9 11148.1 11313.4 11579.7 10854.2 10960.3 10984.9 11124.9 11972.7 9120.0 9245.6 9392.6 9441.3 9694.0 9416.3 9504.4 9519.9 9587.8 9640.7 20:36:42 INFO - PROCESS | 1678 | -------- Summary: end -------- 20:36:42 INFO - PROCESS | 1678 | 20:36:43 INFO - TEST-INFO | 1678: exit 0 20:36:43 INFO - TEST-OK | dromaeo_css | took 1114469ms 20:36:43 INFO - TEST-START | kraken 20:36:43 INFO - operating with platform_type : mac_ 20:36:43 INFO - Initialising browser for kraken test... 20:36:43 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpJtsgyC/profile http://localhost:49276/getInfo.html) 20:36:45 INFO - PROCESS | 1684 | __metrics Screen width/height:1600/1200 20:36:45 INFO - PROCESS | 1684 | colorDepth:24 20:36:45 INFO - PROCESS | 1684 | Browser inner width/height: 1024/689 20:36:45 INFO - PROCESS | 1684 | __metrics 20:36:45 INFO - PROCESS | 1684 | 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" 20:36:45 ERROR - PROCESS | 1684 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:36:46 INFO - TEST-INFO | 1684: exit 0 20:36:46 INFO - Browser initialized. 20:36:46 INFO - Running cycle 1/1 for kraken test... 20:36:46 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmpJtsgyC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:36:58 INFO - PROCESS | 1686 | RSS: Main: 210804736 20:36:58 INFO - PROCESS | 1686 | 20:37:00 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 20:37:01 INFO - PROCESS | 1686 | RSS: Main: 271859712 20:37:01 INFO - PROCESS | 1686 | 20:37:04 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 20:37:05 INFO - PROCESS | 1686 | RSS: Main: 287875072 20:37:05 INFO - PROCESS | 1686 | 20:37:08 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 20:37:08 INFO - PROCESS | 1686 | RSS: Main: 374104064 20:37:08 INFO - PROCESS | 1686 | 20:37:11 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 20:37:11 INFO - PROCESS | 1686 | RSS: Main: 280166400 20:37:11 INFO - PROCESS | 1686 | 20:37:13 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:37:14 INFO - PROCESS | 1686 | RSS: Main: 280641536 20:37:14 INFO - PROCESS | 1686 | 20:37:17 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 20:37:17 INFO - PROCESS | 1686 | RSS: Main: 317947904 20:37:17 INFO - PROCESS | 1686 | 20:37:20 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 20:37:21 INFO - PROCESS | 1686 | RSS: Main: 319295488 20:37:21 INFO - PROCESS | 1686 | 20:37:24 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 20:37:24 INFO - PROCESS | 1686 | RSS: Main: 317247488 20:37:24 INFO - PROCESS | 1686 | 20:37:26 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:37:26 INFO - PROCESS | 1686 | RSS: Main: 309489664 20:37:26 INFO - PROCESS | 1686 | 20:37:28 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:37:29 INFO - PROCESS | 1686 | RSS: Main: 315863040 20:37:29 INFO - PROCESS | 1686 | 20:37:31 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:37:32 INFO - PROCESS | 1686 | RSS: Main: 319324160 20:37:32 INFO - PROCESS | 1686 | 20:37:34 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:37:35 INFO - PROCESS | 1686 | RSS: Main: 324276224 20:37:35 INFO - PROCESS | 1686 | 20:37:38 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:37:39 INFO - PROCESS | 1686 | RSS: Main: 322064384 20:37:39 INFO - PROCESS | 1686 | 20:37:40 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 20:37:41 INFO - PROCESS | 1686 | RSS: Main: 386260992 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | __start_tp_report 20:37:41 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 20:37:41 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 20:37:41 INFO - PROCESS | 1686 | |i|pagename|runs| 20:37:41 INFO - PROCESS | 1686 | |0;ai-astar;114;114;121;114;112;114;113;114;115;127 20:37:41 INFO - PROCESS | 1686 | |1;audio-beat-detection;156;119;143;202;151;172;102;176;159;117 20:37:41 INFO - PROCESS | 1686 | |2;audio-dft;186;186;185;186;186;184;186;185;187;186 20:37:41 INFO - PROCESS | 1686 | |3;audio-fft;71;74;75;170;46;62;68;93;158;83 20:37:41 INFO - PROCESS | 1686 | |4;audio-oscillator;96;87;179;117;100;91;122;79;78;118 20:37:41 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;89;94;135;86;87;85;89;85;88;85 20:37:41 INFO - PROCESS | 1686 | |6;imaging-darkroom;94;93;94;101;93;94;95;93;93;94 20:37:41 INFO - PROCESS | 1686 | |7;imaging-desaturate;86;87;83;86;86;83;83;86;86;87 20:37:41 INFO - PROCESS | 1686 | |8;json-parse-financial;60;64;64;64;64;64;66;82;61;58 20:37:41 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;64;64;67;74;75;72;68;68;67;67 20:37:41 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;83;81;80;80;81;81;81;81;82;86 20:37:41 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;131;128;126;129;126;128;127;129;149;129 20:37:41 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;167;167;183;219;193;166;166;174;171;164 20:37:41 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;67;69;68;69;67;67;69;69;67;67 20:37:41 INFO - PROCESS | 1686 | __end_tp_report 20:37:41 INFO - PROCESS | 1686 | __start_cc_report 20:37:41 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,2020 20:37:41 INFO - PROCESS | 1686 | __end_cc_report 20:37:41 INFO - PROCESS | 1686 | __startTimestamp1464406661096__endTimestamp 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | ------- Summary: start ------- 20:37:41 INFO - PROCESS | 1686 | Number of tests: 14 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:115.80 Median:114.00 stddev:4.61 (4.0%) stddev-sans-first:4.85 20:37:41 INFO - PROCESS | 1686 | Values: 114.0 114.0 121.0 114.0 112.0 114.0 113.0 114.0 115.0 127.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:149.70 Median:157.50 stddev:30.52 (19.4%) stddev-sans-first:32.29 20:37:41 INFO - PROCESS | 1686 | Values: 156.0 119.0 143.0 202.0 151.0 172.0 102.0 176.0 159.0 117.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:185.70 Median:186.00 stddev:0.82 (0.4%) stddev-sans-first:0.87 20:37:41 INFO - PROCESS | 1686 | Values: 186.0 186.0 185.0 186.0 186.0 184.0 186.0 185.0 187.0 186.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:90.00 Median:72.50 stddev:41.00 (56.5%) stddev-sans-first:42.91 20:37:41 INFO - PROCESS | 1686 | Values: 71.0 74.0 75.0 170.0 46.0 62.0 68.0 93.0 158.0 83.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:106.70 Median:78.50 stddev:29.96 (38.2%) stddev-sans-first:31.53 20:37:41 INFO - PROCESS | 1686 | Values: 96.0 87.0 179.0 117.0 100.0 91.0 122.0 79.0 78.0 118.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:92.30 Median:87.50 stddev:15.25 (17.4%) stddev-sans-first:16.13 20:37:41 INFO - PROCESS | 1686 | Values: 89.0 94.0 135.0 86.0 87.0 85.0 89.0 85.0 88.0 85.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:94.00 stddev:2.41 (2.6%) stddev-sans-first:2.55 20:37:41 INFO - PROCESS | 1686 | Values: 94.0 93.0 94.0 101.0 93.0 94.0 95.0 93.0 93.0 94.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:86.00 stddev:1.64 (1.9%) stddev-sans-first:1.72 20:37:41 INFO - PROCESS | 1686 | Values: 86.0 87.0 83.0 86.0 86.0 83.0 83.0 86.0 86.0 87.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:64.00 stddev:6.53 (10.2%) stddev-sans-first:6.70 20:37:41 INFO - PROCESS | 1686 | Values: 60.0 64.0 64.0 64.0 64.0 64.0 66.0 82.0 61.0 58.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:68.00 stddev:3.84 (5.6%) stddev-sans-first:3.69 20:37:41 INFO - PROCESS | 1686 | Values: 64.0 64.0 67.0 74.0 75.0 72.0 68.0 68.0 67.0 67.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:81.00 stddev:1.78 (2.2%) stddev-sans-first:1.81 20:37:41 INFO - PROCESS | 1686 | Values: 83.0 81.0 80.0 80.0 81.0 81.0 81.0 81.0 82.0 86.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:130.20 Median:129.00 stddev:6.78 (5.3%) stddev-sans-first:7.18 20:37:41 INFO - PROCESS | 1686 | Values: 131.0 128.0 126.0 129.0 126.0 128.0 127.0 129.0 149.0 129.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.00 Median:172.50 stddev:17.36 (10.1%) stddev-sans-first:18.03 20:37:41 INFO - PROCESS | 1686 | Values: 167.0 167.0 183.0 219.0 193.0 166.0 166.0 174.0 171.0 164.0 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.50 stddev:0.99 (1.5%) stddev-sans-first:1.00 20:37:41 INFO - PROCESS | 1686 | Values: 67.0 69.0 68.0 69.0 67.0 67.0 69.0 69.0 67.0 67.0 20:37:41 INFO - PROCESS | 1686 | -------- Summary: end -------- 20:37:41 INFO - PROCESS | 1686 | 20:37:41 INFO - TEST-INFO | 1686: exit 0 20:37:41 INFO - TEST-OK | kraken | took 58537ms 20:37:41 INFO - SUITE-END | took 1173s 20:37:42 INFO - Completed test suite (00:19:33) 20:37:42 INFO - javascript benchmark 20:37:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2859.885604575817, 3389.661033896612, 3390.677885600867, 3410.7782994105364, 3457.464093065574, 5512.862178445541, 5583.776648934037, 5585.832425027245, 5597.888042239153, 5599.888002239953, 5554.611177217596, 5618.438156184379, 5623.634463759863, 5624.690642014688, 5680.715964201794, 5001.049800537886, 5049.924251136245, 5094.1085310070775, 5150.922736158961, 5195.844124676266, 5078.898422031557, 5159.0, 5160.4323524412475, 5164.974175129138, 5168.22476628505, 5088.058709138802, 5111.974440127794, 5138.0, 5142.717150556718, 5153.433122356538, 5120.61595380348, 5136.794528218867, 5150.768215430315, 5163.0, 5190.4550022247495, 4955.430125535573, 5017.824376146854, 5062.898742025176, 5065.924011139836, 5103.362079740033, 5458.699771512565, 5468.398476167618, 5507.697076660782, 5516.979358818631, 5526.696031718254, 6129.785457508966, 6139.017757158877, 6146.231721034871, 6149.385061493859, 6159.722812473449, 5449.291592093042, 6007.609505382136, 6090.939090609126, 6147.2930612979635, 6242.875142497124, 5722.37053924068, 5723.8569035774435, 5751.051076572402, 5755.482006619424, 5793.073108302693, 6308.148399965946, 6335.3981371769605, 6354.364563543609, 6372.139761132281, 6375.585586936834, 5835.824925252249, 5915.3788852170765, 5927.2590926134235, 6058.515318774487, 6070.696465176724, 6188.381161883775, 6188.907166392508, 6193.84515387119, 6202.968985155046, 6221.844453888599, 6092.68232078519, 6094.451499365078, 6099.634021958696, 6108.08378743192, 6113.833133127588, 5929.940700592939, 5968.074948383008, 6108.77496446079, 6125.510168486495, 6137.560853825989, 4814.735189564608, 4853.878653033702, 4855.053264613366, 4856.8785780355065, 4871.0, 4357.172137293904, 4373.606375426227, 4382.2112019836095, 4397.252467080604, 4409.250427427345, 5445.292112025411, 5524.58565607573, 5576.581756368289, 5594.41258667834, 5601.0, 6127.80043271483, 6208.441240288395, 6213.782517611862, 6217.191765070512, 6251.812445626639, 5567.359753628303, 5568.805091821848, 5572.5263352614675, 5578.944210557843, 5602.38373778884, 5219.321488206509, 5292.311999440049, 5299.6025298102795, 5301.0, 5335.253064570963, 6288.52836037299, 6339.4611458025665, 6344.524160687874, 6348.999999999907, 6370.872582548416, 6255.812325630239, 6289.119523266838, 6299.905501417574, 6324.30432652417, 6337.397947195009, 6242.968785156045, 6318.27339855904, 6349.238091429058, 6351.364863513611, 6365.395287447685, 5248.396434409939, 5996.70016499182, 6127.601705889103, 6130.785422510191, 6137.41694539018, 5136.691798492103, 6199.597026193283, 6212.627242365473, 6216.906746398718, 6219.160413344322, 5088.567471764942, 5319.521243088141, 5891.1752354669525, 5896.968030594715, 6022.307434645072, 5807.070868660994, 5880.323762767336, 5883.117532370179, 5921.3190483094995, 6010.1585777991995, 5815.447532484407, 5816.47651711348, 5822.592418530662, 5837.766489340379, 5861.0915308127305, 5791.8552036199435, 5904.3210030847, 5949.881002379841, 5955.166276721176, 5995.490383317292, 4925.408950925912, 5572.498475137257, 5799.942000580109, 5816.331121921034, 5842.240508734008], "value": 5587.082996487133, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [525.9631825772195, 537.5323468582333, 585.9853503662409, 602.8124594548738, 622.2874808344443, 28178.718212817865, 33772.4934125988, 37723.43414848776, 38862.611373886255, 38888.22223555527, 42631.573684263145, 42736.572634273645, 42755.572444275545, 42780.716578502695, 42823.78588107063, 8582.313414926823, 9511.048895110504, 9692.757681057994, 9693.127618514332, 9698.563564639608, 8683.261922736576, 11567.710807229803, 11743.177977541533, 11748.706282342924, 11757.294562326288, 11463.541458341699, 11549.422528873565, 11552.942235288812, 11559.53761849525, 11568.074554035657, 8690.565471726388, 11482.598109066184, 11553.364564948883, 11575.594854180063, 11651.76696466066, 11387.259828111146, 11509.712257193554, 11518.712032199179, 11520.76958460826, 11523.942380288128, 38278.808605956794, 38723.41914871279, 38783.0, 38862.611373886466, 38885.80557097196, 3213.630432500269, 3272.4109660261142, 3380.4422270325367, 3432.9656703433147, 3537.8584856605703, 27695.723042769518, 28581.0, 28695.13914582566, 29135.41729165426, 29487.70512294871, 2708.9729102708916, 2711.8372897626205, 2751.2159034675024, 2795.4688609164195, 2843.4739573179027, 348572.0, 352477.0, 353158.0, 353288.0, 353407.0, 36423.63576364255, 36834.447483287775, 36931.81534092312, 60689.393106069256, 61466.38533614696, 35327.823360883034, 35440.82279588637, 35671.64328356683, 35710.46434303487, 35746.0, 15608.609784755245, 15818.60453488672, 15820.76268855968, 15835.129067901376, 15903.363865445484, 12344.506219751289, 12512.436940337706, 13187.670308242179, 13348.065635405428, 13489.392977316043, 7277.017602623578, 7727.343175830118, 8011.359091272684, 8030.957433528898, 8085.070216925129, 2942.955855662167, 3349.179451034512, 3369.359821633882, 3370.1406141433777, 3411.795292282471, 822.4653974916232, 1015.6445244164483, 1022.8772547294373, 1070.2080460459204, 1073.8885253762476, 28232.435351292857, 28366.0, 28726.038796346802, 29027.564586531218, 29145.27136821554, 949.924006079512, 1124.3029321820525, 1128.1707944660668, 1230.7600017996583, 1259.5150866916304, 15022.849771502364, 15171.468998584996, 15176.772348414783, 15409.0, 15519.37922483088, 593757.0, 601984.0, 602613.0, 605317.0, 605536.0, 62029.37970620236, 62521.68739156275, 62526.37473625205, 62542.37457625366, 62555.6872215636, 1189.5301355964345, 1477.7340078785921, 1502.737021021295, 1524.6569521857493, 1533.0, 28963.42073158483, 29455.0, 29601.25996850096, 29883.402331953675, 30285.545716813824, 53054.204186936455, 53779.19331210113, 53904.921901562535, 53935.0, 54026.459735402146, 492157.0, 505785.0, 505909.4704526454, 505962.0, 507607.0, 24190.153344632854, 27248.182554523395, 27448.725512744615, 27669.0, 28068.29829254285, 45595.77202113968, 45675.54324456713, 45692.0, 45820.083598328514, 47265.52734472611, 31003.68996310008, 36086.27827443489, 36809.07977300589, 37647.0, 37701.24597508089, 36485.817570911975, 37109.0, 37148.44277335788, 37153.442698360086, 37917.431238530895, 36588.0, 37316.626833731316, 38235.044123897125, 38296.2340753189, 38320.0, 27485.450290993667, 32216.35567288688, 32761.344773103927, 32889.684412623224, 32918.341633166725, 24813.0, 24864.756762162182, 25040.49919001646, 25380.873095634404, 25631.23106306814], "value": 18550.598046049483, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.1816285695752, 400.8476778824047, 406.0842799487156, 406.2849385082254, 417.06161137440756, 13988.230647314394, 16004.919975400122, 16667.499975000737, 19979.0, 20436.284730034433, 16031.83968160318, 16902.49292521223, 20007.899960500214, 20507.487312817186, 20513.0, 5230.9738451307785, 5539.446055394468, 5540.750666220029, 5552.639078459907, 5572.498475137237, 4036.515618125829, 5460.044492213867, 5539.0, 5539.224508568804, 5540.2243685884005, 5901.763929442838, 6164.352742961992, 6172.105044768506, 6189.659568723719, 6206.565540412173, 6153.507719382439, 6158.7228574714245, 6158.768246350725, 6284.497240220771, 6722.66386680664, 5895.64626122434, 6033.637981721067, 6067.484263837579, 6073.939260607382, 6074.453299203049, 13236.669083272995, 14319.713605727931, 24973.876175572143, 25417.110401135873, 25698.87150564254, 2103.5372218111993, 2126.319577735125, 2188.7592364839857, 2361.8700971446565, 2787.6236708044357, 9745.90254097457, 9826.90173098267, 9978.251631127694, 10174.982501749766, 10216.591336346537, 1701.6086300150912, 1762.5769815244248, 1823.9968017590272, 1884.3781552087914, 1910.0927059646694, 109538.45230773874, 112718.43640781744, 115343.26985095315, 115981.0, 116559.0, 11578.536858525733, 11795.52817887292, 11829.467673954692, 11831.349275789915, 11838.171328007129, 15069.397224111131, 15099.924500377647, 15133.243337833064, 15140.864435167407, 15147.394104235927, 6457.192850893638, 7682.961585192038, 8752.474851508803, 8908.73273801787, 8998.820023599492, 7476.028116344949, 8193.79515512105, 8406.747797566082, 9079.636814527477, 9219.677311294072, 4720.079584481062, 4741.620670346364, 4743.051389722069, 4752.429708434941, 4770.0936822003705, 1710.811810700822, 1944.397236856579, 1994.9800501995085, 2004.679251319811, 2040.8775473471342, 917.4357769971552, 1013.6705570689555, 1030.577463240068, 1033.0495943731635, 1039.3763741755038, 20394.796052039586, 20532.0, 20842.895785520974, 20902.268420605207, 20921.581568368852, 971.2278738402987, 980.7695191629962, 1016.2581315639625, 1049.3441599000626, 1051.279873286801, 10735.51690173944, 11196.272242304223, 11201.327920324806, 11233.798367465233, 11234.606788762354, 569520.304796955, 574617.0, 582768.0861595665, 585910.0, 591397.0, 112865.0, 113730.0, 117237.41381293039, 118740.40629796969, 119098.8090119105, 1050.7635781949, 1053.3258714422775, 1064.1912146768511, 1067.7277294289906, 1068.1614932278123, 17883.105844707454, 17915.73126403131, 18171.0, 18371.0, 18600.34898778536, 40572.79713601413, 50832.237516436544, 52814.88740450339, 53719.38841834751, 54587.0, 52043.73978130085, 53308.73345633247, 68602.31397685959, 70483.9427408579, 73050.0, 6362.840928976812, 8137.593120344078, 8268.214519620626, 8299.419040667095, 8323.292520135856, 8997.82004359896, 12312.0, 12390.938045309716, 12656.6835829105, 12701.4284357206, 8415.368847336596, 10763.085137763064, 11099.778004439704, 11321.603743868925, 11336.75295717487, 10755.677329680122, 10827.0, 10893.237473376788, 11001.559937602726, 11168.720781980515, 11100.500477478694, 11155.832662509907, 11418.371989540656, 11477.885221147682, 11516.0, 6464.288928217987, 8043.436959413019, 8073.798155046171, 8191.467554608931, 8389.244967952913, 8301.335893128657, 9198.126178013077, 10616.89383106159, 11261.21171517986, 11293.096552275634], "value": 9368.117268385213, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.1603595860458, 1529.9464518741845, 1559.5867095219762, 1816.900070496123, 1989.5424052467931, 7901.841963160733, 8595.484270943747, 8744.0, 8749.650013999433, 8757.737267881957, 8472.449290796094, 8696.34777391695, 8709.520976346299, 8718.258947989429, 8741.519216443094, 2134.7118139051186, 2386.4153282445736, 2430.173740928084, 2450.926472205828, 2451.4974430241846, 2138.1661152150673, 2324.1052194904914, 2464.2730394533655, 2467.284487498623, 2467.7902378297867, 2360.1503458754833, 2408.0728919365997, 2428.4171798768257, 2431.2706188143575, 2432.914847980321, 2133.5946170227685, 2377.8811059446957, 2430.149447693311, 2431.5501632197925, 2435.1233555919853, 2408.915687950931, 2443.1937460637946, 2446.657467954488, 2446.9845014319035, 2447.021191523387, 8597.441166324168, 8665.653373865038, 8675.69635062776, 8680.131986801332, 8680.262177714902, 5095.592352611782, 5316.12283973144, 5345.625806193568, 5351.973240133774, 5353.0, 3470.9305813883834, 3500.457429098494, 3516.1561225306, 3563.964360356389, 3567.1438854674952, 965.54619328916, 984.4929861121528, 1018.1396719771781, 1038.68839348196, 1054.752133248686, 11939.343336116597, 11969.94015029928, 11988.940055299754, 11989.721952828935, 11990.100742444352, 4046.4537287465814, 4778.1399348116975, 4850.95149048505, 4872.049950259736, 4876.268559716, 10035.197184225244, 10142.695719128438, 10160.33957792741, 10162.085412312927, 10175.542100605491, 5485.451454854562, 5495.120780675153, 5499.615026948155, 5506.559475241971, 5508.55931525477, 4308.978455107705, 4455.22033644111, 4781.378420805273, 4884.804607815648, 4904.509549045139, 3478.982605087009, 4000.2199571083943, 4008.6993475489453, 4070.75575465473, 4117.691173162025, 1336.8863646589955, 1367.2753440676458, 1373.6497193215666, 1380.7100508893045, 1383.0733408616252, 811.1604489353608, 823.279630323467, 867.6789587852535, 909.1999040844147, 917.454114801692, 5509.449055094458, 5528.612997090165, 5574.804881829116, 5716.999525083093, 5894.616849904828, 447.2642503082476, 454.9567791059844, 466.5591016489453, 467.9719216847, 470.62114997426715, 3730.142067324476, 3803.2773772983046, 3847.0959324558708, 3856.537215534154, 3860.9034774130873, 10225.846612300822, 10532.0, 10665.986731260518, 10667.73330666724, 10679.199060070527, 10094.09153176203, 10520.684379468628, 10522.526486308134, 10597.947010265054, 10644.467776611138, 8183.427160098736, 8266.297364724065, 8338.624761885609, 8358.03882553514, 8367.205115513894, 5427.755750991136, 5457.181422786613, 5465.426130256425, 5563.58273129525, 5662.377138514842, 9027.368084234304, 9288.256939444964, 9318.301127415336, 9417.48203848795, 9444.905550944677, 9204.987451380477, 9377.578008989476, 9378.202852757318, 9522.0, 9558.522073896416, 6715.294894036056, 6876.965615172092, 7188.281171882979, 7312.817179570553, 7450.403967682681, 8093.028836539653, 8101.149379315323, 8160.714374996846, 8168.591570421574, 8172.754817355489, 7112.395446387115, 7223.097112860893, 7232.493725439169, 7311.817204569928, 7410.147832999059, 7101.005859179616, 7105.786826395217, 7131.2155662876, 7245.529040612343, 7250.093738282715, 6723.865522689421, 6849.486288528281, 6851.417629501549, 6851.588904665736, 6856.725730970706, 4079.8899849572917, 4699.953000469952, 4700.835470758507, 4779.5220477951925, 4838.346823178826, 4857.441394239708, 4892.535209155125, 4934.950650493593, 4949.183384741477, 5037.899242015066], "value": 4451.355518821282, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.89923224568145, 316.77667244592567, 327.23753653986205, 329.8499182871794, 332.72550146129436, 6279.514869247577, 6452.58058226215, 6533.216014078312, 6558.8688226235445, 6564.967175164124, 5255.86860328492, 6106.938930610704, 6369.936300636992, 6416.9358306416925, 6487.513436492271, 4806.351142595758, 4845.830395936143, 4847.224444088955, 4859.441164266119, 4867.659263851532, 4612.146752850716, 4833.081714474256, 4862.78117484714, 4865.367502224706, 4881.633877459187, 4581.129585378784, 4692.97653511732, 4694.29585562165, 4696.201645720235, 4708.293755936603, 3834.827432765518, 4653.953460465385, 4676.0647870426055, 4701.224297990828, 4709.92935105972, 4064.6951478638925, 4066.410370496291, 4072.2059198456313, 4075.0, 4083.530394004668, 6006.519478441714, 6054.788082417138, 6061.605995610271, 6108.694565271763, 6114.602550834182, 5571.526420254283, 5577.135543990688, 5582.804601838957, 5585.96659617972, 5589.469000444951, 5400.972995135038, 6492.967535162341, 6774.322567743236, 6801.9594121763275, 6835.282295359015, 4401.273789824675, 4436.866893993153, 4437.755923424211, 4442.444694413199, 4444.2222611042935, 7055.400290975222, 7148.0, 7295.607542492713, 7303.45224108194, 7321.26787321269, 4482.0, 4571.2000399929875, 4571.885702857455, 4574.0, 4578.908421831545, 4619.792109355087, 4642.744649044335, 4658.953410465852, 4659.347691323217, 4670.369500117441, 5483.232347471358, 5488.1493368527945, 5493.670379777226, 5497.560195184456, 5504.587155963319, 6184.041473571604, 6373.553851230369, 6943.652817359111, 6946.583205007716, 6953.478489113337, 1575.9369625214863, 1598.5683865356289, 1600.7438809790378, 1600.8799340049543, 1602.3670650093147, 902.7111324376268, 958.7219706285239, 1009.530568285736, 1027.5119318323736, 1031.56674196836, 6334.5565810393755, 6340.904886426707, 6397.488200943913, 6403.48772098231, 6408.166938297992, 7149.0, 7175.820604484825, 7179.533330333512, 7179.533330333512, 7196.532225405332, 6400.647964361911, 6413.551051426449, 6416.582922110141, 6425.58233714807, 6433.228012638419, 2923.473774720571, 3301.5377847101427, 3640.199156185635, 3766.472693822867, 3830.71269654777, 6309.9588567885785, 7223.422126229889, 7464.962675186589, 7501.099868015764, 7515.286047825448, 7316.487845850739, 7376.373008294355, 7407.518511296857, 7409.703611855573, 7435.628218589157, 7527.698892044366, 7532.17146113927, 7533.660985255787, 7545.207753185946, 7545.434092443089, 6990.965045174945, 7125.608091555015, 7208.315210055036, 7222.422206223595, 7236.674349654383, 5680.914786278292, 7012.4390048796995, 7049.436045116481, 7063.682134304071, 7107.822304442224, 7446.925530744623, 7447.81380465471, 7471.290227428385, 7479.401647868266, 7495.737649182252, 4593.28804035375, 4598.770061496979, 4603.838865639686, 4605.286180642006, 4606.332081848181, 5756.884862302814, 7125.714971401086, 7134.714611415485, 7143.785686429415, 7144.67848946809, 5609.775608975595, 6725.19297684281, 6938.791836244921, 6940.514164008471, 6960.303969602999, 6831.795046148623, 6912.135983002125, 6921.307869213038, 6973.58158510491, 6995.895061574182, 5868.735906884115, 6446.580972236977, 6795.660216989229, 6806.65966701653, 6810.514162330614, 6704.363085506869, 6955.8956615649795, 6982.33667801558, 6986.790396288119, 6989.231184569795, 6575.704093315908, 6702.162229721285, 6711.899321510084, 6814.318568143146, 6820.863582728219], "value": 5000.777386121274, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.168636733634, 737.3179808676972, 748.5097261293851, 755.1919446192572, 760.7527553545092, 14417.711645767078, 14651.560453186408, 14657.780133298009, 14663.193524356157, 14664.266786660652, 15117.39530418782, 15579.84420155798, 15594.844051559481, 15613.375464981387, 15624.2969066392, 736.5323019882386, 745.3738859358139, 904.7964208053185, 913.0732306708696, 922.2161163011453, 913.6117150211134, 914.7210100919217, 917.4678686361895, 917.7338571814166, 917.807260475301, 978.1441238915949, 978.2907392140712, 978.6966040527424, 978.8678528398682, 979.4955597867104, 764.5718397697308, 948.1087777489138, 969.4813274897966, 970.2238209432426, 973.8831340239182, 723.1105739939851, 724.1997592660088, 725.021221351177, 725.5465334166146, 726.5531698005741, 14812.925935370362, 14884.62788430287, 14889.47886823956, 14903.0, 14908.18005009724, 12029.398530073462, 12101.515939362414, 12222.022238220921, 12306.200096993667, 12376.07179461544, 9986.0, 10134.493275336205, 10228.386296822215, 10359.689209323733, 10470.947645261802, 3336.1492819330915, 3339.716124129452, 3398.6261511233743, 3405.080628230389, 3480.2343484433386, 83869.58065209696, 83891.0, 84453.57773211095, 84815.0, 85147.5742621283, 8851.982022067416, 8928.062553431926, 8940.642374304955, 8990.370674052885, 9271.953640231755, 20664.690029649566, 20696.068676909574, 20715.27496537644, 20730.689039664416, 20796.168153274004, 7214.67533960973, 7358.300961408658, 7435.25647435263, 7457.121551081283, 7546.698132074765, 4073.735207211522, 4084.0606660468256, 4170.207660544487, 4245.087306229176, 4362.192994296065, 3452.741044421628, 3508.9298214035575, 3514.525539052195, 3517.7889326640484, 3522.225110475691, 600.83777380108, 610.2402508876477, 634.1407392982443, 636.8821767972847, 641.5252712992352, 518.5436815602246, 522.4253321346489, 574.5719439017959, 595.85401576614, 608.4888693497395, 8045.718399855977, 8123.268905798388, 8127.268545830903, 8263.132371100948, 8345.374096942754, 531.2880739808675, 558.6536347464598, 574.4600075928614, 581.0876923230572, 607.3804719186405, 3799.0692280390926, 3811.27585758705, 3847.0959324558708, 3854.383298672199, 3898.805059747058, 67552.0, 87113.1288687105, 88415.55792221126, 88780.5560972191, 89256.0, 72687.18282042886, 73751.63124184452, 74157.6292118536, 74984.0, 75332.24667753362, 66484.66757666181, 67216.0, 68348.0, 68627.65686171537, 70007.29992700109, 7562.848743025219, 7682.769516914502, 7723.420743444377, 7736.806579835549, 7774.339181169664, 47844.76077619589, 48607.27089093737, 48749.53751387464, 49072.75463622802, 49704.01183952746, 62235.6888215556, 63160.73678526312, 67382.66308668621, 67807.66096169487, 68744.0, 9654.0, 9933.056359645821, 10080.798384032132, 10125.34185277984, 10129.392236465836, 10576.629817956335, 10689.679309620724, 10722.356658600196, 10948.178886583697, 10968.835467468154, 10354.378737275787, 10534.315269507457, 10553.788924221626, 10625.149988000787, 11876.228045177037, 10895.073918716998, 11114.944425277821, 11148.108151348035, 11313.377764223047, 11579.652610421701, 10854.240203185702, 10960.28758130719, 10984.890151098387, 11124.888751112385, 11972.700682483008, 9120.0, 9245.58394872219, 9392.577334019848, 9441.291903107433, 9694.0, 9416.293777966543, 9504.382215155993, 9519.904800951901, 9587.760305992128, 9640.662576809778], "value": 6770.098427143182, "unit": "score"}], "name": "dromaeo_css", "value": 7259.26871386841, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 121.0, 114.0, 112.0, 114.0, 113.0, 114.0, 115.0, 127.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 119.0, 143.0, 202.0, 151.0, 172.0, 102.0, 176.0, 159.0, 117.0], "value": 149.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 185.0, 186.0, 186.0, 184.0, 186.0, 185.0, 187.0, 186.0], "value": 185.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 75.0, 170.0, 46.0, 62.0, 68.0, 93.0, 158.0, 83.0], "value": 90.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 87.0, 179.0, 117.0, 100.0, 91.0, 122.0, 79.0, 78.0, 118.0], "value": 106.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 135.0, 86.0, 87.0, 85.0, 89.0, 85.0, 88.0, 85.0], "value": 92.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 101.0, 93.0, 94.0, 95.0, 93.0, 93.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 83.0, 86.0, 86.0, 83.0, 83.0, 86.0, 86.0, 87.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 64.0, 64.0, 64.0, 64.0, 66.0, 82.0, 61.0, 58.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 67.0, 74.0, 75.0, 72.0, 68.0, 68.0, 67.0, 67.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 80.0, 80.0, 81.0, 81.0, 81.0, 81.0, 82.0, 86.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 128.0, 126.0, 129.0, 126.0, 128.0, 127.0, 129.0, 149.0, 129.0], "value": 130.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 183.0, 219.0, 193.0, 166.0, 166.0, 174.0, 171.0, 164.0], "value": 177.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 69.0, 67.0, 67.0, 69.0, 69.0, 67.0, 67.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1509.9, "alertThreshold": 2.0}]} 20:37:42 INFO - Return code: 0 20:37:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:37:42 ERROR - # TBPL SUCCESS # 20:37:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:37:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:37:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:37:42 INFO - 2016-05-27 20:37:42.400 screenresolution[1690:12194] starting screenresolution argv=screenresolution get 20:37:42 INFO - 2016-05-27 20:37:42.403 screenresolution[1690:12194] Display 0: 1600x1200x32@60 20:37:42 INFO - 2016-05-27 20:37:42.408 screenresolution[1691:12197] starting screenresolution argv=screenresolution list 20:37:42 INFO - Available Modes on Display 0 20:37:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:37:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:37:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:37:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:37:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:37:42 INFO - Intel Iris: 20:37:42 INFO - Chipset Model: Intel Iris 20:37:42 INFO - Type: GPU 20:37:42 INFO - Bus: Built-In 20:37:42 INFO - VRAM (Dynamic, Max): 1536 MB 20:37:42 INFO - Vendor: Intel (0x8086) 20:37:42 INFO - Device ID: 0x0a2e 20:37:42 INFO - Revision ID: 0x0009 20:37:42 INFO - Displays: 20:37:42 INFO - Display: 20:37:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:37:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:37:42 INFO - Display Serial Number: 1600x1200 60 20:37:42 INFO - Main Display: Yes 20:37:42 INFO - Mirror: Off 20:37:42 INFO - Online: Yes 20:37:42 INFO - Rotation: Supported 20:37:42 INFO - Return code: 0 20:37:42 INFO - Running post-action listener: _resource_record_post_action 20:37:42 INFO - Running post-run listener: _resource_record_post_run 20:37:43 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 32599552; Write bytes: 654671872; Read time: 318; Write time: 2033 20:37:43 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 348959232; Write bytes: 347104256; Read time: 27524; Write time: 650 20:37:43 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 30842368; Write bytes: 296774656; Read time: 300; Write time: 1345 20:37:43 INFO - Running post-run listener: _upload_blobber_files 20:37:43 INFO - Blob upload gear active. 20:37:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:37:43 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 20:37:43 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'] 20:37:43 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 20:37:44 INFO - (blobuploader) - INFO - Open directory for files ... 20:37:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:37:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:37:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:45 INFO - (blobuploader) - INFO - Done attempting. 20:37:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:37:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:37:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:46 INFO - (blobuploader) - INFO - Done attempting. 20:37:46 INFO - (blobuploader) - INFO - Iteration through files over. 20:37:46 INFO - Return code: 0 20:37:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:37:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:37:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2db7aa211e4f64b1d8edf860f4775c9c7b2f5490307cc64b3911f79d8ac6e4d2097b52b7262022a8f4ba7dd49866dcd6c3f89d07efecad3820a8cca162c675d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a47852f07539813abe410fd0a793874dc1991b68584dcc14212ce1502b0cb039838e57f61326e5ff1beede35bd0516054131222079bd8ddee9bdc7392f42f31a"} 20:37:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:37:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:37:46 INFO - Contents: 20:37:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2db7aa211e4f64b1d8edf860f4775c9c7b2f5490307cc64b3911f79d8ac6e4d2097b52b7262022a8f4ba7dd49866dcd6c3f89d07efecad3820a8cca162c675d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a47852f07539813abe410fd0a793874dc1991b68584dcc14212ce1502b0cb039838e57f61326e5ff1beede35bd0516054131222079bd8ddee9bdc7392f42f31a"} 20:37:46 INFO - Running post-run listener: copy_logs_to_upload_dir 20:37:46 INFO - Copying logs to upload dir... 20:37:46 INFO - mkdir: /builds/slave/test/build/upload/logs 20:37:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1346.638138 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 26 secs) (at 2016-05-27 20:37:46.345173) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 20:37:46.348685) ========= 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.TUUrEJbQt9/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.xamfJhk0Og/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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/2db7aa211e4f64b1d8edf860f4775c9c7b2f5490307cc64b3911f79d8ac6e4d2097b52b7262022a8f4ba7dd49866dcd6c3f89d07efecad3820a8cca162c675d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a47852f07539813abe410fd0a793874dc1991b68584dcc14212ce1502b0cb039838e57f61326e5ff1beede35bd0516054131222079bd8ddee9bdc7392f42f31a"} build_url:https://queue.taskcluster.net/v1/task/Q1QXTvMXQbCkCswp6daCUQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011109 build_url: 'https://queue.taskcluster.net/v1/task/Q1QXTvMXQbCkCswp6daCUQ/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/2db7aa211e4f64b1d8edf860f4775c9c7b2f5490307cc64b3911f79d8ac6e4d2097b52b7262022a8f4ba7dd49866dcd6c3f89d07efecad3820a8cca162c675d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a47852f07539813abe410fd0a793874dc1991b68584dcc14212ce1502b0cb039838e57f61326e5ff1beede35bd0516054131222079bd8ddee9bdc7392f42f31a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 20:37:46.379077) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 20:37:46.379385) ========= 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.TUUrEJbQt9/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.xamfJhk0Og/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.005151 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 20:37:46.428241) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 20:37:46.428575) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 20:37:46.428921) ========= ========= Total master_lag: 0.16 =========