builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0082 starttime: 1464069203.03 results: success (0) buildid: 20160523193703 builduid: f8fadc9d25bb43b29e1d138089a15f18 revision: 9e938575c485151e55c05dd7a1f824bdd8c64837 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:23.025755) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:23.026185) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:23.026479) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:23.053202) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:23.053516) ========= 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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-23 22:53:22-- 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% 152M=0s 2016-05-23 22:53:22 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116793 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:23.189435) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:23.189731) ========= 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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.031470 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:23.238057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-05-23 22:53:23.238380) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9e938575c485151e55c05dd7a1f824bdd8c64837 --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 9e938575c485151e55c05dd7a1f824bdd8c64837 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-23 22:53:22,784 truncating revision to first 12 chars 2016-05-23 22:53:22,784 Setting DEBUG logging. 2016-05-23 22:53:22,784 attempt 1/10 2016-05-23 22:53:22,784 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9e938575c485?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 22:53:24,720 unpacking tar archive at: fx-team-9e938575c485/testing/mozharness/ program finished with exit code 0 elapsedTime=2.182581 ========= master_lag: 1.91 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-05-23 22:53:27.328333) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:27.328603) ========= script_repo_revision: 9e938575c485151e55c05dd7a1f824bdd8c64837 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:27.328980) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:27.329262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 22:53:27.346077) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-05-23 22:53:27.346415) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 22:53:27 INFO - MultiFileLogger online at 20160523 22:53:27 in /builds/slave/test 22:53:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:53:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:53:27 INFO - {'append_to_log': False, 22:53:27 INFO - 'base_work_dir': '/builds/slave/test', 22:53:27 INFO - 'blob_upload_branch': 'Fx-Team', 22:53:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:53:27 INFO - 'branch': 'Fx-Team', 22:53:27 INFO - 'buildbot_json_path': 'buildprops.json', 22:53:27 INFO - 'config_files': ('talos/mac_config.py',), 22:53:27 INFO - 'default_actions': ('clobber', 22:53:27 INFO - 'read-buildbot-config', 22:53:27 INFO - 'download-and-extract', 22:53:27 INFO - 'populate-webroot', 22:53:27 INFO - 'create-virtualenv', 22:53:27 INFO - 'install', 22:53:27 INFO - 'run-tests'), 22:53:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:53:27 INFO - 'download_minidump_stackwalk': True, 22:53:27 INFO - 'download_symbols': 'ondemand', 22:53:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:53:27 INFO - 'tooltool.py': '/tools/tooltool.py', 22:53:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:53:27 INFO - '/tools/misc-python/virtualenv.py')}, 22:53:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:53:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:53:27 INFO - 'installer_path': 'installer.exe', 22:53:27 INFO - 'log_level': 'info', 22:53:27 INFO - 'log_name': 'talos', 22:53:27 INFO - 'log_to_console': True, 22:53:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:53:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:53:27 INFO - 'opt_config_files': (), 22:53:27 INFO - 'pip_index': False, 22:53:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:53:27 INFO - 'cmd': ('bash', 22:53:27 INFO - '-c', 22:53:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:53:27 INFO - 'enabled': True, 22:53:27 INFO - 'halt_on_failure': False, 22:53:27 INFO - 'name': 'check_screen_resolution'},), 22:53:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:53:27 INFO - 'cmd': ('bash', 22:53:27 INFO - '-c', 22:53:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:53:27 INFO - 'enabled': True, 22:53:27 INFO - 'halt_on_failure': False, 22:53:27 INFO - 'name': 'check_screen_resolution'},), 22:53:27 INFO - 'run_cmd_checks_enabled': True, 22:53:27 INFO - 'sps_profile': False, 22:53:27 INFO - 'sps_profile_interval': 0, 22:53:27 INFO - 'suite': 'dromaeojs-e10s', 22:53:27 INFO - 'system_bits': '32', 22:53:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:53:27 INFO - 'title': 't-yosemite-r7-0082', 22:53:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:53:27 INFO - 'use_talos_json': True, 22:53:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:53:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:53:27 INFO - 'work_dir': 'build'} 22:53:27 INFO - ##### 22:53:27 INFO - ##### Running clobber step. 22:53:27 INFO - ##### 22:53:27 INFO - Running pre-action listener: _resource_record_pre_action 22:53:27 INFO - Running main action method: clobber 22:53:27 INFO - rmtree: /builds/slave/test/build 22:53:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:53:28 INFO - Running post-action listener: _resource_record_post_action 22:53:28 INFO - ##### 22:53:28 INFO - ##### Finished clobber step (success) 22:53:28 INFO - ##### 22:53:28 INFO - ##### 22:53:28 INFO - ##### Running read-buildbot-config step. 22:53:28 INFO - ##### 22:53:28 INFO - Running pre-action listener: _resource_record_pre_action 22:53:28 INFO - Running main action method: read_buildbot_config 22:53:28 INFO - Using buildbot properties: 22:53:28 INFO - { 22:53:28 INFO - "project": "", 22:53:28 INFO - "product": "firefox", 22:53:28 INFO - "script_repo_revision": "production", 22:53:28 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 22:53:28 INFO - "repository": "", 22:53:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 22:53:28 INFO - "buildid": "20160523193703", 22:53:28 INFO - "pgo_build": "False", 22:53:28 INFO - "basedir": "/builds/slave/test", 22:53:28 INFO - "buildnumber": 13, 22:53:28 INFO - "slavename": "t-yosemite-r7-0082", 22:53:28 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 22:53:28 INFO - "platform": "yosemite_r7", 22:53:28 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 22:53:28 INFO - "branch": "fx-team", 22:53:28 INFO - "repo_path": "integration/fx-team", 22:53:28 INFO - "revision": "9e938575c485151e55c05dd7a1f824bdd8c64837", 22:53:28 INFO - "stage_platform": "macosx64", 22:53:28 INFO - "builduid": "f8fadc9d25bb43b29e1d138089a15f18", 22:53:28 INFO - "slavebuilddir": "test" 22:53:28 INFO - } 22:53:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 22:53:28 INFO - Running post-action listener: _resource_record_post_action 22:53:28 INFO - ##### 22:53:28 INFO - ##### Finished read-buildbot-config step (success) 22:53:28 INFO - ##### 22:53:28 INFO - ##### 22:53:28 INFO - ##### Running download-and-extract step. 22:53:28 INFO - ##### 22:53:28 INFO - Running pre-action listener: _resource_record_pre_action 22:53:28 INFO - Running main action method: download_and_extract 22:53:28 INFO - mkdir: /builds/slave/test/build/tests 22:53:28 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')]} 22:53:28 INFO - https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:53:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:53:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:53:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/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 22:53:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/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 22:53:30 INFO - Downloaded 1199 bytes. 22:53:30 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 22:53:30 INFO - Using the following test package requirements: 22:53:30 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:53:30 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:53:30 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 22:53:30 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:53:30 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 22:53:30 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:53:30 INFO - u'jsshell-mac.zip'], 22:53:30 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:53:30 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 22:53:30 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:53:30 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:53:30 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 22:53:30 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:53:30 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 22:53:30 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:53:30 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 22:53:30 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:53:30 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 22:53:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:30 INFO - https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:53:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:53:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:53:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/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 22:53:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/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 22:53:32 INFO - Downloaded 18630541 bytes. 22:53:32 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'] 22:53:32 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 22:53:35 INFO - Return code: 0 22:53:35 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 22:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:35 INFO - https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:53:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:53:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:53:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/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 22:53:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/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 22:53:47 INFO - Downloaded 18630541 bytes. 22:53:47 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'] 22:53:47 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 22:53:50 INFO - Return code: 0 22:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:50 INFO - https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:53:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:53:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:53:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/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 22:53:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/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 22:53:51 INFO - Downloaded 10840359 bytes. 22:53:51 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'] 22:53:51 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 22:53:51 INFO - Return code: 0 22:53: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')]} 22:53:51 INFO - https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:53:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:53:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:53:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 22:53:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tk5sXH0-TOiEdRmGypAy6w/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 22:53:58 INFO - Downloaded 110167764 bytes. 22:53:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:53:58 INFO - mkdir: /builds/slave/test/properties 22:53:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:53:58 INFO - Writing to file /builds/slave/test/properties/build_url 22:53:58 INFO - Contents: 22:53:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:53:59 INFO - Running post-action listener: _resource_record_post_action 22:53:59 INFO - Running post-action listener: set_extra_try_arguments 22:53:59 INFO - ##### 22:53:59 INFO - ##### Finished download-and-extract step (success) 22:53:59 INFO - ##### 22:53:59 INFO - ##### 22:53:59 INFO - ##### Running populate-webroot step. 22:53:59 INFO - ##### 22:53:59 INFO - Running pre-action listener: _resource_record_pre_action 22:53:59 INFO - Running main action method: populate_webroot 22:53:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:53:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:53:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:53:59 INFO - '--tppagecycles', 22:53:59 INFO - '7'], 22:53:59 INFO - 'tests': ['tsvgm']}}, 22:53:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:53:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:53:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:53:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:53:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:53:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:53:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:59 INFO - 'talos_options': ['--disable-e10s'], 22:53:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:53:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:53:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:59 INFO - 'talos_options': ['--disable-e10s'], 22:53:59 INFO - 'tests': ['damp', 'tps']}, 22:53:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:59 INFO - 'tests': ['damp', 'tps']}, 22:53:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:53:59 INFO - 'tests': ['dromaeo_dom']}, 22:53:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:53:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:53:59 INFO - 'tests': ['a11yr', 22:53:59 INFO - 'ts_paint', 22:53:59 INFO - 'tpaint', 22:53:59 INFO - 'sessionrestore', 22:53:59 INFO - 'sessionrestore_no_auto_restore', 22:53:59 INFO - 'tabpaint']}, 22:53:59 INFO - 'other-e10s': {'tests': ['a11yr', 22:53:59 INFO - 'ts_paint', 22:53:59 INFO - 'tpaint', 22:53:59 INFO - 'sessionrestore', 22:53:59 INFO - 'sessionrestore_no_auto_restore', 22:53:59 INFO - 'tabpaint']}, 22:53:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:53:59 INFO - 'ts_paint', 22:53:59 INFO - 'tpaint', 22:53:59 INFO - 'sessionrestore', 22:53:59 INFO - 'sessionrestore_no_auto_restore', 22:53:59 INFO - 'tabpaint']}, 22:53:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:53:59 INFO - 'ts_paint', 22:53:59 INFO - 'tpaint', 22:53:59 INFO - 'sessionrestore', 22:53:59 INFO - 'sessionrestore_no_auto_restore', 22:53:59 INFO - 'tabpaint']}, 22:53:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:53:59 INFO - 'tests': ['a11yr', 22:53:59 INFO - 'ts_paint', 22:53:59 INFO - 'tpaint', 22:53:59 INFO - 'sessionrestore', 22:53:59 INFO - 'sessionrestore_no_auto_restore', 22:53:59 INFO - 'tabpaint']}, 22:53:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:53:59 INFO - 'tests': ['a11yr', 22:53:59 INFO - 'ts_paint', 22:53:59 INFO - 'tpaint', 22:53:59 INFO - 'sessionrestore', 22:53:59 INFO - 'sessionrestore_no_auto_restore', 22:53:59 INFO - 'tabpaint']}, 22:53:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:53:59 INFO - 'tests': ['tsvgx', 22:53:59 INFO - 'tsvgr_opacity', 22:53:59 INFO - 'tart', 22:53:59 INFO - 'tscrollx', 22:53:59 INFO - 'cart']}, 22:53:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:53:59 INFO - 'tsvgr_opacity', 22:53:59 INFO - 'tart', 22:53:59 INFO - 'tscrollx', 22:53:59 INFO - 'cart']}, 22:53:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:59 INFO - 'talos_options': ['--disable-e10s'], 22:53:59 INFO - 'tests': ['tp5o']}, 22:53:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:59 INFO - 'tests': ['tp5o']}, 22:53:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:59 INFO - 'talos_options': ['--disable-e10s', 22:53:59 INFO - '--xperf_path', 22:53:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:53:59 INFO - 'tests': ['tp5n']}, 22:53:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:53:59 INFO - 'talos_options': ['--xperf_path', 22:53:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:53:59 INFO - 'tests': ['tp5n']}}, 22:53:59 INFO - 'talos.zip': {'path': '', 22:53:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:53:59 INFO - Running post-action listener: _resource_record_post_action 22:53:59 INFO - ##### 22:53:59 INFO - ##### Finished populate-webroot step (success) 22:53:59 INFO - ##### 22:53:59 INFO - ##### 22:53:59 INFO - ##### Running create-virtualenv step. 22:53:59 INFO - ##### 22:53:59 INFO - Running pre-action listener: _resource_record_pre_action 22:53:59 INFO - Running main action method: create_virtualenv 22:53:59 INFO - Creating virtualenv /builds/slave/test/build/venv 22:53:59 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 22:53:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:53:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:53:59 INFO - Using real prefix '/tools/python27' 22:53:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:54:00 INFO - Installing distribute.............................................................................................................................................................................................done. 22:54:03 INFO - Installing pip.................done. 22:54:03 INFO - Return code: 0 22:54:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:03 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 0x104799a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef50cd7a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463dd30>, '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 0x104461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104634b20>, '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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 22:54:03 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 22:54:03 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 22:54:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKVUk0MvwK/Render', 22:54:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:03 INFO - 'HOME': '/Users/cltbld', 22:54:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:03 INFO - 'LOGNAME': 'cltbld', 22:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:03 INFO - 'MOZ_NO_REMOTE': '1', 22:54:03 INFO - 'NO_EM_RESTART': '1', 22:54:03 INFO - 'PAGER': '/bin/cat', 22:54:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:03 INFO - 'PWD': '/builds/slave/test', 22:54:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:03 INFO - 'SHELL': '/bin/bash', 22:54:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9IgNQT6Xmr/Listeners', 22:54:03 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 22:54:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:03 INFO - 'USER': 'cltbld', 22:54:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:03 INFO - 'XPC_FLAGS': '0x0', 22:54:03 INFO - 'XPC_SERVICE_NAME': '0', 22:54:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:03 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 22:54:03 INFO - Cleaning up... 22:54:03 INFO - Return code: 0 22:54:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:03 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 0x104799a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef50cd7a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463dd30>, '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 0x104461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104634b20>, '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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 22:54:03 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 22:54:03 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 22:54:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKVUk0MvwK/Render', 22:54:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:03 INFO - 'HOME': '/Users/cltbld', 22:54:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:03 INFO - 'LOGNAME': 'cltbld', 22:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:03 INFO - 'MOZ_NO_REMOTE': '1', 22:54:03 INFO - 'NO_EM_RESTART': '1', 22:54:03 INFO - 'PAGER': '/bin/cat', 22:54:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:03 INFO - 'PWD': '/builds/slave/test', 22:54:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:03 INFO - 'SHELL': '/bin/bash', 22:54:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9IgNQT6Xmr/Listeners', 22:54:03 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 22:54:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:03 INFO - 'USER': 'cltbld', 22:54:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:03 INFO - 'XPC_FLAGS': '0x0', 22:54:03 INFO - 'XPC_SERVICE_NAME': '0', 22:54:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:04 INFO - Downloading/unpacking psutil>=3.1.1 22:54: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 22:54: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 22:54: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 22:54: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 22:54: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 22:54: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 22:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:54:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:54:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:54:06 INFO - Installing collected packages: psutil 22:54:06 INFO - Running setup.py install for psutil 22:54:06 INFO - building 'psutil._psutil_osx' extension 22:54:06 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 22:54:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:54:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:54:07 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 22:54:07 INFO - building 'psutil._psutil_posix' extension 22:54:07 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 22:54:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:54:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:54:07 INFO - ^ 22:54:07 INFO - 1 warning generated. 22:54:07 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 22:54:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:54:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:54:07 INFO - Successfully installed psutil 22:54:07 INFO - Cleaning up... 22:54:07 INFO - Return code: 0 22:54:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104799a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef50cd7a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463dd30>, '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 0x104461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104634b20>, '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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 22:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKVUk0MvwK/Render', 22:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:07 INFO - 'HOME': '/Users/cltbld', 22:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:07 INFO - 'LOGNAME': 'cltbld', 22:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:07 INFO - 'MOZ_NO_REMOTE': '1', 22:54:07 INFO - 'NO_EM_RESTART': '1', 22:54:07 INFO - 'PAGER': '/bin/cat', 22:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:07 INFO - 'PWD': '/builds/slave/test', 22:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:07 INFO - 'SHELL': '/bin/bash', 22:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9IgNQT6Xmr/Listeners', 22:54:07 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 22:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:07 INFO - 'USER': 'cltbld', 22:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:07 INFO - 'XPC_FLAGS': '0x0', 22:54:07 INFO - 'XPC_SERVICE_NAME': '0', 22:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:54: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 22:54: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 22:54: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 22:54: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 22:54: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 22:54: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 22:54:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:54:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:54:10 INFO - Installing collected packages: mozsystemmonitor 22:54:10 INFO - Running setup.py install for mozsystemmonitor 22:54:10 INFO - Successfully installed mozsystemmonitor 22:54:10 INFO - Cleaning up... 22:54:10 INFO - Return code: 0 22:54:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:54:10 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')]} 22:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104799a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef50cd7a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463dd30>, '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 0x104461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104634b20>, '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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 22:54:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:54:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:54:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKVUk0MvwK/Render', 22:54:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:11 INFO - 'HOME': '/Users/cltbld', 22:54:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:11 INFO - 'LOGNAME': 'cltbld', 22:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:11 INFO - 'MOZ_NO_REMOTE': '1', 22:54:11 INFO - 'NO_EM_RESTART': '1', 22:54:11 INFO - 'PAGER': '/bin/cat', 22:54:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:11 INFO - 'PWD': '/builds/slave/test', 22:54:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:11 INFO - 'SHELL': '/bin/bash', 22:54:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9IgNQT6Xmr/Listeners', 22:54:11 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 22:54:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:11 INFO - 'USER': 'cltbld', 22:54:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:11 INFO - 'XPC_FLAGS': '0x0', 22:54:11 INFO - 'XPC_SERVICE_NAME': '0', 22:54:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:11 INFO - Downloading/unpacking blobuploader==1.2.4 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:11 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 22:54:14 INFO - Downloading blobuploader-1.2.4.tar.gz 22:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:54:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:54:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:15 INFO - Downloading docopt-0.6.1.tar.gz 22:54:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:54:15 INFO - Installing collected packages: blobuploader, requests, docopt 22:54:15 INFO - Running setup.py install for blobuploader 22:54:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:54:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:54:15 INFO - Running setup.py install for requests 22:54:16 INFO - Running setup.py install for docopt 22:54:16 INFO - Successfully installed blobuploader requests docopt 22:54:16 INFO - Cleaning up... 22:54:16 INFO - Return code: 0 22:54:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:54: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')]} 22:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54: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')]} 22:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104799a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef50cd7a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463dd30>, '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 0x104461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104634b20>, '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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 22:54:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:54:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:54:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKVUk0MvwK/Render', 22:54:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:16 INFO - 'HOME': '/Users/cltbld', 22:54:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:16 INFO - 'LOGNAME': 'cltbld', 22:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:16 INFO - 'MOZ_NO_REMOTE': '1', 22:54:16 INFO - 'NO_EM_RESTART': '1', 22:54:16 INFO - 'PAGER': '/bin/cat', 22:54:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:16 INFO - 'PWD': '/builds/slave/test', 22:54:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:16 INFO - 'SHELL': '/bin/bash', 22:54:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9IgNQT6Xmr/Listeners', 22:54:16 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 22:54:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:16 INFO - 'USER': 'cltbld', 22:54:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:16 INFO - 'XPC_FLAGS': '0x0', 22:54:16 INFO - 'XPC_SERVICE_NAME': '0', 22:54:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:54:16 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-G3IZS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:54:16 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-SXyaPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:54:17 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-JJLmVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:54:17 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-lIZTyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:54:17 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-7R8O73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:54:17 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-H5idyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:54:17 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-IBJibx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:54:17 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-relxem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:54:17 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-WuKJVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:54:17 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-DnPWuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:54:17 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-31RaeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:54:18 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-nsB5xW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:54:18 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-_thara-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:54:18 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Tpp1PX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:18 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-JAAw5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:54:18 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-yddEuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:54:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:54:18 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ec9CsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:54:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:54:18 INFO - Running setup.py install for manifestparser 22:54:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:54:18 INFO - Running setup.py install for mozcrash 22:54:18 INFO - Running setup.py install for mozdebug 22:54:19 INFO - Running setup.py install for mozdevice 22:54:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:54:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:54:19 INFO - Running setup.py install for mozfile 22:54:19 INFO - Running setup.py install for mozhttpd 22:54:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:54:19 INFO - Running setup.py install for mozinfo 22:54:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:54:19 INFO - Running setup.py install for mozInstall 22:54:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:54:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:54:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:54:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:54:19 INFO - Running setup.py install for mozleak 22:54:19 INFO - Running setup.py install for mozlog 22:54:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:54:20 INFO - Running setup.py install for moznetwork 22:54:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:54:20 INFO - Running setup.py install for mozprocess 22:54:20 INFO - Running setup.py install for mozprofile 22:54:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:54:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:54:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:54:20 INFO - Running setup.py install for mozrunner 22:54:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:54:20 INFO - Running setup.py install for mozscreenshot 22:54:20 INFO - Running setup.py install for moztest 22:54:21 INFO - Running setup.py install for mozversion 22:54:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:54:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:54:21 INFO - Cleaning up... 22:54:21 INFO - Return code: 0 22:54:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104799a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef50cd7a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463dd30>, '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 0x104461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104634b20>, '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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 22:54:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:54:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKVUk0MvwK/Render', 22:54:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:21 INFO - 'HOME': '/Users/cltbld', 22:54:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:21 INFO - 'LOGNAME': 'cltbld', 22:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:21 INFO - 'MOZ_NO_REMOTE': '1', 22:54:21 INFO - 'NO_EM_RESTART': '1', 22:54:21 INFO - 'PAGER': '/bin/cat', 22:54:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:21 INFO - 'PWD': '/builds/slave/test', 22:54:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:21 INFO - 'SHELL': '/bin/bash', 22:54:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9IgNQT6Xmr/Listeners', 22:54:21 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 22:54:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:21 INFO - 'USER': 'cltbld', 22:54:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:21 INFO - 'XPC_FLAGS': '0x0', 22:54:21 INFO - 'XPC_SERVICE_NAME': '0', 22:54:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:54:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-XRH3e_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:54:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-gMRqu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:54:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-5gzabP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:54:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-510ijM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:54:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-DWfZCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:54:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-AdThgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:54:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-7zbfk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:54:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-evSqPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:54:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-sYZySv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:54:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-wd4RxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:54:22 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)) 22:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:54:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-9Crkr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:54:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-MRLk38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:54:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Xxask2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:54:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-aLFc9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:54:22 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)) 22:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:22 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-g8M7h8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:54:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Z5RPXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:54:23 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-q2E8qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:54:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:25 INFO - Downloading blessings-1.6.tar.gz 22:54:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:54:25 INFO - Installing collected packages: blessings 22:54:25 INFO - Running setup.py install for blessings 22:54:26 INFO - Successfully installed blessings 22:54:26 INFO - Cleaning up... 22:54:26 INFO - Return code: 0 22:54:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:54:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:54:26 INFO - Reading from file tmpfile_stdout 22:54:26 INFO - Current package versions: 22:54:26 INFO - blessings == 1.6 22:54:26 INFO - blobuploader == 1.2.4 22:54:26 INFO - docopt == 0.6.1 22:54:26 INFO - manifestparser == 1.1 22:54:26 INFO - mozInstall == 1.12 22:54:26 INFO - mozcrash == 0.17 22:54:26 INFO - mozdebug == 0.1 22:54:26 INFO - mozdevice == 0.48 22:54:26 INFO - mozfile == 1.2 22:54:26 INFO - mozhttpd == 0.7 22:54:26 INFO - mozinfo == 0.9 22:54:26 INFO - mozleak == 0.1 22:54:26 INFO - mozlog == 3.2 22:54:26 INFO - moznetwork == 0.27 22:54:26 INFO - mozprocess == 0.22 22:54:26 INFO - mozprofile == 0.28 22:54:26 INFO - mozrunner == 6.11 22:54:26 INFO - mozscreenshot == 0.1 22:54:26 INFO - mozsystemmonitor == 0.3 22:54:26 INFO - moztest == 0.7 22:54:26 INFO - mozversion == 1.4 22:54:26 INFO - psutil == 3.1.1 22:54:26 INFO - requests == 1.2.3 22:54:26 INFO - wsgiref == 0.1.2 22:54:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104799a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef50cd7a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463dd30>, '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 0x104461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104634b20>, '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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 22:54:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:54:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKVUk0MvwK/Render', 22:54:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:26 INFO - 'HOME': '/Users/cltbld', 22:54:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:26 INFO - 'LOGNAME': 'cltbld', 22:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:26 INFO - 'MOZ_NO_REMOTE': '1', 22:54:26 INFO - 'NO_EM_RESTART': '1', 22:54:26 INFO - 'PAGER': '/bin/cat', 22:54:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:26 INFO - 'PWD': '/builds/slave/test', 22:54:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:26 INFO - 'SHELL': '/bin/bash', 22:54:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9IgNQT6Xmr/Listeners', 22:54:26 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 22:54:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:26 INFO - 'USER': 'cltbld', 22:54:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:26 INFO - 'XPC_FLAGS': '0x0', 22:54:26 INFO - 'XPC_SERVICE_NAME': '0', 22:54:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:54:26 INFO - Cleaning up... 22:54:26 INFO - Return code: 0 22:54:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104799a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef50cd7a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463dd30>, '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 0x104461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104634b20>, '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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 22:54:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:54:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKVUk0MvwK/Render', 22:54:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:26 INFO - 'HOME': '/Users/cltbld', 22:54:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:26 INFO - 'LOGNAME': 'cltbld', 22:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:26 INFO - 'MOZ_NO_REMOTE': '1', 22:54:26 INFO - 'NO_EM_RESTART': '1', 22:54:26 INFO - 'PAGER': '/bin/cat', 22:54:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:26 INFO - 'PWD': '/builds/slave/test', 22:54:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:26 INFO - 'SHELL': '/bin/bash', 22:54:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9IgNQT6Xmr/Listeners', 22:54:26 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 22:54:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:26 INFO - 'USER': 'cltbld', 22:54:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:26 INFO - 'XPC_FLAGS': '0x0', 22:54:26 INFO - 'XPC_SERVICE_NAME': '0', 22:54:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:26 INFO - Downloading/unpacking jsonschema 22:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:54:29 INFO - Downloading/unpacking functools32 (from jsonschema) 22:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:29 INFO - Downloading functools32-3.2.3-2.tar.gz 22:54:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:54:29 INFO - warning: no files found matching '*.txt' 22:54:29 INFO - no previously-included directories found matching 'build' 22:54:29 INFO - no previously-included directories found matching 'dist' 22:54:29 INFO - no previously-included directories found matching '.git*' 22:54:29 INFO - Installing collected packages: jsonschema, functools32 22:54:29 INFO - Running setup.py install for functools32 22:54:30 INFO - warning: no files found matching '*.txt' 22:54:30 INFO - no previously-included directories found matching 'build' 22:54:30 INFO - no previously-included directories found matching 'dist' 22:54:30 INFO - no previously-included directories found matching '.git*' 22:54:30 INFO - Successfully installed jsonschema functools32 22:54:30 INFO - Cleaning up... 22:54:30 INFO - Return code: 0 22:54:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104799a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10479f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef50cd7a10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463dd30>, '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 0x104461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104634b20>, '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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 22:54:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKVUk0MvwK/Render', 22:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:30 INFO - 'HOME': '/Users/cltbld', 22:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:30 INFO - 'LOGNAME': 'cltbld', 22:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:30 INFO - 'MOZ_NO_REMOTE': '1', 22:54:30 INFO - 'NO_EM_RESTART': '1', 22:54:30 INFO - 'PAGER': '/bin/cat', 22:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:30 INFO - 'PWD': '/builds/slave/test', 22:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:30 INFO - 'SHELL': '/bin/bash', 22:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9IgNQT6Xmr/Listeners', 22:54:30 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 22:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:30 INFO - 'USER': 'cltbld', 22:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:30 INFO - 'XPC_FLAGS': '0x0', 22:54:30 INFO - 'XPC_SERVICE_NAME': '0', 22:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:30 INFO - Downloading/unpacking flake8 22:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:54:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:54:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:54:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:54:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:54:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:54:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:54:34 INFO - no previously-included directories found matching 'docs/_build' 22:54:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:54:34 INFO - Running setup.py install for pep8 22:54:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:54:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:54:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:54:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:54:34 INFO - no previously-included directories found matching 'docs/_build' 22:54:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:54:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:54:34 INFO - Cleaning up... 22:54:34 INFO - Return code: 0 22:54:34 INFO - Running post-action listener: _resource_record_post_action 22:54:34 INFO - Running post-action listener: _start_resource_monitoring 22:54:34 INFO - Starting resource monitoring. 22:54:34 INFO - ##### 22:54:34 INFO - ##### Finished create-virtualenv step (success) 22:54:34 INFO - ##### 22:54:34 INFO - ##### 22:54:34 INFO - ##### Running install step. 22:54:34 INFO - ##### 22:54:34 INFO - Running pre-action listener: _resource_record_pre_action 22:54:34 INFO - Running main action method: install 22:54:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:54:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:54:35 INFO - Reading from file tmpfile_stdout 22:54:35 INFO - Detecting whether we're running mozinstall >=1.0... 22:54:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:54:35 INFO - Reading from file tmpfile_stdout 22:54:35 INFO - Output received: 22:54:35 INFO - Usage: mozinstall [options] installer 22:54:35 INFO - Options: 22:54:35 INFO - -h, --help show this help message and exit 22:54:35 INFO - -d DEST, --destination=DEST 22:54:35 INFO - Directory to install application into. [default: 22:54:35 INFO - "/builds/slave/test"] 22:54:35 INFO - --app=APP Application being installed. [default: firefox] 22:54:35 INFO - mkdir: /builds/slave/test/build/application 22:54:35 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'] 22:54:35 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 22:55:09 INFO - Reading from file tmpfile_stdout 22:55:09 INFO - Output received: 22:55:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:55:09 INFO - Running post-action listener: _resource_record_post_action 22:55:09 INFO - ##### 22:55:09 INFO - ##### Finished install step (success) 22:55:09 INFO - ##### 22:55:09 INFO - ##### 22:55:09 INFO - ##### Running run-tests step. 22:55:09 INFO - ##### 22:55:09 INFO - Running pre-action listener: _resource_record_pre_action 22:55:09 INFO - Running main action method: run_tests 22:55:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:55:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:55:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:55:09 INFO - 2016-05-23 22:55:09.325 screenresolution[1644:7681] starting screenresolution argv=screenresolution get 22:55:09 INFO - 2016-05-23 22:55:09.328 screenresolution[1644:7681] Display 0: 1600x1200x32@60 22:55:09 INFO - 2016-05-23 22:55:09.334 screenresolution[1645:7683] starting screenresolution argv=screenresolution list 22:55:09 INFO - Available Modes on Display 0 22:55:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:55:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:55:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:55:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:55:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:55:09 INFO - Intel Iris: 22:55:09 INFO - Chipset Model: Intel Iris 22:55:09 INFO - Type: GPU 22:55:09 INFO - Bus: Built-In 22:55:09 INFO - VRAM (Dynamic, Max): 1536 MB 22:55:09 INFO - Vendor: Intel (0x8086) 22:55:09 INFO - Device ID: 0x0a2e 22:55:09 INFO - Revision ID: 0x0009 22:55:09 INFO - Displays: 22:55:09 INFO - Display: 22:55:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:55:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:55:09 INFO - Display Serial Number: 1600x1200 60 22:55:09 INFO - Main Display: Yes 22:55:09 INFO - Mirror: Off 22:55:09 INFO - Online: Yes 22:55:09 INFO - Rotation: Supported 22:55:09 INFO - Return code: 0 22:55:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:55:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:55:09 INFO - Python 2.7.3 22:55:09 INFO - Return code: 0 22:55:09 INFO - grabbing minidump binary from tooltool 22:55:09 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')]} 22:55:09 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 0x10463dd30>, '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 0x104461db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104634b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:55:09 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 22:55:09 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 22:55:09 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 22:55:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:55:09 INFO - Return code: 0 22:55:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:55:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:55:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:55:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:55:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:55:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:55:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:55:10 INFO - Return code: 0 22:55:10 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:55:10 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0082 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EKVUk0MvwK/Render', 22:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:55:10 INFO - 'HOME': '/Users/cltbld', 22:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:55:10 INFO - 'LOGNAME': 'cltbld', 22:55:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:55:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:10 INFO - 'MOZ_NO_REMOTE': '1', 22:55:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:55:10 INFO - 'NO_EM_RESTART': '1', 22:55:10 INFO - 'PAGER': '/bin/cat', 22:55:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:10 INFO - 'PWD': '/builds/slave/test', 22:55:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:55:10 INFO - 'SHELL': '/bin/bash', 22:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9IgNQT6Xmr/Listeners', 22:55:10 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 22:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:55:10 INFO - 'USER': 'cltbld', 22:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:10 INFO - 'XPC_FLAGS': '0x0', 22:55:10 INFO - 'XPC_SERVICE_NAME': '0', 22:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:55:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:55:11 INFO - mozversion application_buildid: 20160523193703 22:55:11 INFO - mozversion application_changeset: 9e938575c485151e55c05dd7a1f824bdd8c64837 22:55:11 INFO - mozversion application_display_name: Nightly 22:55:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:55:11 INFO - mozversion application_name: Firefox 22:55:11 INFO - mozversion application_remotingname: firefox 22:55:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:55:11 INFO - mozversion application_vendor: Mozilla 22:55:11 INFO - mozversion application_version: 49.0a1 22:55:11 INFO - mozversion platform_buildid: 20160523193703 22:55:11 INFO - mozversion platform_changeset: 9e938575c485151e55c05dd7a1f824bdd8c64837 22:55:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:55:11 INFO - mozversion platform_version: 49.0a1 22:55:11 INFO - using testdate: 1464069311 22:55:11 INFO - actual date: 1464069311 22:55:11 INFO - starting webserver on 'localhost:49275' 22:55:11 INFO - SUITE-START | Running 2 tests 22:55:11 INFO - TEST-START | dromaeo_css 22:55:11 INFO - operating with platform_type : mac_ 22:55:11 INFO - Initialising browser for dromaeo_css test... 22:55:11 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpj48Dwq/profile http://localhost:49275/getInfo.html) 22:55:13 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 22:55:13 INFO - PROCESS | 1663 | colorDepth:24 22:55:13 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 22:55:13 INFO - PROCESS | 1663 | __metrics 22:55:14 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 22:55:14 INFO - TEST-INFO | 1663: exit 0 22:55:14 INFO - Browser initialized. 22:55:14 INFO - Running cycle 1/1 for dromaeo_css test... 22:55:14 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpj48Dwq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:55:26 INFO - PROCESS | 1666 | RSS: Main: 249704448 22:55:26 INFO - PROCESS | 1666 | 22:56:16 INFO - PROCESS | 1666 | 1464069376461 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] 22:58:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 22:58:28 INFO - PROCESS | 1666 | RSS: Main: 260726784 22:58:28 INFO - PROCESS | 1666 | 23:01:30 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 23:01:31 INFO - PROCESS | 1666 | RSS: Main: 273211392 23:01:31 INFO - PROCESS | 1666 | 23:04:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 23:04:33 INFO - PROCESS | 1666 | RSS: Main: 275492864 23:04:33 INFO - PROCESS | 1666 | 23:07:35 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 23:07:36 INFO - PROCESS | 1666 | RSS: Main: 276946944 23:07:36 INFO - PROCESS | 1666 | 23:10:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 23:10:39 INFO - PROCESS | 1666 | RSS: Main: 280133632 23:10:39 INFO - PROCESS | 1666 | 23:13:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 23:13:42 INFO - PROCESS | 1666 | RSS: Main: 281055232 23:13:42 INFO - PROCESS | 1666 | 23:13:42 INFO - PROCESS | 1666 | __start_tp_report 23:13:42 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 23:13:42 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 23:13:42 INFO - PROCESS | 1666 | |i|pagename|runs| 23:13:42 INFO - PROCESS | 1666 | |0;dojo.html;3530.399832028555;3541.3979623464;3552.236269202121;3561.572611286645;3568.9821550892257;5761.942380576193;5857.882842343156;5901.380355062721;5946;5969.701514924258;5708.686022268774;5774.971125144379;5790.68151251681;5819.854503637411;5924.763009479616;5206.947930520693;5245.86885327866;5344.91982620259;5365.919511207335;5392.272043274147;409.0382451878679;4396.900774806299;5248.5276325130735;5363.758630861621;5372.167314066326;5273.182656688219;5286.286351342558;5327.493888080626;5355.196720491938;5356.812511562116;5344.866378340573;5357.383900851413;5373.328333958255;5412.3505179378335;5414.269073675043;5194.974025129888;5227.26818245446;5266.131088370415;5266.657667251655;5335.813246536352;4590.380298659705;5405.486463790145;5754.856128596777;5781.017227071366;5828.941710582924;5047.318611987408;5656.91514627281;6032.155498230251;6252.3122456529745;6267.811965641038;6167.352427995041;6206.472449841724;6217.813465596039;6272.40412160844;6291.182146320949;6004.219451471325;6025.875053615413;6037.547183961176;6049.969750151221;6074.392560743979;6199.690015499207;6352.36476352361;6383.776567820104;6403.743850246031;6412.070249813751;6076.787312444043;6098.207233059763;6100.48145907594;6141.969290153521;6246.344133865879;6184.81445556625;6186.257649082139;6188.8762224755255;6206.162168107338;6260.311365749764;4953.950460495349;5147.305113809588;6138.7237574309265;6229.688515574203;6287.151234583364;6186.81439556805;6214.627122372672;6261.9373806262265;6267.4986001119805;6277.623342599459;4351;5065.822696205615;5078.847634570969;5086.338775959176;5116.9744151279;4447.555244475527;4502.099580083996;4521.728696278234;4527.049319642846;4544.795484203152;5580.330360356783;5950.345461999176;5962.61243019211;5973.223480947536;6039.396060393926;6262.7245731992625;6284.528660350492;6299.527535434769;6318.557700960889;6319.115323854664;5831.183634291203;5902.763889444374;5935.703214839241;5951.404859514014;6041.969790151021;5515.944840551623;5534.584906132056;5535.723213839292;5536.2249285100115;5545.61180717354;6458.515611329169;6464.773732919325;6469.288378278386;6473.449756770728;6487.3836985486305;6196.318404975359;6196.349383314687;6239.313675495782;6264.718087685974;6312.810615681537;6452.838679033061;6492.253390860112;6497.415232629086;6542.640154791532;6668.533202675961;6120.690942950841;6277.30949595535;6297.590656607489;6301.8109456716375;6309.526785491198;6206.224221972254;6224.221972253468;6266.404691554295;6292.433680968735;6321.778737744157;6235.812925612239;6289.7169627365965;6293.874122517615;6299.874002519832;6300.275468321019;6117.888303246575;6126.6017708848785;6267.592606480564;6291.465225455888;6320.62076275425;4945.381827271591;5031.742064483879;6114.174586430775;6170.660613666291;6183.134361189527;6160.414760597736;6277.874442511215;6278.121063051268;6296.433321001132;6316.778912738032;5994.0409534474275;6096.8780624388155;6125;6182.567220294536;6192.256929168529 23:13:42 INFO - PROCESS | 1666 | |1;ext.html;480.5770921589002;500.7921712489316;501.83690300652273;502.291768606265;552.1331509530035;33696;35468.645313546855;41967.79016104915;42046.15907681845;42212;41587.58412415875;41745;42324.57675423245;43112;43211.70364889059;9407.20038796703;9602.279829012852;9652.324337296357;9654.420734755937;9662.710118696416;9521.571529281164;9635.759106022335;9642.180414664763;9642.710718678416;9667.613295468173;9622.278329125309;9624.47065411402;9645.807083858319;9649.228061755079;9699.854502182472;9554.140127388533;9625.181859541948;9640.37337573059;9646.469444180568;9651.034896510362;9508.524573771283;9616.278779091595;9620.374675646131;9640.517974101267;9646.276529260263;39230.607693923266;39578.60421395807;39599.20801583952;39600.40599390983;39652.80173599113;2338.310198491432;2572.5883858582724;3018.713222243883;3021.486347320939;3133.984330078335;26550.86724566365;27199.1840244791;27296.181114566596;27361.726382736117;27962.021329253377;2641.44529648774;2680.26292769488;2697.136916186821;2795.412963277714;2876.1515352970923;472296;478432;479614;480588.5970570125;480698;34258.14354641154;34413.13967150841;34440.82779586086;34489.13777155541;34509.309813803586;35131.946041618794;35435.82282088573;35751.82124089415;35792.28415431677;37332.62667373346;16966;16983.23575439108;17171.055591942564;17185.226664800113;17242.224099915533;12725.236485810881;12995.350232488337;13074.607761767162;13086.934565327303;13109.672258193621;19419;19550.31573894907;19588.43059252824;19728.506787330145;19775;6677.205436414448;7854.842903142019;7946.682132714743;8039.5176289422825;8045.879311810328;715.9511315922199;840.5125027484045;883.2227639677043;908.4776253654176;945.3807755919884;29343;29466;29605.85197074001;29685.294526947655;30031.399372012438;1025.410389026313;1059.8251288537458;1090.5092708281304;1116.1851848150895;1121.450489260273;14376.065555738844;14530.05554638945;14715.819355870346;14749.262536873113;14778.408863645549;702741;703550;712973;714523;716476;57642.423575764544;58303.125453118235;58743;58795.41204587985;58934;1407.345584303304;1417.1284659934272;1420.786881967713;1442.6609746709514;1446.6600348918207;28199;28473;28795.856020719762;28935.987240447324;29222.269443263238;51631.74184129055;52451;52741.472585275194;52749.7362513185;52765.20852187297;643354;648024;648300;648398;648770;25154;25348.619770703084;25689.743102569482;25821.3544661385;26151.869240654436;44592.55407445884;45267;45351.866203345184;45716;46843.531564683915;35308.64691353054;36221.81889090538;36289.092772680895;37470.81264593769;38157.80921095377;33130.50304245487;36085.819570903026;36656.26687466289;36948.261034779694;37023;36676;36941;37345;37486.62513374831;37974.62025379822;25532;27733.861330693217;28136;32601.67398326081;32677.509837352933;20279.69580456324;23674.881625591763;23796.524069518167;24238.15166469165;24264.908079136127 23:13:42 INFO - PROCESS | 1666 | |2;jquery.html;322.7821220676044;384.45215567815853;384.92686389585975;399.2175336340772;408.2121505494396;17277.654446911056;18894.771839830417;20578.0739866706;20661.896690516565;21251.468713282175;19896.602067958633;20711.585768284625;21145.471363215926;21154.153833846627;21373.893130534365;5659.717014149297;5775.595708300431;5931.762729490837;5938.910916336258;6007.729652165641;5849.239598852165;5888.558358123137;5937;5938.851528711773;5948.524118070544;5640.576956728241;5870.442307980735;5875.294964604253;5880.617759845618;5897.882042359151;5740.512056475204;5791.942080579224;5850.678212698321;5867.7066146692705;5869.325027621814;5094.516020978038;5137.768800403991;5161.122609156452;5178.326817513736;5190.4550022247495;25438;25519.872400638065;25550.97796088154;25649.74350256492;25663.871680641478;2233.3188377544843;2328.906843726249;2380.7619238076227;2475.4306509502735;2486.502699460097;14588.487793245247;14802;15168.087582345715;15300.311485983157;15320.770188447183;1717.407494414425;1803.1705415508882;1810.4840120565575;1823.2706917233081;1844.428227249557;49619.75190124063;104258;104931.95068049298;110513.12465569163;115675;9930.900690992998;10367.844482332772;10920.617778377718;10988.175886808522;11198.384088875027;13579.185248885098;13601;13648.931755341358;13654.931725341508;13701.040927135005;8668;8748;8904.065073167354;8961.910380896237;8986.55067246635;8812.250958668585;8975.416597921114;8989.235914947172;9079.182873541413;9087.954560227288;6818.522703410714;6927.445804335641;6973.12835895513;6984.092068031225;7011.579305241702;1717.4933394648608;1882.8493720502327;1925;1943.378119001934;1947.8441724661993;883.430187529053;965.1844191658038;969.5685419988038;1028.876534815827;1040.8646875906084;14425.639359015899;16855;20563.485912852;21267.46831329198;21378;953.5518306395983;955.4888134847823;991.2466525440717;1006.0744115413837;1053.2206167436043;9742.707718768448;10757.408342541235;11342.546298148147;11478.253913495597;11515.366654833897;601239;603318;630522;635589;641300;80215.59892200619;101137.49431252944;125492;125880.3353849079;126392.36803816106;970.1123472023161;1014.5079636376499;1045.0542259255385;1046.7016900183412;1047.9109275711498;19365.70951435758;19562.706559401904;19606.313779017666;19630.80369196347;19687.901560492104;50314;51413.485865142364;52191.2171317423;52312;52385.47614523806;74639.62680186781;75163;75492;75648;78478;5267.3152490176035;5458.4814442627885;7724.884126737991;7895.092064412436;8112.2293382130965;6998.895016574654;10893.019628233496;11656.650300491;12583.93708031454;12677.239365638092;9624.893137289302;10782.514786834454;11040.171987100839;11395.430228488376;11472.598459053894;10053.69838904834;10402.427866467417;10811.891881081088;11289.491972861077;11319.830202547133;10498.422586757497;11127.94436027847;11159.44202789841;11416.771664566497;11458.140639452242;6904.378605925491;8086.83826323482;8142.226488483585;8152.633131508977;8444.915550844413;9205.677801276923;9385.436873787594;10979.902009798956;11079.16906232052;11219.943900280447 23:13:42 INFO - PROCESS | 1666 | |3;mootools.html;1556.8520990505901;1601.351452661672;1818.2545156485844;1883.3502441657631;1931.0344827586207;8010.158933311991;8547.78630534237;8561.914380856197;9026.684066057682;9050.411723237994;8682.392232543725;8730.083341249172;8830.470171789697;8836.823263534741;8839;1999.9800002000013;2356.4697942962866;2363.7399886012527;2384.916527921523;2393.1623931623967;1950.4343740315292;2029.7767245602972;2386.486905315357;2389.0443822471066;2391.0435825669697;2365.8462199957034;2367.4199821043867;2373.786359227669;2379.286214135761;2395.964060539093;2362.5189560220297;2365.1721897335965;2374.8693821839706;2376.7266764322153;2382.749811269809;2247.1573160064977;2252.7747225277503;2253.256425379621;2254.334971183505;2254.5490901819703;8720.956395218047;8745;8829.955850220707;8832.205101540829;8848.867266991001;5268.8155914543195;5415.079436495806;5433;5439.728013599304;5446.91829622556;3402.642722514125;3443.724502039831;3580.0691820126954;3605.296967091418;3613.5844377896615;1029.2897900448665;1031.5203430404822;1033.720895358249;1035.6168217759403;1045.4668119259234;10012.24908131893;11267.943660281728;11707.063434925185;11711.121665875009;11718.179727419085;4678.60231880294;4758.643101767381;4842.83050093245;4844.297576851337;4866.659333846667;9983.351082179635;9987.201023918215;10027.849582256273;10029.949850250701;10051.69844904654;5335.6531825432;5352.437994010651;5415.6479828811;5429.104197807317;5454;4420.690551661417;4679.532046795362;4797.688150270221;4902.240152776325;4952.702837829742;3872.43849641806;3976.264391087658;4042.8180731867137;4092.7953602319767;4098.303288440972;1115.8995690387903;1288.9291088990008;1304.7390521895659;1314.4216544720293;1322.16703476809;831.9209675080857;902.3503077783985;922.7324076018014;940.1961323068814;946.3422921069928;5673.574481913873;5844.298684157929;5854.560907931922;5894.17481552583;6037.184980027728;423.3247969488661;428.49223669950584;430.77229894800195;444.1516703097069;454.22101096619457;3150.9212269692985;3765.7175711821724;3820.255050265227;4017.4375587417785;4050.4936882889638;10448.791024179474;10645.78708425827;10652.893471065345;10772.407517586607;10799.892001079888;10571.312864663772;10577.735556611146;10618.522166502526;10717.732056698645;10891.618793342195;7930.9603451982375;8039;8164.020317561931;8314.459560128573;8369.246767790806;5603.971980140073;5763.308402991668;5819.243498345188;5874.794382196774;5900;9913.107820296207;10064.647737329158;10069.395836249849;10100.141487973604;10119.848202277119;9847.015298470096;9980.301378903698;10074.89925100769;10192.235582331206;10468.424236666751;6882.518223724492;7185.640717964186;7444.330010299099;7491.063617047869;7558.508696934681;8300.501969881827;8306.6677332906;8401.70594029206;8414.705485307984;8416.957915210385;6228.719707613259;6636.2700102987665;7237.131544214728;7428.702851885864;7675.002249707502;6016.308124565731;7224.783256502314;7253.3834624057545;7384.889226661497;7403.370713489198;6826.624535650587;6968.536607312319;7012.088428504262;7018.894716579153;7019.017337572642;4916.778744956557;4932.63005274613;4950.356453661145;5019.598432125348;5163.509466600667;4914.140025495595;4972.428170760409;4996.450390457119;5053.444121146597;5225.137852254487 23:13:42 INFO - PROCESS | 1666 | |4;prototype.html;250.88333924724998;312.46880303484073;319.74420463629093;322.3488553122694;323.948168293073;5236.031334203175;5326.893462130755;5503.3395992480855;5579.414161513044;5693.572982026349;4945.961348116891;5436.677841116312;5502.284702988606;5616.466435688613;5625.609116125641;4014.7316766027247;4275.422817919588;4282.635975942048;4289.691644048564;4301.440812694345;3549.875754348598;4153.252414565377;4294.861861606677;4295.334223195409;4308.590683885026;3508.9472658494683;3637.8362973666117;4008.158286759784;4017.9799101004455;4229.238737027334;3598.6581274778855;4219.249011660173;4252.809802951482;4291.090464743191;4324.870253892388;3706.036430528055;3714.5728241252064;3725.18046029873;3731.869384571527;3755.868544600953;4045.676345892351;4543.7728113594185;5270.736463176864;5309.62832601718;5327.041132596131;3859.6526312631718;4430.158269928703;4840.298156767249;4864.2217245240945;4892.021595680878;5150.871228219287;5179.669148883017;5187.974060129675;5461.535769459561;5851.912221316641;3608.1520842601967;3779.8677046303246;4088.6524645405175;4135.731177473454;4161.958380416218;6246.345303246102;6301.502666020357;6336.841578960517;6338.683065846735;6339.397757213057;3616.3309787689363;3686.926261474809;3983.322835117979;4153.522344930311;4225.218334608107;3903.863364782219;4094.9385759213637;4187.97906010468;4208.642265407413;4228.429162063082;4331.657186272203;4910.558049775537;4930.235813448921;4934.259861020875;4966.950330496721;5659.585103724069;5840.415958404211;5997.311653333256;6014.789482368096;6021.2473440819895;1251.5682089679046;1359.8305457306708;1452.1867754057762;1460.8247010358612;1461.7149655817227;751.5678484871221;870.8040690844635;899.2805755395657;900.4642237868458;904.3850181876387;4330.935035974463;4605.976970115128;4654.767261636905;4657.836975705834;4722.952770472251;4533.569310915458;4673.813047478131;5091.0836049511445;5221.477852214824;5259.921101183409;4258.893527661833;4401.525488961234;4447;4680.602148817321;4689.718616882998;2513.6732224810657;2629.500394924958;2658.7208343123693;2673.46530693862;2750.477409292228;4679.438467383936;4788.3056956741775;4795.592374648124;5077.543021128116;5397.514223719884;3967.682585393162;4478.820847166076;4778.4982576829625;4796.496367881392;5300.469953004668;4789.736564488987;4879.926801097987;5396.676199427969;5416.783328666809;5435.728213589226;4505.48186958504;4601.608863246662;4818.338452318581;4866.126541281703;5212.592599998055;4534.410526631516;4562.5665561771575;4769.785359658755;4917.01659668075;5127.66670166438;4624.161945585223;5247.55395791362;5386.741021681826;5397.460253974571;5421.915616876561;3282.3927573398996;3654.2691461708296;3670.33933891902;3672.4307732301536;3699.7040236780454;4360.890977725582;4719.787609557647;5179.559737422362;5203.751099736111;5222.97388513055;5007.799688012438;5867;5884.205632239764;5887.411258874078;5896.941030589639;4484.215262329014;4854.927176092432;4916.852494425173;5208.296879921314;5915.704214789157;4884.78018489176;4982.377202849644;5384.388571542877;5755.3381361144;5764.9711751442655;5166.612504062286;5678.779062501582;5900.734466948912;5912.935671579157;5939.7921072763975;5469.699166545878;5754.769809207585;5811.070228763378;5816.331121921034;5849.795257165979 23:13:42 INFO - PROCESS | 1666 | |5;yui.html;724.9492535522514;729.8394353242285;739.7595781371056;747.6486051555767;751.1024325930512;12934.870651293484;15006.774898376532;15214.695706085871;15433.459828905976;15457;14274.714505709879;14390.352434140463;14403.639909002253;14407.495737649184;14409.927950360234;718.6694120704482;893.2719833335826;898.3397203055749;914.1224424552438;919.9816003679922;906.7415786500849;914.1726737302752;914.4056363363834;918.4948278446861;920.0615372320229;900.5317235037777;953.7234202081388;971.5142428785607;974.5955428497165;981.1660088924428;926.9258459323238;967.9032096790335;969.781799095205;971.1647982734843;973.1533565797766;687.8246047257917;687.8693048320853;689.069755829631;717.9856402871914;718.529363267061;14238.501652442219;14515.491957781533;14565.563033108918;14578.854211457856;14709.11745295275;11011;12603.873961260362;12645.557405490763;12800.231986080771;12958.09293349466;9837.163841073518;9902.306838521306;10009.949950250202;10453.790924181474;10666.36001839892;3203.6636153203813;3312.2216279174377;3489.755717099804;3489.755717099804;3565.447355659877;74989.25010749932;81035;81298.59350703268;82301.58849205716;83261.58369208116;9366.355420182836;9381;9437.480938548446;9759.12167904892;9818.80362392748;21988.670169947465;21993.560128797333;21998.89000554987;22000.33998980033;22058.558828823334;6955.513114082067;7019.227884932653;7109.715611375487;7122.643867806589;7254.347108760131;3888.475055867478;3926.9214615708097;3929.941050884239;3966.4446977423186;4000.699947503949;3309.652486488932;3320.4023275810105;3416.982915085409;3418.453047512386;3429.022728522359;658.4666420199653;661.9371159739817;666.2238492156583;672.7309076369492;673.0409166937095;545.6944111297686;570.725658251866;578.8147792706378;599.4215581963426;609.0316396928815;7932.444728868923;8276.213759692819;8368.497890126611;8370.204830541088;8401.873971890427;569.4618585436723;571.502792570458;581.8574449259874;600.1867469578746;623.1556241292983;3872.3804191329814;3884.0872394987155;3920.2551515212017;3941.901452463654;4006.198760247904;80764;83120;96165.03834961701;97138.514307428;97939;71219;76225.61887190529;78679.60660196662;78944.21055789484;80970.16604418817;72350.63824680948;72690;73138.9029164563;73188.26811731921;76009.23990760022;7966.681332746625;7982.84034319322;8201.548914809742;8216.876746848922;8224.095349511668;45157.64527064194;45351;45726.314105289115;45799.855003625176;45992.77003615094;60630;60985.780284394954;62333;62391;62628;9765.462899540593;10875.456227188452;10963.616273430393;11137.331760094421;11196.608118716133;11745.88254117482;11785.233959792586;11811.17321787501;11841.5855445059;11981.940090299842;11120.944395277971;11135.777284454103;11280.887191128311;11437.085033197158;11535.769284614093;11139.554417823196;11239;11272;11561.884381156417;11596;10967.451627418757;10987.83518247211;11406.657800266004;11431.885681143081;11586.71033224176;9117.863232051657;9166.541672916193;9246.398984066014;9287.582058807235;9365.391249568756;8652.048274689905;8716.956415217883;8827.117288271378;9062.410943288929;9175.357724959189 23:13:42 INFO - PROCESS | 1666 | __end_tp_report 23:13:42 INFO - PROCESS | 1666 | __start_cc_report 23:13:42 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,425 23:13:42 INFO - PROCESS | 1666 | __end_cc_report 23:13:42 INFO - PROCESS | 1666 | __startTimestamp1464070422499__endTimestamp 23:13:42 INFO - PROCESS | 1666 | 23:13:42 INFO - PROCESS | 1666 | ------- Summary: start ------- 23:13:42 INFO - PROCESS | 1666 | Number of tests: 6 23:13:42 INFO - PROCESS | 1666 | 23:13:42 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5769.87 Median:6074.39 stddev:777.40 (12.8%) stddev-sans-first:759.67 23:13:42 INFO - PROCESS | 1666 | Values: 3530.4 3541.4 3552.2 3561.6 3569.0 5761.9 5857.9 5901.4 5946.0 5969.7 5708.7 5775.0 5790.7 5819.9 5924.8 5206.9 5245.9 5344.9 5365.9 5392.3 409.0 4396.9 5248.5 5363.8 5372.2 5273.2 5286.3 5327.5 5355.2 5356.8 5344.9 5357.4 5373.3 5412.4 5414.3 5195.0 5227.3 5266.1 5266.7 5335.8 4590.4 5405.5 5754.9 5781.0 5828.9 5047.3 5656.9 6032.2 6252.3 6267.8 6167.4 6206.5 6217.8 6272.4 6291.2 6004.2 6025.9 6037.5 6050.0 6074.4 6199.7 6352.4 6383.8 6403.7 6412.1 6076.8 6098.2 6100.5 6142.0 6246.3 6184.8 6186.3 6188.9 6206.2 6260.3 4954.0 5147.3 6138.7 6229.7 6287.2 6186.8 6214.6 6261.9 6267.5 6277.6 4351.0 5065.8 5078.8 5086.3 5117.0 4447.6 4502.1 4521.7 4527.0 4544.8 5580.3 5950.3 5962.6 5973.2 6039.4 6262.7 6284.5 6299.5 6318.6 6319.1 5831.2 5902.8 5935.7 5951.4 6042.0 5515.9 5534.6 5535.7 5536.2 5545.6 6458.5 6464.8 6469.3 6473.4 6487.4 6196.3 6196.3 6239.3 6264.7 6312.8 6452.8 6492.3 6497.4 6542.6 6668.5 6120.7 6277.3 6297.6 6301.8 6309.5 6206.2 6224.2 6266.4 6292.4 6321.8 6235.8 6289.7 6293.9 6299.9 6300.3 6117.9 6126.6 6267.6 6291.5 6320.6 4945.4 5031.7 6114.2 6170.7 6183.1 6160.4 6277.9 6278.1 6296.4 6316.8 5994.0 6096.9 6125.0 6182.6 6192.3 23:13:42 INFO - PROCESS | 1666 | 23:13:42 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:71859.70 Median:37177.81 stddev:166460.26 (447.7%) stddev-sans-first:166840.95 23:13:42 INFO - PROCESS | 1666 | Values: 480.6 500.8 501.8 502.3 552.1 33696.0 35468.6 41967.8 42046.2 42212.0 41587.6 41745.0 42324.6 43112.0 43211.7 9407.2 9602.3 9652.3 9654.4 9662.7 9521.6 9635.8 9642.2 9642.7 9667.6 9622.3 9624.5 9645.8 9649.2 9699.9 9554.1 9625.2 9640.4 9646.5 9651.0 9508.5 9616.3 9620.4 9640.5 9646.3 39230.6 39578.6 39599.2 39600.4 39652.8 2338.3 2572.6 3018.7 3021.5 3134.0 26550.9 27199.2 27296.2 27361.7 27962.0 2641.4 2680.3 2697.1 2795.4 2876.2 472296.0 478432.0 479614.0 480588.6 480698.0 34258.1 34413.1 34440.8 34489.1 34509.3 35131.9 35435.8 35751.8 35792.3 37332.6 16966.0 16983.2 17171.1 17185.2 17242.2 12725.2 12995.4 13074.6 13086.9 13109.7 19419.0 19550.3 19588.4 19728.5 19775.0 6677.2 7854.8 7946.7 8039.5 8045.9 716.0 840.5 883.2 908.5 945.4 29343.0 29466.0 29605.9 29685.3 30031.4 1025.4 1059.8 1090.5 1116.2 1121.5 14376.1 14530.1 14715.8 14749.3 14778.4 702741.0 703550.0 712973.0 714523.0 716476.0 57642.4 58303.1 58743.0 58795.4 58934.0 1407.3 1417.1 1420.8 1442.7 1446.7 28199.0 28473.0 28795.9 28936.0 29222.3 51631.7 52451.0 52741.5 52749.7 52765.2 643354.0 648024.0 648300.0 648398.0 648770.0 25154.0 25348.6 25689.7 25821.4 26151.9 44592.6 45267.0 45351.9 45716.0 46843.5 35308.6 36221.8 36289.1 37470.8 38157.8 33130.5 36085.8 36656.3 36948.3 37023.0 36676.0 36941.0 37345.0 37486.6 37974.6 25532.0 27733.9 28136.0 32601.7 32677.5 20279.7 23674.9 23796.5 24238.2 24264.9 23:13:42 INFO - PROCESS | 1666 | 23:13:42 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34597.79 Median:25478.94 stddev:102999.67 (404.3%) stddev-sans-first:103256.46 23:13:42 INFO - PROCESS | 1666 | Values: 322.8 384.5 384.9 399.2 408.2 17277.7 18894.8 20578.1 20661.9 21251.5 19896.6 20711.6 21145.5 21154.2 21373.9 5659.7 5775.6 5931.8 5938.9 6007.7 5849.2 5888.6 5937.0 5938.9 5948.5 5640.6 5870.4 5875.3 5880.6 5897.9 5740.5 5791.9 5850.7 5867.7 5869.3 5094.5 5137.8 5161.1 5178.3 5190.5 25438.0 25519.9 25551.0 25649.7 25663.9 2233.3 2328.9 2380.8 2475.4 2486.5 14588.5 14802.0 15168.1 15300.3 15320.8 1717.4 1803.2 1810.5 1823.3 1844.4 49619.8 104258.0 104932.0 110513.1 115675.0 9930.9 10367.8 10920.6 10988.2 11198.4 13579.2 13601.0 13648.9 13654.9 13701.0 8668.0 8748.0 8904.1 8961.9 8986.6 8812.3 8975.4 8989.2 9079.2 9088.0 6818.5 6927.4 6973.1 6984.1 7011.6 1717.5 1882.8 1925.0 1943.4 1947.8 883.4 965.2 969.6 1028.9 1040.9 14425.6 16855.0 20563.5 21267.5 21378.0 953.6 955.5 991.2 1006.1 1053.2 9742.7 10757.4 11342.5 11478.3 11515.4 601239.0 603318.0 630522.0 635589.0 641300.0 80215.6 101137.5 125492.0 125880.3 126392.4 970.1 1014.5 1045.1 1046.7 1047.9 19365.7 19562.7 19606.3 19630.8 19687.9 50314.0 51413.5 52191.2 52312.0 52385.5 74639.6 75163.0 75492.0 75648.0 78478.0 5267.3 5458.5 7724.9 7895.1 8112.2 6998.9 10893.0 11656.7 12583.9 12677.2 9624.9 10782.5 11040.2 11395.4 11472.6 10053.7 10402.4 10811.9 11289.5 11319.8 10498.4 11127.9 11159.4 11416.8 11458.1 6904.4 8086.8 8142.2 8152.6 8444.9 9205.7 9385.4 10979.9 11079.2 11219.9 23:13:42 INFO - PROCESS | 1666 | 23:13:42 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5672.42 Median:4843.56 stddev:3203.40 (66.1%) stddev-sans-first:3197.46 23:13:42 INFO - PROCESS | 1666 | Values: 1556.9 1601.4 1818.3 1883.4 1931.0 8010.2 8547.8 8561.9 9026.7 9050.4 8682.4 8730.1 8830.5 8836.8 8839.0 2000.0 2356.5 2363.7 2384.9 2393.2 1950.4 2029.8 2386.5 2389.0 2391.0 2365.8 2367.4 2373.8 2379.3 2396.0 2362.5 2365.2 2374.9 2376.7 2382.7 2247.2 2252.8 2253.3 2254.3 2254.5 8721.0 8745.0 8830.0 8832.2 8848.9 5268.8 5415.1 5433.0 5439.7 5446.9 3402.6 3443.7 3580.1 3605.3 3613.6 1029.3 1031.5 1033.7 1035.6 1045.5 10012.2 11267.9 11707.1 11711.1 11718.2 4678.6 4758.6 4842.8 4844.3 4866.7 9983.4 9987.2 10027.8 10029.9 10051.7 5335.7 5352.4 5415.6 5429.1 5454.0 4420.7 4679.5 4797.7 4902.2 4952.7 3872.4 3976.3 4042.8 4092.8 4098.3 1115.9 1288.9 1304.7 1314.4 1322.2 831.9 902.4 922.7 940.2 946.3 5673.6 5844.3 5854.6 5894.2 6037.2 423.3 428.5 430.8 444.2 454.2 3150.9 3765.7 3820.3 4017.4 4050.5 10448.8 10645.8 10652.9 10772.4 10799.9 10571.3 10577.7 10618.5 10717.7 10891.6 7931.0 8039.0 8164.0 8314.5 8369.2 5604.0 5763.3 5819.2 5874.8 5900.0 9913.1 10064.6 10069.4 10100.1 10119.8 9847.0 9980.3 10074.9 10192.2 10468.4 6882.5 7185.6 7444.3 7491.1 7558.5 8300.5 8306.7 8401.7 8414.7 8417.0 6228.7 6636.3 7237.1 7428.7 7675.0 6016.3 7224.8 7253.4 7384.9 7403.4 6826.6 6968.5 7012.1 7018.9 7019.0 4916.8 4932.6 4950.4 5019.6 5163.5 4914.1 4972.4 4996.5 5053.4 5225.1 23:13:42 INFO - PROCESS | 1666 | 23:13:42 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4440.75 Median:4789.02 stddev:1342.45 (28.0%) stddev-sans-first:1308.86 23:13:42 INFO - PROCESS | 1666 | Values: 250.9 312.5 319.7 322.3 323.9 5236.0 5326.9 5503.3 5579.4 5693.6 4946.0 5436.7 5502.3 5616.5 5625.6 4014.7 4275.4 4282.6 4289.7 4301.4 3549.9 4153.3 4294.9 4295.3 4308.6 3508.9 3637.8 4008.2 4018.0 4229.2 3598.7 4219.2 4252.8 4291.1 4324.9 3706.0 3714.6 3725.2 3731.9 3755.9 4045.7 4543.8 5270.7 5309.6 5327.0 3859.7 4430.2 4840.3 4864.2 4892.0 5150.9 5179.7 5188.0 5461.5 5851.9 3608.2 3779.9 4088.7 4135.7 4162.0 6246.3 6301.5 6336.8 6338.7 6339.4 3616.3 3686.9 3983.3 4153.5 4225.2 3903.9 4094.9 4188.0 4208.6 4228.4 4331.7 4910.6 4930.2 4934.3 4967.0 5659.6 5840.4 5997.3 6014.8 6021.2 1251.6 1359.8 1452.2 1460.8 1461.7 751.6 870.8 899.3 900.5 904.4 4330.9 4606.0 4654.8 4657.8 4723.0 4533.6 4673.8 5091.1 5221.5 5259.9 4258.9 4401.5 4447.0 4680.6 4689.7 2513.7 2629.5 2658.7 2673.5 2750.5 4679.4 4788.3 4795.6 5077.5 5397.5 3967.7 4478.8 4778.5 4796.5 5300.5 4789.7 4879.9 5396.7 5416.8 5435.7 4505.5 4601.6 4818.3 4866.1 5212.6 4534.4 4562.6 4769.8 4917.0 5127.7 4624.2 5247.6 5386.7 5397.5 5421.9 3282.4 3654.3 3670.3 3672.4 3699.7 4360.9 4719.8 5179.6 5203.8 5223.0 5007.8 5867.0 5884.2 5887.4 5896.9 4484.2 4854.9 4916.9 5208.3 5915.7 4884.8 4982.4 5384.4 5755.3 5765.0 5166.6 5678.8 5900.7 5912.9 5939.8 5469.7 5754.8 5811.1 5816.3 5849.8 23:13:42 INFO - PROCESS | 1666 | 23:13:42 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17981.43 Median:62509.50 stddev:25344.10 (40.5%) stddev-sans-first:25382.07 23:13:42 INFO - PROCESS | 1666 | Values: 724.9 729.8 739.8 747.6 751.1 12934.9 15006.8 15214.7 15433.5 15457.0 14274.7 14390.4 14403.6 14407.5 14409.9 718.7 893.3 898.3 914.1 920.0 906.7 914.2 914.4 918.5 920.1 900.5 953.7 971.5 974.6 981.2 926.9 967.9 969.8 971.2 973.2 687.8 687.9 689.1 718.0 718.5 14238.5 14515.5 14565.6 14578.9 14709.1 11011.0 12603.9 12645.6 12800.2 12958.1 9837.2 9902.3 10009.9 10453.8 10666.4 3203.7 3312.2 3489.8 3489.8 3565.4 74989.3 81035.0 81298.6 82301.6 83261.6 9366.4 9381.0 9437.5 9759.1 9818.8 21988.7 21993.6 21998.9 22000.3 22058.6 6955.5 7019.2 7109.7 7122.6 7254.3 3888.5 3926.9 3929.9 3966.4 4000.7 3309.7 3320.4 3417.0 3418.5 3429.0 658.5 661.9 666.2 672.7 673.0 545.7 570.7 578.8 599.4 609.0 7932.4 8276.2 8368.5 8370.2 8401.9 569.5 571.5 581.9 600.2 623.2 3872.4 3884.1 3920.3 3941.9 4006.2 80764.0 83120.0 96165.0 97138.5 97939.0 71219.0 76225.6 78679.6 78944.2 80970.2 72350.6 72690.0 73138.9 73188.3 76009.2 7966.7 7982.8 8201.5 8216.9 8224.1 45157.6 45351.0 45726.3 45799.9 45992.8 60630.0 60985.8 62333.0 62391.0 62628.0 9765.5 10875.5 10963.6 11137.3 11196.6 11745.9 11785.2 11811.2 11841.6 11981.9 11120.9 11135.8 11280.9 11437.1 11535.8 11139.6 11239.0 11272.0 11561.9 11596.0 10967.5 10987.8 11406.7 11431.9 11586.7 9117.9 9166.5 9246.4 9287.6 9365.4 8652.0 8717.0 8827.1 9062.4 9175.4 23:13:42 INFO - PROCESS | 1666 | -------- Summary: end -------- 23:13:42 INFO - PROCESS | 1666 | 23:13:43 INFO - TEST-INFO | 1666: exit 0 23:13:43 INFO - TEST-OK | dromaeo_css | took 1111974ms 23:13:43 INFO - TEST-START | kraken 23:13:43 INFO - operating with platform_type : mac_ 23:13:43 INFO - Initialising browser for kraken test... 23:13:43 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpCIhJ3B/profile http://localhost:49275/getInfo.html) 23:13:46 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 23:13:46 INFO - PROCESS | 1675 | colorDepth:24 23:13:46 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 23:13:46 INFO - PROCESS | 1675 | __metrics 23:13:46 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 23:13:46 INFO - TEST-INFO | 1675: exit 0 23:13:46 INFO - Browser initialized. 23:13:46 INFO - Running cycle 1/1 for kraken test... 23:13:46 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpCIhJ3B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:13:58 INFO - PROCESS | 1678 | RSS: Main: 250109952 23:13:58 INFO - PROCESS | 1678 | 23:14:01 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 23:14:02 INFO - PROCESS | 1678 | RSS: Main: 261705728 23:14:02 INFO - PROCESS | 1678 | 23:14:05 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 23:14:06 INFO - PROCESS | 1678 | RSS: Main: 266944512 23:14:06 INFO - PROCESS | 1678 | 23:14:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 23:14:10 INFO - PROCESS | 1678 | RSS: Main: 267284480 23:14:10 INFO - PROCESS | 1678 | 23:14:12 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 23:14:12 INFO - PROCESS | 1678 | RSS: Main: 267485184 23:14:12 INFO - PROCESS | 1678 | 23:14:15 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:14:15 INFO - PROCESS | 1678 | RSS: Main: 268492800 23:14:15 INFO - PROCESS | 1678 | 23:14:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 23:14:19 INFO - PROCESS | 1678 | RSS: Main: 268308480 23:14:19 INFO - PROCESS | 1678 | 23:14:22 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 23:14:23 INFO - PROCESS | 1678 | RSS: Main: 268910592 23:14:23 INFO - PROCESS | 1678 | 23:14:26 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 23:14:27 INFO - PROCESS | 1678 | RSS: Main: 269033472 23:14:27 INFO - PROCESS | 1678 | 23:14:28 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:14:29 INFO - PROCESS | 1678 | RSS: Main: 269090816 23:14:29 INFO - PROCESS | 1678 | 23:14:31 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:14:32 INFO - PROCESS | 1678 | RSS: Main: 269049856 23:14:32 INFO - PROCESS | 1678 | 23:14:34 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:14:35 INFO - PROCESS | 1678 | RSS: Main: 268574720 23:14:35 INFO - PROCESS | 1678 | 23:14:38 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:14:39 INFO - PROCESS | 1678 | RSS: Main: 269234176 23:14:39 INFO - PROCESS | 1678 | 23:14:42 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:14:42 INFO - PROCESS | 1678 | RSS: Main: 268898304 23:14:42 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 23:14:44 INFO - PROCESS | 1678 | RSS: Main: 271843328 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | __start_tp_report 23:14:44 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 23:14:44 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 23:14:44 INFO - PROCESS | 1678 | |i|pagename|runs| 23:14:44 INFO - PROCESS | 1678 | |0;ai-astar;116;112;150;134;118;116;116;125;164;116 23:14:44 INFO - PROCESS | 1678 | |1;audio-beat-detection;178;136;140;194;120;138;139;119;174;116 23:14:44 INFO - PROCESS | 1678 | |2;audio-dft;197;202;228;265;240;203;201;195;199;200 23:14:44 INFO - PROCESS | 1678 | |3;audio-fft;73;81;74;175;67;78;83;99;131;59 23:14:44 INFO - PROCESS | 1678 | |4;audio-oscillator;81;83;145;85;114;70;116;82;67;143 23:14:44 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;90;86;89;88;90;87;91;92;89;87 23:14:44 INFO - PROCESS | 1678 | |6;imaging-darkroom;95;96;121;149;96;111;148;109;113;150 23:14:44 INFO - PROCESS | 1678 | |7;imaging-desaturate;91;92;107;149;113;90;91;114;114;91 23:14:44 INFO - PROCESS | 1678 | |8;json-parse-financial;57;62;90;69;54;50;79;173;96;89 23:14:44 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;67;75;82;70;84;74;69;79;92;95 23:14:44 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;87;88;88;87;84;99;110;76;109;107 23:14:44 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;146;160;160;131;148;153;152;156;218;140 23:14:44 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;171;163;172;174;186;211;164;229;227;187 23:14:44 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;62;78;88;76;68;55;73;92;85;91 23:14:44 INFO - PROCESS | 1678 | __end_tp_report 23:14:44 INFO - PROCESS | 1678 | __start_cc_report 23:14:44 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1094 23:14:44 INFO - PROCESS | 1678 | __end_cc_report 23:14:44 INFO - PROCESS | 1678 | __startTimestamp1464070484954__endTimestamp 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | ------- Summary: start ------- 23:14:44 INFO - PROCESS | 1678 | Number of tests: 14 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:126.70 Median:121.50 stddev:17.45 (14.4%) stddev-sans-first:18.07 23:14:44 INFO - PROCESS | 1678 | Values: 116.0 112.0 150.0 134.0 118.0 116.0 116.0 125.0 164.0 116.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:145.40 Median:139.50 stddev:27.21 (19.5%) stddev-sans-first:26.18 23:14:44 INFO - PROCESS | 1678 | Values: 178.0 136.0 140.0 194.0 120.0 138.0 139.0 119.0 174.0 116.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:213.00 Median:202.50 stddev:23.49 (11.6%) stddev-sans-first:24.20 23:14:44 INFO - PROCESS | 1678 | Values: 197.0 202.0 228.0 265.0 240.0 203.0 201.0 195.0 199.0 200.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:92.00 Median:76.00 stddev:35.36 (46.5%) stddev-sans-first:36.84 23:14:44 INFO - PROCESS | 1678 | Values: 73.0 81.0 74.0 175.0 67.0 78.0 83.0 99.0 131.0 59.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:75.50 stddev:28.86 (38.2%) stddev-sans-first:29.90 23:14:44 INFO - PROCESS | 1678 | Values: 81.0 83.0 145.0 85.0 114.0 70.0 116.0 82.0 67.0 143.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:89.50 stddev:1.91 (2.1%) stddev-sans-first:1.99 23:14:44 INFO - PROCESS | 1678 | Values: 90.0 86.0 89.0 88.0 90.0 87.0 91.0 92.0 89.0 87.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:118.80 Median:149.50 stddev:22.46 (15.0%) stddev-sans-first:22.11 23:14:44 INFO - PROCESS | 1678 | Values: 95.0 96.0 121.0 149.0 96.0 111.0 148.0 109.0 113.0 150.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:105.20 Median:90.50 stddev:18.70 (20.7%) stddev-sans-first:19.12 23:14:44 INFO - PROCESS | 1678 | Values: 91.0 92.0 107.0 149.0 113.0 90.0 91.0 114.0 114.0 91.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:81.90 Median:74.00 stddev:35.90 (48.5%) stddev-sans-first:36.93 23:14:44 INFO - PROCESS | 1678 | Values: 57.0 62.0 90.0 69.0 54.0 50.0 79.0 173.0 96.0 89.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:78.70 Median:80.50 stddev:9.57 (11.9%) stddev-sans-first:9.17 23:14:44 INFO - PROCESS | 1678 | Values: 67.0 75.0 82.0 70.0 84.0 74.0 69.0 79.0 92.0 95.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:93.50 Median:87.00 stddev:11.86 (13.6%) stddev-sans-first:12.35 23:14:44 INFO - PROCESS | 1678 | Values: 87.0 88.0 88.0 87.0 84.0 99.0 110.0 76.0 109.0 107.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:156.40 Median:154.50 stddev:23.44 (15.2%) stddev-sans-first:24.56 23:14:44 INFO - PROCESS | 1678 | Values: 146.0 160.0 160.0 131.0 148.0 153.0 152.0 156.0 218.0 140.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.40 Median:186.50 stddev:25.11 (13.5%) stddev-sans-first:25.84 23:14:44 INFO - PROCESS | 1678 | Values: 171.0 163.0 172.0 174.0 186.0 211.0 164.0 229.0 227.0 187.0 23:14:44 INFO - PROCESS | 1678 | 23:14:44 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.80 Median:81.50 stddev:12.53 (15.4%) stddev-sans-first:12.09 23:14:44 INFO - PROCESS | 1678 | Values: 62.0 78.0 88.0 76.0 68.0 55.0 73.0 92.0 85.0 91.0 23:14:44 INFO - PROCESS | 1678 | -------- Summary: end -------- 23:14:44 INFO - PROCESS | 1678 | 23:14:45 INFO - TEST-INFO | 1678: exit 0 23:14:45 INFO - TEST-OK | kraken | took 62825ms 23:14:45 INFO - SUITE-END | took 1174s 23:14:46 INFO - Completed test suite (00:19:35) 23:14:46 INFO - javascript benchmark 23:14:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7097.014394710883, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3530.399832028555, 3541.3979623464, 3552.236269202121, 3561.572611286645, 3568.9821550892257, 5761.942380576193, 5857.882842343156, 5901.380355062721, 5946.0, 5969.701514924258, 5708.686022268774, 5774.971125144379, 5790.68151251681, 5819.854503637411, 5924.763009479616, 5206.947930520693, 5245.86885327866, 5344.91982620259, 5365.919511207335, 5392.272043274147, 409.0382451878679, 4396.900774806299, 5248.5276325130735, 5363.758630861621, 5372.167314066326, 5273.182656688219, 5286.286351342558, 5327.493888080626, 5355.196720491938, 5356.812511562116, 5344.866378340573, 5357.383900851413, 5373.328333958255, 5412.3505179378335, 5414.269073675043, 5194.974025129888, 5227.26818245446, 5266.131088370415, 5266.657667251655, 5335.813246536352, 4590.380298659705, 5405.486463790145, 5754.856128596777, 5781.017227071366, 5828.941710582924, 5047.318611987408, 5656.91514627281, 6032.155498230251, 6252.3122456529745, 6267.811965641038, 6167.352427995041, 6206.472449841724, 6217.813465596039, 6272.40412160844, 6291.182146320949, 6004.219451471325, 6025.875053615413, 6037.547183961176, 6049.969750151221, 6074.392560743979, 6199.690015499207, 6352.36476352361, 6383.776567820104, 6403.743850246031, 6412.070249813751, 6076.787312444043, 6098.207233059763, 6100.48145907594, 6141.969290153521, 6246.344133865879, 6184.81445556625, 6186.257649082139, 6188.8762224755255, 6206.162168107338, 6260.311365749764, 4953.950460495349, 5147.305113809588, 6138.7237574309265, 6229.688515574203, 6287.151234583364, 6186.81439556805, 6214.627122372672, 6261.9373806262265, 6267.4986001119805, 6277.623342599459, 4351.0, 5065.822696205615, 5078.847634570969, 5086.338775959176, 5116.9744151279, 4447.555244475527, 4502.099580083996, 4521.728696278234, 4527.049319642846, 4544.795484203152, 5580.330360356783, 5950.345461999176, 5962.61243019211, 5973.223480947536, 6039.396060393926, 6262.7245731992625, 6284.528660350492, 6299.527535434769, 6318.557700960889, 6319.115323854664, 5831.183634291203, 5902.763889444374, 5935.703214839241, 5951.404859514014, 6041.969790151021, 5515.944840551623, 5534.584906132056, 5535.723213839292, 5536.2249285100115, 5545.61180717354, 6458.515611329169, 6464.773732919325, 6469.288378278386, 6473.449756770728, 6487.3836985486305, 6196.318404975359, 6196.349383314687, 6239.313675495782, 6264.718087685974, 6312.810615681537, 6452.838679033061, 6492.253390860112, 6497.415232629086, 6542.640154791532, 6668.533202675961, 6120.690942950841, 6277.30949595535, 6297.590656607489, 6301.8109456716375, 6309.526785491198, 6206.224221972254, 6224.221972253468, 6266.404691554295, 6292.433680968735, 6321.778737744157, 6235.812925612239, 6289.7169627365965, 6293.874122517615, 6299.874002519832, 6300.275468321019, 6117.888303246575, 6126.6017708848785, 6267.592606480564, 6291.465225455888, 6320.62076275425, 4945.381827271591, 5031.742064483879, 6114.174586430775, 6170.660613666291, 6183.134361189527, 6160.414760597736, 6277.874442511215, 6278.121063051268, 6296.433321001132, 6316.778912738032, 5994.0409534474275, 6096.8780624388155, 6125.0, 6182.567220294536, 6192.256929168529], "value": 5724.169518153568, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.5770921589002, 500.7921712489316, 501.83690300652273, 502.291768606265, 552.1331509530035, 33696.0, 35468.645313546855, 41967.79016104915, 42046.15907681845, 42212.0, 41587.58412415875, 41745.0, 42324.57675423245, 43112.0, 43211.70364889059, 9407.20038796703, 9602.279829012852, 9652.324337296357, 9654.420734755937, 9662.710118696416, 9521.571529281164, 9635.759106022335, 9642.180414664763, 9642.710718678416, 9667.613295468173, 9622.278329125309, 9624.47065411402, 9645.807083858319, 9649.228061755079, 9699.854502182472, 9554.140127388533, 9625.181859541948, 9640.37337573059, 9646.469444180568, 9651.034896510362, 9508.524573771283, 9616.278779091595, 9620.374675646131, 9640.517974101267, 9646.276529260263, 39230.607693923266, 39578.60421395807, 39599.20801583952, 39600.40599390983, 39652.80173599113, 2338.310198491432, 2572.5883858582724, 3018.713222243883, 3021.486347320939, 3133.984330078335, 26550.86724566365, 27199.1840244791, 27296.181114566596, 27361.726382736117, 27962.021329253377, 2641.44529648774, 2680.26292769488, 2697.136916186821, 2795.412963277714, 2876.1515352970923, 472296.0, 478432.0, 479614.0, 480588.5970570125, 480698.0, 34258.14354641154, 34413.13967150841, 34440.82779586086, 34489.13777155541, 34509.309813803586, 35131.946041618794, 35435.82282088573, 35751.82124089415, 35792.28415431677, 37332.62667373346, 16966.0, 16983.23575439108, 17171.055591942564, 17185.226664800113, 17242.224099915533, 12725.236485810881, 12995.350232488337, 13074.607761767162, 13086.934565327303, 13109.672258193621, 19419.0, 19550.31573894907, 19588.43059252824, 19728.506787330145, 19775.0, 6677.205436414448, 7854.842903142019, 7946.682132714743, 8039.5176289422825, 8045.879311810328, 715.9511315922199, 840.5125027484045, 883.2227639677043, 908.4776253654176, 945.3807755919884, 29343.0, 29466.0, 29605.85197074001, 29685.294526947655, 30031.399372012438, 1025.410389026313, 1059.8251288537458, 1090.5092708281304, 1116.1851848150895, 1121.450489260273, 14376.065555738844, 14530.05554638945, 14715.819355870346, 14749.262536873113, 14778.408863645549, 702741.0, 703550.0, 712973.0, 714523.0, 716476.0, 57642.423575764544, 58303.125453118235, 58743.0, 58795.41204587985, 58934.0, 1407.345584303304, 1417.1284659934272, 1420.786881967713, 1442.6609746709514, 1446.6600348918207, 28199.0, 28473.0, 28795.856020719762, 28935.987240447324, 29222.269443263238, 51631.74184129055, 52451.0, 52741.472585275194, 52749.7362513185, 52765.20852187297, 643354.0, 648024.0, 648300.0, 648398.0, 648770.0, 25154.0, 25348.619770703084, 25689.743102569482, 25821.3544661385, 26151.869240654436, 44592.55407445884, 45267.0, 45351.866203345184, 45716.0, 46843.531564683915, 35308.64691353054, 36221.81889090538, 36289.092772680895, 37470.81264593769, 38157.80921095377, 33130.50304245487, 36085.819570903026, 36656.26687466289, 36948.261034779694, 37023.0, 36676.0, 36941.0, 37345.0, 37486.62513374831, 37974.62025379822, 25532.0, 27733.861330693217, 28136.0, 32601.67398326081, 32677.509837352933, 20279.69580456324, 23674.881625591763, 23796.524069518167, 24238.15166469165, 24264.908079136127], "value": 18983.277451854887, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.7821220676044, 384.45215567815853, 384.92686389585975, 399.2175336340772, 408.2121505494396, 17277.654446911056, 18894.771839830417, 20578.0739866706, 20661.896690516565, 21251.468713282175, 19896.602067958633, 20711.585768284625, 21145.471363215926, 21154.153833846627, 21373.893130534365, 5659.717014149297, 5775.595708300431, 5931.762729490837, 5938.910916336258, 6007.729652165641, 5849.239598852165, 5888.558358123137, 5937.0, 5938.851528711773, 5948.524118070544, 5640.576956728241, 5870.442307980735, 5875.294964604253, 5880.617759845618, 5897.882042359151, 5740.512056475204, 5791.942080579224, 5850.678212698321, 5867.7066146692705, 5869.325027621814, 5094.516020978038, 5137.768800403991, 5161.122609156452, 5178.326817513736, 5190.4550022247495, 25438.0, 25519.872400638065, 25550.97796088154, 25649.74350256492, 25663.871680641478, 2233.3188377544843, 2328.906843726249, 2380.7619238076227, 2475.4306509502735, 2486.502699460097, 14588.487793245247, 14802.0, 15168.087582345715, 15300.311485983157, 15320.770188447183, 1717.407494414425, 1803.1705415508882, 1810.4840120565575, 1823.2706917233081, 1844.428227249557, 49619.75190124063, 104258.0, 104931.95068049298, 110513.12465569163, 115675.0, 9930.900690992998, 10367.844482332772, 10920.617778377718, 10988.175886808522, 11198.384088875027, 13579.185248885098, 13601.0, 13648.931755341358, 13654.931725341508, 13701.040927135005, 8668.0, 8748.0, 8904.065073167354, 8961.910380896237, 8986.55067246635, 8812.250958668585, 8975.416597921114, 8989.235914947172, 9079.182873541413, 9087.954560227288, 6818.522703410714, 6927.445804335641, 6973.12835895513, 6984.092068031225, 7011.579305241702, 1717.4933394648608, 1882.8493720502327, 1925.0, 1943.378119001934, 1947.8441724661993, 883.430187529053, 965.1844191658038, 969.5685419988038, 1028.876534815827, 1040.8646875906084, 14425.639359015899, 16855.0, 20563.485912852, 21267.46831329198, 21378.0, 953.5518306395983, 955.4888134847823, 991.2466525440717, 1006.0744115413837, 1053.2206167436043, 9742.707718768448, 10757.408342541235, 11342.546298148147, 11478.253913495597, 11515.366654833897, 601239.0, 603318.0, 630522.0, 635589.0, 641300.0, 80215.59892200619, 101137.49431252944, 125492.0, 125880.3353849079, 126392.36803816106, 970.1123472023161, 1014.5079636376499, 1045.0542259255385, 1046.7016900183412, 1047.9109275711498, 19365.70951435758, 19562.706559401904, 19606.313779017666, 19630.80369196347, 19687.901560492104, 50314.0, 51413.485865142364, 52191.2171317423, 52312.0, 52385.47614523806, 74639.62680186781, 75163.0, 75492.0, 75648.0, 78478.0, 5267.3152490176035, 5458.4814442627885, 7724.884126737991, 7895.092064412436, 8112.2293382130965, 6998.895016574654, 10893.019628233496, 11656.650300491, 12583.93708031454, 12677.239365638092, 9624.893137289302, 10782.514786834454, 11040.171987100839, 11395.430228488376, 11472.598459053894, 10053.69838904834, 10402.427866467417, 10811.891881081088, 11289.491972861077, 11319.830202547133, 10498.422586757497, 11127.94436027847, 11159.44202789841, 11416.771664566497, 11458.140639452242, 6904.378605925491, 8086.83826323482, 8142.226488483585, 8152.633131508977, 8444.915550844413, 9205.677801276923, 9385.436873787594, 10979.902009798956, 11079.16906232052, 11219.943900280447], "value": 9571.313082332941, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1556.8520990505901, 1601.351452661672, 1818.2545156485844, 1883.3502441657631, 1931.0344827586207, 8010.158933311991, 8547.78630534237, 8561.914380856197, 9026.684066057682, 9050.411723237994, 8682.392232543725, 8730.083341249172, 8830.470171789697, 8836.823263534741, 8839.0, 1999.9800002000013, 2356.4697942962866, 2363.7399886012527, 2384.916527921523, 2393.1623931623967, 1950.4343740315292, 2029.7767245602972, 2386.486905315357, 2389.0443822471066, 2391.0435825669697, 2365.8462199957034, 2367.4199821043867, 2373.786359227669, 2379.286214135761, 2395.964060539093, 2362.5189560220297, 2365.1721897335965, 2374.8693821839706, 2376.7266764322153, 2382.749811269809, 2247.1573160064977, 2252.7747225277503, 2253.256425379621, 2254.334971183505, 2254.5490901819703, 8720.956395218047, 8745.0, 8829.955850220707, 8832.205101540829, 8848.867266991001, 5268.8155914543195, 5415.079436495806, 5433.0, 5439.728013599304, 5446.91829622556, 3402.642722514125, 3443.724502039831, 3580.0691820126954, 3605.296967091418, 3613.5844377896615, 1029.2897900448665, 1031.5203430404822, 1033.720895358249, 1035.6168217759403, 1045.4668119259234, 10012.24908131893, 11267.943660281728, 11707.063434925185, 11711.121665875009, 11718.179727419085, 4678.60231880294, 4758.643101767381, 4842.83050093245, 4844.297576851337, 4866.659333846667, 9983.351082179635, 9987.201023918215, 10027.849582256273, 10029.949850250701, 10051.69844904654, 5335.6531825432, 5352.437994010651, 5415.6479828811, 5429.104197807317, 5454.0, 4420.690551661417, 4679.532046795362, 4797.688150270221, 4902.240152776325, 4952.702837829742, 3872.43849641806, 3976.264391087658, 4042.8180731867137, 4092.7953602319767, 4098.303288440972, 1115.8995690387903, 1288.9291088990008, 1304.7390521895659, 1314.4216544720293, 1322.16703476809, 831.9209675080857, 902.3503077783985, 922.7324076018014, 940.1961323068814, 946.3422921069928, 5673.574481913873, 5844.298684157929, 5854.560907931922, 5894.17481552583, 6037.184980027728, 423.3247969488661, 428.49223669950584, 430.77229894800195, 444.1516703097069, 454.22101096619457, 3150.9212269692985, 3765.7175711821724, 3820.255050265227, 4017.4375587417785, 4050.4936882889638, 10448.791024179474, 10645.78708425827, 10652.893471065345, 10772.407517586607, 10799.892001079888, 10571.312864663772, 10577.735556611146, 10618.522166502526, 10717.732056698645, 10891.618793342195, 7930.9603451982375, 8039.0, 8164.020317561931, 8314.459560128573, 8369.246767790806, 5603.971980140073, 5763.308402991668, 5819.243498345188, 5874.794382196774, 5900.0, 9913.107820296207, 10064.647737329158, 10069.395836249849, 10100.141487973604, 10119.848202277119, 9847.015298470096, 9980.301378903698, 10074.89925100769, 10192.235582331206, 10468.424236666751, 6882.518223724492, 7185.640717964186, 7444.330010299099, 7491.063617047869, 7558.508696934681, 8300.501969881827, 8306.6677332906, 8401.70594029206, 8414.705485307984, 8416.957915210385, 6228.719707613259, 6636.2700102987665, 7237.131544214728, 7428.702851885864, 7675.002249707502, 6016.308124565731, 7224.783256502314, 7253.3834624057545, 7384.889226661497, 7403.370713489198, 6826.624535650587, 6968.536607312319, 7012.088428504262, 7018.894716579153, 7019.017337572642, 4916.778744956557, 4932.63005274613, 4950.356453661145, 5019.598432125348, 5163.509466600667, 4914.140025495595, 4972.428170760409, 4996.450390457119, 5053.444121146597, 5225.137852254487], "value": 4477.279125667658, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.88333924724998, 312.46880303484073, 319.74420463629093, 322.3488553122694, 323.948168293073, 5236.031334203175, 5326.893462130755, 5503.3395992480855, 5579.414161513044, 5693.572982026349, 4945.961348116891, 5436.677841116312, 5502.284702988606, 5616.466435688613, 5625.609116125641, 4014.7316766027247, 4275.422817919588, 4282.635975942048, 4289.691644048564, 4301.440812694345, 3549.875754348598, 4153.252414565377, 4294.861861606677, 4295.334223195409, 4308.590683885026, 3508.9472658494683, 3637.8362973666117, 4008.158286759784, 4017.9799101004455, 4229.238737027334, 3598.6581274778855, 4219.249011660173, 4252.809802951482, 4291.090464743191, 4324.870253892388, 3706.036430528055, 3714.5728241252064, 3725.18046029873, 3731.869384571527, 3755.868544600953, 4045.676345892351, 4543.7728113594185, 5270.736463176864, 5309.62832601718, 5327.041132596131, 3859.6526312631718, 4430.158269928703, 4840.298156767249, 4864.2217245240945, 4892.021595680878, 5150.871228219287, 5179.669148883017, 5187.974060129675, 5461.535769459561, 5851.912221316641, 3608.1520842601967, 3779.8677046303246, 4088.6524645405175, 4135.731177473454, 4161.958380416218, 6246.345303246102, 6301.502666020357, 6336.841578960517, 6338.683065846735, 6339.397757213057, 3616.3309787689363, 3686.926261474809, 3983.322835117979, 4153.522344930311, 4225.218334608107, 3903.863364782219, 4094.9385759213637, 4187.97906010468, 4208.642265407413, 4228.429162063082, 4331.657186272203, 4910.558049775537, 4930.235813448921, 4934.259861020875, 4966.950330496721, 5659.585103724069, 5840.415958404211, 5997.311653333256, 6014.789482368096, 6021.2473440819895, 1251.5682089679046, 1359.8305457306708, 1452.1867754057762, 1460.8247010358612, 1461.7149655817227, 751.5678484871221, 870.8040690844635, 899.2805755395657, 900.4642237868458, 904.3850181876387, 4330.935035974463, 4605.976970115128, 4654.767261636905, 4657.836975705834, 4722.952770472251, 4533.569310915458, 4673.813047478131, 5091.0836049511445, 5221.477852214824, 5259.921101183409, 4258.893527661833, 4401.525488961234, 4447.0, 4680.602148817321, 4689.718616882998, 2513.6732224810657, 2629.500394924958, 2658.7208343123693, 2673.46530693862, 2750.477409292228, 4679.438467383936, 4788.3056956741775, 4795.592374648124, 5077.543021128116, 5397.514223719884, 3967.682585393162, 4478.820847166076, 4778.4982576829625, 4796.496367881392, 5300.469953004668, 4789.736564488987, 4879.926801097987, 5396.676199427969, 5416.783328666809, 5435.728213589226, 4505.48186958504, 4601.608863246662, 4818.338452318581, 4866.126541281703, 5212.592599998055, 4534.410526631516, 4562.5665561771575, 4769.785359658755, 4917.01659668075, 5127.66670166438, 4624.161945585223, 5247.55395791362, 5386.741021681826, 5397.460253974571, 5421.915616876561, 3282.3927573398996, 3654.2691461708296, 3670.33933891902, 3672.4307732301536, 3699.7040236780454, 4360.890977725582, 4719.787609557647, 5179.559737422362, 5203.751099736111, 5222.97388513055, 5007.799688012438, 5867.0, 5884.205632239764, 5887.411258874078, 5896.941030589639, 4484.215262329014, 4854.927176092432, 4916.852494425173, 5208.296879921314, 5915.704214789157, 4884.78018489176, 4982.377202849644, 5384.388571542877, 5755.3381361144, 5764.9711751442655, 5166.612504062286, 5678.779062501582, 5900.734466948912, 5912.935671579157, 5939.7921072763975, 5469.699166545878, 5754.769809207585, 5811.070228763378, 5816.331121921034, 5849.795257165979], "value": 4018.691200612105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.9492535522514, 729.8394353242285, 739.7595781371056, 747.6486051555767, 751.1024325930512, 12934.870651293484, 15006.774898376532, 15214.695706085871, 15433.459828905976, 15457.0, 14274.714505709879, 14390.352434140463, 14403.639909002253, 14407.495737649184, 14409.927950360234, 718.6694120704482, 893.2719833335826, 898.3397203055749, 914.1224424552438, 919.9816003679922, 906.7415786500849, 914.1726737302752, 914.4056363363834, 918.4948278446861, 920.0615372320229, 900.5317235037777, 953.7234202081388, 971.5142428785607, 974.5955428497165, 981.1660088924428, 926.9258459323238, 967.9032096790335, 969.781799095205, 971.1647982734843, 973.1533565797766, 687.8246047257917, 687.8693048320853, 689.069755829631, 717.9856402871914, 718.529363267061, 14238.501652442219, 14515.491957781533, 14565.563033108918, 14578.854211457856, 14709.11745295275, 11011.0, 12603.873961260362, 12645.557405490763, 12800.231986080771, 12958.09293349466, 9837.163841073518, 9902.306838521306, 10009.949950250202, 10453.790924181474, 10666.36001839892, 3203.6636153203813, 3312.2216279174377, 3489.755717099804, 3489.755717099804, 3565.447355659877, 74989.25010749932, 81035.0, 81298.59350703268, 82301.58849205716, 83261.58369208116, 9366.355420182836, 9381.0, 9437.480938548446, 9759.12167904892, 9818.80362392748, 21988.670169947465, 21993.560128797333, 21998.89000554987, 22000.33998980033, 22058.558828823334, 6955.513114082067, 7019.227884932653, 7109.715611375487, 7122.643867806589, 7254.347108760131, 3888.475055867478, 3926.9214615708097, 3929.941050884239, 3966.4446977423186, 4000.699947503949, 3309.652486488932, 3320.4023275810105, 3416.982915085409, 3418.453047512386, 3429.022728522359, 658.4666420199653, 661.9371159739817, 666.2238492156583, 672.7309076369492, 673.0409166937095, 545.6944111297686, 570.725658251866, 578.8147792706378, 599.4215581963426, 609.0316396928815, 7932.444728868923, 8276.213759692819, 8368.497890126611, 8370.204830541088, 8401.873971890427, 569.4618585436723, 571.502792570458, 581.8574449259874, 600.1867469578746, 623.1556241292983, 3872.3804191329814, 3884.0872394987155, 3920.2551515212017, 3941.901452463654, 4006.198760247904, 80764.0, 83120.0, 96165.03834961701, 97138.514307428, 97939.0, 71219.0, 76225.61887190529, 78679.60660196662, 78944.21055789484, 80970.16604418817, 72350.63824680948, 72690.0, 73138.9029164563, 73188.26811731921, 76009.23990760022, 7966.681332746625, 7982.84034319322, 8201.548914809742, 8216.876746848922, 8224.095349511668, 45157.64527064194, 45351.0, 45726.314105289115, 45799.855003625176, 45992.77003615094, 60630.0, 60985.780284394954, 62333.0, 62391.0, 62628.0, 9765.462899540593, 10875.456227188452, 10963.616273430393, 11137.331760094421, 11196.608118716133, 11745.88254117482, 11785.233959792586, 11811.17321787501, 11841.5855445059, 11981.940090299842, 11120.944395277971, 11135.777284454103, 11280.887191128311, 11437.085033197158, 11535.769284614093, 11139.554417823196, 11239.0, 11272.0, 11561.884381156417, 11596.0, 10967.451627418757, 10987.83518247211, 11406.657800266004, 11431.885681143081, 11586.71033224176, 9117.863232051657, 9166.541672916193, 9246.398984066014, 9287.582058807235, 9365.391249568756, 8652.048274689905, 8716.956415217883, 8827.117288271378, 9062.410943288929, 9175.357724959189], "value": 6827.386371176786, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1664.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 150.0, 134.0, 118.0, 116.0, 116.0, 125.0, 164.0, 116.0], "value": 126.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 136.0, 140.0, 194.0, 120.0, 138.0, 139.0, 119.0, 174.0, 116.0], "value": 145.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 202.0, 228.0, 265.0, 240.0, 203.0, 201.0, 195.0, 199.0, 200.0], "value": 213.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 81.0, 74.0, 175.0, 67.0, 78.0, 83.0, 99.0, 131.0, 59.0], "value": 92.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 145.0, 85.0, 114.0, 70.0, 116.0, 82.0, 67.0, 143.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 88.0, 90.0, 87.0, 91.0, 92.0, 89.0, 87.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 121.0, 149.0, 96.0, 111.0, 148.0, 109.0, 113.0, 150.0], "value": 118.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 107.0, 149.0, 113.0, 90.0, 91.0, 114.0, 114.0, 91.0], "value": 105.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 90.0, 69.0, 54.0, 50.0, 79.0, 173.0, 96.0, 89.0], "value": 81.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 82.0, 70.0, 84.0, 74.0, 69.0, 79.0, 92.0, 95.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 88.0, 87.0, 84.0, 99.0, 110.0, 76.0, 109.0, 107.0], "value": 93.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 160.0, 160.0, 131.0, 148.0, 153.0, 152.0, 156.0, 218.0, 140.0], "value": 156.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 163.0, 172.0, 174.0, 186.0, 211.0, 164.0, 229.0, 227.0, 187.0], "value": 188.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 78.0, 88.0, 76.0, 68.0, 55.0, 73.0, 92.0, 85.0, 91.0], "value": 76.8, "unit": "score"}]}]} 23:14:46 INFO - Return code: 0 23:14:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:14:46 ERROR - # TBPL SUCCESS # 23:14:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:14:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:14:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:14:46 INFO - 2016-05-23 23:14:46.259 screenresolution[1683:10291] starting screenresolution argv=screenresolution get 23:14:46 INFO - 2016-05-23 23:14:46.263 screenresolution[1683:10291] Display 0: 1600x1200x32@60 23:14:46 INFO - 2016-05-23 23:14:46.269 screenresolution[1684:10294] starting screenresolution argv=screenresolution list 23:14:46 INFO - Available Modes on Display 0 23:14:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:14:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:14:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:14:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:14:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:14:46 INFO - Intel Iris: 23:14:46 INFO - Chipset Model: Intel Iris 23:14:46 INFO - Type: GPU 23:14:46 INFO - Bus: Built-In 23:14:46 INFO - VRAM (Dynamic, Max): 1536 MB 23:14:46 INFO - Vendor: Intel (0x8086) 23:14:46 INFO - Device ID: 0x0a2e 23:14:46 INFO - Revision ID: 0x0009 23:14:46 INFO - Displays: 23:14:46 INFO - Display: 23:14:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:14:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:14:46 INFO - Display Serial Number: 1600x1200 60 23:14:46 INFO - Main Display: Yes 23:14:46 INFO - Mirror: Off 23:14:46 INFO - Online: Yes 23:14:46 INFO - Rotation: Supported 23:14:46 INFO - Return code: 0 23:14:46 INFO - Running post-action listener: _resource_record_post_action 23:14:46 INFO - ##### 23:14:46 INFO - ##### Finished run-tests step (success) 23:14:46 INFO - ##### 23:14:46 INFO - Running post-run listener: _resource_record_post_run 23:14:46 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37946880; Write bytes: 702130688; Read time: 462; Write time: 2099 23:14:46 INFO - TinderboxPrint: CPU usage
54.5% 23:14:46 INFO - TinderboxPrint: I/O read bytes / time
37,946,880 / 462 23:14:46 INFO - TinderboxPrint: I/O write bytes / time
702,130,688 / 2,099 23:14:46 INFO - TinderboxPrint: CPU idle
2,206.3 (45.5%) 23:14:46 INFO - TinderboxPrint: CPU system
974.7 (20.1%) 23:14:46 INFO - TinderboxPrint: CPU user
1,664.7 (34.4%) 23:14:46 INFO - TinderboxPrint: Swap in / out
434,487,296 / 0 23:14:46 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 393492992; Write bytes: 383379456; Read time: 29851; Write time: 872 23:14:46 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30780928; Write bytes: 301339136; Read time: 306; Write time: 1183 23:14:46 INFO - Running post-run listener: _upload_blobber_files 23:14:46 INFO - Blob upload gear active. 23:14:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:14:46 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 23:14:46 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'] 23:14:46 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 23:14:47 INFO - (blobuploader) - INFO - Open directory for files ... 23:14:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:14:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:14:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:48 INFO - (blobuploader) - INFO - Done attempting. 23:14:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:14:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:14:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:49 INFO - (blobuploader) - INFO - Done attempting. 23:14:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:14:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:14:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:14:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:14:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:14:51 INFO - (blobuploader) - INFO - Done attempting. 23:14:51 INFO - (blobuploader) - INFO - Iteration through files over. 23:14:51 INFO - Return code: 0 23:14:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:14:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:14:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7451440e72f577b698688b4859b725f61c6ef4ceaa0569aa677c90c3067d83acb3996e7d6302382bcffd34e4374b64096f15c25115192495121f0376225d4ad9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/db0dc5666046700c3088244dd9378e0a7dd964e4b69371968e31fa049be243d698aa47b7f80e10279450245b2b036bada42296d0550aaa493e673dbabaa6aca3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6063514590a2b8d61436abd436e7c8f172522088ae73339035a2e36d2ac0dea7e49c1f5eb2e9ece0a5dc28224d9281720f9822b1f0557ae8328f47af27db7e8d"} 23:14:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:14:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:14:51 INFO - Contents: 23:14:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7451440e72f577b698688b4859b725f61c6ef4ceaa0569aa677c90c3067d83acb3996e7d6302382bcffd34e4374b64096f15c25115192495121f0376225d4ad9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/db0dc5666046700c3088244dd9378e0a7dd964e4b69371968e31fa049be243d698aa47b7f80e10279450245b2b036bada42296d0550aaa493e673dbabaa6aca3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6063514590a2b8d61436abd436e7c8f172522088ae73339035a2e36d2ac0dea7e49c1f5eb2e9ece0a5dc28224d9281720f9822b1f0557ae8328f47af27db7e8d"} 23:14:51 INFO - Running post-run listener: copy_logs_to_upload_dir 23:14:51 INFO - Copying logs to upload dir... 23:14:51 INFO - mkdir: /builds/slave/test/build/upload/logs 23:14:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.664360 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2016-05-23 23:14:52.044231) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 23:14:52.047339) ========= 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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7451440e72f577b698688b4859b725f61c6ef4ceaa0569aa677c90c3067d83acb3996e7d6302382bcffd34e4374b64096f15c25115192495121f0376225d4ad9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/db0dc5666046700c3088244dd9378e0a7dd964e4b69371968e31fa049be243d698aa47b7f80e10279450245b2b036bada42296d0550aaa493e673dbabaa6aca3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6063514590a2b8d61436abd436e7c8f172522088ae73339035a2e36d2ac0dea7e49c1f5eb2e9ece0a5dc28224d9281720f9822b1f0557ae8328f47af27db7e8d"} build_url:https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011366 build_url: 'https://queue.taskcluster.net/v1/task/Tk5sXH0-TOiEdRmGypAy6w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7451440e72f577b698688b4859b725f61c6ef4ceaa0569aa677c90c3067d83acb3996e7d6302382bcffd34e4374b64096f15c25115192495121f0376225d4ad9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/db0dc5666046700c3088244dd9378e0a7dd964e4b69371968e31fa049be243d698aa47b7f80e10279450245b2b036bada42296d0550aaa493e673dbabaa6aca3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6063514590a2b8d61436abd436e7c8f172522088ae73339035a2e36d2ac0dea7e49c1f5eb2e9ece0a5dc28224d9281720f9822b1f0557ae8328f47af27db7e8d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 23:14:52.076072) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:14:52.076386) ========= 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.EKVUk0MvwK/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.9IgNQT6Xmr/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005191 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 23:14:52.124713) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 23:14:52.125048) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 23:14:52.125389) ========= ========= Total master_lag: 2.04 =========