builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0185 starttime: 1463487013.4 results: success (0) buildid: 20160517031837 builduid: 3944d8780eb547cdbb0a922b939efddc revision: 036f62007472d4e2ada1564718b617f60cd00d43 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:13.404175) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:13.405156) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:13.405798) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:13.426618) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:13.427315) ========= 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.apqxMCPst0/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.EOtdwBkDxW/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-17 05:10:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.0M=0s 2016-05-17 05:10:13 (39.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.549454 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:13.995835) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:13.996221) ========= 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.apqxMCPst0/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.EOtdwBkDxW/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.020275 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:14.048261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 05:10:14.049105) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 036f62007472d4e2ada1564718b617f60cd00d43 --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 036f62007472d4e2ada1564718b617f60cd00d43 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.apqxMCPst0/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.EOtdwBkDxW/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-17 05:10:14,124 truncating revision to first 12 chars 2016-05-17 05:10:14,124 Setting DEBUG logging. 2016-05-17 05:10:14,124 attempt 1/10 2016-05-17 05:10:14,124 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/036f62007472?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 05:10:15,761 unpacking tar archive at: fx-team-036f62007472/testing/mozharness/ program finished with exit code 0 elapsedTime=1.877064 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 05:10:15.948858) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:15.949651) ========= script_repo_revision: 036f62007472d4e2ada1564718b617f60cd00d43 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:15.950629) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:15.951374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 05:10:15.966938) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-17 05:10:15.967637) ========= /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.apqxMCPst0/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.EOtdwBkDxW/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:10:16 INFO - MultiFileLogger online at 20160517 05:10:16 in /builds/slave/test 05:10:16 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 05:10:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:10:16 INFO - {'append_to_log': False, 05:10:16 INFO - 'base_work_dir': '/builds/slave/test', 05:10:16 INFO - 'blob_upload_branch': 'Fx-Team', 05:10:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:10:16 INFO - 'branch': 'Fx-Team', 05:10:16 INFO - 'buildbot_json_path': 'buildprops.json', 05:10:16 INFO - 'config_files': ('talos/mac_config.py',), 05:10:16 INFO - 'default_actions': ('clobber', 05:10:16 INFO - 'read-buildbot-config', 05:10:16 INFO - 'download-and-extract', 05:10:16 INFO - 'populate-webroot', 05:10:16 INFO - 'create-virtualenv', 05:10:16 INFO - 'install', 05:10:16 INFO - 'run-tests'), 05:10:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:10:16 INFO - 'download_minidump_stackwalk': True, 05:10:16 INFO - 'download_symbols': 'ondemand', 05:10:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:10:16 INFO - 'tooltool.py': '/tools/tooltool.py', 05:10:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:10:16 INFO - '/tools/misc-python/virtualenv.py')}, 05:10:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:10:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:10:16 INFO - 'installer_path': 'installer.exe', 05:10:16 INFO - 'log_level': 'info', 05:10:16 INFO - 'log_name': 'talos', 05:10:16 INFO - 'log_to_console': True, 05:10:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:10:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:10:16 INFO - 'opt_config_files': (), 05:10:16 INFO - 'pip_index': False, 05:10:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:16 INFO - 'cmd': ('bash', 05:10:16 INFO - '-c', 05:10:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:16 INFO - 'enabled': True, 05:10:16 INFO - 'halt_on_failure': False, 05:10:16 INFO - 'name': 'check_screen_resolution'},), 05:10:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:16 INFO - 'cmd': ('bash', 05:10:16 INFO - '-c', 05:10:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:16 INFO - 'enabled': True, 05:10:16 INFO - 'halt_on_failure': False, 05:10:16 INFO - 'name': 'check_screen_resolution'},), 05:10:16 INFO - 'run_cmd_checks_enabled': True, 05:10:16 INFO - 'sps_profile': False, 05:10:16 INFO - 'sps_profile_interval': 0, 05:10:16 INFO - 'suite': 'dromaeojs', 05:10:16 INFO - 'system_bits': '32', 05:10:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:10:16 INFO - 'title': 't-yosemite-r7-0185', 05:10:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:10:16 INFO - 'use_talos_json': True, 05:10:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:10:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:10:16 INFO - 'work_dir': 'build'} 05:10:16 INFO - ##### 05:10:16 INFO - ##### Running clobber step. 05:10:16 INFO - ##### 05:10:16 INFO - Running pre-action listener: _resource_record_pre_action 05:10:16 INFO - Running main action method: clobber 05:10:16 INFO - rmtree: /builds/slave/test/build 05:10:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:10:16 INFO - Running post-action listener: _resource_record_post_action 05:10:16 INFO - ##### 05:10:16 INFO - ##### Finished clobber step (success) 05:10:16 INFO - ##### 05:10:16 INFO - ##### 05:10:16 INFO - ##### Running read-buildbot-config step. 05:10:16 INFO - ##### 05:10:16 INFO - Running pre-action listener: _resource_record_pre_action 05:10:16 INFO - Running main action method: read_buildbot_config 05:10:16 INFO - Using buildbot properties: 05:10:16 INFO - { 05:10:16 INFO - "project": "", 05:10:16 INFO - "product": "firefox", 05:10:16 INFO - "script_repo_revision": "production", 05:10:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:10:16 INFO - "repository": "", 05:10:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:10:16 INFO - "buildid": "20160517031837", 05:10:16 INFO - "pgo_build": "False", 05:10:16 INFO - "basedir": "/builds/slave/test", 05:10:16 INFO - "buildnumber": 21, 05:10:16 INFO - "slavename": "t-yosemite-r7-0185", 05:10:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:10:16 INFO - "platform": "yosemite_r7", 05:10:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:10:16 INFO - "branch": "fx-team", 05:10:16 INFO - "repo_path": "integration/fx-team", 05:10:16 INFO - "revision": "036f62007472d4e2ada1564718b617f60cd00d43", 05:10:16 INFO - "stage_platform": "macosx64", 05:10:16 INFO - "builduid": "3944d8780eb547cdbb0a922b939efddc", 05:10:16 INFO - "slavebuilddir": "test" 05:10:16 INFO - } 05:10:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:10:16 INFO - Running post-action listener: _resource_record_post_action 05:10:16 INFO - ##### 05:10:16 INFO - ##### Finished read-buildbot-config step (success) 05:10:16 INFO - ##### 05:10:16 INFO - ##### 05:10:16 INFO - ##### Running download-and-extract step. 05:10:16 INFO - ##### 05:10:16 INFO - Running pre-action listener: _resource_record_pre_action 05:10:16 INFO - Running main action method: download_and_extract 05:10:16 INFO - mkdir: /builds/slave/test/build/tests 05:10: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')]} 05:10:16 INFO - https://queue.taskcluster.net/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:10:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:10:17 INFO - Downloaded 1199 bytes. 05:10:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:10:17 INFO - Using the following test package requirements: 05:10:17 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:10:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:17 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:10:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:17 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:10:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:17 INFO - u'jsshell-mac.zip'], 05:10:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:17 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:10:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:10:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:17 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:10:17 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:17 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:10:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:17 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:10:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:10:17 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:10:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:10:17 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')]} 05:10:17 INFO - https://queue.taskcluster.net/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:10:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:10:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:10:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:10:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:10:19 INFO - Downloaded 18267729 bytes. 05:10:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:10:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:10:22 INFO - Return code: 0 05:10:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:10: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')]} 05:10:22 INFO - https://queue.taskcluster.net/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:10:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:10:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:10:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:10:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:10:23 INFO - Downloaded 18267729 bytes. 05:10:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:10:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:10:27 INFO - Return code: 0 05:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:10:27 INFO - https://queue.taskcluster.net/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:10:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:10:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:10:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:10:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:10:29 INFO - Downloaded 10840194 bytes. 05:10:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:10:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:10:29 INFO - Return code: 0 05:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:29 INFO - https://queue.taskcluster.net/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:10:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:10:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:10:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:10:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:10:33 INFO - Downloaded 109962602 bytes. 05:10:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:10:33 INFO - mkdir: /builds/slave/test/properties 05:10:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:10:33 INFO - Writing to file /builds/slave/test/properties/build_url 05:10:33 INFO - Contents: 05:10:33 INFO - build_url:https://queue.taskcluster.net/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:10:35 INFO - Running post-action listener: _resource_record_post_action 05:10:35 INFO - Running post-action listener: set_extra_try_arguments 05:10:35 INFO - ##### 05:10:35 INFO - ##### Finished download-and-extract step (success) 05:10:35 INFO - ##### 05:10:35 INFO - ##### 05:10:35 INFO - ##### Running populate-webroot step. 05:10:35 INFO - ##### 05:10:35 INFO - Running pre-action listener: _resource_record_pre_action 05:10:35 INFO - Running main action method: populate_webroot 05:10:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:10:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:10:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:10:35 INFO - '--tppagecycles', 05:10:35 INFO - '7'], 05:10:35 INFO - 'tests': ['tsvgm']}}, 05:10:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:10:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:10:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:10:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:10:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:10:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:10:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:35 INFO - 'talos_options': ['--disable-e10s'], 05:10:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:10:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:10:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:35 INFO - 'talos_options': ['--disable-e10s'], 05:10:35 INFO - 'tests': ['damp', 'tps']}, 05:10:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:35 INFO - 'tests': ['damp', 'tps']}, 05:10:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:10:35 INFO - 'tests': ['dromaeo_dom']}, 05:10:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:10:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:10:35 INFO - 'tests': ['a11yr', 05:10:35 INFO - 'ts_paint', 05:10:35 INFO - 'tpaint', 05:10:35 INFO - 'sessionrestore', 05:10:35 INFO - 'sessionrestore_no_auto_restore', 05:10:35 INFO - 'tabpaint']}, 05:10:35 INFO - 'other-e10s': {'tests': ['a11yr', 05:10:35 INFO - 'ts_paint', 05:10:35 INFO - 'tpaint', 05:10:35 INFO - 'sessionrestore', 05:10:35 INFO - 'sessionrestore_no_auto_restore', 05:10:35 INFO - 'tabpaint']}, 05:10:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:10:35 INFO - 'ts_paint', 05:10:35 INFO - 'tpaint', 05:10:35 INFO - 'sessionrestore', 05:10:35 INFO - 'sessionrestore_no_auto_restore', 05:10:35 INFO - 'tabpaint']}, 05:10:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:10:35 INFO - 'ts_paint', 05:10:35 INFO - 'tpaint', 05:10:35 INFO - 'sessionrestore', 05:10:35 INFO - 'sessionrestore_no_auto_restore', 05:10:35 INFO - 'tabpaint']}, 05:10:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:10:35 INFO - 'tests': ['a11yr', 05:10:35 INFO - 'ts_paint', 05:10:35 INFO - 'tpaint', 05:10:35 INFO - 'sessionrestore', 05:10:35 INFO - 'sessionrestore_no_auto_restore', 05:10:35 INFO - 'tabpaint']}, 05:10:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:10:35 INFO - 'tests': ['a11yr', 05:10:35 INFO - 'ts_paint', 05:10:35 INFO - 'tpaint', 05:10:35 INFO - 'sessionrestore', 05:10:35 INFO - 'sessionrestore_no_auto_restore', 05:10:35 INFO - 'tabpaint']}, 05:10:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:10:35 INFO - 'tests': ['tsvgx', 05:10:35 INFO - 'tsvgr_opacity', 05:10:35 INFO - 'tart', 05:10:35 INFO - 'tscrollx', 05:10:35 INFO - 'cart']}, 05:10:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:10:35 INFO - 'tsvgr_opacity', 05:10:35 INFO - 'tart', 05:10:35 INFO - 'tscrollx', 05:10:35 INFO - 'cart']}, 05:10:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:35 INFO - 'talos_options': ['--disable-e10s'], 05:10:35 INFO - 'tests': ['tp5o']}, 05:10:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:35 INFO - 'tests': ['tp5o']}, 05:10:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:35 INFO - 'talos_options': ['--disable-e10s', 05:10:35 INFO - '--xperf_path', 05:10:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:10:35 INFO - 'tests': ['tp5n']}, 05:10:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:10:35 INFO - 'talos_options': ['--xperf_path', 05:10:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:10:35 INFO - 'tests': ['tp5n']}}, 05:10:35 INFO - 'talos.zip': {'path': '', 05:10:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:10:35 INFO - Running post-action listener: _resource_record_post_action 05:10:35 INFO - ##### 05:10:35 INFO - ##### Finished populate-webroot step (success) 05:10:35 INFO - ##### 05:10:35 INFO - ##### 05:10:35 INFO - ##### Running create-virtualenv step. 05:10:35 INFO - ##### 05:10:35 INFO - Running pre-action listener: _resource_record_pre_action 05:10:35 INFO - Running main action method: create_virtualenv 05:10:35 INFO - Creating virtualenv /builds/slave/test/build/venv 05:10:35 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 05:10:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:10:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:10:35 INFO - Using real prefix '/tools/python27' 05:10:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:10:35 INFO - Installing distribute.............................................................................................................................................................................................done. 05:10:38 INFO - Installing pip.................done. 05:10:38 INFO - Return code: 0 05:10:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:38 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 0x1078e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce38ccb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107786d30>, '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 0x1075aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10777db20>, '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.apqxMCPst0/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.EOtdwBkDxW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:10:38 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 05:10:38 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 05:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.apqxMCPst0/Render', 05:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:38 INFO - 'HOME': '/Users/cltbld', 05:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:38 INFO - 'LOGNAME': 'cltbld', 05:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:38 INFO - 'MOZ_NO_REMOTE': '1', 05:10:38 INFO - 'NO_EM_RESTART': '1', 05:10:38 INFO - 'PAGER': '/bin/cat', 05:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:38 INFO - 'PWD': '/builds/slave/test', 05:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:38 INFO - 'SHELL': '/bin/bash', 05:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EOtdwBkDxW/Listeners', 05:10:38 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:38 INFO - 'USER': 'cltbld', 05:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:38 INFO - 'XPC_FLAGS': '0x0', 05:10:38 INFO - 'XPC_SERVICE_NAME': '0', 05:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:38 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 05:10:38 INFO - Cleaning up... 05:10:38 INFO - Return code: 0 05:10:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1078e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce38ccb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107786d30>, '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 0x1075aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10777db20>, '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.apqxMCPst0/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.EOtdwBkDxW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:10:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:10:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.apqxMCPst0/Render', 05:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:38 INFO - 'HOME': '/Users/cltbld', 05:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:38 INFO - 'LOGNAME': 'cltbld', 05:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:38 INFO - 'MOZ_NO_REMOTE': '1', 05:10:38 INFO - 'NO_EM_RESTART': '1', 05:10:38 INFO - 'PAGER': '/bin/cat', 05:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:38 INFO - 'PWD': '/builds/slave/test', 05:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:38 INFO - 'SHELL': '/bin/bash', 05:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EOtdwBkDxW/Listeners', 05:10:38 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:38 INFO - 'USER': 'cltbld', 05:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:38 INFO - 'XPC_FLAGS': '0x0', 05:10:38 INFO - 'XPC_SERVICE_NAME': '0', 05:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:39 INFO - Downloading/unpacking psutil>=3.1.1 05:10:39 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 05:10:39 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 05:10:39 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 05:10:39 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 05:10:39 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 05:10:39 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 05:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:10:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:10:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:10:42 INFO - Installing collected packages: psutil 05:10:42 INFO - Running setup.py install for psutil 05:10:42 INFO - building 'psutil._psutil_osx' extension 05:10:42 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 05:10:42 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 05:10:42 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 05:10:42 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 05:10:42 INFO - building 'psutil._psutil_posix' extension 05:10:42 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 05:10:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:10:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:10:42 INFO - ^ 05:10:42 INFO - 1 warning generated. 05:10:42 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 05:10:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:10:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:10:43 INFO - Successfully installed psutil 05:10:43 INFO - Cleaning up... 05:10:43 INFO - Return code: 0 05:10:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1078e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce38ccb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107786d30>, '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 0x1075aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10777db20>, '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.apqxMCPst0/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.EOtdwBkDxW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:10:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:10:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.apqxMCPst0/Render', 05:10:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:43 INFO - 'HOME': '/Users/cltbld', 05:10:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:43 INFO - 'LOGNAME': 'cltbld', 05:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:43 INFO - 'MOZ_NO_REMOTE': '1', 05:10:43 INFO - 'NO_EM_RESTART': '1', 05:10:43 INFO - 'PAGER': '/bin/cat', 05:10:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:43 INFO - 'PWD': '/builds/slave/test', 05:10:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:43 INFO - 'SHELL': '/bin/bash', 05:10:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EOtdwBkDxW/Listeners', 05:10:43 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:10:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:43 INFO - 'USER': 'cltbld', 05:10:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:43 INFO - 'XPC_FLAGS': '0x0', 05:10:43 INFO - 'XPC_SERVICE_NAME': '0', 05:10:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:10:43 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 05:10:43 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 05:10:43 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 05:10:43 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 05:10:43 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 05:10:43 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 05:10:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:10:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:10:46 INFO - Installing collected packages: mozsystemmonitor 05:10:46 INFO - Running setup.py install for mozsystemmonitor 05:10:46 INFO - Successfully installed mozsystemmonitor 05:10:46 INFO - Cleaning up... 05:10:46 INFO - Return code: 0 05:10:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:10:46 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')]} 05:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:46 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')]} 05:10:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:46 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 0x1078e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce38ccb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107786d30>, '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 0x1075aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10777db20>, '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.apqxMCPst0/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.EOtdwBkDxW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:10:46 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 05:10:46 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 05:10:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.apqxMCPst0/Render', 05:10:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:46 INFO - 'HOME': '/Users/cltbld', 05:10:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:46 INFO - 'LOGNAME': 'cltbld', 05:10:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:46 INFO - 'MOZ_NO_REMOTE': '1', 05:10:46 INFO - 'NO_EM_RESTART': '1', 05:10:46 INFO - 'PAGER': '/bin/cat', 05:10:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:46 INFO - 'PWD': '/builds/slave/test', 05:10:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:46 INFO - 'SHELL': '/bin/bash', 05:10:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EOtdwBkDxW/Listeners', 05:10:46 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:10:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:46 INFO - 'USER': 'cltbld', 05:10:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:46 INFO - 'XPC_FLAGS': '0x0', 05:10:46 INFO - 'XPC_SERVICE_NAME': '0', 05:10:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:46 INFO - Downloading/unpacking blobuploader==1.2.4 05:10:46 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 05:10:46 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 05:10:46 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 05:10:46 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 05:10:46 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 05:10:46 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 05:10:48 INFO - Downloading blobuploader-1.2.4.tar.gz 05:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:10:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:10:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:50 INFO - Downloading docopt-0.6.1.tar.gz 05:10:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:10:50 INFO - Installing collected packages: blobuploader, requests, docopt 05:10:50 INFO - Running setup.py install for blobuploader 05:10:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:10:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:10:50 INFO - Running setup.py install for requests 05:10:50 INFO - Running setup.py install for docopt 05:10:51 INFO - Successfully installed blobuploader requests docopt 05:10:51 INFO - Cleaning up... 05:10:51 INFO - Return code: 0 05:10:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:51 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')]} 05:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:51 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')]} 05:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:51 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 0x1078e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce38ccb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107786d30>, '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 0x1075aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10777db20>, '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.apqxMCPst0/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.EOtdwBkDxW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:10:51 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 05:10:51 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 05:10:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.apqxMCPst0/Render', 05:10:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:51 INFO - 'HOME': '/Users/cltbld', 05:10:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:51 INFO - 'LOGNAME': 'cltbld', 05:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:51 INFO - 'MOZ_NO_REMOTE': '1', 05:10:51 INFO - 'NO_EM_RESTART': '1', 05:10:51 INFO - 'PAGER': '/bin/cat', 05:10:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:51 INFO - 'PWD': '/builds/slave/test', 05:10:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:51 INFO - 'SHELL': '/bin/bash', 05:10:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EOtdwBkDxW/Listeners', 05:10:51 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:10:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:51 INFO - 'USER': 'cltbld', 05:10:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:51 INFO - 'XPC_FLAGS': '0x0', 05:10:51 INFO - 'XPC_SERVICE_NAME': '0', 05:10:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:51 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ftxSRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:51 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-NXNOJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:51 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-T433Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:51 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-BM4YNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:51 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-4Kb7r0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:51 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ZAttfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:51 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-nJ2rsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-M69CA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-kd0kJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-zXiz78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-qF8gZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-chAVt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-bT5TrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-M2bHCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-dwfwbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:52 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-xsbeyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:53 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-j2MTEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:10:53 INFO - Running setup.py install for manifestparser 05:10:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:10:53 INFO - Running setup.py install for mozcrash 05:10:53 INFO - Running setup.py install for mozdebug 05:10:53 INFO - Running setup.py install for mozdevice 05:10:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:10:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:10:53 INFO - Running setup.py install for mozfile 05:10:53 INFO - Running setup.py install for mozhttpd 05:10:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:10:54 INFO - Running setup.py install for mozinfo 05:10:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:10:54 INFO - Running setup.py install for mozInstall 05:10:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:10:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:10:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:10:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:10:54 INFO - Running setup.py install for mozleak 05:10:54 INFO - Running setup.py install for mozlog 05:10:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:10:54 INFO - Running setup.py install for moznetwork 05:10:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:10:54 INFO - Running setup.py install for mozprocess 05:10:54 INFO - Running setup.py install for mozprofile 05:10:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:10:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:10:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:10:55 INFO - Running setup.py install for mozrunner 05:10:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:10:55 INFO - Running setup.py install for mozscreenshot 05:10:55 INFO - Running setup.py install for moztest 05:10:55 INFO - Running setup.py install for mozversion 05:10:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:10:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:10:55 INFO - Cleaning up... 05:10:55 INFO - Return code: 0 05:10:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:55 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 0x1078e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce38ccb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107786d30>, '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 0x1075aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10777db20>, '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.apqxMCPst0/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.EOtdwBkDxW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:10:55 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 05:10:55 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 05:10:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.apqxMCPst0/Render', 05:10:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:55 INFO - 'HOME': '/Users/cltbld', 05:10:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:55 INFO - 'LOGNAME': 'cltbld', 05:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:55 INFO - 'MOZ_NO_REMOTE': '1', 05:10:55 INFO - 'NO_EM_RESTART': '1', 05:10:55 INFO - 'PAGER': '/bin/cat', 05:10:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:55 INFO - 'PWD': '/builds/slave/test', 05:10:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:55 INFO - 'SHELL': '/bin/bash', 05:10:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EOtdwBkDxW/Listeners', 05:10:55 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:10:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:55 INFO - 'USER': 'cltbld', 05:10:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:55 INFO - 'XPC_FLAGS': '0x0', 05:10:55 INFO - 'XPC_SERVICE_NAME': '0', 05:10:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:55 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-GXchpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:56 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)) 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:56 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-Xzx_c0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:56 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)) 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:56 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-AY5MPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:56 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)) 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:56 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-n5W34d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:56 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)) 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:56 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-0rOfLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:56 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)) 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:56 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-etxSQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:56 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)) 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:56 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-LaMRXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:56 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)) 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:56 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-OcxnYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:56 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)) 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:56 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-C18z6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:56 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)) 05:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:56 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-dVe4vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 05:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-26_tzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:57 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)) 05:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-USwtTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:57 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)) 05:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ULOLsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:57 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)) 05:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-Y6UYSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:57 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)) 05:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-NUxq9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:57 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)) 05:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-VGZ8Jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:57 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)) 05:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:57 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ctfrfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:57 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)) 05:10:57 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)) 05:10:57 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)) 05:10:57 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)) 05:10:57 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)) 05:10:57 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)) 05:10:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:00 INFO - Downloading blessings-1.6.tar.gz 05:11:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:11:00 INFO - Installing collected packages: blessings 05:11:00 INFO - Running setup.py install for blessings 05:11:00 INFO - Successfully installed blessings 05:11:00 INFO - Cleaning up... 05:11:00 INFO - Return code: 0 05:11:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:11:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:00 INFO - Reading from file tmpfile_stdout 05:11:00 INFO - Current package versions: 05:11:00 INFO - blessings == 1.6 05:11:00 INFO - blobuploader == 1.2.4 05:11:00 INFO - docopt == 0.6.1 05:11:00 INFO - manifestparser == 1.1 05:11:00 INFO - mozInstall == 1.12 05:11:00 INFO - mozcrash == 0.17 05:11:00 INFO - mozdebug == 0.1 05:11:00 INFO - mozdevice == 0.48 05:11:00 INFO - mozfile == 1.2 05:11:00 INFO - mozhttpd == 0.7 05:11:00 INFO - mozinfo == 0.9 05:11:00 INFO - mozleak == 0.1 05:11:00 INFO - mozlog == 3.2 05:11:00 INFO - moznetwork == 0.27 05:11:00 INFO - mozprocess == 0.22 05:11:00 INFO - mozprofile == 0.28 05:11:00 INFO - mozrunner == 6.11 05:11:00 INFO - mozscreenshot == 0.1 05:11:00 INFO - mozsystemmonitor == 0.3 05:11:00 INFO - moztest == 0.7 05:11:00 INFO - mozversion == 1.4 05:11:00 INFO - psutil == 3.1.1 05:11:00 INFO - requests == 1.2.3 05:11:00 INFO - wsgiref == 0.1.2 05:11:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11: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')]} 05:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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')]} 05:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:00 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 0x1078e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce38ccb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107786d30>, '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 0x1075aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10777db20>, '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.apqxMCPst0/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.EOtdwBkDxW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:11:00 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 05:11:00 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 05:11:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.apqxMCPst0/Render', 05:11:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:00 INFO - 'HOME': '/Users/cltbld', 05:11:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:00 INFO - 'LOGNAME': 'cltbld', 05:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:00 INFO - 'MOZ_NO_REMOTE': '1', 05:11:00 INFO - 'NO_EM_RESTART': '1', 05:11:00 INFO - 'PAGER': '/bin/cat', 05:11:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:00 INFO - 'PWD': '/builds/slave/test', 05:11:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:00 INFO - 'SHELL': '/bin/bash', 05:11:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EOtdwBkDxW/Listeners', 05:11:00 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:11:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:00 INFO - 'USER': 'cltbld', 05:11:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:00 INFO - 'XPC_FLAGS': '0x0', 05:11:00 INFO - 'XPC_SERVICE_NAME': '0', 05:11:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:01 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)) 05:11:01 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)) 05:11:01 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)) 05:11:01 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)) 05:11:01 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)) 05:11:01 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)) 05:11:01 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)) 05:11:01 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)) 05:11:01 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)) 05:11:01 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)) 05:11:01 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)) 05:11:01 INFO - Cleaning up... 05:11:01 INFO - Return code: 0 05:11:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:11:01 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')]} 05:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:01 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')]} 05:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:01 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 0x1078e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce38ccb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107786d30>, '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 0x1075aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10777db20>, '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.apqxMCPst0/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.EOtdwBkDxW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:11:01 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 05:11:01 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 05:11:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.apqxMCPst0/Render', 05:11:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:01 INFO - 'HOME': '/Users/cltbld', 05:11:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:01 INFO - 'LOGNAME': 'cltbld', 05:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:01 INFO - 'MOZ_NO_REMOTE': '1', 05:11:01 INFO - 'NO_EM_RESTART': '1', 05:11:01 INFO - 'PAGER': '/bin/cat', 05:11:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:01 INFO - 'PWD': '/builds/slave/test', 05:11:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:01 INFO - 'SHELL': '/bin/bash', 05:11:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EOtdwBkDxW/Listeners', 05:11:01 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:11:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:01 INFO - 'USER': 'cltbld', 05:11:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:01 INFO - 'XPC_FLAGS': '0x0', 05:11:01 INFO - 'XPC_SERVICE_NAME': '0', 05:11:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:01 INFO - Downloading/unpacking jsonschema 05:11:01 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 05:11:01 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 05:11:01 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 05:11:01 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 05:11:01 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 05:11:01 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 05:11:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:11:03 INFO - Downloading/unpacking functools32 (from jsonschema) 05:11:03 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 05:11:03 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 05:11:03 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 05:11:03 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 05:11:03 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 05:11:03 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 05:11:04 INFO - Downloading functools32-3.2.3-2.tar.gz 05:11:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:11:04 INFO - warning: no files found matching '*.txt' 05:11:04 INFO - no previously-included directories found matching 'build' 05:11:04 INFO - no previously-included directories found matching 'dist' 05:11:04 INFO - no previously-included directories found matching '.git*' 05:11:04 INFO - Installing collected packages: jsonschema, functools32 05:11:04 INFO - Running setup.py install for functools32 05:11:04 INFO - warning: no files found matching '*.txt' 05:11:04 INFO - no previously-included directories found matching 'build' 05:11:04 INFO - no previously-included directories found matching 'dist' 05:11:04 INFO - no previously-included directories found matching '.git*' 05:11:04 INFO - Successfully installed jsonschema functools32 05:11:04 INFO - Cleaning up... 05:11:04 INFO - Return code: 0 05:11:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:11: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')]} 05:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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')]} 05:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1078e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce38ccb600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107786d30>, '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 0x1075aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10777db20>, '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.apqxMCPst0/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.EOtdwBkDxW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 05:11: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', 'flake8'] in /builds/slave/test/build 05:11: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 flake8 05:11:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.apqxMCPst0/Render', 05:11:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:04 INFO - 'HOME': '/Users/cltbld', 05:11:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:04 INFO - 'LOGNAME': 'cltbld', 05:11:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:04 INFO - 'MOZ_NO_REMOTE': '1', 05:11:04 INFO - 'NO_EM_RESTART': '1', 05:11:04 INFO - 'PAGER': '/bin/cat', 05:11:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:04 INFO - 'PWD': '/builds/slave/test', 05:11:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:04 INFO - 'SHELL': '/bin/bash', 05:11:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EOtdwBkDxW/Listeners', 05:11:04 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:11:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:04 INFO - 'USER': 'cltbld', 05:11:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:04 INFO - 'XPC_FLAGS': '0x0', 05:11:04 INFO - 'XPC_SERVICE_NAME': '0', 05:11:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:04 INFO - Downloading/unpacking flake8 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:11:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:11:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:11:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:11:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:11:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:11:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:11:08 INFO - no previously-included directories found matching 'docs/_build' 05:11:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:11:08 INFO - Running setup.py install for pep8 05:11:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:11:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:11:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:11:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:11:09 INFO - no previously-included directories found matching 'docs/_build' 05:11:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:11:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:11:09 INFO - Cleaning up... 05:11:09 INFO - Return code: 0 05:11:09 INFO - Running post-action listener: _resource_record_post_action 05:11:09 INFO - Running post-action listener: _start_resource_monitoring 05:11:09 INFO - Starting resource monitoring. 05:11:09 INFO - ##### 05:11:09 INFO - ##### Finished create-virtualenv step (success) 05:11:09 INFO - ##### 05:11:09 INFO - ##### 05:11:09 INFO - ##### Running install step. 05:11:09 INFO - ##### 05:11:09 INFO - Running pre-action listener: _resource_record_pre_action 05:11:09 INFO - Running main action method: install 05:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:09 INFO - Reading from file tmpfile_stdout 05:11:09 INFO - Detecting whether we're running mozinstall >=1.0... 05:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:11:09 INFO - Reading from file tmpfile_stdout 05:11:09 INFO - Output received: 05:11:09 INFO - Usage: mozinstall [options] installer 05:11:09 INFO - Options: 05:11:09 INFO - -h, --help show this help message and exit 05:11:09 INFO - -d DEST, --destination=DEST 05:11:09 INFO - Directory to install application into. [default: 05:11:09 INFO - "/builds/slave/test"] 05:11:09 INFO - --app=APP Application being installed. [default: firefox] 05:11:09 INFO - mkdir: /builds/slave/test/build/application 05:11:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:11:42 INFO - Reading from file tmpfile_stdout 05:11:42 INFO - Output received: 05:11:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:11:42 INFO - Running post-action listener: _resource_record_post_action 05:11:42 INFO - ##### 05:11:42 INFO - ##### Finished install step (success) 05:11:42 INFO - ##### 05:11:42 INFO - ##### 05:11:42 INFO - ##### Running run-tests step. 05:11:42 INFO - ##### 05:11:42 INFO - Running pre-action listener: _resource_record_pre_action 05:11:42 INFO - Running main action method: run_tests 05:11:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:11:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:11:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:11:42 INFO - 2016-05-17 05:11:42.920 screenresolution[6270:104398] starting screenresolution argv=screenresolution get 05:11:42 INFO - 2016-05-17 05:11:42.922 screenresolution[6270:104398] Display 0: 1600x1200x32@60 05:11:42 INFO - 2016-05-17 05:11:42.929 screenresolution[6271:104400] starting screenresolution argv=screenresolution list 05:11:42 INFO - Available Modes on Display 0 05:11:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:11:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:11:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:11:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:11:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:11:43 INFO - Intel Iris: 05:11:43 INFO - Chipset Model: Intel Iris 05:11:43 INFO - Type: GPU 05:11:43 INFO - Bus: Built-In 05:11:43 INFO - VRAM (Dynamic, Max): 1536 MB 05:11:43 INFO - Vendor: Intel (0x8086) 05:11:43 INFO - Device ID: 0x0a2e 05:11:43 INFO - Revision ID: 0x0009 05:11:43 INFO - Displays: 05:11:43 INFO - Display: 05:11:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:11:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:11:43 INFO - Display Serial Number: 1600x1200 60 05:11:43 INFO - Main Display: Yes 05:11:43 INFO - Mirror: Off 05:11:43 INFO - Online: Yes 05:11:43 INFO - Rotation: Supported 05:11:43 INFO - Return code: 0 05:11:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:11:43 INFO - Python 2.7.3 05:11:43 INFO - Return code: 0 05:11:43 INFO - grabbing minidump binary from tooltool 05:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:43 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107786d30>, '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 0x1075aadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10777db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:11:43 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 05:11:43 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 05:11:43 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:11:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:11:43 INFO - Return code: 0 05:11:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:11:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:11:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:11:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:11:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:11:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:11:44 INFO - Return code: 0 05:11:44 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/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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 05:11:44 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/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0185 --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 05:11:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.apqxMCPst0/Render', 05:11:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:44 INFO - 'HOME': '/Users/cltbld', 05:11:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:44 INFO - 'LOGNAME': 'cltbld', 05:11:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:11:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:44 INFO - 'MOZ_NO_REMOTE': '1', 05:11:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:11:44 INFO - 'NO_EM_RESTART': '1', 05:11:44 INFO - 'PAGER': '/bin/cat', 05:11:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:44 INFO - 'PWD': '/builds/slave/test', 05:11:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:11:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:44 INFO - 'SHELL': '/bin/bash', 05:11:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EOtdwBkDxW/Listeners', 05:11:44 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 05:11:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:44 INFO - 'USER': 'cltbld', 05:11:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:44 INFO - 'XPC_FLAGS': '0x0', 05:11:44 INFO - 'XPC_SERVICE_NAME': '0', 05:11:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:44 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/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--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 05:11:44 INFO - mozversion application_buildid: 20160517031837 05:11:44 INFO - mozversion application_changeset: 036f62007472d4e2ada1564718b617f60cd00d43 05:11:44 INFO - mozversion application_display_name: Nightly 05:11:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:11:44 INFO - mozversion application_name: Firefox 05:11:44 INFO - mozversion application_remotingname: firefox 05:11:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:11:44 INFO - mozversion application_vendor: Mozilla 05:11:44 INFO - mozversion application_version: 49.0a1 05:11:44 INFO - mozversion platform_buildid: 20160517031837 05:11:44 INFO - mozversion platform_changeset: 036f62007472d4e2ada1564718b617f60cd00d43 05:11:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:11:44 INFO - mozversion platform_version: 49.0a1 05:11:44 INFO - using testdate: 1463487104 05:11:44 INFO - actual date: 1463487104 05:11:44 INFO - starting webserver on 'localhost:57062' 05:11:44 INFO - SUITE-START | Running 2 tests 05:11:44 INFO - TEST-START | dromaeo_css 05:11:44 INFO - operating with platform_type : mac_ 05:11:44 INFO - Initialising browser for dromaeo_css test... 05:11:44 INFO - TEST-INFO | started process 6289 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpGs41gd/profile http://localhost:57062/getInfo.html) 05:11:46 INFO - PROCESS | 6289 | __metrics Screen width/height:1600/1200 05:11:46 INFO - PROCESS | 6289 | colorDepth:24 05:11:46 INFO - PROCESS | 6289 | Browser inner width/height: 1024/689 05:11:46 INFO - PROCESS | 6289 | __metrics 05:11:47 INFO - PROCESS | 6289 | 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" 05:11:47 ERROR - PROCESS | 6289 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:11:47 INFO - TEST-INFO | 6289: exit 0 05:11:47 INFO - Browser initialized. 05:11:47 INFO - Running cycle 1/1 for dromaeo_css test... 05:11:47 INFO - TEST-INFO | started process 6292 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpGs41gd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:59 INFO - PROCESS | 6292 | RSS: Main: 239353856 05:11:59 INFO - PROCESS | 6292 | 05:12:50 INFO - PROCESS | 6292 | 1463487170321 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] 05:15:01 INFO - PROCESS | 6292 | Cycle 1(1): loaded http://localhost:57062/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57062/tests/dromaeo/cssquery-ext.html) 05:15:03 INFO - PROCESS | 6292 | RSS: Main: 326885376 05:15:03 INFO - PROCESS | 6292 | 05:18:06 INFO - PROCESS | 6292 | Cycle 1(1): loaded http://localhost:57062/tests/dromaeo/cssquery-ext.html (next: http://localhost:57062/tests/dromaeo/cssquery-jquery.html) 05:18:06 INFO - PROCESS | 6292 | RSS: Main: 367403008 05:18:06 INFO - PROCESS | 6292 | 05:21:08 INFO - PROCESS | 6292 | Cycle 1(1): loaded http://localhost:57062/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57062/tests/dromaeo/cssquery-mootools.html) 05:21:09 INFO - PROCESS | 6292 | RSS: Main: 369541120 05:21:09 INFO - PROCESS | 6292 | 05:24:11 INFO - PROCESS | 6292 | Cycle 1(1): loaded http://localhost:57062/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57062/tests/dromaeo/cssquery-prototype.html) 05:24:12 INFO - PROCESS | 6292 | RSS: Main: 362872832 05:24:12 INFO - PROCESS | 6292 | 05:27:15 INFO - PROCESS | 6292 | Cycle 1(1): loaded http://localhost:57062/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57062/tests/dromaeo/cssquery-yui.html) 05:27:16 INFO - PROCESS | 6292 | RSS: Main: 330399744 05:27:16 INFO - PROCESS | 6292 | 05:30:19 INFO - PROCESS | 6292 | Cycle 1(1): loaded http://localhost:57062/tests/dromaeo/cssquery-yui.html (next: http://localhost:57062/tests/dromaeo/cssquery-dojo.html) 05:30:19 INFO - PROCESS | 6292 | RSS: Main: 375963648 05:30:19 INFO - PROCESS | 6292 | 05:30:19 INFO - PROCESS | 6292 | __start_tp_report 05:30:19 INFO - PROCESS | 6292 | _x_x_mozilla_page_load 05:30:19 INFO - PROCESS | 6292 | _x_x_mozilla_page_load_details 05:30:19 INFO - PROCESS | 6292 | |i|pagename|runs| 05:30:19 INFO - PROCESS | 6292 | |0;dojo.html;3405;3406.744494162937;3426.0749597608633;3434.6393628669;3481.3385456763212;5601.215829783833;5683.516901063409;5687.17535957286;5831.300243970724;5845.970770146148;5767.8269651910405;5802.419758024195;5827.475527202551;5832.854178645536;5840.269966254218;5148.382194136709;5197.662151960129;5234.319538459995;5245.396779370364;5288.101022826128;5259.447757985414;5261.158214685651;5268.130758424855;5283.498067683583;5292.894142117175;5157.406898206698;5166.689998600096;5182.766775495093;5187.896242075156;5283.022640811443;5246.108161612535;5281.70950597717;5291.629585928987;5305.973470132663;5345.732713364317;5101.056304583627;5116.667416617908;5160.7419629018395;5199.870003249911;5210.687358758487;5598.692071936042;5613.129964855454;5623;5651.321841379061;5656.4060773618585;6211.130441738161;6232.532560057968;6233.781817636361;6241.9375806241815;6264.561480696353;5294.523492885659;6167.136600875881;6179.87640247197;6213.627182369072;6217.96891015542;5929.436703513159;5979.103134529855;5989.940100598982;6002.609830361012;6005.969970150165;6269.404406581368;6282.528810339243;6306.24325080984;6309.589876658002;6317.020861766342;5876.6180198286975;6061.77931986162;6138.202033735586;6161.353057928853;6201.379862013853;5286.867828304246;6184.721687524072;6199.473044791153;6206.875862482725;6208.037754148114;6229.0967809667345;6237.4698150656795;6238.220222472191;6251.562390632612;6272.655003974825;6111.694415279218;6181.134641150243;6190.071489276645;6191.442770150708;6292.087647291117;4964.925526117111;4991.401031876198;4992;4994.001199760062;5026.396832380138;4491.73049617024;4493.213687604722;4505.301678239878;4522.706024108423;4549.613282870927;5650.830475085754;5677.489025987681;5683.630564013326;5726.141078838207;5726.455986681259;5288.656237344637;5541.833744987657;6208.161898143783;6211.192544969125;6220.906686399708;5712.857178570486;5721.256236689203;5730.426957304319;5753.367129615739;5753.568482363756;5310.8141215057285;5353.652012619167;5378.650387724863;5411.107167317427;5426.375966763794;6297.653629050263;6339.587926784744;6348.238211414659;6351.904721429275;6355.237371515448;6148.9692551536955;6200.472959798468;6225;6244;6255.593386429868;6251.249850017845;6269.341719119427;6299.622022678654;6395.040743888454;6410.006449000412;6220.191375121205;6230.283517395557;6232.501399888089;6237.157983672328;6251.124842522141;6160.476359509492;6167.044108163242;6201.782937597162;6213.409726076015;6220.129181914445;5990.670513121646;6067.605605635619;6080.48315893154;6082.482988945989;6124.6325220486915;6003.6397816131175;6041.728122234598;6051.818445446644;6075.878482430415;6081.483073938764;5792.4497172768515;5909.55678324136;5909.734061967136;5923.378045305181;5929.347771745019;6010.098485227251;6077.908831367445;6105.61138071644;6108.389161083856;6141.88493709207;5876.706164691663;6036.607620504828;6045.637261764308;6055.848603784764;6080.817575472743 05:30:19 INFO - PROCESS | 6292 | |1;ext.html;497.12257864868513;511.89506151238993;515.819463187884;517.5755880178256;560.011579562073;30715.69284307159;31606.525902111458;42242.577574224284;43387.13225735483;43638.56361436385;44980.10039799202;45269.54730452695;45366.865828354305;45378;45499.77250113745;9704.22366210705;9896.752581185456;9896.950515247414;9901.603935842593;9906.157976571963;8744.650213991432;8952.283817294634;8968.372213945026;11727.648170554898;11996.04031677468;11912.106592005592;11954.402279886015;11956.402179891014;11983.340916249603;12001.51993920242;11822.290662560232;11891.28652280866;11902.928736413722;11903.583374581847;11912.094064949566;8682.044975052739;11521.827172592419;11817.94091029548;11833.585824496187;11872.703182420508;41698.79150604258;41844.58155418437;42006;42062.78968605137;42129.57870421287;2934.505670180351;3258.201740573551;3350.977951724723;3360.092774950774;3369.25876307212;29188.562171567442;29780.659870305888;29878.701212988028;29900.70099299001;30050.92447117843;2697.6979710173737;2730.6859711133275;2760.83434993899;2802.467531169071;2843.516602177635;388046;389847;390771;391014;512476;60311.698441508386;60673.69663151744;60899.69550152309;61044;61098;37272.81363593165;38032.859014229616;38168.61831381706;39109.60890391117;39294.21411571753;17046.57383565396;17076.316947322215;17271.481855544353;17277.740833887503;17286.308547658205;11932.76134477323;13094.476220951246;13217.537386191438;13241.940644748589;13248.867511324956;16764.58088547796;17315.567110822078;17463.38878139259;19609.803901960797;20714.689279660815;8107.067687216046;8288.295494883003;8306.501609903426;8345.290650294794;8387.580620968927;927.4759760735298;1045.7333379988054;1108.4623957380663;1146.8623765148236;1171.4611278811672;25055.373615659755;27207.455850883267;30253.243668908453;30439;30646;1098.2586753941057;1126.9042131418921;1134.6028889888473;1162.1458228202264;1169.771894480568;14146.504872329419;15608.609784755245;15687.294071766797;15712.60718482047;16078.919605402132;587081;721529;730216;730391;733208;60832.695836520536;63201.68399158067;63303;63364;63570;1326.2572959142717;1359.503781119884;1463.9341229644479;1498.9625259368386;1518.7114448254797;29793.179993855952;30963.845180774853;31039.379212416075;31384.372312553165;32704;53360;53571.46428535665;54189;54211;54556.999365035306;565995;567015;568718;570131;570327.1483642556;23955.16156934499;44459;44551.55448445474;45282.547174527834;46152.53847461483;47165.764171178926;47342;48890.51109488859;49779.253311201086;51133.48866511436;38903;40088.59911400965;40327.39508907427;40963.59036409598;41101;37185.4422183662;39549;39764.602353976086;39914.80042599768;41060;39729;40010.599894001854;40221.396679049256;40514.39228411517;40588.39117413182;34257.486137707456;35371.64628353786;36145.81927090348;36283.27433451264;36496.63503364933;25612.615810763225;26052.34869128287;26128.477430450905;26133.607995879698;26726.465470690087 05:30:19 INFO - PROCESS | 6292 | |2;jquery.html;386.1215734204684;391.7551530293567;391.8883118311282;393.16648704745927;417.94566706328175;13961.741668791015;19213.519662008457;20942.95285235736;21047;21153.471163220926;15962.840371596281;16083.276252568634;20329.695054574193;20934.685979710277;21096.68354974673;5773.451522105393;5980.192673989;5988.401159884021;5995.760169593211;6023.909641355383;5904.793332233373;5940.316863560703;5945.435183657545;5961.523078153759;6009.5192384609345;5767.30792304924;5889.381614930435;5898.321693005295;5939.940600593982;5943.643381397108;5130.9230361544605;5652.406497317764;5800.651960882339;5911.172435858962;5920.763169473216;5081.771320290596;5137.255098010804;5141.871453213662;5143.382794064699;5144.945286216317;25244.49511009788;25328.366790830194;25545;25736.356591085187;26388.60417093745;2186.650135978252;2190.5509370578993;2197.560487902426;2230.9553808923893;2355.5171189906027;14028.368723407471;14447.277636118151;14750.409983600643;15325.386984520606;15396.769048464283;1756.4906177208595;1802.738602902585;1828.4240464253717;1831.4505648305587;1916.865819392643;96523.06953860953;123302;125668;130333;133234;10237.94881025605;10660.46697665129;10958.726031849108;10966.780664386668;11414.543418263342;13993.950453716012;14035.78946315806;14041.71916561663;14061.78907316391;14069.015168938076;7585;7672.923270767221;8691.565421729014;8910.287177025823;8926.776830579287;7345.595992220372;7473.177950425449;8831.3376496763;8853.645854165761;8854.955725221462;5597.804076857372;6602.471802255808;6736.090627765287;6740.528163028541;6758.256591774901;1881.7365568820376;1917.597304566029;1918.213532451688;1923.9711404328946;1953.667876461005;915.5605309451367;952.5332587032304;1013.366645846346;1060.7189094889861;1114.9944250278697;20323.695144572845;20850.06174722141;21394.144234230767;21411.35765927025;21473;997.6730947839357;1001.9398836069859;1011.3173607814842;1030.3766221436074;1033.9327943683786;10176.898231017743;11020.228583999044;11201.383923884265;11259.605913792979;11429.257098288585;523790;607070;624302;626458;626508;82719.17280827115;83893.58053209817;113113;146671;150331;957.0477375011768;1010.5553556435145;1022.3303736052898;1086.4839201379282;1088.3796236145322;18108.366207182684;18384;18506.629867402848;18607.72088418702;18817;41247.793761031004;50800.23799642934;51142.23286650629;51930.92276308905;53027.46972530225;70243.29756702571;72894.27105728874;72921.27078729145;74728;75487;7605.847883042419;8176.83646327082;8237.423380363318;8325.333973282002;8520.446170998865;11874.821877672015;12502.937485312515;12548.811767823307;12881.162724422882;12898.613041608767;10987.61543345979;11159.38623375722;11293.548258069914;11354.318740875575;11427.714307142389;10726.517306721373;10871.29336593119;10902.127829773757;11186.72033199144;11193.328400296008;10901.291416057931;11394.886051139383;11643.650690479299;11684.47419866125;11693.239939403911;6959.96520017417;8060.959695201486;8246.628901699318;8280.58597070156;8469.61886715087;9597.136257736836;11400.714982125513;11415.429228538706;11758.823617645914;11763.000144987429 05:30:19 INFO - PROCESS | 6292 | |3;mootools.html;1370.6367812529688;1422.2391020803868;1586.3258115300998;1799.4781513361127;1950.3563823938093;7981.441299109065;8452.450590711607;8474.703385381514;8789.2968562515;9062.592183351748;8831.735047948556;8920.73237802867;9017.233535149504;9022.774430639221;9028.458292502439;2432.768886955745;2471.9876400617973;2473.196211231348;2473.4558397152596;2473.9980307975297;2421.8546887186735;2484.465839844433;2484.776370126688;2486.2416962826333;2486.8383555068863;2385.6302273147603;2401.7838394544488;2476.405662640971;2476.4428003699204;2476.913308034219;2088.9450827332175;2372.5492156490213;2404.507076049423;2411.553862535428;2453.411181316489;2191.5288213034273;2320.2575175943703;2407.1935901473025;2433.111914151322;2438.2928950604305;8955.014948355676;9033.954830225872;9040.638374465014;9052.094790520961;9052.592633331516;5367.919481207785;5370.301860758115;5559.749811258463;5563.193336966157;5569.415211402826;3498.3178280235365;3652.543432070991;3713.925721485582;3732.2908647356917;3736.925261494755;1044.7954815339626;1046.9005444482762;1058.867641544807;1063.940856820408;1073.044989958937;11700.765984680258;11906.46420911061;11976.580819671357;12211.389430528527;12223.205491643015;4682.157211701926;4700.153972285021;4749.097671442415;4781.904361912742;4803.807847686054;9407.811843763224;9410.058994100535;9413.246940244764;9416.905830941603;9419.058094190663;5425.972870135624;5429.647072940246;5430.0768869292315;5435.646682965674;5454.181872719045;4500.707454015478;4601.976990115028;4877.756112194376;4981.875453113628;5066.012127635149;3826.8660596878976;3863.1307955710304;3922.195949830278;3961.0493481564217;4039.5354534228354;1219.8902098810972;1229.7847876621556;1229.8708635593314;1236.8206610041493;1247.7878760610718;669.5279827721445;824.2046425199711;917.5091326140483;939.8214339275515;976.0965031441643;5722.942770572325;5937.050071988461;5963.2844058713235;5981.551383646157;6086.391360863879;845.7758693946109;952.457099453305;963.5230560872413;965.8261512927741;990.757264470195;3776.5656949451163;3957.8614748483665;3996.120853412303;4010.218007488512;4050.594940505926;10647.627333043307;10875.728106797234;11071.225014249087;11082.335059896432;11094.22340436178;11018.50416731249;11031.338119712844;11064.170187235828;11078.612248571262;11114.888851111547;8009.359251259885;8385.161483851565;8422.578871056423;8498.192671696179;8512.744617661356;5068.518490743373;6034.487068599218;6038.215032045806;6079.574429789872;6158.753649853956;9804;10053.497325133569;10404;10407.386855037481;10460.738481538023;10155.187584993035;10238.897611023795;10574.365538067741;10594.735131621772;10613.734656633645;7452.478326517092;7680.3471704905705;7711.151773305044;7716.92283077162;7718.575478348745;8241.752747417588;8359.373047021376;8361.790955226168;8396.916030839613;8431.873521897054;7636.770896873103;7639.350655194371;7667.654955526903;7826.882596760939;7840.84318313642;7426.108866936002;7525;7532.661030253542;7660.80847978805;7697.384209263358;6897.379235868796;6907.378335949788;7009.1589009319205;7059.117610298713;7085.220625731063;4821.325014497903;4872.3909511311085;4875.68308059975;4902.852914412573;4937;4918.0327868851555;4955.058538877602;4989.650724449254;5026.673266237683;5114.64197506171 05:30:19 INFO - PROCESS | 6292 | |4;prototype.html;303.32358839787275;317.82360789761674;321.52735478846097;325.55724215067505;329.75598057437503;4940.654154209206;5173;5359.598030147745;5393.757280922358;5448.291722076135;4509.684322097454;5303.738201006382;5323.760430780614;5371.57027437805;5413.621046526745;3507.6141624421293;3838.4242363645467;4005.3391190453685;4093.9181216375655;4154.896127596804;3589;4014.2974979378637;4088.693347998897;4135.7518548887165;4136.97931510342;3557.3418917500344;4020.577839326858;4053.939190912124;4075.286824805662;4082.3464747913945;3544.6278140795102;3746.3631182699005;3845.096402345461;4024.577419370953;4062.878113656595;2916.6354205724283;3025.9092227233;3125.734312583433;3504.6320136385566;3518.437050072002;4166.604172603597;5105.489451054865;5134.64057515974;5183.3002544656365;5213.4525874782985;3504.3867323218333;4671.439427268716;4687.531246875319;4709.2229782085915;4724.11035585765;5385.542228910547;5438.456154384569;5617.118265381102;5629.4652008059165;5646.57650676201;3846.942295865564;3874.496315478998;3877.4377715231135;3881.3983832506005;3887.455756194135;5269.815556455505;5458.645188062763;5600.5799565032785;5832.941670583282;5860.941390586082;3874.670652994471;3894.7273690841935;3903.531576210873;3913.8434462621176;3922.6270805218514;3391.4573668212965;3661.8718344857803;3719.8512059517316;3963.2271707016853;3986.262541429845;3957.8614748483665;4634.119517291704;4638.443386793607;4671.416072990876;4698.2247929092;4865.783554111472;5324.281222034976;5379.973100134474;5502.8349149525575;5657.6322539035655;1307.3136603283353;1342.838859336886;1342.9462821487032;1345.8560223809684;1346.6667998681235;767.7179110884836;773.2653978720239;777.7316825695126;792.4532072869702;839.6515446089926;4886.706797592155;5097.311862898536;5100.438951715309;5112.539871411591;5186.299849520342;4926.9014619707405;5458.699771512525;5468.589855760754;5763.308402991668;5845.415458454206;4876.853694389103;5032.949670503322;5230.869228269324;5261.237120617489;5283.260343551829;2516.9496610067695;2635.5124302003805;2688.7445692659167;2782.916512504628;3050.3289276359164;5079.923801142986;5238.528532432099;5439.48324909133;5603.551715862721;5830.618241420051;5265.289185959923;5865.941340586625;5960.152352770655;5961.731722072517;5990.9515834728745;5920.8815823684145;5957.880842383128;5963.373845746308;5990.73041713124;6027.9095813562835;5438.483344082306;5769.192313076089;5823.1847541343395;5846.239988801599;5847.72527384666;5579.051561234519;5733.308673941114;5736.311642602915;5766.163906233656;5783.826485205451;5427.050266203478;5443.237946687546;5505.513511351993;6072.1498990140535;6099.817005489843;3229.4187046331886;3682.0978860179425;3893.610638936084;3905.121347696859;3959.0894094359037;5576.386597474194;5858.619189752652;5880.353161152356;5880.676562789087;5893.499052580579;4391.670624703224;4474.395956545824;5561.916571251353;5702.600817942704;5730.971345143247;5063.392392912874;5641.153826925994;5665.433456654301;5695.914561281501;5733.942660573341;4439.780109945027;4677.181493238738;4933.703977761345;4971.65198436106;5405.351357837085;5074.767102474899;5468.28912241406;5617.43825617435;5671.0075736745075;5722.914156287742;4547.567981041796;5215.9739201305265;5552.944470555242;5614.5789065821045;5653 05:30:19 INFO - PROCESS | 6292 | |5;yui.html;647.7732793522266;673.8618583190447;738.8042168825259;772.992270077299;805.8468890910723;13130.671733206675;15252.847471525281;15493.457728979474;15645.765313520278;15651.843481565182;12847.807282890764;14454.060486068425;14508.637284067878;14520.85479145211;15908.681826363465;716.1334784910265;932.3473568502042;933.0855761353861;939.3800091939321;942.3403617467766;740.4113729584993;905.3345790843724;934.4019827310527;942.0485309837045;944.0276515189366;979.0943377375935;980.7940332530177;982.7985263021063;986.896375880533;988.4316518002141;738.7857521318865;949.4493193947494;981.362114625492;981.5877331520743;981.6269817469388;720.909886264217;732.1616748822621;732.7215658049907;733.7651951375562;736.2269616902284;15692.450764223197;15735.68528629421;15735.763963540556;15771.447999320084;15790.973586716827;12042.277463352139;12286.75426491465;12287.078469114853;12420.316882571455;12582.748345033047;10779.946100269448;10815.67552973412;10859.782804343948;11068.778624427547;11440.027597654209;3985.262726395626;3997.780122093284;4005;4118.217538667674;4129.194807012634;91749;92192;93670.53164734201;94112;95683.56474652955;9246.662002649591;9341.859872101788;9390.483523406141;9516.952415238018;9619.18236949853;21098.156073756876;21101;21163.47091322729;21168.153273868873;21229.78770212278;7182;7182.461315401366;7231;7381.66782494789;7484.550926944292;4018.2967980603275;4069.2675318442966;4086.693497987663;4086.775227362464;4093.4473846031;3336.916577085544;3492.5808902931813;3494.178867966026;3494.790312581253;3495.580530336376;639.3255115852727;651.312864927506;653.650297091054;657.135866335767;661.8146918862726;474.3430348966706;583.4544700704854;591.7130191856946;617.3795335687605;631.1416473595906;6703.564268322543;7854.803629909184;8047.919520804834;8259.463134896212;8338.541380224146;587.2629849538802;615.3292910727329;617.6263360666821;617.6818938246806;626.6271568416785;3762.981185094057;3861.189150278417;3912.5304963404005;3972.066564357374;4130.545639979599;88206;106499.467502662;106872;109163.45418272857;110330;82802;88021.67967480492;88333.67499487512;88939.55530222307;90336;69683.30316696869;69747.65126174438;69935;74070.62964685034;78749.4250115006;8275.62759675828;8341.282142999067;8429.4942303462;8519.23326900582;8590.355723320901;47185.292220617404;48008.759956199996;48278;49609.75195124001;50290.49709502858;65334.34665653283;65515;65962;66111;66550;10969.561217551527;11022.889771102185;11290.49192786343;11356.488957997075;11445.828312575484;10791.56833726642;11473;11594.18840681178;11957.461914214055;12027.21823081497;11359.772804543698;11483.770324593293;11484.598039068593;11513.712157196138;11625.418729063344;11166.720831979266;11298.661040168809;11415.258008229439;11426.314421134759;11433.942830285796;11151.996320330885;11205.327680339205;11210.66368008961;11363.147763917574;11594;9482.525873706425;9499.240060795259;9673;9759.359613231618;9919.553620087258;9607;9863.852042219229;9880.308378413441;9887;9922.702318930444 05:30:19 INFO - PROCESS | 6292 | __end_tp_report 05:30:19 INFO - PROCESS | 6292 | __start_cc_report 05:30:19 INFO - PROCESS | 6292 | _x_x_mozilla_cycle_collect,1378 05:30:19 INFO - PROCESS | 6292 | __end_cc_report 05:30:19 INFO - PROCESS | 6292 | __startTimestamp1463488219449__endTimestamp 05:30:19 INFO - PROCESS | 6292 | 05:30:19 INFO - PROCESS | 6292 | ------- Summary: start ------- 05:30:19 INFO - PROCESS | 6292 | Number of tests: 6 05:30:19 INFO - PROCESS | 6292 | 05:30:19 INFO - PROCESS | 6292 | [#0] dojo.html Cycles:165 Average:5740.92 Median:5979.10 stddev:618.01 (10.3%) stddev-sans-first:592.11 05:30:19 INFO - PROCESS | 6292 | Values: 3405.0 3406.7 3426.1 3434.6 3481.3 5601.2 5683.5 5687.2 5831.3 5846.0 5767.8 5802.4 5827.5 5832.9 5840.3 5148.4 5197.7 5234.3 5245.4 5288.1 5259.4 5261.2 5268.1 5283.5 5292.9 5157.4 5166.7 5182.8 5187.9 5283.0 5246.1 5281.7 5291.6 5306.0 5345.7 5101.1 5116.7 5160.7 5199.9 5210.7 5598.7 5613.1 5623.0 5651.3 5656.4 6211.1 6232.5 6233.8 6241.9 6264.6 5294.5 6167.1 6179.9 6213.6 6218.0 5929.4 5979.1 5989.9 6002.6 6006.0 6269.4 6282.5 6306.2 6309.6 6317.0 5876.6 6061.8 6138.2 6161.4 6201.4 5286.9 6184.7 6199.5 6206.9 6208.0 6229.1 6237.5 6238.2 6251.6 6272.7 6111.7 6181.1 6190.1 6191.4 6292.1 4964.9 4991.4 4992.0 4994.0 5026.4 4491.7 4493.2 4505.3 4522.7 4549.6 5650.8 5677.5 5683.6 5726.1 5726.5 5288.7 5541.8 6208.2 6211.2 6220.9 5712.9 5721.3 5730.4 5753.4 5753.6 5310.8 5353.7 5378.7 5411.1 5426.4 6297.7 6339.6 6348.2 6351.9 6355.2 6149.0 6200.5 6225.0 6244.0 6255.6 6251.2 6269.3 6299.6 6395.0 6410.0 6220.2 6230.3 6232.5 6237.2 6251.1 6160.5 6167.0 6201.8 6213.4 6220.1 5990.7 6067.6 6080.5 6082.5 6124.6 6003.6 6041.7 6051.8 6075.9 6081.5 5792.4 5909.6 5909.7 5923.4 5929.3 6010.1 6077.9 6105.6 6108.4 6141.9 5876.7 6036.6 6045.6 6055.8 6080.8 05:30:19 INFO - PROCESS | 6292 | 05:30:19 INFO - PROCESS | 6292 | [#1] ext.html Cycles:180 Average:70098.04 Median:38100.74 stddev:153813.14 (403.7%) stddev-sans-first:154155.86 05:30:19 INFO - PROCESS | 6292 | Values: 497.1 511.9 515.8 517.6 560.0 30715.7 31606.5 42242.6 43387.1 43638.6 44980.1 45269.5 45366.9 45378.0 45499.8 9704.2 9896.8 9897.0 9901.6 9906.2 8744.7 8952.3 8968.4 11727.6 11996.0 11912.1 11954.4 11956.4 11983.3 12001.5 11822.3 11891.3 11902.9 11903.6 11912.1 8682.0 11521.8 11817.9 11833.6 11872.7 41698.8 41844.6 42006.0 42062.8 42129.6 2934.5 3258.2 3351.0 3360.1 3369.3 29188.6 29780.7 29878.7 29900.7 30050.9 2697.7 2730.7 2760.8 2802.5 2843.5 388046.0 389847.0 390771.0 391014.0 512476.0 60311.7 60673.7 60899.7 61044.0 61098.0 37272.8 38032.9 38168.6 39109.6 39294.2 17046.6 17076.3 17271.5 17277.7 17286.3 11932.8 13094.5 13217.5 13241.9 13248.9 16764.6 17315.6 17463.4 19609.8 20714.7 8107.1 8288.3 8306.5 8345.3 8387.6 927.5 1045.7 1108.5 1146.9 1171.5 25055.4 27207.5 30253.2 30439.0 30646.0 1098.3 1126.9 1134.6 1162.1 1169.8 14146.5 15608.6 15687.3 15712.6 16078.9 587081.0 721529.0 730216.0 730391.0 733208.0 60832.7 63201.7 63303.0 63364.0 63570.0 1326.3 1359.5 1463.9 1499.0 1518.7 29793.2 30963.8 31039.4 31384.4 32704.0 53360.0 53571.5 54189.0 54211.0 54557.0 565995.0 567015.0 568718.0 570131.0 570327.1 23955.2 44459.0 44551.6 45282.5 46152.5 47165.8 47342.0 48890.5 49779.3 51133.5 38903.0 40088.6 40327.4 40963.6 41101.0 37185.4 39549.0 39764.6 39914.8 41060.0 39729.0 40010.6 40221.4 40514.4 40588.4 34257.5 35371.6 36145.8 36283.3 36496.6 25612.6 26052.3 26128.5 26133.6 26726.5 05:30:19 INFO - PROCESS | 6292 | 05:30:19 INFO - PROCESS | 6292 | [#2] jquery.html Cycles:180 Average:34735.46 Median:21442.18 stddev:100431.78 (468.4%) stddev-sans-first:100680.40 05:30:19 INFO - PROCESS | 6292 | Values: 386.1 391.8 391.9 393.2 417.9 13961.7 19213.5 20943.0 21047.0 21153.5 15962.8 16083.3 20329.7 20934.7 21096.7 5773.5 5980.2 5988.4 5995.8 6023.9 5904.8 5940.3 5945.4 5961.5 6009.5 5767.3 5889.4 5898.3 5939.9 5943.6 5130.9 5652.4 5800.7 5911.2 5920.8 5081.8 5137.3 5141.9 5143.4 5144.9 25244.5 25328.4 25545.0 25736.4 26388.6 2186.7 2190.6 2197.6 2231.0 2355.5 14028.4 14447.3 14750.4 15325.4 15396.8 1756.5 1802.7 1828.4 1831.5 1916.9 96523.1 123302.0 125668.0 130333.0 133234.0 10237.9 10660.5 10958.7 10966.8 11414.5 13994.0 14035.8 14041.7 14061.8 14069.0 7585.0 7672.9 8691.6 8910.3 8926.8 7345.6 7473.2 8831.3 8853.6 8855.0 5597.8 6602.5 6736.1 6740.5 6758.3 1881.7 1917.6 1918.2 1924.0 1953.7 915.6 952.5 1013.4 1060.7 1115.0 20323.7 20850.1 21394.1 21411.4 21473.0 997.7 1001.9 1011.3 1030.4 1033.9 10176.9 11020.2 11201.4 11259.6 11429.3 523790.0 607070.0 624302.0 626458.0 626508.0 82719.2 83893.6 113113.0 146671.0 150331.0 957.0 1010.6 1022.3 1086.5 1088.4 18108.4 18384.0 18506.6 18607.7 18817.0 41247.8 50800.2 51142.2 51930.9 53027.5 70243.3 72894.3 72921.3 74728.0 75487.0 7605.8 8176.8 8237.4 8325.3 8520.4 11874.8 12502.9 12548.8 12881.2 12898.6 10987.6 11159.4 11293.5 11354.3 11427.7 10726.5 10871.3 10902.1 11186.7 11193.3 10901.3 11394.9 11643.7 11684.5 11693.2 6960.0 8061.0 8246.6 8280.6 8469.6 9597.1 11400.7 11415.4 11758.8 11763.0 05:30:19 INFO - PROCESS | 6292 | 05:30:19 INFO - PROCESS | 6292 | [#3] mootools.html Cycles:180 Average:5800.70 Median:4910.44 stddev:3265.18 (66.5%) stddev-sans-first:3257.36 05:30:19 INFO - PROCESS | 6292 | Values: 1370.6 1422.2 1586.3 1799.5 1950.4 7981.4 8452.5 8474.7 8789.3 9062.6 8831.7 8920.7 9017.2 9022.8 9028.5 2432.8 2472.0 2473.2 2473.5 2474.0 2421.9 2484.5 2484.8 2486.2 2486.8 2385.6 2401.8 2476.4 2476.4 2476.9 2088.9 2372.5 2404.5 2411.6 2453.4 2191.5 2320.3 2407.2 2433.1 2438.3 8955.0 9034.0 9040.6 9052.1 9052.6 5367.9 5370.3 5559.7 5563.2 5569.4 3498.3 3652.5 3713.9 3732.3 3736.9 1044.8 1046.9 1058.9 1063.9 1073.0 11700.8 11906.5 11976.6 12211.4 12223.2 4682.2 4700.2 4749.1 4781.9 4803.8 9407.8 9410.1 9413.2 9416.9 9419.1 5426.0 5429.6 5430.1 5435.6 5454.2 4500.7 4602.0 4877.8 4981.9 5066.0 3826.9 3863.1 3922.2 3961.0 4039.5 1219.9 1229.8 1229.9 1236.8 1247.8 669.5 824.2 917.5 939.8 976.1 5722.9 5937.1 5963.3 5981.6 6086.4 845.8 952.5 963.5 965.8 990.8 3776.6 3957.9 3996.1 4010.2 4050.6 10647.6 10875.7 11071.2 11082.3 11094.2 11018.5 11031.3 11064.2 11078.6 11114.9 8009.4 8385.2 8422.6 8498.2 8512.7 5068.5 6034.5 6038.2 6079.6 6158.8 9804.0 10053.5 10404.0 10407.4 10460.7 10155.2 10238.9 10574.4 10594.7 10613.7 7452.5 7680.3 7711.2 7716.9 7718.6 8241.8 8359.4 8361.8 8396.9 8431.9 7636.8 7639.4 7667.7 7826.9 7840.8 7426.1 7525.0 7532.7 7660.8 7697.4 6897.4 6907.4 7009.2 7059.1 7085.2 4821.3 4872.4 4875.7 4902.9 4937.0 4918.0 4955.1 4989.7 5026.7 5114.6 05:30:19 INFO - PROCESS | 6292 | 05:30:19 INFO - PROCESS | 6292 | [#4] prototype.html Cycles:180 Average:4517.07 Median:5109.01 stddev:1417.82 (27.8%) stddev-sans-first:1386.07 05:30:19 INFO - PROCESS | 6292 | Values: 303.3 317.8 321.5 325.6 329.8 4940.7 5173.0 5359.6 5393.8 5448.3 4509.7 5303.7 5323.8 5371.6 5413.6 3507.6 3838.4 4005.3 4093.9 4154.9 3589.0 4014.3 4088.7 4135.8 4137.0 3557.3 4020.6 4053.9 4075.3 4082.3 3544.6 3746.4 3845.1 4024.6 4062.9 2916.6 3025.9 3125.7 3504.6 3518.4 4166.6 5105.5 5134.6 5183.3 5213.5 3504.4 4671.4 4687.5 4709.2 4724.1 5385.5 5438.5 5617.1 5629.5 5646.6 3846.9 3874.5 3877.4 3881.4 3887.5 5269.8 5458.6 5600.6 5832.9 5860.9 3874.7 3894.7 3903.5 3913.8 3922.6 3391.5 3661.9 3719.9 3963.2 3986.3 3957.9 4634.1 4638.4 4671.4 4698.2 4865.8 5324.3 5380.0 5502.8 5657.6 1307.3 1342.8 1342.9 1345.9 1346.7 767.7 773.3 777.7 792.5 839.7 4886.7 5097.3 5100.4 5112.5 5186.3 4926.9 5458.7 5468.6 5763.3 5845.4 4876.9 5032.9 5230.9 5261.2 5283.3 2516.9 2635.5 2688.7 2782.9 3050.3 5079.9 5238.5 5439.5 5603.6 5830.6 5265.3 5865.9 5960.2 5961.7 5991.0 5920.9 5957.9 5963.4 5990.7 6027.9 5438.5 5769.2 5823.2 5846.2 5847.7 5579.1 5733.3 5736.3 5766.2 5783.8 5427.1 5443.2 5505.5 6072.1 6099.8 3229.4 3682.1 3893.6 3905.1 3959.1 5576.4 5858.6 5880.4 5880.7 5893.5 4391.7 4474.4 5561.9 5702.6 5731.0 5063.4 5641.2 5665.4 5695.9 5733.9 4439.8 4677.2 4933.7 4971.7 5405.4 5074.8 5468.3 5617.4 5671.0 5722.9 4547.6 5216.0 5552.9 5614.6 5653.0 05:30:19 INFO - PROCESS | 6292 | 05:30:19 INFO - PROCESS | 6292 | [#5] yui.html Cycles:180 Average:19278.14 Median:617.65 stddev:28235.27 (4571.4%) stddev-sans-first:28279.83 05:30:19 INFO - PROCESS | 6292 | Values: 647.8 673.9 738.8 773.0 805.8 13130.7 15252.8 15493.5 15645.8 15651.8 12847.8 14454.1 14508.6 14520.9 15908.7 716.1 932.3 933.1 939.4 942.3 740.4 905.3 934.4 942.0 944.0 979.1 980.8 982.8 986.9 988.4 738.8 949.4 981.4 981.6 981.6 720.9 732.2 732.7 733.8 736.2 15692.5 15735.7 15735.8 15771.4 15791.0 12042.3 12286.8 12287.1 12420.3 12582.7 10779.9 10815.7 10859.8 11068.8 11440.0 3985.3 3997.8 4005.0 4118.2 4129.2 91749.0 92192.0 93670.5 94112.0 95683.6 9246.7 9341.9 9390.5 9517.0 9619.2 21098.2 21101.0 21163.5 21168.2 21229.8 7182.0 7182.5 7231.0 7381.7 7484.6 4018.3 4069.3 4086.7 4086.8 4093.4 3336.9 3492.6 3494.2 3494.8 3495.6 639.3 651.3 653.7 657.1 661.8 474.3 583.5 591.7 617.4 631.1 6703.6 7854.8 8047.9 8259.5 8338.5 587.3 615.3 617.6 617.7 626.6 3763.0 3861.2 3912.5 3972.1 4130.5 88206.0 106499.5 106872.0 109163.5 110330.0 82802.0 88021.7 88333.7 88939.6 90336.0 69683.3 69747.7 69935.0 74070.6 78749.4 8275.6 8341.3 8429.5 8519.2 8590.4 47185.3 48008.8 48278.0 49609.8 50290.5 65334.3 65515.0 65962.0 66111.0 66550.0 10969.6 11022.9 11290.5 11356.5 11445.8 10791.6 11473.0 11594.2 11957.5 12027.2 11359.8 11483.8 11484.6 11513.7 11625.4 11166.7 11298.7 11415.3 11426.3 11433.9 11152.0 11205.3 11210.7 11363.1 11594.0 9482.5 9499.2 9673.0 9759.4 9919.6 9607.0 9863.9 9880.3 9887.0 9922.7 05:30:19 INFO - PROCESS | 6292 | -------- Summary: end -------- 05:30:19 INFO - PROCESS | 6292 | 05:30:19 INFO - TEST-INFO | 6292: exit 0 05:30:20 INFO - TEST-OK | dromaeo_css | took 1115380ms 05:30:20 INFO - TEST-START | kraken 05:30:20 INFO - operating with platform_type : mac_ 05:30:20 INFO - Initialising browser for kraken test... 05:30:20 INFO - TEST-INFO | started process 6295 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpdqkOFV/profile http://localhost:57062/getInfo.html) 05:30:22 INFO - PROCESS | 6295 | __metrics Screen width/height:1600/1200 05:30:22 INFO - PROCESS | 6295 | colorDepth:24 05:30:22 INFO - PROCESS | 6295 | Browser inner width/height: 1024/689 05:30:22 INFO - PROCESS | 6295 | __metrics 05:30:22 INFO - PROCESS | 6295 | 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" 05:30:22 ERROR - PROCESS | 6295 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:30:22 INFO - TEST-INFO | 6295: exit 0 05:30:22 INFO - Browser initialized. 05:30:22 INFO - Running cycle 1/1 for kraken test... 05:30:22 INFO - TEST-INFO | started process 6297 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpdqkOFV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:34 INFO - PROCESS | 6297 | RSS: Main: 239280128 05:30:34 INFO - PROCESS | 6297 | 05:30:37 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57062/tests/kraken/driver.html?testName=audio-beat-detection) 05:30:38 INFO - PROCESS | 6297 | RSS: Main: 289087488 05:30:38 INFO - PROCESS | 6297 | 05:30:41 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57062/tests/kraken/driver.html?testName=audio-dft) 05:30:42 INFO - PROCESS | 6297 | RSS: Main: 298475520 05:30:42 INFO - PROCESS | 6297 | 05:30:45 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57062/tests/kraken/driver.html?testName=audio-fft) 05:30:46 INFO - PROCESS | 6297 | RSS: Main: 386420736 05:30:46 INFO - PROCESS | 6297 | 05:30:48 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57062/tests/kraken/driver.html?testName=audio-oscillator) 05:30:49 INFO - PROCESS | 6297 | RSS: Main: 286625792 05:30:49 INFO - PROCESS | 6297 | 05:30:51 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57062/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:30:51 INFO - PROCESS | 6297 | RSS: Main: 290648064 05:30:51 INFO - PROCESS | 6297 | 05:30:55 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57062/tests/kraken/driver.html?testName=imaging-darkroom) 05:30:55 INFO - PROCESS | 6297 | RSS: Main: 327102464 05:30:55 INFO - PROCESS | 6297 | 05:30:58 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57062/tests/kraken/driver.html?testName=imaging-desaturate) 05:30:59 INFO - PROCESS | 6297 | RSS: Main: 327516160 05:30:59 INFO - PROCESS | 6297 | 05:31:02 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57062/tests/kraken/driver.html?testName=json-parse-financial) 05:31:03 INFO - PROCESS | 6297 | RSS: Main: 326750208 05:31:03 INFO - PROCESS | 6297 | 05:31:04 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57062/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:31:05 INFO - PROCESS | 6297 | RSS: Main: 316235776 05:31:05 INFO - PROCESS | 6297 | 05:31:07 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57062/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:31:08 INFO - PROCESS | 6297 | RSS: Main: 322965504 05:31:08 INFO - PROCESS | 6297 | 05:31:10 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57062/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:31:10 INFO - PROCESS | 6297 | RSS: Main: 324587520 05:31:10 INFO - PROCESS | 6297 | 05:31:13 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57062/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:31:14 INFO - PROCESS | 6297 | RSS: Main: 329191424 05:31:14 INFO - PROCESS | 6297 | 05:31:17 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57062/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:31:18 INFO - PROCESS | 6297 | RSS: Main: 326946816 05:31:18 INFO - PROCESS | 6297 | 05:31:19 INFO - PROCESS | 6297 | Cycle 1(1): loaded http://localhost:57062/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57062/tests/kraken/driver.html?testName=ai-astar) 05:31:20 INFO - PROCESS | 6297 | RSS: Main: 373907456 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | __start_tp_report 05:31:20 INFO - PROCESS | 6297 | _x_x_mozilla_page_load 05:31:20 INFO - PROCESS | 6297 | _x_x_mozilla_page_load_details 05:31:20 INFO - PROCESS | 6297 | |i|pagename|runs| 05:31:20 INFO - PROCESS | 6297 | |0;ai-astar;116;154;157;112;111;111;112;112;111;112 05:31:20 INFO - PROCESS | 6297 | |1;audio-beat-detection;155;137;129;195;134;161;156;103;189;139 05:31:20 INFO - PROCESS | 6297 | |2;audio-dft;185;186;214;253;211;185;186;187;182;184 05:31:20 INFO - PROCESS | 6297 | |3;audio-fft;80;100;56;122;55;59;56;59;110;55 05:31:20 INFO - PROCESS | 6297 | |4;audio-oscillator;94;96;133;79;80;77;102;81;77;100 05:31:20 INFO - PROCESS | 6297 | |5;imaging-gaussian-blur;89;91;131;92;94;84;88;85;88;85 05:31:20 INFO - PROCESS | 6297 | |6;imaging-darkroom;93;94;93;94;94;98;138;94;93;94 05:31:20 INFO - PROCESS | 6297 | |7;imaging-desaturate;84;83;85;85;84;84;84;85;86;84 05:31:20 INFO - PROCESS | 6297 | |8;json-parse-financial;58;57;55;56;54;76;83;159;56;54 05:31:20 INFO - PROCESS | 6297 | |9;json-stringify-tinderbox;62;67;65;77;72;71;68;66;67;67 05:31:20 INFO - PROCESS | 6297 | |10;stanford-crypto-aes;84;84;85;85;84;84;82;83;85;85 05:31:20 INFO - PROCESS | 6297 | |11;stanford-crypto-ccm;137;136;134;136;121;135;138;140;245;144 05:31:20 INFO - PROCESS | 6297 | |12;stanford-crypto-pbkdf2;174;161;163;166;161;163;160;163;160;210 05:31:20 INFO - PROCESS | 6297 | |13;stanford-crypto-sha256-iterative;65;66;66;63;64;63;65;63;65;63 05:31:20 INFO - PROCESS | 6297 | __end_tp_report 05:31:20 INFO - PROCESS | 6297 | __start_cc_report 05:31:20 INFO - PROCESS | 6297 | _x_x_mozilla_cycle_collect,2271 05:31:20 INFO - PROCESS | 6297 | __end_cc_report 05:31:20 INFO - PROCESS | 6297 | __startTimestamp1463488280123__endTimestamp 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | ------- Summary: start ------- 05:31:20 INFO - PROCESS | 6297 | Number of tests: 14 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#0] ai-astar Cycles:10 Average:120.80 Median:112.00 stddev:18.36 (16.4%) stddev-sans-first:19.39 05:31:20 INFO - PROCESS | 6297 | Values: 116.0 154.0 157.0 112.0 111.0 111.0 112.0 112.0 111.0 112.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#1] audio-beat-detection Cycles:10 Average:149.80 Median:155.50 stddev:27.74 (17.8%) stddev-sans-first:29.35 05:31:20 INFO - PROCESS | 6297 | Values: 155.0 137.0 129.0 195.0 134.0 161.0 156.0 103.0 189.0 139.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#2] audio-dft Cycles:10 Average:197.30 Median:186.50 stddev:22.72 (12.2%) stddev-sans-first:23.65 05:31:20 INFO - PROCESS | 6297 | Values: 185.0 186.0 214.0 253.0 211.0 185.0 186.0 187.0 182.0 184.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#3] audio-fft Cycles:10 Average:75.20 Median:56.00 stddev:26.07 (46.6%) stddev-sans-first:27.60 05:31:20 INFO - PROCESS | 6297 | Values: 80.0 100.0 56.0 122.0 55.0 59.0 56.0 59.0 110.0 55.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:79.50 stddev:17.48 (22.0%) stddev-sans-first:18.52 05:31:20 INFO - PROCESS | 6297 | Values: 94.0 96.0 133.0 79.0 80.0 77.0 102.0 81.0 77.0 100.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:88.50 stddev:13.84 (15.6%) stddev-sans-first:14.62 05:31:20 INFO - PROCESS | 6297 | Values: 89.0 91.0 131.0 92.0 94.0 84.0 88.0 85.0 88.0 85.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#6] imaging-darkroom Cycles:10 Average:98.50 Median:94.00 stddev:13.95 (14.8%) stddev-sans-first:14.66 05:31:20 INFO - PROCESS | 6297 | Values: 93.0 94.0 93.0 94.0 94.0 98.0 138.0 94.0 93.0 94.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.50 stddev:0.84 (1.0%) stddev-sans-first:0.88 05:31:20 INFO - PROCESS | 6297 | Values: 84.0 83.0 85.0 85.0 84.0 84.0 84.0 85.0 86.0 84.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#8] json-parse-financial Cycles:10 Average:70.80 Median:56.50 stddev:32.59 (57.7%) stddev-sans-first:34.24 05:31:20 INFO - PROCESS | 6297 | Values: 58.0 57.0 55.0 56.0 54.0 76.0 83.0 159.0 56.0 54.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:67.50 stddev:4.18 (6.2%) stddev-sans-first:3.79 05:31:20 INFO - PROCESS | 6297 | Values: 62.0 67.0 65.0 77.0 72.0 71.0 68.0 66.0 67.0 67.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.50 stddev:0.99 (1.2%) stddev-sans-first:1.05 05:31:20 INFO - PROCESS | 6297 | Values: 84.0 84.0 85.0 85.0 84.0 84.0 82.0 83.0 85.0 85.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:137.50 stddev:35.08 (25.5%) stddev-sans-first:37.03 05:31:20 INFO - PROCESS | 6297 | Values: 137.0 136.0 134.0 136.0 121.0 135.0 138.0 140.0 245.0 144.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.10 Median:163.00 stddev:15.29 (9.4%) stddev-sans-first:16.07 05:31:20 INFO - PROCESS | 6297 | Values: 174.0 161.0 163.0 166.0 161.0 163.0 160.0 163.0 160.0 210.0 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - PROCESS | 6297 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:65.00 stddev:1.25 (1.9%) stddev-sans-first:1.30 05:31:20 INFO - PROCESS | 6297 | Values: 65.0 66.0 66.0 63.0 64.0 63.0 65.0 63.0 65.0 63.0 05:31:20 INFO - PROCESS | 6297 | -------- Summary: end -------- 05:31:20 INFO - PROCESS | 6297 | 05:31:20 INFO - TEST-INFO | 6297: exit 0 05:31:20 INFO - TEST-OK | kraken | took 60962ms 05:31:20 INFO - SUITE-END | took 1176s 05:31:21 INFO - Completed test suite (00:19:36) 05:31:21 INFO - javascript benchmark 05:31:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3405.0, 3406.744494162937, 3426.0749597608633, 3434.6393628669, 3481.3385456763212, 5601.215829783833, 5683.516901063409, 5687.17535957286, 5831.300243970724, 5845.970770146148, 5767.8269651910405, 5802.419758024195, 5827.475527202551, 5832.854178645536, 5840.269966254218, 5148.382194136709, 5197.662151960129, 5234.319538459995, 5245.396779370364, 5288.101022826128, 5259.447757985414, 5261.158214685651, 5268.130758424855, 5283.498067683583, 5292.894142117175, 5157.406898206698, 5166.689998600096, 5182.766775495093, 5187.896242075156, 5283.022640811443, 5246.108161612535, 5281.70950597717, 5291.629585928987, 5305.973470132663, 5345.732713364317, 5101.056304583627, 5116.667416617908, 5160.7419629018395, 5199.870003249911, 5210.687358758487, 5598.692071936042, 5613.129964855454, 5623.0, 5651.321841379061, 5656.4060773618585, 6211.130441738161, 6232.532560057968, 6233.781817636361, 6241.9375806241815, 6264.561480696353, 5294.523492885659, 6167.136600875881, 6179.87640247197, 6213.627182369072, 6217.96891015542, 5929.436703513159, 5979.103134529855, 5989.940100598982, 6002.609830361012, 6005.969970150165, 6269.404406581368, 6282.528810339243, 6306.24325080984, 6309.589876658002, 6317.020861766342, 5876.6180198286975, 6061.77931986162, 6138.202033735586, 6161.353057928853, 6201.379862013853, 5286.867828304246, 6184.721687524072, 6199.473044791153, 6206.875862482725, 6208.037754148114, 6229.0967809667345, 6237.4698150656795, 6238.220222472191, 6251.562390632612, 6272.655003974825, 6111.694415279218, 6181.134641150243, 6190.071489276645, 6191.442770150708, 6292.087647291117, 4964.925526117111, 4991.401031876198, 4992.0, 4994.001199760062, 5026.396832380138, 4491.73049617024, 4493.213687604722, 4505.301678239878, 4522.706024108423, 4549.613282870927, 5650.830475085754, 5677.489025987681, 5683.630564013326, 5726.141078838207, 5726.455986681259, 5288.656237344637, 5541.833744987657, 6208.161898143783, 6211.192544969125, 6220.906686399708, 5712.857178570486, 5721.256236689203, 5730.426957304319, 5753.367129615739, 5753.568482363756, 5310.8141215057285, 5353.652012619167, 5378.650387724863, 5411.107167317427, 5426.375966763794, 6297.653629050263, 6339.587926784744, 6348.238211414659, 6351.904721429275, 6355.237371515448, 6148.9692551536955, 6200.472959798468, 6225.0, 6244.0, 6255.593386429868, 6251.249850017845, 6269.341719119427, 6299.622022678654, 6395.040743888454, 6410.006449000412, 6220.191375121205, 6230.283517395557, 6232.501399888089, 6237.157983672328, 6251.124842522141, 6160.476359509492, 6167.044108163242, 6201.782937597162, 6213.409726076015, 6220.129181914445, 5990.670513121646, 6067.605605635619, 6080.48315893154, 6082.482988945989, 6124.6325220486915, 6003.6397816131175, 6041.728122234598, 6051.818445446644, 6075.878482430415, 6081.483073938764, 5792.4497172768515, 5909.55678324136, 5909.734061967136, 5923.378045305181, 5929.347771745019, 6010.098485227251, 6077.908831367445, 6105.61138071644, 6108.389161083856, 6141.88493709207, 5876.706164691663, 6036.607620504828, 6045.637261764308, 6055.848603784764, 6080.817575472743], "value": 5703.152314275595, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.12257864868513, 511.89506151238993, 515.819463187884, 517.5755880178256, 560.011579562073, 30715.69284307159, 31606.525902111458, 42242.577574224284, 43387.13225735483, 43638.56361436385, 44980.10039799202, 45269.54730452695, 45366.865828354305, 45378.0, 45499.77250113745, 9704.22366210705, 9896.752581185456, 9896.950515247414, 9901.603935842593, 9906.157976571963, 8744.650213991432, 8952.283817294634, 8968.372213945026, 11727.648170554898, 11996.04031677468, 11912.106592005592, 11954.402279886015, 11956.402179891014, 11983.340916249603, 12001.51993920242, 11822.290662560232, 11891.28652280866, 11902.928736413722, 11903.583374581847, 11912.094064949566, 8682.044975052739, 11521.827172592419, 11817.94091029548, 11833.585824496187, 11872.703182420508, 41698.79150604258, 41844.58155418437, 42006.0, 42062.78968605137, 42129.57870421287, 2934.505670180351, 3258.201740573551, 3350.977951724723, 3360.092774950774, 3369.25876307212, 29188.562171567442, 29780.659870305888, 29878.701212988028, 29900.70099299001, 30050.92447117843, 2697.6979710173737, 2730.6859711133275, 2760.83434993899, 2802.467531169071, 2843.516602177635, 388046.0, 389847.0, 390771.0, 391014.0, 512476.0, 60311.698441508386, 60673.69663151744, 60899.69550152309, 61044.0, 61098.0, 37272.81363593165, 38032.859014229616, 38168.61831381706, 39109.60890391117, 39294.21411571753, 17046.57383565396, 17076.316947322215, 17271.481855544353, 17277.740833887503, 17286.308547658205, 11932.76134477323, 13094.476220951246, 13217.537386191438, 13241.940644748589, 13248.867511324956, 16764.58088547796, 17315.567110822078, 17463.38878139259, 19609.803901960797, 20714.689279660815, 8107.067687216046, 8288.295494883003, 8306.501609903426, 8345.290650294794, 8387.580620968927, 927.4759760735298, 1045.7333379988054, 1108.4623957380663, 1146.8623765148236, 1171.4611278811672, 25055.373615659755, 27207.455850883267, 30253.243668908453, 30439.0, 30646.0, 1098.2586753941057, 1126.9042131418921, 1134.6028889888473, 1162.1458228202264, 1169.771894480568, 14146.504872329419, 15608.609784755245, 15687.294071766797, 15712.60718482047, 16078.919605402132, 587081.0, 721529.0, 730216.0, 730391.0, 733208.0, 60832.695836520536, 63201.68399158067, 63303.0, 63364.0, 63570.0, 1326.2572959142717, 1359.503781119884, 1463.9341229644479, 1498.9625259368386, 1518.7114448254797, 29793.179993855952, 30963.845180774853, 31039.379212416075, 31384.372312553165, 32704.0, 53360.0, 53571.46428535665, 54189.0, 54211.0, 54556.999365035306, 565995.0, 567015.0, 568718.0, 570131.0, 570327.1483642556, 23955.16156934499, 44459.0, 44551.55448445474, 45282.547174527834, 46152.53847461483, 47165.764171178926, 47342.0, 48890.51109488859, 49779.253311201086, 51133.48866511436, 38903.0, 40088.59911400965, 40327.39508907427, 40963.59036409598, 41101.0, 37185.4422183662, 39549.0, 39764.602353976086, 39914.80042599768, 41060.0, 39729.0, 40010.599894001854, 40221.396679049256, 40514.39228411517, 40588.39117413182, 34257.486137707456, 35371.64628353786, 36145.81927090348, 36283.27433451264, 36496.63503364933, 25612.615810763225, 26052.34869128287, 26128.477430450905, 26133.607995879698, 26726.465470690087], "value": 20621.463776810644, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.1215734204684, 391.7551530293567, 391.8883118311282, 393.16648704745927, 417.94566706328175, 13961.741668791015, 19213.519662008457, 20942.95285235736, 21047.0, 21153.471163220926, 15962.840371596281, 16083.276252568634, 20329.695054574193, 20934.685979710277, 21096.68354974673, 5773.451522105393, 5980.192673989, 5988.401159884021, 5995.760169593211, 6023.909641355383, 5904.793332233373, 5940.316863560703, 5945.435183657545, 5961.523078153759, 6009.5192384609345, 5767.30792304924, 5889.381614930435, 5898.321693005295, 5939.940600593982, 5943.643381397108, 5130.9230361544605, 5652.406497317764, 5800.651960882339, 5911.172435858962, 5920.763169473216, 5081.771320290596, 5137.255098010804, 5141.871453213662, 5143.382794064699, 5144.945286216317, 25244.49511009788, 25328.366790830194, 25545.0, 25736.356591085187, 26388.60417093745, 2186.650135978252, 2190.5509370578993, 2197.560487902426, 2230.9553808923893, 2355.5171189906027, 14028.368723407471, 14447.277636118151, 14750.409983600643, 15325.386984520606, 15396.769048464283, 1756.4906177208595, 1802.738602902585, 1828.4240464253717, 1831.4505648305587, 1916.865819392643, 96523.06953860953, 123302.0, 125668.0, 130333.0, 133234.0, 10237.94881025605, 10660.46697665129, 10958.726031849108, 10966.780664386668, 11414.543418263342, 13993.950453716012, 14035.78946315806, 14041.71916561663, 14061.78907316391, 14069.015168938076, 7585.0, 7672.923270767221, 8691.565421729014, 8910.287177025823, 8926.776830579287, 7345.595992220372, 7473.177950425449, 8831.3376496763, 8853.645854165761, 8854.955725221462, 5597.804076857372, 6602.471802255808, 6736.090627765287, 6740.528163028541, 6758.256591774901, 1881.7365568820376, 1917.597304566029, 1918.213532451688, 1923.9711404328946, 1953.667876461005, 915.5605309451367, 952.5332587032304, 1013.366645846346, 1060.7189094889861, 1114.9944250278697, 20323.695144572845, 20850.06174722141, 21394.144234230767, 21411.35765927025, 21473.0, 997.6730947839357, 1001.9398836069859, 1011.3173607814842, 1030.3766221436074, 1033.9327943683786, 10176.898231017743, 11020.228583999044, 11201.383923884265, 11259.605913792979, 11429.257098288585, 523790.0, 607070.0, 624302.0, 626458.0, 626508.0, 82719.17280827115, 83893.58053209817, 113113.0, 146671.0, 150331.0, 957.0477375011768, 1010.5553556435145, 1022.3303736052898, 1086.4839201379282, 1088.3796236145322, 18108.366207182684, 18384.0, 18506.629867402848, 18607.72088418702, 18817.0, 41247.793761031004, 50800.23799642934, 51142.23286650629, 51930.92276308905, 53027.46972530225, 70243.29756702571, 72894.27105728874, 72921.27078729145, 74728.0, 75487.0, 7605.847883042419, 8176.83646327082, 8237.423380363318, 8325.333973282002, 8520.446170998865, 11874.821877672015, 12502.937485312515, 12548.811767823307, 12881.162724422882, 12898.613041608767, 10987.61543345979, 11159.38623375722, 11293.548258069914, 11354.318740875575, 11427.714307142389, 10726.517306721373, 10871.29336593119, 10902.127829773757, 11186.72033199144, 11193.328400296008, 10901.291416057931, 11394.886051139383, 11643.650690479299, 11684.47419866125, 11693.239939403911, 6959.96520017417, 8060.959695201486, 8246.628901699318, 8280.58597070156, 8469.61886715087, 9597.136257736836, 11400.714982125513, 11415.429228538706, 11758.823617645914, 11763.000144987429], "value": 9692.562939780668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.6367812529688, 1422.2391020803868, 1586.3258115300998, 1799.4781513361127, 1950.3563823938093, 7981.441299109065, 8452.450590711607, 8474.703385381514, 8789.2968562515, 9062.592183351748, 8831.735047948556, 8920.73237802867, 9017.233535149504, 9022.774430639221, 9028.458292502439, 2432.768886955745, 2471.9876400617973, 2473.196211231348, 2473.4558397152596, 2473.9980307975297, 2421.8546887186735, 2484.465839844433, 2484.776370126688, 2486.2416962826333, 2486.8383555068863, 2385.6302273147603, 2401.7838394544488, 2476.405662640971, 2476.4428003699204, 2476.913308034219, 2088.9450827332175, 2372.5492156490213, 2404.507076049423, 2411.553862535428, 2453.411181316489, 2191.5288213034273, 2320.2575175943703, 2407.1935901473025, 2433.111914151322, 2438.2928950604305, 8955.014948355676, 9033.954830225872, 9040.638374465014, 9052.094790520961, 9052.592633331516, 5367.919481207785, 5370.301860758115, 5559.749811258463, 5563.193336966157, 5569.415211402826, 3498.3178280235365, 3652.543432070991, 3713.925721485582, 3732.2908647356917, 3736.925261494755, 1044.7954815339626, 1046.9005444482762, 1058.867641544807, 1063.940856820408, 1073.044989958937, 11700.765984680258, 11906.46420911061, 11976.580819671357, 12211.389430528527, 12223.205491643015, 4682.157211701926, 4700.153972285021, 4749.097671442415, 4781.904361912742, 4803.807847686054, 9407.811843763224, 9410.058994100535, 9413.246940244764, 9416.905830941603, 9419.058094190663, 5425.972870135624, 5429.647072940246, 5430.0768869292315, 5435.646682965674, 5454.181872719045, 4500.707454015478, 4601.976990115028, 4877.756112194376, 4981.875453113628, 5066.012127635149, 3826.8660596878976, 3863.1307955710304, 3922.195949830278, 3961.0493481564217, 4039.5354534228354, 1219.8902098810972, 1229.7847876621556, 1229.8708635593314, 1236.8206610041493, 1247.7878760610718, 669.5279827721445, 824.2046425199711, 917.5091326140483, 939.8214339275515, 976.0965031441643, 5722.942770572325, 5937.050071988461, 5963.2844058713235, 5981.551383646157, 6086.391360863879, 845.7758693946109, 952.457099453305, 963.5230560872413, 965.8261512927741, 990.757264470195, 3776.5656949451163, 3957.8614748483665, 3996.120853412303, 4010.218007488512, 4050.594940505926, 10647.627333043307, 10875.728106797234, 11071.225014249087, 11082.335059896432, 11094.22340436178, 11018.50416731249, 11031.338119712844, 11064.170187235828, 11078.612248571262, 11114.888851111547, 8009.359251259885, 8385.161483851565, 8422.578871056423, 8498.192671696179, 8512.744617661356, 5068.518490743373, 6034.487068599218, 6038.215032045806, 6079.574429789872, 6158.753649853956, 9804.0, 10053.497325133569, 10404.0, 10407.386855037481, 10460.738481538023, 10155.187584993035, 10238.897611023795, 10574.365538067741, 10594.735131621772, 10613.734656633645, 7452.478326517092, 7680.3471704905705, 7711.151773305044, 7716.92283077162, 7718.575478348745, 8241.752747417588, 8359.373047021376, 8361.790955226168, 8396.916030839613, 8431.873521897054, 7636.770896873103, 7639.350655194371, 7667.654955526903, 7826.882596760939, 7840.84318313642, 7426.108866936002, 7525.0, 7532.661030253542, 7660.80847978805, 7697.384209263358, 6897.379235868796, 6907.378335949788, 7009.1589009319205, 7059.117610298713, 7085.220625731063, 4821.325014497903, 4872.3909511311085, 4875.68308059975, 4902.852914412573, 4937.0, 4918.0327868851555, 4955.058538877602, 4989.650724449254, 5026.673266237683, 5114.64197506171], "value": 4647.020883093775, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.32358839787275, 317.82360789761674, 321.52735478846097, 325.55724215067505, 329.75598057437503, 4940.654154209206, 5173.0, 5359.598030147745, 5393.757280922358, 5448.291722076135, 4509.684322097454, 5303.738201006382, 5323.760430780614, 5371.57027437805, 5413.621046526745, 3507.6141624421293, 3838.4242363645467, 4005.3391190453685, 4093.9181216375655, 4154.896127596804, 3589.0, 4014.2974979378637, 4088.693347998897, 4135.7518548887165, 4136.97931510342, 3557.3418917500344, 4020.577839326858, 4053.939190912124, 4075.286824805662, 4082.3464747913945, 3544.6278140795102, 3746.3631182699005, 3845.096402345461, 4024.577419370953, 4062.878113656595, 2916.6354205724283, 3025.9092227233, 3125.734312583433, 3504.6320136385566, 3518.437050072002, 4166.604172603597, 5105.489451054865, 5134.64057515974, 5183.3002544656365, 5213.4525874782985, 3504.3867323218333, 4671.439427268716, 4687.531246875319, 4709.2229782085915, 4724.11035585765, 5385.542228910547, 5438.456154384569, 5617.118265381102, 5629.4652008059165, 5646.57650676201, 3846.942295865564, 3874.496315478998, 3877.4377715231135, 3881.3983832506005, 3887.455756194135, 5269.815556455505, 5458.645188062763, 5600.5799565032785, 5832.941670583282, 5860.941390586082, 3874.670652994471, 3894.7273690841935, 3903.531576210873, 3913.8434462621176, 3922.6270805218514, 3391.4573668212965, 3661.8718344857803, 3719.8512059517316, 3963.2271707016853, 3986.262541429845, 3957.8614748483665, 4634.119517291704, 4638.443386793607, 4671.416072990876, 4698.2247929092, 4865.783554111472, 5324.281222034976, 5379.973100134474, 5502.8349149525575, 5657.6322539035655, 1307.3136603283353, 1342.838859336886, 1342.9462821487032, 1345.8560223809684, 1346.6667998681235, 767.7179110884836, 773.2653978720239, 777.7316825695126, 792.4532072869702, 839.6515446089926, 4886.706797592155, 5097.311862898536, 5100.438951715309, 5112.539871411591, 5186.299849520342, 4926.9014619707405, 5458.699771512525, 5468.589855760754, 5763.308402991668, 5845.415458454206, 4876.853694389103, 5032.949670503322, 5230.869228269324, 5261.237120617489, 5283.260343551829, 2516.9496610067695, 2635.5124302003805, 2688.7445692659167, 2782.916512504628, 3050.3289276359164, 5079.923801142986, 5238.528532432099, 5439.48324909133, 5603.551715862721, 5830.618241420051, 5265.289185959923, 5865.941340586625, 5960.152352770655, 5961.731722072517, 5990.9515834728745, 5920.8815823684145, 5957.880842383128, 5963.373845746308, 5990.73041713124, 6027.9095813562835, 5438.483344082306, 5769.192313076089, 5823.1847541343395, 5846.239988801599, 5847.72527384666, 5579.051561234519, 5733.308673941114, 5736.311642602915, 5766.163906233656, 5783.826485205451, 5427.050266203478, 5443.237946687546, 5505.513511351993, 6072.1498990140535, 6099.817005489843, 3229.4187046331886, 3682.0978860179425, 3893.610638936084, 3905.121347696859, 3959.0894094359037, 5576.386597474194, 5858.619189752652, 5880.353161152356, 5880.676562789087, 5893.499052580579, 4391.670624703224, 4474.395956545824, 5561.916571251353, 5702.600817942704, 5730.971345143247, 5063.392392912874, 5641.153826925994, 5665.433456654301, 5695.914561281501, 5733.942660573341, 4439.780109945027, 4677.181493238738, 4933.703977761345, 4971.65198436106, 5405.351357837085, 5074.767102474899, 5468.28912241406, 5617.43825617435, 5671.0075736745075, 5722.914156287742, 4547.567981041796, 5215.9739201305265, 5552.944470555242, 5614.5789065821045, 5653.0], "value": 4062.598803592243, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.7732793522266, 673.8618583190447, 738.8042168825259, 772.992270077299, 805.8468890910723, 13130.671733206675, 15252.847471525281, 15493.457728979474, 15645.765313520278, 15651.843481565182, 12847.807282890764, 14454.060486068425, 14508.637284067878, 14520.85479145211, 15908.681826363465, 716.1334784910265, 932.3473568502042, 933.0855761353861, 939.3800091939321, 942.3403617467766, 740.4113729584993, 905.3345790843724, 934.4019827310527, 942.0485309837045, 944.0276515189366, 979.0943377375935, 980.7940332530177, 982.7985263021063, 986.896375880533, 988.4316518002141, 738.7857521318865, 949.4493193947494, 981.362114625492, 981.5877331520743, 981.6269817469388, 720.909886264217, 732.1616748822621, 732.7215658049907, 733.7651951375562, 736.2269616902284, 15692.450764223197, 15735.68528629421, 15735.763963540556, 15771.447999320084, 15790.973586716827, 12042.277463352139, 12286.75426491465, 12287.078469114853, 12420.316882571455, 12582.748345033047, 10779.946100269448, 10815.67552973412, 10859.782804343948, 11068.778624427547, 11440.027597654209, 3985.262726395626, 3997.780122093284, 4005.0, 4118.217538667674, 4129.194807012634, 91749.0, 92192.0, 93670.53164734201, 94112.0, 95683.56474652955, 9246.662002649591, 9341.859872101788, 9390.483523406141, 9516.952415238018, 9619.18236949853, 21098.156073756876, 21101.0, 21163.47091322729, 21168.153273868873, 21229.78770212278, 7182.0, 7182.461315401366, 7231.0, 7381.66782494789, 7484.550926944292, 4018.2967980603275, 4069.2675318442966, 4086.693497987663, 4086.775227362464, 4093.4473846031, 3336.916577085544, 3492.5808902931813, 3494.178867966026, 3494.790312581253, 3495.580530336376, 639.3255115852727, 651.312864927506, 653.650297091054, 657.135866335767, 661.8146918862726, 474.3430348966706, 583.4544700704854, 591.7130191856946, 617.3795335687605, 631.1416473595906, 6703.564268322543, 7854.803629909184, 8047.919520804834, 8259.463134896212, 8338.541380224146, 587.2629849538802, 615.3292910727329, 617.6263360666821, 617.6818938246806, 626.6271568416785, 3762.981185094057, 3861.189150278417, 3912.5304963404005, 3972.066564357374, 4130.545639979599, 88206.0, 106499.467502662, 106872.0, 109163.45418272857, 110330.0, 82802.0, 88021.67967480492, 88333.67499487512, 88939.55530222307, 90336.0, 69683.30316696869, 69747.65126174438, 69935.0, 74070.62964685034, 78749.4250115006, 8275.62759675828, 8341.282142999067, 8429.4942303462, 8519.23326900582, 8590.355723320901, 47185.292220617404, 48008.759956199996, 48278.0, 49609.75195124001, 50290.49709502858, 65334.34665653283, 65515.0, 65962.0, 66111.0, 66550.0, 10969.561217551527, 11022.889771102185, 11290.49192786343, 11356.488957997075, 11445.828312575484, 10791.56833726642, 11473.0, 11594.18840681178, 11957.461914214055, 12027.21823081497, 11359.772804543698, 11483.770324593293, 11484.598039068593, 11513.712157196138, 11625.418729063344, 11166.720831979266, 11298.661040168809, 11415.258008229439, 11426.314421134759, 11433.942830285796, 11151.996320330885, 11205.327680339205, 11210.66368008961, 11363.147763917574, 11594.0, 9482.525873706425, 9499.240060795259, 9673.0, 9759.359613231618, 9919.553620087258, 9607.0, 9863.852042219229, 9880.308378413441, 9887.0, 9922.702318930444], "value": 7040.2530151876845, "unit": "score"}], "name": "dromaeo_css", "value": 7301.540771049776, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 154.0, 157.0, 112.0, 111.0, 111.0, 112.0, 112.0, 111.0, 112.0], "value": 120.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 137.0, 129.0, 195.0, 134.0, 161.0, 156.0, 103.0, 189.0, 139.0], "value": 149.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 214.0, 253.0, 211.0, 185.0, 186.0, 187.0, 182.0, 184.0], "value": 197.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 100.0, 56.0, 122.0, 55.0, 59.0, 56.0, 59.0, 110.0, 55.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 133.0, 79.0, 80.0, 77.0, 102.0, 81.0, 77.0, 100.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 131.0, 92.0, 94.0, 84.0, 88.0, 85.0, 88.0, 85.0], "value": 92.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 94.0, 98.0, 138.0, 94.0, 93.0, 94.0], "value": 98.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 85.0, 84.0, 84.0, 84.0, 85.0, 86.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 55.0, 56.0, 54.0, 76.0, 83.0, 159.0, 56.0, 54.0], "value": 70.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 65.0, 77.0, 72.0, 71.0, 68.0, 66.0, 67.0, 67.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 85.0, 84.0, 84.0, 82.0, 83.0, 85.0, 85.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 134.0, 136.0, 121.0, 135.0, 138.0, 140.0, 245.0, 144.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 161.0, 163.0, 166.0, 161.0, 163.0, 160.0, 163.0, 160.0, 210.0], "value": 168.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 63.0, 64.0, 63.0, 65.0, 63.0, 65.0, 63.0], "value": 64.3, "unit": "score"}], "name": "kraken", "value": 1512.6999999999996, "alertThreshold": 2.0}]} 05:31:21 INFO - Return code: 0 05:31:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:31:21 ERROR - # TBPL SUCCESS # 05:31:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:31:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:31:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:31:21 INFO - 2016-05-17 05:31:21.428 screenresolution[6301:106334] starting screenresolution argv=screenresolution get 05:31:21 INFO - 2016-05-17 05:31:21.431 screenresolution[6301:106334] Display 0: 1600x1200x32@60 05:31:21 INFO - 2016-05-17 05:31:21.437 screenresolution[6302:106337] starting screenresolution argv=screenresolution list 05:31:21 INFO - Available Modes on Display 0 05:31:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:31:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:31:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:31:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:31:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:31:21 INFO - Intel Iris: 05:31:21 INFO - Chipset Model: Intel Iris 05:31:21 INFO - Type: GPU 05:31:21 INFO - Bus: Built-In 05:31:21 INFO - VRAM (Dynamic, Max): 1536 MB 05:31:21 INFO - Vendor: Intel (0x8086) 05:31:21 INFO - Device ID: 0x0a2e 05:31:21 INFO - Revision ID: 0x0009 05:31:21 INFO - Displays: 05:31:21 INFO - Display: 05:31:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:31:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:31:21 INFO - Display Serial Number: 1600x1200 60 05:31:21 INFO - Main Display: Yes 05:31:21 INFO - Mirror: Off 05:31:21 INFO - Online: Yes 05:31:21 INFO - Rotation: Supported 05:31:21 INFO - Return code: 0 05:31:21 INFO - Running post-action listener: _resource_record_post_action 05:31:21 INFO - ##### 05:31:21 INFO - ##### Finished run-tests step (success) 05:31:21 INFO - ##### 05:31:21 INFO - Running post-run listener: _resource_record_post_run 05:31:21 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1519104; Write bytes: 694456832; Read time: 24; Write time: 1899 05:31:21 INFO - TinderboxPrint: CPU usage
53.9% 05:31:21 INFO - TinderboxPrint: I/O read bytes / time
1,519,104 / 24 05:31:21 INFO - TinderboxPrint: I/O write bytes / time
694,456,832 / 1,899 05:31:21 INFO - TinderboxPrint: CPU idle
2,236.6 (46.1%) 05:31:21 INFO - TinderboxPrint: CPU system
970.4 (20.0%) 05:31:21 INFO - TinderboxPrint: CPU user
1,642.7 (33.9%) 05:31:21 INFO - TinderboxPrint: Swap in / out
397,381,632 / 0 05:31:21 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380342784; Write bytes: 374360064; Read time: 28491; Write time: 700 05:31:21 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1519104; Write bytes: 297310720; Read time: 24; Write time: 1148 05:31:22 INFO - Running post-run listener: _upload_blobber_files 05:31:22 INFO - Blob upload gear active. 05:31:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:31:22 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 05:31:22 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'] 05:31:22 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 05:31:22 INFO - (blobuploader) - INFO - Open directory for files ... 05:31:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:31:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:31:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:23 INFO - (blobuploader) - INFO - Done attempting. 05:31:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:31:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:31:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:24 INFO - (blobuploader) - INFO - Done attempting. 05:31:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:31:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:31:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:27 INFO - (blobuploader) - INFO - Done attempting. 05:31:27 INFO - (blobuploader) - INFO - Iteration through files over. 05:31:27 INFO - Return code: 0 05:31:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:31:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:31:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7afcf50b60f965d7a84618715f4fe19f77105c9b51cc8baed4f833d42f6133d4cbf0c847cb03719925c227206e498d0867b8cfc9cd2d66a8ed106ec750bb897a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd07784ebc6a714836033cbc0f07a8bdd432da2f2576d99653a002ab0a6b626aaef5ac65bc41cb1d94509368b1953766bd14f6dceb0e9cbb2e1e677d88cfa666", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de1daafce8687a821145ec13feafba0105c316325f68fdfc776590ab99a1a01d2e952ee2a58b661abb9ea14b9fe54e38e65fecc1a2ac8156a9c1b841a31c6d50"} 05:31:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:31:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:31:27 INFO - Contents: 05:31:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7afcf50b60f965d7a84618715f4fe19f77105c9b51cc8baed4f833d42f6133d4cbf0c847cb03719925c227206e498d0867b8cfc9cd2d66a8ed106ec750bb897a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd07784ebc6a714836033cbc0f07a8bdd432da2f2576d99653a002ab0a6b626aaef5ac65bc41cb1d94509368b1953766bd14f6dceb0e9cbb2e1e677d88cfa666", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de1daafce8687a821145ec13feafba0105c316325f68fdfc776590ab99a1a01d2e952ee2a58b661abb9ea14b9fe54e38e65fecc1a2ac8156a9c1b841a31c6d50"} 05:31:27 INFO - Running post-run listener: copy_logs_to_upload_dir 05:31:27 INFO - Copying logs to upload dir... 05:31:27 INFO - mkdir: /builds/slave/test/build/upload/logs 05:31:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.110855 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-17 05:31:27.112676) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 05:31:27.116422) ========= 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.apqxMCPst0/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.EOtdwBkDxW/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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/7afcf50b60f965d7a84618715f4fe19f77105c9b51cc8baed4f833d42f6133d4cbf0c847cb03719925c227206e498d0867b8cfc9cd2d66a8ed106ec750bb897a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd07784ebc6a714836033cbc0f07a8bdd432da2f2576d99653a002ab0a6b626aaef5ac65bc41cb1d94509368b1953766bd14f6dceb0e9cbb2e1e677d88cfa666", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de1daafce8687a821145ec13feafba0105c316325f68fdfc776590ab99a1a01d2e952ee2a58b661abb9ea14b9fe54e38e65fecc1a2ac8156a9c1b841a31c6d50"} build_url:https://queue.taskcluster.net/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011221 build_url: 'https://queue.taskcluster.net/v1/task/Vq5uVjrfSlCPGevgWhbrDg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7afcf50b60f965d7a84618715f4fe19f77105c9b51cc8baed4f833d42f6133d4cbf0c847cb03719925c227206e498d0867b8cfc9cd2d66a8ed106ec750bb897a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd07784ebc6a714836033cbc0f07a8bdd432da2f2576d99653a002ab0a6b626aaef5ac65bc41cb1d94509368b1953766bd14f6dceb0e9cbb2e1e677d88cfa666", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/de1daafce8687a821145ec13feafba0105c316325f68fdfc776590ab99a1a01d2e952ee2a58b661abb9ea14b9fe54e38e65fecc1a2ac8156a9c1b841a31c6d50"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 05:31:27.143884) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:31:27.144208) ========= 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.apqxMCPst0/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.EOtdwBkDxW/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.005404 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 05:31:27.192701) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 05:31:27.193003) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 05:31:27.193331) ========= ========= Total master_lag: 0.17 =========