builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0019 starttime: 1460648795.68 results: success (0) buildid: 20160414074538 builduid: e44f1848eee34552bfde55587b277c28 revision: 995e7890dd613843c3914a1d9d46676f400152c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:35.678889) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:35.679358) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:35.679664) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:35.702658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:35.702944) ========= 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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-04-14 08:46:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.44M=0.002s 2016-04-14 08:46:35 (7.44 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.143980 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:35.866443) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:35.866767) ========= 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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.019558 ========= master_lag: 0.14 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:36.028277) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 08:46:36.028645) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 995e7890dd613843c3914a1d9d46676f400152c1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 995e7890dd613843c3914a1d9d46676f400152c1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0TPR8vDAqe/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.2YB8kVdMjE/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-04-14 08:46:36,101 truncating revision to first 12 chars 2016-04-14 08:46:36,101 Setting DEBUG logging. 2016-04-14 08:46:36,101 attempt 1/10 2016-04-14 08:46:36,101 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/995e7890dd61?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-14 08:46:38,190 unpacking tar archive at: fx-team-995e7890dd61/testing/mozharness/ program finished with exit code 0 elapsedTime=2.329200 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 08:46:38.414856) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:38.415177) ========= script_repo_revision: 995e7890dd613843c3914a1d9d46676f400152c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:38.415581) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:38.415850) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 08:46:38.434468) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-04-14 08:46:38.434740) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0TPR8vDAqe/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.2YB8kVdMjE/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:46:38 INFO - MultiFileLogger online at 20160414 08:46:38 in /builds/slave/test 08:46:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:46:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:46:38 INFO - {'append_to_log': False, 08:46:38 INFO - 'base_work_dir': '/builds/slave/test', 08:46:38 INFO - 'blob_upload_branch': 'Fx-Team', 08:46:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:46:38 INFO - 'branch': 'Fx-Team', 08:46:38 INFO - 'buildbot_json_path': 'buildprops.json', 08:46:38 INFO - 'config_files': ('talos/mac_config.py',), 08:46:38 INFO - 'default_actions': ('clobber', 08:46:38 INFO - 'read-buildbot-config', 08:46:38 INFO - 'download-and-extract', 08:46:38 INFO - 'populate-webroot', 08:46:38 INFO - 'create-virtualenv', 08:46:38 INFO - 'install', 08:46:38 INFO - 'run-tests'), 08:46:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:46:38 INFO - 'download_minidump_stackwalk': True, 08:46:38 INFO - 'download_symbols': 'ondemand', 08:46:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:46:38 INFO - 'tooltool.py': '/tools/tooltool.py', 08:46:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:46:38 INFO - '/tools/misc-python/virtualenv.py')}, 08:46:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:46:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:46:38 INFO - 'installer_path': 'installer.exe', 08:46:38 INFO - 'log_level': 'info', 08:46:38 INFO - 'log_name': 'talos', 08:46:38 INFO - 'log_to_console': True, 08:46:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:46:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:46:38 INFO - 'opt_config_files': (), 08:46:38 INFO - 'pip_index': False, 08:46:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:46:38 INFO - 'cmd': ('bash', 08:46:38 INFO - '-c', 08:46:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:46:38 INFO - 'enabled': True, 08:46:38 INFO - 'halt_on_failure': False, 08:46:38 INFO - 'name': 'check_screen_resolution'},), 08:46:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:46:38 INFO - 'cmd': ('bash', 08:46:38 INFO - '-c', 08:46:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:46:38 INFO - 'enabled': True, 08:46:38 INFO - 'halt_on_failure': False, 08:46:38 INFO - 'name': 'check_screen_resolution'},), 08:46:38 INFO - 'run_cmd_checks_enabled': True, 08:46:38 INFO - 'sps_profile': False, 08:46:38 INFO - 'sps_profile_interval': 0, 08:46:38 INFO - 'suite': 'dromaeojs', 08:46:38 INFO - 'system_bits': '32', 08:46:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:46:38 INFO - 'title': 't-yosemite-r7-0019', 08:46:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:46:38 INFO - 'use_talos_json': True, 08:46:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:46:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:46:38 INFO - 'work_dir': 'build'} 08:46:38 INFO - ##### 08:46:38 INFO - ##### Running clobber step. 08:46:38 INFO - ##### 08:46:38 INFO - Running pre-action listener: _resource_record_pre_action 08:46:38 INFO - Running main action method: clobber 08:46:38 INFO - rmtree: /builds/slave/test/build 08:46:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:46:40 INFO - Running post-action listener: _resource_record_post_action 08:46:40 INFO - ##### 08:46:40 INFO - ##### Running read-buildbot-config step. 08:46:40 INFO - ##### 08:46:40 INFO - Running pre-action listener: _resource_record_pre_action 08:46:40 INFO - Running main action method: read_buildbot_config 08:46:40 INFO - Using buildbot properties: 08:46:40 INFO - { 08:46:40 INFO - "project": "", 08:46:40 INFO - "product": "firefox", 08:46:40 INFO - "script_repo_revision": "production", 08:46:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:46:40 INFO - "repository": "", 08:46:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:46:40 INFO - "buildid": "20160414074538", 08:46:40 INFO - "pgo_build": "False", 08:46:40 INFO - "basedir": "/builds/slave/test", 08:46:40 INFO - "buildnumber": 718, 08:46:40 INFO - "slavename": "t-yosemite-r7-0019", 08:46:40 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:46:40 INFO - "platform": "yosemite_r7", 08:46:40 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:46:40 INFO - "branch": "fx-team", 08:46:40 INFO - "repo_path": "integration/fx-team", 08:46:40 INFO - "revision": "995e7890dd613843c3914a1d9d46676f400152c1", 08:46:40 INFO - "stage_platform": "macosx64", 08:46:40 INFO - "builduid": "e44f1848eee34552bfde55587b277c28", 08:46:40 INFO - "slavebuilddir": "test" 08:46:40 INFO - } 08:46:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 08:46:40 INFO - Running post-action listener: _resource_record_post_action 08:46:40 INFO - ##### 08:46:40 INFO - ##### Running download-and-extract step. 08:46:40 INFO - ##### 08:46:40 INFO - Running pre-action listener: _resource_record_pre_action 08:46:40 INFO - Running main action method: download_and_extract 08:46:40 INFO - mkdir: /builds/slave/test/build/tests 08:46:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:46:40 INFO - https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:46:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 08:46:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 08:46:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 08:46:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 08:46:41 INFO - Downloaded 1199 bytes. 08:46:41 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 08:46:41 INFO - Using the following test package requirements: 08:46:41 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 08:46:41 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:46:41 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 08:46:41 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:46:41 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 08:46:41 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:46:41 INFO - u'jsshell-mac.zip'], 08:46:41 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:46:41 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 08:46:41 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 08:46:41 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:46:41 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 08:46:41 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:46:41 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 08:46:41 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:46:41 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 08:46:41 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 08:46:41 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 08:46:41 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:46:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:46:41 INFO - https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:46:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:46:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:46:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:46:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:46:42 INFO - Downloaded 18031162 bytes. 08:46:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:46:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:46:45 INFO - Return code: 0 08:46:45 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:46:45 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')]} 08:46:45 INFO - https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:46:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:46:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:46:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 08:46:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:46:46 INFO - Downloaded 18031162 bytes. 08:46:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:46:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:46:50 INFO - Return code: 0 08:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:46:50 INFO - https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:46:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:46:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:46:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 08:46:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:46:52 INFO - Downloaded 10841076 bytes. 08:46:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:46:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:46:52 INFO - Return code: 0 08:46:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:46:52 INFO - https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:46:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:46:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:46:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 08:46:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 08:46:55 INFO - Downloaded 109831870 bytes. 08:46:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:46:55 INFO - mkdir: /builds/slave/test/properties 08:46:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:46:55 INFO - Writing to file /builds/slave/test/properties/build_url 08:46:55 INFO - Contents: 08:46:55 INFO - build_url:https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 08:46:56 INFO - Running post-action listener: _resource_record_post_action 08:46:56 INFO - Running post-action listener: set_extra_try_arguments 08:46:56 INFO - ##### 08:46:56 INFO - ##### Running populate-webroot step. 08:46:56 INFO - ##### 08:46:56 INFO - Running pre-action listener: _resource_record_pre_action 08:46:56 INFO - Running main action method: populate_webroot 08:46:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:46:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:46:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:46:56 INFO - '--tppagecycles', 08:46:56 INFO - '7'], 08:46:56 INFO - 'tests': ['tsvgm']}}, 08:46:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:46:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:46:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:46:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:46:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:46:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:46:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:56 INFO - 'talos_options': ['--disable-e10s'], 08:46:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:56 INFO - 'talos_options': ['--disable-e10s'], 08:46:56 INFO - 'tests': ['damp', 'tps']}, 08:46:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:56 INFO - 'tests': ['damp', 'tps']}, 08:46:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:46:56 INFO - 'tests': ['dromaeo_dom']}, 08:46:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:46:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:46:56 INFO - 'tests': ['a11yr', 08:46:56 INFO - 'ts_paint', 08:46:56 INFO - 'tpaint', 08:46:56 INFO - 'sessionrestore', 08:46:56 INFO - 'sessionrestore_no_auto_restore', 08:46:56 INFO - 'tabpaint']}, 08:46:56 INFO - 'other-e10s': {'tests': ['a11yr', 08:46:56 INFO - 'ts_paint', 08:46:56 INFO - 'tpaint', 08:46:56 INFO - 'sessionrestore', 08:46:56 INFO - 'sessionrestore_no_auto_restore', 08:46:56 INFO - 'tabpaint']}, 08:46:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:46:56 INFO - 'ts_paint', 08:46:56 INFO - 'tpaint', 08:46:56 INFO - 'sessionrestore', 08:46:56 INFO - 'sessionrestore_no_auto_restore', 08:46:56 INFO - 'tabpaint']}, 08:46:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:46:56 INFO - 'ts_paint', 08:46:56 INFO - 'tpaint', 08:46:56 INFO - 'sessionrestore', 08:46:56 INFO - 'sessionrestore_no_auto_restore', 08:46:56 INFO - 'tabpaint']}, 08:46:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:46:56 INFO - 'tests': ['a11yr', 08:46:56 INFO - 'ts_paint', 08:46:56 INFO - 'tpaint', 08:46:56 INFO - 'sessionrestore', 08:46:56 INFO - 'sessionrestore_no_auto_restore', 08:46:56 INFO - 'tabpaint']}, 08:46:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:46:56 INFO - 'tests': ['a11yr', 08:46:56 INFO - 'ts_paint', 08:46:56 INFO - 'tpaint', 08:46:56 INFO - 'sessionrestore', 08:46:56 INFO - 'sessionrestore_no_auto_restore', 08:46:56 INFO - 'tabpaint']}, 08:46:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:46:56 INFO - 'tests': ['tsvgx', 08:46:56 INFO - 'tsvgr_opacity', 08:46:56 INFO - 'tart', 08:46:56 INFO - 'tscrollx', 08:46:56 INFO - 'cart']}, 08:46:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:46:56 INFO - 'tsvgr_opacity', 08:46:56 INFO - 'tart', 08:46:56 INFO - 'tscrollx', 08:46:56 INFO - 'cart']}, 08:46:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:56 INFO - 'talos_options': ['--disable-e10s'], 08:46:56 INFO - 'tests': ['tp5o']}, 08:46:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:56 INFO - 'tests': ['tp5o']}, 08:46:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:56 INFO - 'talos_options': ['--disable-e10s', 08:46:56 INFO - '--xperf_path', 08:46:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:56 INFO - 'tests': ['tp5n']}, 08:46:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:56 INFO - 'talos_options': ['--xperf_path', 08:46:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:56 INFO - 'tests': ['tp5n']}}, 08:46:56 INFO - 'talos.zip': {'path': '', 08:46:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:46:56 INFO - Running post-action listener: _resource_record_post_action 08:46:56 INFO - ##### 08:46:56 INFO - ##### Running create-virtualenv step. 08:46:56 INFO - ##### 08:46:56 INFO - Running pre-action listener: _resource_record_pre_action 08:46:56 INFO - Running main action method: create_virtualenv 08:46:56 INFO - Creating virtualenv /builds/slave/test/build/venv 08:46:56 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 08:46:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:46:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:46:56 INFO - Using real prefix '/tools/python27' 08:46:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:46:57 INFO - Installing distribute.............................................................................................................................................................................................done. 08:47:00 INFO - Installing pip.................done. 08:47:00 INFO - Return code: 0 08:47:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:47:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:00 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 0x10e708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e718420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8301d6a330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7e00>, '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 0x10e3d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2b20>, '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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:47:00 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 08:47:00 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 08:47:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TPR8vDAqe/Render', 08:47:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:00 INFO - 'HOME': '/Users/cltbld', 08:47:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:00 INFO - 'LOGNAME': 'cltbld', 08:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:00 INFO - 'MOZ_NO_REMOTE': '1', 08:47:00 INFO - 'NO_EM_RESTART': '1', 08:47:00 INFO - 'PAGER': '/bin/cat', 08:47:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:00 INFO - 'PWD': '/builds/slave/test', 08:47:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:00 INFO - 'SHELL': '/bin/bash', 08:47:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YB8kVdMjE/Listeners', 08:47:00 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:47:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:00 INFO - 'USER': 'cltbld', 08:47:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:00 INFO - 'XPC_FLAGS': '0x0', 08:47:00 INFO - 'XPC_SERVICE_NAME': '0', 08:47:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:00 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 08:47:00 INFO - Cleaning up... 08:47:00 INFO - Return code: 0 08:47:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:47:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:00 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 0x10e708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e718420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8301d6a330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7e00>, '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 0x10e3d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2b20>, '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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:47:00 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 08:47:00 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 08:47:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TPR8vDAqe/Render', 08:47:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:00 INFO - 'HOME': '/Users/cltbld', 08:47:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:00 INFO - 'LOGNAME': 'cltbld', 08:47:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:00 INFO - 'MOZ_NO_REMOTE': '1', 08:47:00 INFO - 'NO_EM_RESTART': '1', 08:47:00 INFO - 'PAGER': '/bin/cat', 08:47:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:00 INFO - 'PWD': '/builds/slave/test', 08:47:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:00 INFO - 'SHELL': '/bin/bash', 08:47:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YB8kVdMjE/Listeners', 08:47:00 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:47:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:00 INFO - 'USER': 'cltbld', 08:47:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:00 INFO - 'XPC_FLAGS': '0x0', 08:47:00 INFO - 'XPC_SERVICE_NAME': '0', 08:47:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:00 INFO - Downloading/unpacking psutil>=0.7.1 08:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:47:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:47:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:47:03 INFO - Installing collected packages: psutil 08:47:03 INFO - Running setup.py install for psutil 08:47:03 INFO - building 'psutil._psutil_osx' extension 08:47:03 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 08:47:03 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 08:47:03 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 08:47:03 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 08:47:04 INFO - building 'psutil._psutil_posix' extension 08:47:04 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 08:47:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:47:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:47:04 INFO - ^ 08:47:04 INFO - 1 warning generated. 08:47:04 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 08:47:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:47:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:47:04 INFO - Successfully installed psutil 08:47:04 INFO - Cleaning up... 08:47:04 INFO - Return code: 0 08:47:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:47: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')]} 08:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47: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')]} 08:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47: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', '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 0x10e708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e718420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8301d6a330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7e00>, '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 0x10e3d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2b20>, '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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:47: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:47: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 mozsystemmonitor==0.0.0 08:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TPR8vDAqe/Render', 08:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:04 INFO - 'HOME': '/Users/cltbld', 08:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:04 INFO - 'LOGNAME': 'cltbld', 08:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:04 INFO - 'MOZ_NO_REMOTE': '1', 08:47:04 INFO - 'NO_EM_RESTART': '1', 08:47:04 INFO - 'PAGER': '/bin/cat', 08:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:04 INFO - 'PWD': '/builds/slave/test', 08:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:04 INFO - 'SHELL': '/bin/bash', 08:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YB8kVdMjE/Listeners', 08:47:04 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:04 INFO - 'USER': 'cltbld', 08:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:04 INFO - 'XPC_FLAGS': '0x0', 08:47:04 INFO - 'XPC_SERVICE_NAME': '0', 08:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:47: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 08:47: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 08:47: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 08:47: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 08:47: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 08:47: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 08:47:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:47:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:47:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:47:07 INFO - Installing collected packages: mozsystemmonitor 08:47:07 INFO - Running setup.py install for mozsystemmonitor 08:47:07 INFO - Successfully installed mozsystemmonitor 08:47:07 INFO - Cleaning up... 08:47:07 INFO - Return code: 0 08:47:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:47: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')]} 08:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47: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')]} 08:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:07 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 0x10e708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e718420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8301d6a330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7e00>, '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 0x10e3d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2b20>, '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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:47:07 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 08:47:07 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 08:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TPR8vDAqe/Render', 08:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:07 INFO - 'HOME': '/Users/cltbld', 08:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:07 INFO - 'LOGNAME': 'cltbld', 08:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:07 INFO - 'MOZ_NO_REMOTE': '1', 08:47:07 INFO - 'NO_EM_RESTART': '1', 08:47:07 INFO - 'PAGER': '/bin/cat', 08:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:07 INFO - 'PWD': '/builds/slave/test', 08:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:07 INFO - 'SHELL': '/bin/bash', 08:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YB8kVdMjE/Listeners', 08:47:07 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:07 INFO - 'USER': 'cltbld', 08:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:07 INFO - 'XPC_FLAGS': '0x0', 08:47:07 INFO - 'XPC_SERVICE_NAME': '0', 08:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:07 INFO - Downloading/unpacking blobuploader==1.2.4 08:47:07 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 08:47:07 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 08:47:07 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 08:47:07 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 08:47:07 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 08:47:07 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 08:47:09 INFO - Downloading blobuploader-1.2.4.tar.gz 08:47:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:47:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:47:10 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 08:47:10 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 08:47:10 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 08:47:10 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 08:47:10 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 08:47:10 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 08:47:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:47:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:47:10 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 08:47:10 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 08:47:10 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 08:47:10 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 08:47:10 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 08:47:10 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 08:47:11 INFO - Downloading docopt-0.6.1.tar.gz 08:47:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:47:11 INFO - Installing collected packages: blobuploader, requests, docopt 08:47:11 INFO - Running setup.py install for blobuploader 08:47:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:47:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:47:11 INFO - Running setup.py install for requests 08:47:11 INFO - Running setup.py install for docopt 08:47:12 INFO - Successfully installed blobuploader requests docopt 08:47:12 INFO - Cleaning up... 08:47:12 INFO - Return code: 0 08:47:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:47:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:12 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 0x10e708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e718420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8301d6a330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7e00>, '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 0x10e3d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2b20>, '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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:47:12 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 08:47:12 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 08:47:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TPR8vDAqe/Render', 08:47:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:12 INFO - 'HOME': '/Users/cltbld', 08:47:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:12 INFO - 'LOGNAME': 'cltbld', 08:47:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:12 INFO - 'MOZ_NO_REMOTE': '1', 08:47:12 INFO - 'NO_EM_RESTART': '1', 08:47:12 INFO - 'PAGER': '/bin/cat', 08:47:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:12 INFO - 'PWD': '/builds/slave/test', 08:47:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:12 INFO - 'SHELL': '/bin/bash', 08:47:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YB8kVdMjE/Listeners', 08:47:12 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:47:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:12 INFO - 'USER': 'cltbld', 08:47:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:12 INFO - 'XPC_FLAGS': '0x0', 08:47:12 INFO - 'XPC_SERVICE_NAME': '0', 08:47:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:47:12 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-jKlmsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:47:12 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-YXcHVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:47:12 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-rEpv6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:47:12 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-FDjjo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:47:12 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-NASNF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:47:12 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-cgBXQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:47:13 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-KlHMb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:47:13 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-HZB_Ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:47:13 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-vkh_jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:47:13 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-xIu3zF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:47:13 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-YH06TV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:47:13 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-g0W93g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:47:13 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-oE2M5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:47:13 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-joNBlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:13 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-hXd7KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:47:13 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-7CpF0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:47:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:47:14 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-n3WdGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:47:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:47:14 INFO - Running setup.py install for manifestparser 08:47:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:47:14 INFO - Running setup.py install for mozcrash 08:47:14 INFO - Running setup.py install for mozdebug 08:47:14 INFO - Running setup.py install for mozdevice 08:47:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:47:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:47:14 INFO - Running setup.py install for mozfile 08:47:14 INFO - Running setup.py install for mozhttpd 08:47:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:47:15 INFO - Running setup.py install for mozinfo 08:47:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:47:15 INFO - Running setup.py install for mozInstall 08:47:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:47:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:47:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:47:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:47:15 INFO - Running setup.py install for mozleak 08:47:15 INFO - Running setup.py install for mozlog 08:47:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:47:15 INFO - Running setup.py install for moznetwork 08:47:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:47:15 INFO - Running setup.py install for mozprocess 08:47:15 INFO - Running setup.py install for mozprofile 08:47:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:47:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:47:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:47:16 INFO - Running setup.py install for mozrunner 08:47:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:47:16 INFO - Running setup.py install for mozscreenshot 08:47:16 INFO - Running setup.py install for moztest 08:47:16 INFO - Running setup.py install for mozversion 08:47:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:47:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:47:16 INFO - Cleaning up... 08:47:16 INFO - Return code: 0 08:47:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47: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')]} 08:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47: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')]} 08:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:16 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 0x10e708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e718420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8301d6a330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7e00>, '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 0x10e3d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2b20>, '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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:47:16 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 08:47:16 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 08:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TPR8vDAqe/Render', 08:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:16 INFO - 'HOME': '/Users/cltbld', 08:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:16 INFO - 'LOGNAME': 'cltbld', 08:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:16 INFO - 'MOZ_NO_REMOTE': '1', 08:47:16 INFO - 'NO_EM_RESTART': '1', 08:47:16 INFO - 'PAGER': '/bin/cat', 08:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:16 INFO - 'PWD': '/builds/slave/test', 08:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:16 INFO - 'SHELL': '/bin/bash', 08:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YB8kVdMjE/Listeners', 08:47:16 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:16 INFO - 'USER': 'cltbld', 08:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:16 INFO - 'XPC_FLAGS': '0x0', 08:47:16 INFO - 'XPC_SERVICE_NAME': '0', 08:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:47:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Gj3iO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:47:17 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)) 08:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:47:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-vDhK8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:47:17 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)) 08:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:47:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-PsQ5dD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:47:17 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)) 08:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:47:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-S_P2t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:47:17 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)) 08:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:47:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-s4PzNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:47:17 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)) 08:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:47:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-SpDnNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:47:17 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)) 08:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:47:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-GgmlbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:47:17 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)) 08:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:47:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-bY75tl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:47:17 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)) 08:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:47:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ZHKDdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:47:17 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)) 08:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:47:17 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-rwDliv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:47:18 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)) 08:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:47:18 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-slkDs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:47:18 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)) 08:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:47:18 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-RZNEnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:47:18 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)) 08:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:47:18 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-KSmZrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:47:18 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)) 08:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:47:18 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-WEP6zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:47:18 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)) 08:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:18 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-vIscAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:47:18 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)) 08:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:47:18 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-7y0zIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:47:18 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)) 08:47:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:47:18 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Zw2XzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:47:18 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)) 08:47:18 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)) 08:47:18 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)) 08:47:18 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)) 08:47:18 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)) 08:47:18 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)) 08:47:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:21 INFO - Downloading blessings-1.6.tar.gz 08:47:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:47:21 INFO - Installing collected packages: blessings 08:47:21 INFO - Running setup.py install for blessings 08:47:21 INFO - Successfully installed blessings 08:47:21 INFO - Cleaning up... 08:47:21 INFO - Return code: 0 08:47:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:47:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:47:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:47:21 INFO - Reading from file tmpfile_stdout 08:47:21 INFO - Current package versions: 08:47:21 INFO - blessings == 1.6 08:47:21 INFO - blobuploader == 1.2.4 08:47:21 INFO - docopt == 0.6.1 08:47:21 INFO - manifestparser == 1.1 08:47:21 INFO - mozInstall == 1.12 08:47:21 INFO - mozcrash == 0.17 08:47:21 INFO - mozdebug == 0.1 08:47:21 INFO - mozdevice == 0.48 08:47:21 INFO - mozfile == 1.2 08:47:21 INFO - mozhttpd == 0.7 08:47:21 INFO - mozinfo == 0.9 08:47:21 INFO - mozleak == 0.1 08:47:21 INFO - mozlog == 3.1 08:47:21 INFO - moznetwork == 0.27 08:47:21 INFO - mozprocess == 0.22 08:47:21 INFO - mozprofile == 0.28 08:47:21 INFO - mozrunner == 6.11 08:47:21 INFO - mozscreenshot == 0.1 08:47:21 INFO - mozsystemmonitor == 0.0 08:47:21 INFO - moztest == 0.7 08:47:21 INFO - mozversion == 1.4 08:47:21 INFO - psutil == 3.1.1 08:47:21 INFO - requests == 1.2.3 08:47:21 INFO - wsgiref == 0.1.2 08:47:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:47: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')]} 08:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47: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')]} 08:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:21 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 0x10e708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e718420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8301d6a330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7e00>, '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 0x10e3d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2b20>, '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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:47:21 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 08:47:21 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 08:47:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TPR8vDAqe/Render', 08:47:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:21 INFO - 'HOME': '/Users/cltbld', 08:47:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:21 INFO - 'LOGNAME': 'cltbld', 08:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:21 INFO - 'MOZ_NO_REMOTE': '1', 08:47:21 INFO - 'NO_EM_RESTART': '1', 08:47:21 INFO - 'PAGER': '/bin/cat', 08:47:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:21 INFO - 'PWD': '/builds/slave/test', 08:47:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:21 INFO - 'SHELL': '/bin/bash', 08:47:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YB8kVdMjE/Listeners', 08:47:21 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:47:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:21 INFO - 'USER': 'cltbld', 08:47:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:21 INFO - 'XPC_FLAGS': '0x0', 08:47:21 INFO - 'XPC_SERVICE_NAME': '0', 08:47:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:22 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)) 08:47:22 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)) 08:47:22 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)) 08:47:22 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)) 08:47:22 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)) 08:47:22 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)) 08:47:22 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)) 08:47:22 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)) 08:47:22 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)) 08:47:22 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)) 08:47:22 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)) 08:47:22 INFO - Cleaning up... 08:47:22 INFO - Return code: 0 08:47:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e718420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8301d6a330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7e00>, '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 0x10e3d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2b20>, '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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:47:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:47:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TPR8vDAqe/Render', 08:47:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:22 INFO - 'HOME': '/Users/cltbld', 08:47:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:22 INFO - 'LOGNAME': 'cltbld', 08:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:22 INFO - 'MOZ_NO_REMOTE': '1', 08:47:22 INFO - 'NO_EM_RESTART': '1', 08:47:22 INFO - 'PAGER': '/bin/cat', 08:47:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:22 INFO - 'PWD': '/builds/slave/test', 08:47:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:22 INFO - 'SHELL': '/bin/bash', 08:47:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YB8kVdMjE/Listeners', 08:47:22 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:47:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:22 INFO - 'USER': 'cltbld', 08:47:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:22 INFO - 'XPC_FLAGS': '0x0', 08:47:22 INFO - 'XPC_SERVICE_NAME': '0', 08:47:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:22 INFO - Downloading/unpacking jsonschema 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:22 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 08:47:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:47:24 INFO - Downloading/unpacking functools32 (from jsonschema) 08:47:24 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 08:47:24 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 08:47:24 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 08:47:24 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 08:47:24 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 08:47:24 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 08:47:25 INFO - Downloading functools32-3.2.3-2.tar.gz 08:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:47:25 INFO - warning: no files found matching '*.txt' 08:47:25 INFO - no previously-included directories found matching 'build' 08:47:25 INFO - no previously-included directories found matching 'dist' 08:47:25 INFO - no previously-included directories found matching '.git*' 08:47:25 INFO - Installing collected packages: jsonschema, functools32 08:47:25 INFO - Running setup.py install for functools32 08:47:25 INFO - warning: no files found matching '*.txt' 08:47:25 INFO - no previously-included directories found matching 'build' 08:47:25 INFO - no previously-included directories found matching 'dist' 08:47:25 INFO - no previously-included directories found matching '.git*' 08:47:25 INFO - Successfully installed jsonschema functools32 08:47:25 INFO - Cleaning up... 08:47:25 INFO - Return code: 0 08:47:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:47:25 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')]} 08:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:25 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')]} 08:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:47:25 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 0x10e708a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e718420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8301d6a330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a7e00>, '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 0x10e3d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2b20>, '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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 08:47:25 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 08:47:25 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 08:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TPR8vDAqe/Render', 08:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:25 INFO - 'HOME': '/Users/cltbld', 08:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:25 INFO - 'LOGNAME': 'cltbld', 08:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:25 INFO - 'MOZ_NO_REMOTE': '1', 08:47:25 INFO - 'NO_EM_RESTART': '1', 08:47:25 INFO - 'PAGER': '/bin/cat', 08:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:25 INFO - 'PWD': '/builds/slave/test', 08:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:25 INFO - 'SHELL': '/bin/bash', 08:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YB8kVdMjE/Listeners', 08:47:25 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:25 INFO - 'USER': 'cltbld', 08:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:25 INFO - 'XPC_FLAGS': '0x0', 08:47:25 INFO - 'XPC_SERVICE_NAME': '0', 08:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:47:25 INFO - Downloading/unpacking flake8 08:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:47:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:47:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:47:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:47:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 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 08:47:28 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 08:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:47:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:47:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:47:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:47:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:47:29 INFO - no previously-included directories found matching 'docs/_build' 08:47:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:47:29 INFO - Running setup.py install for pep8 08:47:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:47:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:47:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:47:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:47:29 INFO - no previously-included directories found matching 'docs/_build' 08:47:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:47:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:47:29 INFO - Cleaning up... 08:47:29 INFO - Return code: 0 08:47:29 INFO - Running post-action listener: _resource_record_post_action 08:47:29 INFO - Running post-action listener: _start_resource_monitoring 08:47:29 INFO - Starting resource monitoring. 08:47:29 INFO - ##### 08:47:29 INFO - ##### Running install step. 08:47:29 INFO - ##### 08:47:29 INFO - Running pre-action listener: _resource_record_pre_action 08:47:29 INFO - Running main action method: install 08:47:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:47:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:47:30 INFO - Reading from file tmpfile_stdout 08:47:30 INFO - Detecting whether we're running mozinstall >=1.0... 08:47:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:47:30 INFO - Reading from file tmpfile_stdout 08:47:30 INFO - Output received: 08:47:30 INFO - Usage: mozinstall [options] installer 08:47:30 INFO - Options: 08:47:30 INFO - -h, --help show this help message and exit 08:47:30 INFO - -d DEST, --destination=DEST 08:47:30 INFO - Directory to install application into. [default: 08:47:30 INFO - "/builds/slave/test"] 08:47:30 INFO - --app=APP Application being installed. [default: firefox] 08:47:30 INFO - mkdir: /builds/slave/test/build/application 08:47:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:48:03 INFO - Reading from file tmpfile_stdout 08:48:03 INFO - Output received: 08:48:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:48:03 INFO - Running post-action listener: _resource_record_post_action 08:48:03 INFO - ##### 08:48:03 INFO - ##### Running run-tests step. 08:48:03 INFO - ##### 08:48:03 INFO - Running pre-action listener: _resource_record_pre_action 08:48:03 INFO - Running main action method: run_tests 08:48:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:48:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:48:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:48:03 INFO - 2016-04-14 08:48:03.702 screenresolution[1972:28011] starting screenresolution argv=screenresolution get 08:48:03 INFO - 2016-04-14 08:48:03.705 screenresolution[1972:28011] Display 0: 1600x1200x32@60 08:48:03 INFO - 2016-04-14 08:48:03.711 screenresolution[1973:28013] starting screenresolution argv=screenresolution list 08:48:03 INFO - Available Modes on Display 0 08:48:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:48:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:48:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:48:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:48:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:48:03 INFO - Intel Iris: 08:48:03 INFO - Chipset Model: Intel Iris 08:48:03 INFO - Type: GPU 08:48:03 INFO - Bus: Built-In 08:48:03 INFO - VRAM (Dynamic, Max): 1536 MB 08:48:03 INFO - Vendor: Intel (0x8086) 08:48:03 INFO - Device ID: 0x0a2e 08:48:03 INFO - Revision ID: 0x0009 08:48:03 INFO - Displays: 08:48:03 INFO - Display: 08:48:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:48:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:48:03 INFO - Display Serial Number: 1600x1200 60 08:48:03 INFO - Main Display: Yes 08:48:03 INFO - Mirror: Off 08:48:03 INFO - Online: Yes 08:48:03 INFO - Rotation: Supported 08:48:03 INFO - Return code: 0 08:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:48:03 INFO - Python 2.7.3 08:48:03 INFO - Return code: 0 08:48:03 INFO - grabbing minidump binary from tooltool 08:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:48:03 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 0x10e5a7e00>, '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 0x10e3d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:48:03 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 08:48:03 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 08:48:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:48:04 INFO - Return code: 0 08:48:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:48:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:48:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:48:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:48:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:48:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:48:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:48:05 INFO - Return code: 0 08:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--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 08:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --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 08:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0TPR8vDAqe/Render', 08:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:05 INFO - 'HOME': '/Users/cltbld', 08:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:05 INFO - 'LOGNAME': 'cltbld', 08:48:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:48:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:05 INFO - 'MOZ_NO_REMOTE': '1', 08:48:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:48:05 INFO - 'NO_EM_RESTART': '1', 08:48:05 INFO - 'PAGER': '/bin/cat', 08:48:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:05 INFO - 'PWD': '/builds/slave/test', 08:48:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:05 INFO - 'SHELL': '/bin/bash', 08:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2YB8kVdMjE/Listeners', 08:48:05 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 08:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:05 INFO - 'USER': 'cltbld', 08:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:05 INFO - 'XPC_FLAGS': '0x0', 08:48:05 INFO - 'XPC_SERVICE_NAME': '0', 08:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--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 08:48:05 INFO - mozversion application_buildid: 20160414074538 08:48:05 INFO - mozversion application_changeset: 995e7890dd613843c3914a1d9d46676f400152c1 08:48:05 INFO - mozversion application_display_name: Nightly 08:48:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:48:05 INFO - mozversion application_name: Firefox 08:48:05 INFO - mozversion application_remotingname: firefox 08:48:05 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:48:05 INFO - mozversion application_vendor: Mozilla 08:48:05 INFO - mozversion application_version: 48.0a1 08:48:05 INFO - mozversion platform_buildid: 20160414074538 08:48:05 INFO - mozversion platform_changeset: 995e7890dd613843c3914a1d9d46676f400152c1 08:48:05 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:48:05 INFO - mozversion platform_version: 48.0a1 08:48:05 INFO - using testdate: 1460648885 08:48:05 INFO - actual date: 1460648885 08:48:05 INFO - starting webserver on 'localhost:52675' 08:48:05 INFO - SUITE-START | Running 2 tests 08:48:05 INFO - TEST-START | dromaeo_css 08:48:05 INFO - operating with platform_type : mac_ 08:48:05 INFO - Initialising browser for dromaeo_css test... 08:48:05 INFO - TEST-INFO | started process 1991 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmp2bBki3/profile http://localhost:52675/getInfo.html) 08:48:07 INFO - PROCESS | 1991 | __metrics Screen width/height:1600/1200 08:48:07 INFO - PROCESS | 1991 | colorDepth:24 08:48:07 INFO - PROCESS | 1991 | Browser inner width/height: 1024/689 08:48:07 INFO - PROCESS | 1991 | __metrics 08:48:07 INFO - PROCESS | 1991 | 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" 08:48:07 ERROR - PROCESS | 1991 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:48:08 INFO - TEST-INFO | 1991: exit 0 08:48:08 INFO - Browser initialized. 08:48:08 INFO - Running cycle 1/1 for dromaeo_css test... 08:48:08 INFO - TEST-INFO | started process 1993 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmp2bBki3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:48:20 INFO - PROCESS | 1993 | RSS: Main: 237862912 08:48:20 INFO - PROCESS | 1993 | 08:49:11 INFO - PROCESS | 1993 | 1460648951925 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] 08:51:22 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52675/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52675/tests/dromaeo/cssquery-ext.html) 08:51:23 INFO - PROCESS | 1993 | RSS: Main: 361467904 08:51:23 INFO - PROCESS | 1993 | 08:54:26 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52675/tests/dromaeo/cssquery-ext.html (next: http://localhost:52675/tests/dromaeo/cssquery-jquery.html) 08:54:27 INFO - PROCESS | 1993 | RSS: Main: 373858304 08:54:27 INFO - PROCESS | 1993 | 08:57:29 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52675/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52675/tests/dromaeo/cssquery-mootools.html) 08:57:30 INFO - PROCESS | 1993 | RSS: Main: 373460992 08:57:30 INFO - PROCESS | 1993 | 09:00:32 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52675/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52675/tests/dromaeo/cssquery-prototype.html) 09:00:32 INFO - PROCESS | 1993 | RSS: Main: 373903360 09:00:32 INFO - PROCESS | 1993 | 09:03:35 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52675/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52675/tests/dromaeo/cssquery-yui.html) 09:03:36 INFO - PROCESS | 1993 | RSS: Main: 341516288 09:03:36 INFO - PROCESS | 1993 | 09:06:39 INFO - PROCESS | 1993 | Cycle 1(1): loaded http://localhost:52675/tests/dromaeo/cssquery-yui.html (next: http://localhost:52675/tests/dromaeo/cssquery-dojo.html) 09:06:39 INFO - PROCESS | 1993 | RSS: Main: 382181376 09:06:39 INFO - PROCESS | 1993 | 09:06:39 INFO - PROCESS | 1993 | __start_tp_report 09:06:39 INFO - PROCESS | 1993 | _x_x_mozilla_page_load 09:06:39 INFO - PROCESS | 1993 | _x_x_mozilla_page_load_details 09:06:39 INFO - PROCESS | 1993 | |i|pagename|runs| 09:06:39 INFO - PROCESS | 1993 | |0;dojo.html;3361.9495707564392;3413.505041768942;3445.7071148952346;3455.1016735648755;3471.722262219021;5533.0870406383;5615.971920140399;5620.494155526002;5628.015097357961;5647.237622920905;5619.01667208239;5622.437756224375;5706.1440783882435;5750.338711048231;5770.971145144268;5073.061483625523;5090.440051594322;5120.846374608749;5132.486751324875;5209.270702101709;5177.896442071156;5190.2474141249495;5222.399424066243;5222.712750798704;5262.552683021948;4998.950010499885;5145.51117643827;5148.330717006803;5165.793368265265;5169.844904652885;5142.794288228466;5155.536001759822;5168.3281173447585;5172.896542069175;5180.274761533388;4165.958340416587;5080.390353157608;5128.461511541272;5131.102057140023;5134.2812006319145;5549.944500555023;5550.47270509301;5561.638493497951;5599.860003499904;5620.988222120017;6065.272167339947;6130.417610327012;6138.631682099043;6153.661548614825;6200.3179650238435;6078.300995385499;6117.541184411142;6159.538034647419;6162.7226774795245;6172.7530898764;5605.523530499871;5666.46168613981;5670.319561652545;5672.886542269172;5706.857328566736;6229.688515574203;6285.277193122758;6294.213223347082;6311.715972781236;6338.239411270585;6093.4515893568905;6113.358097399709;6118.449339559461;6122.724477398528;6123.081537769282;6095.817125486243;6240.625562466266;6241.438270555581;6255.906161407583;6258.374162583706;6079.209702738615;6119.877602447926;6168.537359698041;6170.167027451326;6180.81457556274;6182.629042257479;6196.907046394308;6200.782972595937;6212.9378706213265;6220.191375121296;4760.214564596803;4889.926651100236;4892.706437613754;4898.118338698997;4931.137051016058;4336.9132617348105;4409.735415875058;4412.84555040572;4450.354698568754;4496.887577810581;5607.943920560742;5630.465105814941;5665.518430933417;5673.205751194836;5684.8863022739315;6107.206063211754;6232.345603711636;6250;6265.749370025148;6271.184745983084;5648.915266271009;5684.118961560965;5711.257536520226;5725.31296244445;5753.654780713171;5342.2253773202665;5359.624826262124;5365.892682146336;5366.382865970463;5379.73101344939;5197.376314842243;6244.469220116341;6348.079528468438;6350.936490635219;6397.360263973565;6043.758249669964;6117.9187529241;6118.785842495491;6127.494965205145;6144.53915956314;6239.2667438795625;6281.466075383643;6297.685115744286;6313.273973492924;6323.747050117944;5946.13781001761;5954.851128721643;5981.7308221131025;5987.371326010706;6034.818955431344;6233.438990490878;6238.844028899314;6241.750329986749;6246.718897649707;6251.374862513894;6027.126066720389;6044.637321760709;6058.394160583906;6061.969690151521;6068.817935461943;5916.142159386951;5987.341392446914;6078.270607527125;6093.573449858467;6121.816345509642;5790.218320526844;5812.680302583399;5822.184894114912;5829.7085145743395;5859.794907178229;4657.743824089707;5988.191594134736;6006.969965150146;6032.1856549365275;6079;5771.307443106854;5815.2149459822385;5833.620814647034;5924.881502369843;5939.881202375842 09:06:39 INFO - PROCESS | 1993 | |1;ext.html;495.10633306881067;498.5986281043761;531.3225637312427;573.3893403525245;574.6322353693637;29503;37597.24805503889;38286.61713382865;38339.42490862636;38357.61642383575;41584.79207603958;42035.36946945798;42093;42095.78952105235;42225;8552.743417697478;9612.423254604728;9859.802803943916;9865.950670246639;9867.555959981783;8689.826203475926;11584.47869845859;11876.821847672292;11881.940590297036;11882.405879706022;9784.266180036526;11585.942070289679;11696.7660646787;11701.941490292536;11703.94148029263;8637.265832404253;10481.737956551071;11066.446677666125;11517.48171332292;11637.47631356591;11447.599334023353;11457.083433325313;11653.184277100607;11653.883461165364;11658.708532286675;32665.83667081673;40223.793286201464;40319.39520907189;40319.79840100781;40331.79004629866;2915.702512381991;3163.4622114240633;3247.0908145719236;3375.5949286085583;3504.702100321476;28292;29224;29462.410751784846;29530.114096577137;29690.85154574235;2675.745804148603;2682.3964607963053;2698.9190324290303;2775.320046588599;2934.633170853643;517322;534312;535762;536735.3163234198;537291;56287;56426;57128.85742285131;57158.7142064287;57161;50485.747571261905;61924.38075619186;66441.6677916617;68654;68740.6562967182;16279.51161465158;16517.256723447474;16520.42178523746;16528.173591320385;16536.173191340626;11658.417079146178;11861.169718119649;12512.874871251353;12627.873721262853;12849.807252891214;15344.693106137815;18961.24155033811;18985.335513257247;19056.61886762285;19118.94845783495;7869.213078692085;8079.878801817978;8099.716509922125;8158.632861521246;8206;807.11620775251;905.524599585223;917.2891009467716;1015.9593616255415;1046.8063408269343;23065.88467057654;23091.769082309296;26216.73783262143;28920.710792892223;29561.408771824445;1078.4823284823328;1083.6586475260267;1105.4748994227677;1111.4220605285207;1180.722530205401;14977.251137443085;15063.610687595203;15085.54743357701;15327;15423.845761542241;627719;634214;637020;637316;638432.8078359579;49818.750906245965;59314;59545.404545953985;59558.70220648956;59673.40326596679;1321.5308565459125;1349.1230700044853;1427.5717284814307;1458.183417286323;1501.9774703379458;28317.433651327272;28415.857920710263;29065;29162.27094322574;29178.56232156477;47387.289190661475;56880.71559642341;58508.70745646244;58587.414125858195;59207.703961479914;441114;475286;579636;579671;580569;33971.320573587895;35619.82190089033;41881.79059104685;42589.46336976173;42679.57320426756;41950.37074443947;41965.58034419617;42366.669833159474;42677;42768.57231427646;26676.866615666797;35232.823835880656;35460.82269588635;36087.63912360948;36208;35008;35164.47253291152;36458.54165833337;37398;37399;36385.090372740895;36526;36792.81603591965;37026;37575.43636845395;31084.378312434077;32211.838940805144;32229.677703223606;32746.836265818518;32805.83597081999;24155.275341739776;24300.027998880552;24461.388465287797;24770.876145619877;24889 09:06:39 INFO - PROCESS | 1993 | |2;jquery.html;308.7576252641676;366.35155774279525;370.2410059378272;372.4878292348021;388.1770646229993;19503.804961950376;19643.508912277182;20324.59350812983;21037;21052.8947355263;19226.71159932602;20128.899355503203;20470.897645511788;20588.794112058877;20593.794062059413;5096.38843338799;5099.923501147486;5262.342207224097;5299.973500132494;5306.522412982831;5828.825135245949;5856.180134781134;5881.264841894763;5883.441073098049;5904.88190236195;5225.503577160163;5278.577713782907;5608.569814697246;5823.621464604808;5842.64944103353;5206.713630750307;5246.81636142737;5255.237990491377;5266;5267.55225805807;4091.631753142202;5079.542841144278;5119.616028797818;5126.051680439131;5127.359080114986;12736.936315318457;12881.420336084899;12917.87082129176;12948.741025179537;20730.48173795652;1988.8707234029741;2233.2072114399316;2351.400392899817;2384.6423036544656;2469.851808891472;9767.120959113643;9795.971423000625;9934.758155230596;10041.397516149054;10052.44711540865;1664.5672125247402;1711.700452420834;1750.3086280919092;1766.5936834528002;1767.6022894848684;73483.63258183675;102438;102951.97048029574;110172.4491377546;117761.41119294435;7766.456348055582;8999.730008099768;9704.854427183598;9717.12545870875;9845.50772461374;11019.008289254005;11576.726560078385;12217.572384966661;12326.445309961073;12979.480820767252;6437.710303036374;6954.3393377629045;8401.243888049983;8465.957670211732;8585.441946273471;8417.28453081495;8418.579071046546;8434.409591328671;8556.315494760403;8560.186782255676;4323.524412314643;4407.0524837160165;4424.668149888771;4462.866114016585;4464.2633965396;1838.3106335124328;1906;1910.5414700472065;1918.2064769440617;1950.3856285270379;867.1891781184613;908.0964440381862;942.2320808541017;979.3095867413457;1017.9185665146771;16044.919775401047;19514.21943122259;20023.29918452876;20082.39752807418;20354.89822550878;334.2329354132272;391.2468498141067;391.7629833950419;405.59643155060905;426.51803462087645;5374.1938709193155;5715.742791574389;5737.65574065549;10557.9472102639;11237.887621123684;549086;622767;636836;638124.8093759501;638424;134811.17077121706;137114;137602;140126.59873401135;140848.32365087193;400.6554363247605;409.2531130686509;425.97870106494554;439.40460675784493;441.67978215793937;8408.53753043564;9647.9035209647;9703.708888733348;10160;10251.538680759235;45971;47036.82407939829;47053.76473117749;49202.50797491979;49588.760280993265;55603.053893113545;64907;67144.65710685661;68028;71822.64088679523;7881.211878812073;7953.244441778022;8033.477823941425;8046.155153708776;8317.916820831957;10774;11827.586034489095;12236.265824050586;12669.619911402673;12897.097203196061;10671.519781609692;10893.564257429614;10978.57035815994;10985.121190304917;11303.208775385645;10657.786844262917;10760.3543787373;10884.673459796219;10907;11083.002529772359;8291;10531.368117912949;11194.104471642086;11230.464846716392;11323;7226.638668066471;7867.291943724863;7951.681932722627;8177.264046235868;8184.918150818416;7883.881741773764;10397.688069357931;10773.838392424277;11109.611163609236;11123.054540364159 09:06:39 INFO - PROCESS | 1993 | |3;mootools.html;1397.7903314502817;1580.5811459963109;1592.2436842499815;1842.6867432536467;1974.0721860725464;7766.883496747546;8052.718154864581;8056.234657707523;8063.435559510836;8416.957915210416;8395.664173433055;8492.320614350852;8562.65749370026;8581.742547723563;8593.570321483932;2382.5711371953084;2410.517896420714;2411.6141417373224;2411.8914648840755;2412.4089598048504;1931.8261356477915;2303.1823702585593;2392.35406440261;2392.4856155926473;2395.2574701842395;2381.5832229359794;2402.2192787344097;2405.747396523366;2410.0600765701392;2410.710714714237;2342.4846533762543;2385.630227314769;2390.521895620874;2396.341006223285;2398.5202959408016;2114.5453727448516;2331.2889568681544;2405.675233843426;2412.2280870121567;2412.372783076395;8490.235878770878;8561.1866872647;8561.700340488116;8568.571571421404;8568.828623427496;4842.951570484321;5038.622103342264;5049.2173713074535;5074.2895994560795;5077.669951453144;3011.337505748732;3371.76397652162;3419.4870769384793;3442.5868895732674;3478.9826050869838;937.2548823685187;975.3465178330536;988.5897352598663;989.0258095775655;990.6483198464514;9455.385399948982;11329.546818127264;11340.489677964511;11360.545578176945;11360.602378916697;4522.570355816192;4576.702514336557;4701.388819453449;4707.340972263886;4765.451973023078;9689.90310096904;9706.563204655808;9709.36889102206;9712.417254964725;9722.416655000723;5258.053550360972;5273.841784746463;5296.152615581565;5299.761510732026;5306.204069389622;4378.299472084451;4745.596624286905;4746.691465054761;4814.735189564538;4828.686135401188;3643.981780091083;3867.690584753213;3922.156736301709;3942.743721658083;3961.623645753649;1255.893249073839;1281.1096288079882;1281.858995510493;1291.5737806523825;1298.9155704879154;706.6325510734389;863.8920134983127;911.2209061252667;921.3182245138684;931.4039015030386;5552.305961754781;5665.263515742927;5804.709764511842;5851.444112809277;5933.160720176759;877.0221203358337;888.4757992784288;893.7765558610347;922.4004397141912;941.2611100286242;3776.15036616759;3899.4735710678697;3944.230874979409;3979.940300895431;3989.740666856645;8694.695685650971;10183.847242291371;10569.101626361691;10613.363198208132;10674.199435042403;10226.488675566192;10546.525406356732;10607;10617.309874858263;10712.410817404962;7846.9215307847335;7921.762347129596;8036.919630803734;8053.919460805434;8132.796680083045;5414.512693857571;5577.9401913636275;5587.720613969366;5667.631603945731;5680.943190568207;9834.75413114678;9852.605895764376;9901.900980990098;9917.30578859473;9929.553170107218;9545.809083818423;9755.9024409755;10098.646547370807;10167.491625418847;10169.389836609827;5398.865028374322;6709.798706038827;6996.685149168198;7390.187079421152;7446.14369347532;6874.8625027500175;8022.398320125897;8273.83452330962;8288.295494883003;8301.045379781403;7036.120484939383;7134.108236470442;7142.964285178541;7244.891326630211;7272.854542909006;6956.513044086865;6996.860062798817;7007.894881576883;7127.46544009191;7137.2149063604;6906.894896816485;6928.1339832520935;6983.301669832976;7092.929070709433;7093.1488221413765;4718.929216061831;4731.858044258678;4750.904981900274;4759.428868535798;4861.173600487855;4742.075295317518;4772.475027746876;4817.421909370898;4857.9028419432125;4947.035328110984 09:06:39 INFO - PROCESS | 1993 | |4;prototype.html;289.76239483623425;299.1026919242273;304.85671734284887;311.2824938516718;311.65675936712756;4568.565986231302;4921.507849215077;5092.363454568179;5848.093545500449;5912.172295878575;5521.5306698930535;5540.695261760601;5733.254676891998;5741.425857414257;5832.046264501397;4270.615644591986;4284.143171365731;4301.053768171013;4309.655227581801;4315.755806980384;3686.4838922550866;3832.9808350958206;4291.291936830435;4297.892552686177;4307.892302692427;4034.616711412411;4254.340577210537;4293.484781826185;4307.440032795748;4322.178786030644;3528.3825330567047;3968.9206215875665;4110.301248787713;4265.1469706058915;4342.500612429548;3617.5478065241846;3652.2651740423353;3671.0271777978855;3674.191677830873;3674.3386190485703;4655.883602909955;5519.200578135521;5626.156076588503;5658.886822263532;5689.288838895138;4337.956620433787;4939.185034469308;4939.629527785431;4959.950400496021;4967.55292023716;4563.589276965055;5227.529522343008;6223.564350495467;6239.157713708682;6343.809685709436;3921.8823435296986;4044.737092089005;4049.3521036634293;4073.6537394321517;4113.341865301538;6199.814005579839;6546.705398257042;6604.570702904296;6612.669366531654;6628.834279143011;4129.132882094734;4133.648639865645;4142.46148000758;4146.1914926589025;4155.709100363006;3715.758475699071;4077.2864748668862;4145.440365550672;4212.346221880196;4229.323308270724;4923.507649235048;4939.987302602958;4953.455119936804;4966.255061740696;4974.27872958419;6211.3167551569295;6287.182666253358;6293.622382657055;6296.65368404733;6325.557210995278;1391.6451304917384;1407.7254935287726;1437.3962935567088;1443.501991812823;1453.149907304239;890.9866352004725;916.1983264643437;924.3160061554402;934.9579268932914;938.5025936253796;4732.503087175797;5378.354597448253;5598.932965666078;5801.926643570953;5903.734331955073;6459.806205813833;6467.547271690936;6487.253965793994;6490.545661803723;6503.902441463382;5169.767360468788;5196.402413722471;5486.231927530149;5583.581231407661;5965.79119730816;2864.1550742531003;2892.8842846286334;3386.170388254844;3453.4819777033645;3455.3759732925987;5995.430434108753;6561.475081993429;6840.418564421981;6845.965770171118;6856.417204537571;6683;6693.732250710014;6703.564268322543;6718.529702920749;6740.831479213059;6040.758369665252;6454.967725161344;6606.735730570916;6801.319868013159;6854.9657251713425;5275.709835958905;5602.4957753802355;6517.4460170886005;6525.673716314261;6557.639329836714;6356.300806911144;6415.369698423624;6424.550281480251;6443.903341449788;6466.4503517201565;5499.697516636464;6190.845228869134;6532.314107018695;6834.179898412222;6842.863142737217;3897.9610203897596;4041.2927737646382;4105.794710264535;4114.321137012359;4146.460960075292;6038.426349496791;6293.937060629335;6303.109067279706;6319.178506794087;6327.209098862642;4801.447833499193;5469.288992430958;6273.215848018997;6335.461485773577;6352.237731472253;5458.836234912959;5989.609252144715;6293.622382657055;6355.809325720235;6371.39471750183;5997.520198384032;6145.877082458415;6158.784442544489;6180.536459765445;6197.287311959182;5957.514299025598;6273.15312432834;6291.465225455888;6298.7480500781285;6313.40022697843;6154.261488621394;6167.352427994996;6187.659678717714;6188.319284878749;6211.81364559064 09:06:39 INFO - PROCESS | 1993 | |5;yui.html;649.5583003557581;670.9530332876694;681.8704446155228;695.6695569604439;718.4180813541033;13521.864781352184;14870.553883383502;15354.539363819076;15363.769543456843;15417.074975501464;13823.585292441243;14028.929855350709;14079.225642589654;14179.574612761608;14193.858061419383;906.1482206725657;907.2107266677983;912.995704724804;914.3188324698109;915.8717779510873;721.3363705391052;739.460194058335;743.3124359967036;745.7352639812842;746.7311767763628;936.6159874451477;979.7158823941085;981.4405788700443;981.617169303972;984.7242772023809;954.6706386296717;967.0861036320645;967.2842096848317;969.4474149734654;970.1268858027761;552.353746117044;695.7947405515386;709.893515972603;714.7105422303923;716.4841314253781;15067.698646027127;15128.243587820565;15134.924325378413;15158;15167.469138580207;12560.560380386643;12573.622791316275;12594;12710.555130570385;12772.744545109139;9462.911765146959;9731.41611503312;10009.649662261858;10109.494525273707;10404.635837745718;3485.2680937003006;3506.8246587670515;3516.8241587920757;3572.7677700949616;3609.8736544221088;78230;79523.60238198772;81170;81343;81544.59227703883;9404.529773511296;9504.667336643322;9604.183644390165;9709.902900971041;9777.511124443748;21840;21857.344279671634;21925.561488770134;21926.23258185956;21937.122515099538;7344.853102937911;7517.135529414078;7522.988562756044;7524.097108347033;7624.8475030500185;3957.584453632385;4030.334994725896;4033.4756481657196;4038.1318016626565;4043.2722110020477;3483.111806489329;3596.2627661329366;3602.081469225383;3603.9819800900827;3605.837737301828;548.2954403591445;618.2827919613227;631.5926227583183;647.899575565788;659.0641289369107;508.8651507350555;510.8084468324378;528.5853387119176;595.247012529151;611.911272865432;7872.173421790744;7878.842423151505;7926.048874135141;8070.959645201737;8099.392545559107;562.4684672983991;566.393958464439;580.2398857496693;590.4980766348655;599.3826358850391;3675.044488433027;3853.190829925691;3891.6886649068006;3905.5313362396696;3950.1309711863346;86846.69729954055;86923.56538217269;87804;88173.11826881778;88857;74719.62640186764;74983;75839;76226;76342;65209;66423;67294;67751.66124169348;68241.3175868235;7470.514416562906;7544.321011109027;7676.385889128745;7683.884741728991;7744.961275193588;32256.838715806272;40879.59120408758;42619.360709588764;42833.57166428296;43432.34851477167;61699.07451388323;61989.380106200166;62167.689161553906;62251.688741556005;62301.37698623137;9894.406335619886;9894.604215831574;10724.195685323788;10887;11042.282251653616;11729.237599556001;11939.283642981449;12294.323812190416;12327.815082773586;12742.872571274169;11198.60804871858;11339.206255562362;11356.94321528387;11363.090952723596;11426.2001659883;11173.608923687632;11203.823598522038;11218.270812397168;11236.775264494829;11411.858814118521;10053.69838904834;10466.743990720857;11072.557097716;11115.11079113685;12278.570250041206;8988.910110898807;9400.717978460658;9502.861856082327;9505.904940950502;9872.35829671069;9249;9640.325177237526;9655.420674759536;9677.612895484102;9712.223022158352 09:06:39 INFO - PROCESS | 1993 | __end_tp_report 09:06:39 INFO - PROCESS | 1993 | __start_cc_report 09:06:39 INFO - PROCESS | 1993 | _x_x_mozilla_cycle_collect,1153 09:06:39 INFO - PROCESS | 1993 | __end_cc_report 09:06:39 INFO - PROCESS | 1993 | __startTimestamp1460649999414__endTimestamp 09:06:39 INFO - PROCESS | 1993 | 09:06:39 INFO - PROCESS | 1993 | ------- Summary: start ------- 09:06:39 INFO - PROCESS | 1993 | Number of tests: 6 09:06:39 INFO - PROCESS | 1993 | 09:06:39 INFO - PROCESS | 1993 | [#0] dojo.html Cycles:165 Average:5675.03 Median:5829.71 stddev:637.19 (10.9%) stddev-sans-first:612.76 09:06:39 INFO - PROCESS | 1993 | Values: 3361.9 3413.5 3445.7 3455.1 3471.7 5533.1 5616.0 5620.5 5628.0 5647.2 5619.0 5622.4 5706.1 5750.3 5771.0 5073.1 5090.4 5120.8 5132.5 5209.3 5177.9 5190.2 5222.4 5222.7 5262.6 4999.0 5145.5 5148.3 5165.8 5169.8 5142.8 5155.5 5168.3 5172.9 5180.3 4166.0 5080.4 5128.5 5131.1 5134.3 5549.9 5550.5 5561.6 5599.9 5621.0 6065.3 6130.4 6138.6 6153.7 6200.3 6078.3 6117.5 6159.5 6162.7 6172.8 5605.5 5666.5 5670.3 5672.9 5706.9 6229.7 6285.3 6294.2 6311.7 6338.2 6093.5 6113.4 6118.4 6122.7 6123.1 6095.8 6240.6 6241.4 6255.9 6258.4 6079.2 6119.9 6168.5 6170.2 6180.8 6182.6 6196.9 6200.8 6212.9 6220.2 4760.2 4889.9 4892.7 4898.1 4931.1 4336.9 4409.7 4412.8 4450.4 4496.9 5607.9 5630.5 5665.5 5673.2 5684.9 6107.2 6232.3 6250.0 6265.7 6271.2 5648.9 5684.1 5711.3 5725.3 5753.7 5342.2 5359.6 5365.9 5366.4 5379.7 5197.4 6244.5 6348.1 6350.9 6397.4 6043.8 6117.9 6118.8 6127.5 6144.5 6239.3 6281.5 6297.7 6313.3 6323.7 5946.1 5954.9 5981.7 5987.4 6034.8 6233.4 6238.8 6241.8 6246.7 6251.4 6027.1 6044.6 6058.4 6062.0 6068.8 5916.1 5987.3 6078.3 6093.6 6121.8 5790.2 5812.7 5822.2 5829.7 5859.8 4657.7 5988.2 6007.0 6032.2 6079.0 5771.3 5815.2 5833.6 5924.9 5939.9 09:06:39 INFO - PROCESS | 1993 | 09:06:39 INFO - PROCESS | 1993 | [#1] ext.html Cycles:180 Average:69827.52 Median:35346.82 stddev:152099.28 (430.3%) stddev-sans-first:152436.88 09:06:39 INFO - PROCESS | 1993 | Values: 495.1 498.6 531.3 573.4 574.6 29503.0 37597.2 38286.6 38339.4 38357.6 41584.8 42035.4 42093.0 42095.8 42225.0 8552.7 9612.4 9859.8 9866.0 9867.6 8689.8 11584.5 11876.8 11881.9 11882.4 9784.3 11585.9 11696.8 11701.9 11703.9 8637.3 10481.7 11066.4 11517.5 11637.5 11447.6 11457.1 11653.2 11653.9 11658.7 32665.8 40223.8 40319.4 40319.8 40331.8 2915.7 3163.5 3247.1 3375.6 3504.7 28292.0 29224.0 29462.4 29530.1 29690.9 2675.7 2682.4 2698.9 2775.3 2934.6 517322.0 534312.0 535762.0 536735.3 537291.0 56287.0 56426.0 57128.9 57158.7 57161.0 50485.7 61924.4 66441.7 68654.0 68740.7 16279.5 16517.3 16520.4 16528.2 16536.2 11658.4 11861.2 12512.9 12627.9 12849.8 15344.7 18961.2 18985.3 19056.6 19118.9 7869.2 8079.9 8099.7 8158.6 8206.0 807.1 905.5 917.3 1016.0 1046.8 23065.9 23091.8 26216.7 28920.7 29561.4 1078.5 1083.7 1105.5 1111.4 1180.7 14977.3 15063.6 15085.5 15327.0 15423.8 627719.0 634214.0 637020.0 637316.0 638432.8 49818.8 59314.0 59545.4 59558.7 59673.4 1321.5 1349.1 1427.6 1458.2 1502.0 28317.4 28415.9 29065.0 29162.3 29178.6 47387.3 56880.7 58508.7 58587.4 59207.7 441114.0 475286.0 579636.0 579671.0 580569.0 33971.3 35619.8 41881.8 42589.5 42679.6 41950.4 41965.6 42366.7 42677.0 42768.6 26676.9 35232.8 35460.8 36087.6 36208.0 35008.0 35164.5 36458.5 37398.0 37399.0 36385.1 36526.0 36792.8 37026.0 37575.4 31084.4 32211.8 32229.7 32746.8 32805.8 24155.3 24300.0 24461.4 24770.9 24889.0 09:06:39 INFO - PROCESS | 1993 | 09:06:39 INFO - PROCESS | 1993 | [#2] jquery.html Cycles:180 Average:33848.76 Median:2258.81 stddev:103001.07 (4560.0%) stddev-sans-first:103259.23 09:06:39 INFO - PROCESS | 1993 | Values: 308.8 366.4 370.2 372.5 388.2 19503.8 19643.5 20324.6 21037.0 21052.9 19226.7 20128.9 20470.9 20588.8 20593.8 5096.4 5099.9 5262.3 5300.0 5306.5 5828.8 5856.2 5881.3 5883.4 5904.9 5225.5 5278.6 5608.6 5823.6 5842.6 5206.7 5246.8 5255.2 5266.0 5267.6 4091.6 5079.5 5119.6 5126.1 5127.4 12736.9 12881.4 12917.9 12948.7 20730.5 1988.9 2233.2 2351.4 2384.6 2469.9 9767.1 9796.0 9934.8 10041.4 10052.4 1664.6 1711.7 1750.3 1766.6 1767.6 73483.6 102438.0 102952.0 110172.4 117761.4 7766.5 8999.7 9704.9 9717.1 9845.5 11019.0 11576.7 12217.6 12326.4 12979.5 6437.7 6954.3 8401.2 8466.0 8585.4 8417.3 8418.6 8434.4 8556.3 8560.2 4323.5 4407.1 4424.7 4462.9 4464.3 1838.3 1906.0 1910.5 1918.2 1950.4 867.2 908.1 942.2 979.3 1017.9 16044.9 19514.2 20023.3 20082.4 20354.9 334.2 391.2 391.8 405.6 426.5 5374.2 5715.7 5737.7 10557.9 11237.9 549086.0 622767.0 636836.0 638124.8 638424.0 134811.2 137114.0 137602.0 140126.6 140848.3 400.7 409.3 426.0 439.4 441.7 8408.5 9647.9 9703.7 10160.0 10251.5 45971.0 47036.8 47053.8 49202.5 49588.8 55603.1 64907.0 67144.7 68028.0 71822.6 7881.2 7953.2 8033.5 8046.2 8317.9 10774.0 11827.6 12236.3 12669.6 12897.1 10671.5 10893.6 10978.6 10985.1 11303.2 10657.8 10760.4 10884.7 10907.0 11083.0 8291.0 10531.4 11194.1 11230.5 11323.0 7226.6 7867.3 7951.7 8177.3 8184.9 7883.9 10397.7 10773.8 11109.6 11123.1 09:06:39 INFO - PROCESS | 1993 | 09:06:39 INFO - PROCESS | 1993 | [#3] mootools.html Cycles:180 Average:5542.91 Median:5167.86 stddev:3079.91 (59.6%) stddev-sans-first:3072.80 09:06:39 INFO - PROCESS | 1993 | Values: 1397.8 1580.6 1592.2 1842.7 1974.1 7766.9 8052.7 8056.2 8063.4 8417.0 8395.7 8492.3 8562.7 8581.7 8593.6 2382.6 2410.5 2411.6 2411.9 2412.4 1931.8 2303.2 2392.4 2392.5 2395.3 2381.6 2402.2 2405.7 2410.1 2410.7 2342.5 2385.6 2390.5 2396.3 2398.5 2114.5 2331.3 2405.7 2412.2 2412.4 8490.2 8561.2 8561.7 8568.6 8568.8 4843.0 5038.6 5049.2 5074.3 5077.7 3011.3 3371.8 3419.5 3442.6 3479.0 937.3 975.3 988.6 989.0 990.6 9455.4 11329.5 11340.5 11360.5 11360.6 4522.6 4576.7 4701.4 4707.3 4765.5 9689.9 9706.6 9709.4 9712.4 9722.4 5258.1 5273.8 5296.2 5299.8 5306.2 4378.3 4745.6 4746.7 4814.7 4828.7 3644.0 3867.7 3922.2 3942.7 3961.6 1255.9 1281.1 1281.9 1291.6 1298.9 706.6 863.9 911.2 921.3 931.4 5552.3 5665.3 5804.7 5851.4 5933.2 877.0 888.5 893.8 922.4 941.3 3776.2 3899.5 3944.2 3979.9 3989.7 8694.7 10183.8 10569.1 10613.4 10674.2 10226.5 10546.5 10607.0 10617.3 10712.4 7846.9 7921.8 8036.9 8053.9 8132.8 5414.5 5577.9 5587.7 5667.6 5680.9 9834.8 9852.6 9901.9 9917.3 9929.6 9545.8 9755.9 10098.6 10167.5 10169.4 5398.9 6709.8 6996.7 7390.2 7446.1 6874.9 8022.4 8273.8 8288.3 8301.0 7036.1 7134.1 7143.0 7244.9 7272.9 6956.5 6996.9 7007.9 7127.5 7137.2 6906.9 6928.1 6983.3 7092.9 7093.1 4718.9 4731.9 4750.9 4759.4 4861.2 4742.1 4772.5 4817.4 4857.9 4947.0 09:06:39 INFO - PROCESS | 1993 | 09:06:39 INFO - PROCESS | 1993 | [#4] prototype.html Cycles:180 Average:5003.90 Median:5600.71 stddev:1621.97 (29.0%) stddev-sans-first:1587.45 09:06:39 INFO - PROCESS | 1993 | Values: 289.8 299.1 304.9 311.3 311.7 4568.6 4921.5 5092.4 5848.1 5912.2 5521.5 5540.7 5733.3 5741.4 5832.0 4270.6 4284.1 4301.1 4309.7 4315.8 3686.5 3833.0 4291.3 4297.9 4307.9 4034.6 4254.3 4293.5 4307.4 4322.2 3528.4 3968.9 4110.3 4265.1 4342.5 3617.5 3652.3 3671.0 3674.2 3674.3 4655.9 5519.2 5626.2 5658.9 5689.3 4338.0 4939.2 4939.6 4960.0 4967.6 4563.6 5227.5 6223.6 6239.2 6343.8 3921.9 4044.7 4049.4 4073.7 4113.3 6199.8 6546.7 6604.6 6612.7 6628.8 4129.1 4133.6 4142.5 4146.2 4155.7 3715.8 4077.3 4145.4 4212.3 4229.3 4923.5 4940.0 4953.5 4966.3 4974.3 6211.3 6287.2 6293.6 6296.7 6325.6 1391.6 1407.7 1437.4 1443.5 1453.1 891.0 916.2 924.3 935.0 938.5 4732.5 5378.4 5598.9 5801.9 5903.7 6459.8 6467.5 6487.3 6490.5 6503.9 5169.8 5196.4 5486.2 5583.6 5965.8 2864.2 2892.9 3386.2 3453.5 3455.4 5995.4 6561.5 6840.4 6846.0 6856.4 6683.0 6693.7 6703.6 6718.5 6740.8 6040.8 6455.0 6606.7 6801.3 6855.0 5275.7 5602.5 6517.4 6525.7 6557.6 6356.3 6415.4 6424.6 6443.9 6466.5 5499.7 6190.8 6532.3 6834.2 6842.9 3898.0 4041.3 4105.8 4114.3 4146.5 6038.4 6293.9 6303.1 6319.2 6327.2 4801.4 5469.3 6273.2 6335.5 6352.2 5458.8 5989.6 6293.6 6355.8 6371.4 5997.5 6145.9 6158.8 6180.5 6197.3 5957.5 6273.2 6291.5 6298.7 6313.4 6154.3 6167.4 6187.7 6188.3 6211.8 09:06:39 INFO - PROCESS | 1993 | 09:06:39 INFO - PROCESS | 1993 | [#5] yui.html Cycles:180 Average:17540.84 Median:62078.53 stddev:24407.33 (39.3%) stddev-sans-first:24442.84 09:06:39 INFO - PROCESS | 1993 | Values: 649.6 671.0 681.9 695.7 718.4 13521.9 14870.6 15354.5 15363.8 15417.1 13823.6 14028.9 14079.2 14179.6 14193.9 906.1 907.2 913.0 914.3 915.9 721.3 739.5 743.3 745.7 746.7 936.6 979.7 981.4 981.6 984.7 954.7 967.1 967.3 969.4 970.1 552.4 695.8 709.9 714.7 716.5 15067.7 15128.2 15134.9 15158.0 15167.5 12560.6 12573.6 12594.0 12710.6 12772.7 9462.9 9731.4 10009.6 10109.5 10404.6 3485.3 3506.8 3516.8 3572.8 3609.9 78230.0 79523.6 81170.0 81343.0 81544.6 9404.5 9504.7 9604.2 9709.9 9777.5 21840.0 21857.3 21925.6 21926.2 21937.1 7344.9 7517.1 7523.0 7524.1 7624.8 3957.6 4030.3 4033.5 4038.1 4043.3 3483.1 3596.3 3602.1 3604.0 3605.8 548.3 618.3 631.6 647.9 659.1 508.9 510.8 528.6 595.2 611.9 7872.2 7878.8 7926.0 8071.0 8099.4 562.5 566.4 580.2 590.5 599.4 3675.0 3853.2 3891.7 3905.5 3950.1 86846.7 86923.6 87804.0 88173.1 88857.0 74719.6 74983.0 75839.0 76226.0 76342.0 65209.0 66423.0 67294.0 67751.7 68241.3 7470.5 7544.3 7676.4 7683.9 7745.0 32256.8 40879.6 42619.4 42833.6 43432.3 61699.1 61989.4 62167.7 62251.7 62301.4 9894.4 9894.6 10724.2 10887.0 11042.3 11729.2 11939.3 12294.3 12327.8 12742.9 11198.6 11339.2 11356.9 11363.1 11426.2 11173.6 11203.8 11218.3 11236.8 11411.9 10053.7 10466.7 11072.6 11115.1 12278.6 8988.9 9400.7 9502.9 9505.9 9872.4 9249.0 9640.3 9655.4 9677.6 9712.2 09:06:39 INFO - PROCESS | 1993 | -------- Summary: end -------- 09:06:39 INFO - PROCESS | 1993 | 09:06:39 INFO - TEST-INFO | 1993: exit 0 09:06:39 INFO - TEST-OK | dromaeo_css | took 1114540ms 09:06:39 INFO - TEST-START | kraken 09:06:39 INFO - operating with platform_type : mac_ 09:06:39 INFO - Initialising browser for kraken test... 09:06:40 INFO - TEST-INFO | started process 1996 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpn8xMTt/profile http://localhost:52675/getInfo.html) 09:06:42 INFO - PROCESS | 1996 | __metrics Screen width/height:1600/1200 09:06:42 INFO - PROCESS | 1996 | colorDepth:24 09:06:42 INFO - PROCESS | 1996 | Browser inner width/height: 1024/689 09:06:42 INFO - PROCESS | 1996 | __metrics 09:06:42 INFO - PROCESS | 1996 | 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" 09:06:42 ERROR - PROCESS | 1996 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:06:42 INFO - TEST-INFO | 1996: exit 0 09:06:42 INFO - Browser initialized. 09:06:42 INFO - Running cycle 1/1 for kraken test... 09:06:42 INFO - TEST-INFO | started process 1998 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpn8xMTt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:54 INFO - PROCESS | 1998 | RSS: Main: 237961216 09:06:54 INFO - PROCESS | 1998 | 09:06:57 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52675/tests/kraken/driver.html?testName=audio-beat-detection) 09:06:58 INFO - PROCESS | 1998 | RSS: Main: 286019584 09:06:58 INFO - PROCESS | 1998 | 09:07:01 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52675/tests/kraken/driver.html?testName=audio-dft) 09:07:01 INFO - PROCESS | 1998 | RSS: Main: 299405312 09:07:01 INFO - PROCESS | 1998 | 09:07:05 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52675/tests/kraken/driver.html?testName=audio-fft) 09:07:05 INFO - PROCESS | 1998 | RSS: Main: 388132864 09:07:05 INFO - PROCESS | 1998 | 09:07:07 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52675/tests/kraken/driver.html?testName=audio-oscillator) 09:07:08 INFO - PROCESS | 1998 | RSS: Main: 286707712 09:07:08 INFO - PROCESS | 1998 | 09:07:10 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52675/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:07:11 INFO - PROCESS | 1998 | RSS: Main: 290664448 09:07:11 INFO - PROCESS | 1998 | 09:07:13 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52675/tests/kraken/driver.html?testName=imaging-darkroom) 09:07:14 INFO - PROCESS | 1998 | RSS: Main: 329633792 09:07:14 INFO - PROCESS | 1998 | 09:07:17 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52675/tests/kraken/driver.html?testName=imaging-desaturate) 09:07:18 INFO - PROCESS | 1998 | RSS: Main: 331198464 09:07:18 INFO - PROCESS | 1998 | 09:07:20 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52675/tests/kraken/driver.html?testName=json-parse-financial) 09:07:21 INFO - PROCESS | 1998 | RSS: Main: 329994240 09:07:21 INFO - PROCESS | 1998 | 09:07:23 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52675/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:07:23 INFO - PROCESS | 1998 | RSS: Main: 320237568 09:07:23 INFO - PROCESS | 1998 | 09:07:25 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52675/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:07:26 INFO - PROCESS | 1998 | RSS: Main: 326422528 09:07:26 INFO - PROCESS | 1998 | 09:07:28 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52675/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:07:29 INFO - PROCESS | 1998 | RSS: Main: 330940416 09:07:29 INFO - PROCESS | 1998 | 09:07:31 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:07:32 INFO - PROCESS | 1998 | RSS: Main: 334901248 09:07:32 INFO - PROCESS | 1998 | 09:07:35 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:07:36 INFO - PROCESS | 1998 | RSS: Main: 332525568 09:07:36 INFO - PROCESS | 1998 | 09:07:37 INFO - PROCESS | 1998 | Cycle 1(1): loaded http://localhost:52675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52675/tests/kraken/driver.html?testName=ai-astar) 09:07:38 INFO - PROCESS | 1998 | RSS: Main: 396640256 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | __start_tp_report 09:07:38 INFO - PROCESS | 1998 | _x_x_mozilla_page_load 09:07:38 INFO - PROCESS | 1998 | _x_x_mozilla_page_load_details 09:07:38 INFO - PROCESS | 1998 | |i|pagename|runs| 09:07:38 INFO - PROCESS | 1998 | |0;ai-astar;110;110;111;112;113;113;121;112;111;113 09:07:38 INFO - PROCESS | 1998 | |1;audio-beat-detection;155;124;145;189;152;178;134;139;216;155 09:07:38 INFO - PROCESS | 1998 | |2;audio-dft;186;184;186;182;186;186;183;185;182;182 09:07:38 INFO - PROCESS | 1998 | |3;audio-fft;75;73;85;92;57;57;60;79;85;55 09:07:38 INFO - PROCESS | 1998 | |4;audio-oscillator;89;96;135;77;78;82;124;78;73;124 09:07:38 INFO - PROCESS | 1998 | |5;imaging-gaussian-blur;88;85;88;85;88;84;88;84;87;84 09:07:38 INFO - PROCESS | 1998 | |6;imaging-darkroom;108;151;95;94;94;94;95;94;94;94 09:07:38 INFO - PROCESS | 1998 | |7;imaging-desaturate;85;84;84;84;86;87;85;84;84;84 09:07:38 INFO - PROCESS | 1998 | |8;json-parse-financial;58;60;60;59;58;58;59;77;60;58 09:07:38 INFO - PROCESS | 1998 | |9;json-stringify-tinderbox;66;65;70;75;75;71;68;72;69;72 09:07:38 INFO - PROCESS | 1998 | |10;stanford-crypto-aes;86;87;86;87;84;88;103;114;84;88 09:07:38 INFO - PROCESS | 1998 | |11;stanford-crypto-ccm;150;148;132;163;130;157;163;160;182;142 09:07:38 INFO - PROCESS | 1998 | |12;stanford-crypto-pbkdf2;169;173;169;165;180;169;167;169;168;171 09:07:38 INFO - PROCESS | 1998 | |13;stanford-crypto-sha256-iterative;68;68;69;67;69;66;72;71;72;90 09:07:38 INFO - PROCESS | 1998 | __end_tp_report 09:07:38 INFO - PROCESS | 1998 | __start_cc_report 09:07:38 INFO - PROCESS | 1998 | _x_x_mozilla_cycle_collect,1888 09:07:38 INFO - PROCESS | 1998 | __end_cc_report 09:07:38 INFO - PROCESS | 1998 | __startTimestamp1460650058074__endTimestamp 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | ------- Summary: start ------- 09:07:38 INFO - PROCESS | 1998 | Number of tests: 14 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#0] ai-astar Cycles:10 Average:112.60 Median:112.50 stddev:3.17 (2.8%) stddev-sans-first:3.22 09:07:38 INFO - PROCESS | 1998 | Values: 110.0 110.0 111.0 112.0 113.0 113.0 121.0 112.0 111.0 113.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#1] audio-beat-detection Cycles:10 Average:158.70 Median:155.00 stddev:27.96 (18.0%) stddev-sans-first:29.62 09:07:38 INFO - PROCESS | 1998 | Values: 155.0 124.0 145.0 189.0 152.0 178.0 134.0 139.0 216.0 155.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.50 stddev:1.81 (1.0%) stddev-sans-first:1.80 09:07:38 INFO - PROCESS | 1998 | Values: 186.0 184.0 186.0 182.0 186.0 186.0 183.0 185.0 182.0 182.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#3] audio-fft Cycles:10 Average:71.80 Median:77.00 stddev:13.66 (17.7%) stddev-sans-first:14.44 09:07:38 INFO - PROCESS | 1998 | Values: 75.0 73.0 85.0 92.0 57.0 57.0 60.0 79.0 85.0 55.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:78.00 stddev:23.26 (29.8%) stddev-sans-first:24.55 09:07:38 INFO - PROCESS | 1998 | Values: 89.0 96.0 135.0 77.0 78.0 82.0 124.0 78.0 73.0 124.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.50 stddev:1.85 (2.1%) stddev-sans-first:1.83 09:07:38 INFO - PROCESS | 1998 | Values: 88.0 85.0 88.0 85.0 88.0 84.0 88.0 84.0 87.0 84.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:94.00 stddev:17.99 (19.1%) stddev-sans-first:18.92 09:07:38 INFO - PROCESS | 1998 | Values: 108.0 151.0 95.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:84.50 stddev:1.06 (1.3%) stddev-sans-first:1.12 09:07:38 INFO - PROCESS | 1998 | Values: 85.0 84.0 84.0 84.0 86.0 87.0 85.0 84.0 84.0 84.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:59.50 stddev:5.79 (9.7%) stddev-sans-first:6.06 09:07:38 INFO - PROCESS | 1998 | Values: 58.0 60.0 60.0 59.0 58.0 58.0 59.0 77.0 60.0 58.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.50 stddev:3.40 (4.8%) stddev-sans-first:3.23 09:07:38 INFO - PROCESS | 1998 | Values: 66.0 65.0 70.0 75.0 75.0 71.0 68.0 72.0 69.0 72.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#10] stanford-crypto-aes Cycles:10 Average:90.70 Median:86.50 stddev:9.83 (11.4%) stddev-sans-first:10.28 09:07:38 INFO - PROCESS | 1998 | Values: 86.0 87.0 86.0 87.0 84.0 88.0 103.0 114.0 84.0 88.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#11] stanford-crypto-ccm Cycles:10 Average:152.70 Median:158.50 stddev:15.74 (9.9%) stddev-sans-first:16.67 09:07:38 INFO - PROCESS | 1998 | Values: 150.0 148.0 132.0 163.0 130.0 157.0 163.0 160.0 182.0 142.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:169.00 stddev:4.11 (2.4%) stddev-sans-first:4.34 09:07:38 INFO - PROCESS | 1998 | Values: 169.0 173.0 169.0 165.0 180.0 169.0 167.0 169.0 168.0 171.0 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - PROCESS | 1998 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:70.00 stddev:6.91 (9.9%) stddev-sans-first:7.23 09:07:38 INFO - PROCESS | 1998 | Values: 68.0 68.0 69.0 67.0 69.0 66.0 72.0 71.0 72.0 90.0 09:07:38 INFO - PROCESS | 1998 | -------- Summary: end -------- 09:07:38 INFO - PROCESS | 1998 | 09:07:38 INFO - TEST-INFO | 1998: exit 0 09:07:38 INFO - TEST-OK | kraken | took 58887ms 09:07:38 INFO - SUITE-END | took 1173s 09:07:39 INFO - Completed test suite (00:19:33) 09:07:39 INFO - javascript benchmark 09:07:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3361.9495707564392, 3413.505041768942, 3445.7071148952346, 3455.1016735648755, 3471.722262219021, 5533.0870406383, 5615.971920140399, 5620.494155526002, 5628.015097357961, 5647.237622920905, 5619.01667208239, 5622.437756224375, 5706.1440783882435, 5750.338711048231, 5770.971145144268, 5073.061483625523, 5090.440051594322, 5120.846374608749, 5132.486751324875, 5209.270702101709, 5177.896442071156, 5190.2474141249495, 5222.399424066243, 5222.712750798704, 5262.552683021948, 4998.950010499885, 5145.51117643827, 5148.330717006803, 5165.793368265265, 5169.844904652885, 5142.794288228466, 5155.536001759822, 5168.3281173447585, 5172.896542069175, 5180.274761533388, 4165.958340416587, 5080.390353157608, 5128.461511541272, 5131.102057140023, 5134.2812006319145, 5549.944500555023, 5550.47270509301, 5561.638493497951, 5599.860003499904, 5620.988222120017, 6065.272167339947, 6130.417610327012, 6138.631682099043, 6153.661548614825, 6200.3179650238435, 6078.300995385499, 6117.541184411142, 6159.538034647419, 6162.7226774795245, 6172.7530898764, 5605.523530499871, 5666.46168613981, 5670.319561652545, 5672.886542269172, 5706.857328566736, 6229.688515574203, 6285.277193122758, 6294.213223347082, 6311.715972781236, 6338.239411270585, 6093.4515893568905, 6113.358097399709, 6118.449339559461, 6122.724477398528, 6123.081537769282, 6095.817125486243, 6240.625562466266, 6241.438270555581, 6255.906161407583, 6258.374162583706, 6079.209702738615, 6119.877602447926, 6168.537359698041, 6170.167027451326, 6180.81457556274, 6182.629042257479, 6196.907046394308, 6200.782972595937, 6212.9378706213265, 6220.191375121296, 4760.214564596803, 4889.926651100236, 4892.706437613754, 4898.118338698997, 4931.137051016058, 4336.9132617348105, 4409.735415875058, 4412.84555040572, 4450.354698568754, 4496.887577810581, 5607.943920560742, 5630.465105814941, 5665.518430933417, 5673.205751194836, 5684.8863022739315, 6107.206063211754, 6232.345603711636, 6250.0, 6265.749370025148, 6271.184745983084, 5648.915266271009, 5684.118961560965, 5711.257536520226, 5725.31296244445, 5753.654780713171, 5342.2253773202665, 5359.624826262124, 5365.892682146336, 5366.382865970463, 5379.73101344939, 5197.376314842243, 6244.469220116341, 6348.079528468438, 6350.936490635219, 6397.360263973565, 6043.758249669964, 6117.9187529241, 6118.785842495491, 6127.494965205145, 6144.53915956314, 6239.2667438795625, 6281.466075383643, 6297.685115744286, 6313.273973492924, 6323.747050117944, 5946.13781001761, 5954.851128721643, 5981.7308221131025, 5987.371326010706, 6034.818955431344, 6233.438990490878, 6238.844028899314, 6241.750329986749, 6246.718897649707, 6251.374862513894, 6027.126066720389, 6044.637321760709, 6058.394160583906, 6061.969690151521, 6068.817935461943, 5916.142159386951, 5987.341392446914, 6078.270607527125, 6093.573449858467, 6121.816345509642, 5790.218320526844, 5812.680302583399, 5822.184894114912, 5829.7085145743395, 5859.794907178229, 4657.743824089707, 5988.191594134736, 6006.969965150146, 6032.1856549365275, 6079.0, 5771.307443106854, 5815.2149459822385, 5833.620814647034, 5924.881502369843, 5939.881202375842], "value": 5635.671291145814, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.10633306881067, 498.5986281043761, 531.3225637312427, 573.3893403525245, 574.6322353693637, 29503.0, 37597.24805503889, 38286.61713382865, 38339.42490862636, 38357.61642383575, 41584.79207603958, 42035.36946945798, 42093.0, 42095.78952105235, 42225.0, 8552.743417697478, 9612.423254604728, 9859.802803943916, 9865.950670246639, 9867.555959981783, 8689.826203475926, 11584.47869845859, 11876.821847672292, 11881.940590297036, 11882.405879706022, 9784.266180036526, 11585.942070289679, 11696.7660646787, 11701.941490292536, 11703.94148029263, 8637.265832404253, 10481.737956551071, 11066.446677666125, 11517.48171332292, 11637.47631356591, 11447.599334023353, 11457.083433325313, 11653.184277100607, 11653.883461165364, 11658.708532286675, 32665.83667081673, 40223.793286201464, 40319.39520907189, 40319.79840100781, 40331.79004629866, 2915.702512381991, 3163.4622114240633, 3247.0908145719236, 3375.5949286085583, 3504.702100321476, 28292.0, 29224.0, 29462.410751784846, 29530.114096577137, 29690.85154574235, 2675.745804148603, 2682.3964607963053, 2698.9190324290303, 2775.320046588599, 2934.633170853643, 517322.0, 534312.0, 535762.0, 536735.3163234198, 537291.0, 56287.0, 56426.0, 57128.85742285131, 57158.7142064287, 57161.0, 50485.747571261905, 61924.38075619186, 66441.6677916617, 68654.0, 68740.6562967182, 16279.51161465158, 16517.256723447474, 16520.42178523746, 16528.173591320385, 16536.173191340626, 11658.417079146178, 11861.169718119649, 12512.874871251353, 12627.873721262853, 12849.807252891214, 15344.693106137815, 18961.24155033811, 18985.335513257247, 19056.61886762285, 19118.94845783495, 7869.213078692085, 8079.878801817978, 8099.716509922125, 8158.632861521246, 8206.0, 807.11620775251, 905.524599585223, 917.2891009467716, 1015.9593616255415, 1046.8063408269343, 23065.88467057654, 23091.769082309296, 26216.73783262143, 28920.710792892223, 29561.408771824445, 1078.4823284823328, 1083.6586475260267, 1105.4748994227677, 1111.4220605285207, 1180.722530205401, 14977.251137443085, 15063.610687595203, 15085.54743357701, 15327.0, 15423.845761542241, 627719.0, 634214.0, 637020.0, 637316.0, 638432.8078359579, 49818.750906245965, 59314.0, 59545.404545953985, 59558.70220648956, 59673.40326596679, 1321.5308565459125, 1349.1230700044853, 1427.5717284814307, 1458.183417286323, 1501.9774703379458, 28317.433651327272, 28415.857920710263, 29065.0, 29162.27094322574, 29178.56232156477, 47387.289190661475, 56880.71559642341, 58508.70745646244, 58587.414125858195, 59207.703961479914, 441114.0, 475286.0, 579636.0, 579671.0, 580569.0, 33971.320573587895, 35619.82190089033, 41881.79059104685, 42589.46336976173, 42679.57320426756, 41950.37074443947, 41965.58034419617, 42366.669833159474, 42677.0, 42768.57231427646, 26676.866615666797, 35232.823835880656, 35460.82269588635, 36087.63912360948, 36208.0, 35008.0, 35164.47253291152, 36458.54165833337, 37398.0, 37399.0, 36385.090372740895, 36526.0, 36792.81603591965, 37026.0, 37575.43636845395, 31084.378312434077, 32211.838940805144, 32229.677703223606, 32746.836265818518, 32805.83597081999, 24155.275341739776, 24300.027998880552, 24461.388465287797, 24770.876145619877, 24889.0], "value": 20070.356107195465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.7576252641676, 366.35155774279525, 370.2410059378272, 372.4878292348021, 388.1770646229993, 19503.804961950376, 19643.508912277182, 20324.59350812983, 21037.0, 21052.8947355263, 19226.71159932602, 20128.899355503203, 20470.897645511788, 20588.794112058877, 20593.794062059413, 5096.38843338799, 5099.923501147486, 5262.342207224097, 5299.973500132494, 5306.522412982831, 5828.825135245949, 5856.180134781134, 5881.264841894763, 5883.441073098049, 5904.88190236195, 5225.503577160163, 5278.577713782907, 5608.569814697246, 5823.621464604808, 5842.64944103353, 5206.713630750307, 5246.81636142737, 5255.237990491377, 5266.0, 5267.55225805807, 4091.631753142202, 5079.542841144278, 5119.616028797818, 5126.051680439131, 5127.359080114986, 12736.936315318457, 12881.420336084899, 12917.87082129176, 12948.741025179537, 20730.48173795652, 1988.8707234029741, 2233.2072114399316, 2351.400392899817, 2384.6423036544656, 2469.851808891472, 9767.120959113643, 9795.971423000625, 9934.758155230596, 10041.397516149054, 10052.44711540865, 1664.5672125247402, 1711.700452420834, 1750.3086280919092, 1766.5936834528002, 1767.6022894848684, 73483.63258183675, 102438.0, 102951.97048029574, 110172.4491377546, 117761.41119294435, 7766.456348055582, 8999.730008099768, 9704.854427183598, 9717.12545870875, 9845.50772461374, 11019.008289254005, 11576.726560078385, 12217.572384966661, 12326.445309961073, 12979.480820767252, 6437.710303036374, 6954.3393377629045, 8401.243888049983, 8465.957670211732, 8585.441946273471, 8417.28453081495, 8418.579071046546, 8434.409591328671, 8556.315494760403, 8560.186782255676, 4323.524412314643, 4407.0524837160165, 4424.668149888771, 4462.866114016585, 4464.2633965396, 1838.3106335124328, 1906.0, 1910.5414700472065, 1918.2064769440617, 1950.3856285270379, 867.1891781184613, 908.0964440381862, 942.2320808541017, 979.3095867413457, 1017.9185665146771, 16044.919775401047, 19514.21943122259, 20023.29918452876, 20082.39752807418, 20354.89822550878, 334.2329354132272, 391.2468498141067, 391.7629833950419, 405.59643155060905, 426.51803462087645, 5374.1938709193155, 5715.742791574389, 5737.65574065549, 10557.9472102639, 11237.887621123684, 549086.0, 622767.0, 636836.0, 638124.8093759501, 638424.0, 134811.17077121706, 137114.0, 137602.0, 140126.59873401135, 140848.32365087193, 400.6554363247605, 409.2531130686509, 425.97870106494554, 439.40460675784493, 441.67978215793937, 8408.53753043564, 9647.9035209647, 9703.708888733348, 10160.0, 10251.538680759235, 45971.0, 47036.82407939829, 47053.76473117749, 49202.50797491979, 49588.760280993265, 55603.053893113545, 64907.0, 67144.65710685661, 68028.0, 71822.64088679523, 7881.211878812073, 7953.244441778022, 8033.477823941425, 8046.155153708776, 8317.916820831957, 10774.0, 11827.586034489095, 12236.265824050586, 12669.619911402673, 12897.097203196061, 10671.519781609692, 10893.564257429614, 10978.57035815994, 10985.121190304917, 11303.208775385645, 10657.786844262917, 10760.3543787373, 10884.673459796219, 10907.0, 11083.002529772359, 8291.0, 10531.368117912949, 11194.104471642086, 11230.464846716392, 11323.0, 7226.638668066471, 7867.291943724863, 7951.681932722627, 8177.264046235868, 8184.918150818416, 7883.881741773764, 10397.688069357931, 10773.838392424277, 11109.611163609236, 11123.054540364159], "value": 8322.786070699758, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.7903314502817, 1580.5811459963109, 1592.2436842499815, 1842.6867432536467, 1974.0721860725464, 7766.883496747546, 8052.718154864581, 8056.234657707523, 8063.435559510836, 8416.957915210416, 8395.664173433055, 8492.320614350852, 8562.65749370026, 8581.742547723563, 8593.570321483932, 2382.5711371953084, 2410.517896420714, 2411.6141417373224, 2411.8914648840755, 2412.4089598048504, 1931.8261356477915, 2303.1823702585593, 2392.35406440261, 2392.4856155926473, 2395.2574701842395, 2381.5832229359794, 2402.2192787344097, 2405.747396523366, 2410.0600765701392, 2410.710714714237, 2342.4846533762543, 2385.630227314769, 2390.521895620874, 2396.341006223285, 2398.5202959408016, 2114.5453727448516, 2331.2889568681544, 2405.675233843426, 2412.2280870121567, 2412.372783076395, 8490.235878770878, 8561.1866872647, 8561.700340488116, 8568.571571421404, 8568.828623427496, 4842.951570484321, 5038.622103342264, 5049.2173713074535, 5074.2895994560795, 5077.669951453144, 3011.337505748732, 3371.76397652162, 3419.4870769384793, 3442.5868895732674, 3478.9826050869838, 937.2548823685187, 975.3465178330536, 988.5897352598663, 989.0258095775655, 990.6483198464514, 9455.385399948982, 11329.546818127264, 11340.489677964511, 11360.545578176945, 11360.602378916697, 4522.570355816192, 4576.702514336557, 4701.388819453449, 4707.340972263886, 4765.451973023078, 9689.90310096904, 9706.563204655808, 9709.36889102206, 9712.417254964725, 9722.416655000723, 5258.053550360972, 5273.841784746463, 5296.152615581565, 5299.761510732026, 5306.204069389622, 4378.299472084451, 4745.596624286905, 4746.691465054761, 4814.735189564538, 4828.686135401188, 3643.981780091083, 3867.690584753213, 3922.156736301709, 3942.743721658083, 3961.623645753649, 1255.893249073839, 1281.1096288079882, 1281.858995510493, 1291.5737806523825, 1298.9155704879154, 706.6325510734389, 863.8920134983127, 911.2209061252667, 921.3182245138684, 931.4039015030386, 5552.305961754781, 5665.263515742927, 5804.709764511842, 5851.444112809277, 5933.160720176759, 877.0221203358337, 888.4757992784288, 893.7765558610347, 922.4004397141912, 941.2611100286242, 3776.15036616759, 3899.4735710678697, 3944.230874979409, 3979.940300895431, 3989.740666856645, 8694.695685650971, 10183.847242291371, 10569.101626361691, 10613.363198208132, 10674.199435042403, 10226.488675566192, 10546.525406356732, 10607.0, 10617.309874858263, 10712.410817404962, 7846.9215307847335, 7921.762347129596, 8036.919630803734, 8053.919460805434, 8132.796680083045, 5414.512693857571, 5577.9401913636275, 5587.720613969366, 5667.631603945731, 5680.943190568207, 9834.75413114678, 9852.605895764376, 9901.900980990098, 9917.30578859473, 9929.553170107218, 9545.809083818423, 9755.9024409755, 10098.646547370807, 10167.491625418847, 10169.389836609827, 5398.865028374322, 6709.798706038827, 6996.685149168198, 7390.187079421152, 7446.14369347532, 6874.8625027500175, 8022.398320125897, 8273.83452330962, 8288.295494883003, 8301.045379781403, 7036.120484939383, 7134.108236470442, 7142.964285178541, 7244.891326630211, 7272.854542909006, 6956.513044086865, 6996.860062798817, 7007.894881576883, 7127.46544009191, 7137.2149063604, 6906.894896816485, 6928.1339832520935, 6983.301669832976, 7092.929070709433, 7093.1488221413765, 4718.929216061831, 4731.858044258678, 4750.904981900274, 4759.428868535798, 4861.173600487855, 4742.075295317518, 4772.475027746876, 4817.421909370898, 4857.9028419432125, 4947.035328110984], "value": 4473.717545165084, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.76239483623425, 299.1026919242273, 304.85671734284887, 311.2824938516718, 311.65675936712756, 4568.565986231302, 4921.507849215077, 5092.363454568179, 5848.093545500449, 5912.172295878575, 5521.5306698930535, 5540.695261760601, 5733.254676891998, 5741.425857414257, 5832.046264501397, 4270.615644591986, 4284.143171365731, 4301.053768171013, 4309.655227581801, 4315.755806980384, 3686.4838922550866, 3832.9808350958206, 4291.291936830435, 4297.892552686177, 4307.892302692427, 4034.616711412411, 4254.340577210537, 4293.484781826185, 4307.440032795748, 4322.178786030644, 3528.3825330567047, 3968.9206215875665, 4110.301248787713, 4265.1469706058915, 4342.500612429548, 3617.5478065241846, 3652.2651740423353, 3671.0271777978855, 3674.191677830873, 3674.3386190485703, 4655.883602909955, 5519.200578135521, 5626.156076588503, 5658.886822263532, 5689.288838895138, 4337.956620433787, 4939.185034469308, 4939.629527785431, 4959.950400496021, 4967.55292023716, 4563.589276965055, 5227.529522343008, 6223.564350495467, 6239.157713708682, 6343.809685709436, 3921.8823435296986, 4044.737092089005, 4049.3521036634293, 4073.6537394321517, 4113.341865301538, 6199.814005579839, 6546.705398257042, 6604.570702904296, 6612.669366531654, 6628.834279143011, 4129.132882094734, 4133.648639865645, 4142.46148000758, 4146.1914926589025, 4155.709100363006, 3715.758475699071, 4077.2864748668862, 4145.440365550672, 4212.346221880196, 4229.323308270724, 4923.507649235048, 4939.987302602958, 4953.455119936804, 4966.255061740696, 4974.27872958419, 6211.3167551569295, 6287.182666253358, 6293.622382657055, 6296.65368404733, 6325.557210995278, 1391.6451304917384, 1407.7254935287726, 1437.3962935567088, 1443.501991812823, 1453.149907304239, 890.9866352004725, 916.1983264643437, 924.3160061554402, 934.9579268932914, 938.5025936253796, 4732.503087175797, 5378.354597448253, 5598.932965666078, 5801.926643570953, 5903.734331955073, 6459.806205813833, 6467.547271690936, 6487.253965793994, 6490.545661803723, 6503.902441463382, 5169.767360468788, 5196.402413722471, 5486.231927530149, 5583.581231407661, 5965.79119730816, 2864.1550742531003, 2892.8842846286334, 3386.170388254844, 3453.4819777033645, 3455.3759732925987, 5995.430434108753, 6561.475081993429, 6840.418564421981, 6845.965770171118, 6856.417204537571, 6683.0, 6693.732250710014, 6703.564268322543, 6718.529702920749, 6740.831479213059, 6040.758369665252, 6454.967725161344, 6606.735730570916, 6801.319868013159, 6854.9657251713425, 5275.709835958905, 5602.4957753802355, 6517.4460170886005, 6525.673716314261, 6557.639329836714, 6356.300806911144, 6415.369698423624, 6424.550281480251, 6443.903341449788, 6466.4503517201565, 5499.697516636464, 6190.845228869134, 6532.314107018695, 6834.179898412222, 6842.863142737217, 3897.9610203897596, 4041.2927737646382, 4105.794710264535, 4114.321137012359, 4146.460960075292, 6038.426349496791, 6293.937060629335, 6303.109067279706, 6319.178506794087, 6327.209098862642, 4801.447833499193, 5469.288992430958, 6273.215848018997, 6335.461485773577, 6352.237731472253, 5458.836234912959, 5989.609252144715, 6293.622382657055, 6355.809325720235, 6371.39471750183, 5997.520198384032, 6145.877082458415, 6158.784442544489, 6180.536459765445, 6197.287311959182, 5957.514299025598, 6273.15312432834, 6291.465225455888, 6298.7480500781285, 6313.40022697843, 6154.261488621394, 6167.352427994996, 6187.659678717714, 6188.319284878749, 6211.81364559064], "value": 4473.545772981083, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.5583003557581, 670.9530332876694, 681.8704446155228, 695.6695569604439, 718.4180813541033, 13521.864781352184, 14870.553883383502, 15354.539363819076, 15363.769543456843, 15417.074975501464, 13823.585292441243, 14028.929855350709, 14079.225642589654, 14179.574612761608, 14193.858061419383, 906.1482206725657, 907.2107266677983, 912.995704724804, 914.3188324698109, 915.8717779510873, 721.3363705391052, 739.460194058335, 743.3124359967036, 745.7352639812842, 746.7311767763628, 936.6159874451477, 979.7158823941085, 981.4405788700443, 981.617169303972, 984.7242772023809, 954.6706386296717, 967.0861036320645, 967.2842096848317, 969.4474149734654, 970.1268858027761, 552.353746117044, 695.7947405515386, 709.893515972603, 714.7105422303923, 716.4841314253781, 15067.698646027127, 15128.243587820565, 15134.924325378413, 15158.0, 15167.469138580207, 12560.560380386643, 12573.622791316275, 12594.0, 12710.555130570385, 12772.744545109139, 9462.911765146959, 9731.41611503312, 10009.649662261858, 10109.494525273707, 10404.635837745718, 3485.2680937003006, 3506.8246587670515, 3516.8241587920757, 3572.7677700949616, 3609.8736544221088, 78230.0, 79523.60238198772, 81170.0, 81343.0, 81544.59227703883, 9404.529773511296, 9504.667336643322, 9604.183644390165, 9709.902900971041, 9777.511124443748, 21840.0, 21857.344279671634, 21925.561488770134, 21926.23258185956, 21937.122515099538, 7344.853102937911, 7517.135529414078, 7522.988562756044, 7524.097108347033, 7624.8475030500185, 3957.584453632385, 4030.334994725896, 4033.4756481657196, 4038.1318016626565, 4043.2722110020477, 3483.111806489329, 3596.2627661329366, 3602.081469225383, 3603.9819800900827, 3605.837737301828, 548.2954403591445, 618.2827919613227, 631.5926227583183, 647.899575565788, 659.0641289369107, 508.8651507350555, 510.8084468324378, 528.5853387119176, 595.247012529151, 611.911272865432, 7872.173421790744, 7878.842423151505, 7926.048874135141, 8070.959645201737, 8099.392545559107, 562.4684672983991, 566.393958464439, 580.2398857496693, 590.4980766348655, 599.3826358850391, 3675.044488433027, 3853.190829925691, 3891.6886649068006, 3905.5313362396696, 3950.1309711863346, 86846.69729954055, 86923.56538217269, 87804.0, 88173.11826881778, 88857.0, 74719.62640186764, 74983.0, 75839.0, 76226.0, 76342.0, 65209.0, 66423.0, 67294.0, 67751.66124169348, 68241.3175868235, 7470.514416562906, 7544.321011109027, 7676.385889128745, 7683.884741728991, 7744.961275193588, 32256.838715806272, 40879.59120408758, 42619.360709588764, 42833.57166428296, 43432.34851477167, 61699.07451388323, 61989.380106200166, 62167.689161553906, 62251.688741556005, 62301.37698623137, 9894.406335619886, 9894.604215831574, 10724.195685323788, 10887.0, 11042.282251653616, 11729.237599556001, 11939.283642981449, 12294.323812190416, 12327.815082773586, 12742.872571274169, 11198.60804871858, 11339.206255562362, 11356.94321528387, 11363.090952723596, 11426.2001659883, 11173.608923687632, 11203.823598522038, 11218.270812397168, 11236.775264494829, 11411.858814118521, 10053.69838904834, 10466.743990720857, 11072.557097716, 11115.11079113685, 12278.570250041206, 8988.910110898807, 9400.717978460658, 9502.861856082327, 9505.904940950502, 9872.35829671069, 9249.0, 9640.325177237526, 9655.420674759536, 9677.612895484102, 9712.223022158352], "value": 6729.708601556461, "unit": "score"}], "name": "dromaeo_css", "value": 7087.961499139433, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 111.0, 112.0, 113.0, 113.0, 121.0, 112.0, 111.0, 113.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 124.0, 145.0, 189.0, 152.0, 178.0, 134.0, 139.0, 216.0, 155.0], "value": 158.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 186.0, 182.0, 186.0, 186.0, 183.0, 185.0, 182.0, 182.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 85.0, 92.0, 57.0, 57.0, 60.0, 79.0, 85.0, 55.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 96.0, 135.0, 77.0, 78.0, 82.0, 124.0, 78.0, 73.0, 124.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 84.0, 87.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 151.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 84.0, 86.0, 87.0, 85.0, 84.0, 84.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 60.0, 59.0, 58.0, 58.0, 59.0, 77.0, 60.0, 58.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 70.0, 75.0, 75.0, 71.0, 68.0, 72.0, 69.0, 72.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 87.0, 84.0, 88.0, 103.0, 114.0, 84.0, 88.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 132.0, 163.0, 130.0, 157.0, 163.0, 160.0, 182.0, 142.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 173.0, 169.0, 165.0, 180.0, 169.0, 167.0, 169.0, 168.0, 171.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 67.0, 69.0, 66.0, 72.0, 71.0, 72.0, 90.0], "value": 71.2, "unit": "score"}], "name": "kraken", "value": 1510.6000000000001, "alertThreshold": 2.0}]} 09:07:39 INFO - Return code: 0 09:07:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:07:39 ERROR - # TBPL SUCCESS # 09:07:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:07:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:07:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:07:39 INFO - 2016-04-14 09:07:39.368 screenresolution[2002:29913] starting screenresolution argv=screenresolution get 09:07:39 INFO - 2016-04-14 09:07:39.371 screenresolution[2002:29913] Display 0: 1600x1200x32@60 09:07:39 INFO - 2016-04-14 09:07:39.377 screenresolution[2003:29916] starting screenresolution argv=screenresolution list 09:07:39 INFO - Available Modes on Display 0 09:07:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:07:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:07:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:07:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:07:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:07:39 INFO - Intel Iris: 09:07:39 INFO - Chipset Model: Intel Iris 09:07:39 INFO - Type: GPU 09:07:39 INFO - Bus: Built-In 09:07:39 INFO - VRAM (Dynamic, Max): 1536 MB 09:07:39 INFO - Vendor: Intel (0x8086) 09:07:39 INFO - Device ID: 0x0a2e 09:07:39 INFO - Revision ID: 0x0009 09:07:39 INFO - Displays: 09:07:39 INFO - Display: 09:07:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:07:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:07:39 INFO - Display Serial Number: 1600x1200 60 09:07:39 INFO - Main Display: Yes 09:07:39 INFO - Mirror: Off 09:07:39 INFO - Online: Yes 09:07:39 INFO - Rotation: Supported 09:07:39 INFO - Return code: 0 09:07:39 INFO - Running post-action listener: _resource_record_post_action 09:07:39 INFO - Running post-run listener: _resource_record_post_run 09:07:40 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 1585152; Write bytes: 692174336; Read time: 10; Write time: 1913 09:07:40 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375044608; Write bytes: 369473536; Read time: 28060; Write time: 663 09:07:40 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 1585152; Write bytes: 295540224; Read time: 10; Write time: 1191 09:07:40 INFO - Running post-run listener: _upload_blobber_files 09:07:40 INFO - Blob upload gear active. 09:07:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:07:40 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 09:07:40 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:07:40 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:07:41 INFO - (blobuploader) - INFO - Open directory for files ... 09:07:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:07:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:07:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:41 INFO - (blobuploader) - INFO - Done attempting. 09:07:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:07:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:07:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:42 INFO - (blobuploader) - INFO - Done attempting. 09:07:42 INFO - (blobuploader) - INFO - Iteration through files over. 09:07:42 INFO - Return code: 0 09:07:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:07:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:07:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b060a860f7c14c3fa71def2c870f3a753318be7448adb27fd9ebaeb4e044a3810a2dfcb2ce48b0d8839df6849baca2fbad78eb7b913c615b72ecc3b9d0d67cb6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b01355ca53cdb1164e163ac696b94a101a27fb7f7bf1d47966f243877892a46e4f253e27c3752e8e88283a7aef9601edfa57bc8ff16a42373d28201974932da"} 09:07:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:07:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:07:42 INFO - Contents: 09:07:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b060a860f7c14c3fa71def2c870f3a753318be7448adb27fd9ebaeb4e044a3810a2dfcb2ce48b0d8839df6849baca2fbad78eb7b913c615b72ecc3b9d0d67cb6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b01355ca53cdb1164e163ac696b94a101a27fb7f7bf1d47966f243877892a46e4f253e27c3752e8e88283a7aef9601edfa57bc8ff16a42373d28201974932da"} 09:07:42 INFO - Running post-run listener: copy_logs_to_upload_dir 09:07:42 INFO - Copying logs to upload dir... 09:07:42 INFO - mkdir: /builds/slave/test/build/upload/logs 09:07:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.540905 ========= master_lag: 10.88 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-04-14 09:07:53.860040) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 13 secs) (at 2016-04-14 09:07:53.863960) ========= 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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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/Fx-Team/sha512/b060a860f7c14c3fa71def2c870f3a753318be7448adb27fd9ebaeb4e044a3810a2dfcb2ce48b0d8839df6849baca2fbad78eb7b913c615b72ecc3b9d0d67cb6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b01355ca53cdb1164e163ac696b94a101a27fb7f7bf1d47966f243877892a46e4f253e27c3752e8e88283a7aef9601edfa57bc8ff16a42373d28201974932da"} build_url:https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011780 build_url: 'https://queue.taskcluster.net/v1/task/XQeK8fMOTH-FksCmB8fHRA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b060a860f7c14c3fa71def2c870f3a753318be7448adb27fd9ebaeb4e044a3810a2dfcb2ce48b0d8839df6849baca2fbad78eb7b913c615b72ecc3b9d0d67cb6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b01355ca53cdb1164e163ac696b94a101a27fb7f7bf1d47966f243877892a46e4f253e27c3752e8e88283a7aef9601edfa57bc8ff16a42373d28201974932da"}' ========= master_lag: 13.65 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 13 secs) (at 2016-04-14 09:08:07.522588) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:08:07.522919) ========= 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.0TPR8vDAqe/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.2YB8kVdMjE/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.006000 ========= master_lag: 0.87 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 09:08:08.396017) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 09:08:08.396327) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 09:08:08.449070) ========= ========= Total master_lag: 25.62 =========