builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0347 starttime: 1464052631.73 results: success (0) buildid: 20160523172042 builduid: 153e8b2cbc8e4be7b8bc06f5a32da908 revision: b3835efbf422b78ea753a5072d3e25986b7df180 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:11.731874) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:11.732341) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:11.732615) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:11.762299) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:11.762694) ========= 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.Tg06xlKLnf/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.P8REpe2ORV/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:17:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.0M=0s 2016-05-23 18:17:11 (28.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137005 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:11.918498) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:11.918804) ========= 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.Tg06xlKLnf/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.P8REpe2ORV/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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.024868 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:11.966835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 18:17:11.967221) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b3835efbf422b78ea753a5072d3e25986b7df180 --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 b3835efbf422b78ea753a5072d3e25986b7df180 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Tg06xlKLnf/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.P8REpe2ORV/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:17:12,025 truncating revision to first 12 chars 2016-05-23 18:17:12,026 Setting DEBUG logging. 2016-05-23 18:17:12,026 attempt 1/10 2016-05-23 18:17:12,026 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b3835efbf422?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 18:17:13,429 unpacking tar archive at: fx-team-b3835efbf422/testing/mozharness/ program finished with exit code 0 elapsedTime=1.656838 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 18:17:13.642247) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:13.642544) ========= script_repo_revision: b3835efbf422b78ea753a5072d3e25986b7df180 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:13.642929) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:13.643197) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 18:17:13.657464) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 39 secs) (at 2016-05-23 18:17:13.657732) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Tg06xlKLnf/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.P8REpe2ORV/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:17:13 INFO - MultiFileLogger online at 20160523 18:17:13 in /builds/slave/test 18:17:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:17:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:17:13 INFO - {'append_to_log': False, 18:17:13 INFO - 'base_work_dir': '/builds/slave/test', 18:17:13 INFO - 'blob_upload_branch': 'Fx-Team', 18:17:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:17:13 INFO - 'branch': 'Fx-Team', 18:17:13 INFO - 'buildbot_json_path': 'buildprops.json', 18:17:14 INFO - 'config_files': ('talos/mac_config.py',), 18:17:14 INFO - 'default_actions': ('clobber', 18:17:14 INFO - 'read-buildbot-config', 18:17:14 INFO - 'download-and-extract', 18:17:14 INFO - 'populate-webroot', 18:17:14 INFO - 'create-virtualenv', 18:17:14 INFO - 'install', 18:17:14 INFO - 'run-tests'), 18:17:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:17:14 INFO - 'download_minidump_stackwalk': True, 18:17:14 INFO - 'download_symbols': 'ondemand', 18:17:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:17:14 INFO - 'tooltool.py': '/tools/tooltool.py', 18:17:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:17:14 INFO - '/tools/misc-python/virtualenv.py')}, 18:17:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:17:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:17:14 INFO - 'installer_path': 'installer.exe', 18:17:14 INFO - 'log_level': 'info', 18:17:14 INFO - 'log_name': 'talos', 18:17:14 INFO - 'log_to_console': True, 18:17:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:17:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:17:14 INFO - 'opt_config_files': (), 18:17:14 INFO - 'pip_index': False, 18:17:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:17:14 INFO - 'cmd': ('bash', 18:17:14 INFO - '-c', 18:17:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:17:14 INFO - 'enabled': True, 18:17:14 INFO - 'halt_on_failure': False, 18:17:14 INFO - 'name': 'check_screen_resolution'},), 18:17:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:17:14 INFO - 'cmd': ('bash', 18:17:14 INFO - '-c', 18:17:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:17:14 INFO - 'enabled': True, 18:17:14 INFO - 'halt_on_failure': False, 18:17:14 INFO - 'name': 'check_screen_resolution'},), 18:17:14 INFO - 'run_cmd_checks_enabled': True, 18:17:14 INFO - 'sps_profile': False, 18:17:14 INFO - 'sps_profile_interval': 0, 18:17:14 INFO - 'suite': 'dromaeojs', 18:17:14 INFO - 'system_bits': '32', 18:17:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:17:14 INFO - 'title': 't-yosemite-r7-0347', 18:17:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:17:14 INFO - 'use_talos_json': True, 18:17:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:17:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:17:14 INFO - 'work_dir': 'build'} 18:17:14 INFO - ##### 18:17:14 INFO - ##### Running clobber step. 18:17:14 INFO - ##### 18:17:14 INFO - Running pre-action listener: _resource_record_pre_action 18:17:14 INFO - Running main action method: clobber 18:17:14 INFO - rmtree: /builds/slave/test/build 18:17:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:17:15 INFO - Running post-action listener: _resource_record_post_action 18:17:15 INFO - ##### 18:17:15 INFO - ##### Finished clobber step (success) 18:17:15 INFO - ##### 18:17:15 INFO - ##### 18:17:15 INFO - ##### Running read-buildbot-config step. 18:17:15 INFO - ##### 18:17:15 INFO - Running pre-action listener: _resource_record_pre_action 18:17:15 INFO - Running main action method: read_buildbot_config 18:17:15 INFO - Using buildbot properties: 18:17:15 INFO - { 18:17:15 INFO - "project": "", 18:17:15 INFO - "product": "firefox", 18:17:15 INFO - "script_repo_revision": "production", 18:17:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:17:15 INFO - "repository": "", 18:17:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:17:15 INFO - "buildid": "20160523172042", 18:17:15 INFO - "pgo_build": "False", 18:17:15 INFO - "basedir": "/builds/slave/test", 18:17:15 INFO - "buildnumber": 19, 18:17:15 INFO - "slavename": "t-yosemite-r7-0347", 18:17:15 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:17:15 INFO - "platform": "yosemite_r7", 18:17:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:17:15 INFO - "branch": "fx-team", 18:17:15 INFO - "repo_path": "integration/fx-team", 18:17:15 INFO - "revision": "b3835efbf422b78ea753a5072d3e25986b7df180", 18:17:15 INFO - "stage_platform": "macosx64", 18:17:15 INFO - "builduid": "153e8b2cbc8e4be7b8bc06f5a32da908", 18:17:15 INFO - "slavebuilddir": "test" 18:17:15 INFO - } 18:17:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:17:15 INFO - Running post-action listener: _resource_record_post_action 18:17:15 INFO - ##### 18:17:15 INFO - ##### Finished read-buildbot-config step (success) 18:17:15 INFO - ##### 18:17:15 INFO - ##### 18:17:15 INFO - ##### Running download-and-extract step. 18:17:15 INFO - ##### 18:17:15 INFO - Running pre-action listener: _resource_record_pre_action 18:17:15 INFO - Running main action method: download_and_extract 18:17:15 INFO - mkdir: /builds/slave/test/build/tests 18:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:17:15 INFO - https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:17:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:17:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:17:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/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 18:17:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/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 18:17:37 INFO - Downloaded 1199 bytes. 18:17:37 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:17:37 INFO - Using the following test package requirements: 18:17:37 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:17:37 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:17:37 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:17:37 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:17:37 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:17:37 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:17:37 INFO - u'jsshell-mac.zip'], 18:17:37 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:17:37 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:17:37 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:17:37 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:17:37 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:17:37 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:17:37 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:17:37 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:17:37 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:17:37 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:17:37 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:17:37 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:17:37 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')]} 18:17:37 INFO - https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:17:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:17:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:17:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/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 18:17:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/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 18:17:45 INFO - Downloaded 18630456 bytes. 18:17:45 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'] 18:17:45 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 18:17:48 INFO - Return code: 0 18:17:48 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 18:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:17:48 INFO - https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:17:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:17:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:17:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/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 18:17:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/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 18:17:51 INFO - Downloaded 18630456 bytes. 18:17:51 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'] 18:17:51 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 18:17:55 INFO - Return code: 0 18:17:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:17:55 INFO - https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:17:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:17:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:17:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/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 18:17:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/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 18:17:56 INFO - Downloaded 10840359 bytes. 18:17:56 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'] 18:17:56 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 18:17:57 INFO - Return code: 0 18:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:17:57 INFO - https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:17:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:17:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:17:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:17:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S563Jd2gTYuO9XYkvKlNnw/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 18:17:59 INFO - Downloaded 110167406 bytes. 18:17:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:17:59 INFO - mkdir: /builds/slave/test/properties 18:17:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:17:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:17:59 INFO - Contents: 18:17:59 INFO - build_url:https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:18:01 INFO - Running post-action listener: _resource_record_post_action 18:18:01 INFO - Running post-action listener: set_extra_try_arguments 18:18:01 INFO - ##### 18:18:01 INFO - ##### Finished download-and-extract step (success) 18:18:01 INFO - ##### 18:18:01 INFO - ##### 18:18:01 INFO - ##### Running populate-webroot step. 18:18:01 INFO - ##### 18:18:01 INFO - Running pre-action listener: _resource_record_pre_action 18:18:01 INFO - Running main action method: populate_webroot 18:18:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:18:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:18:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:18:01 INFO - '--tppagecycles', 18:18:01 INFO - '7'], 18:18:01 INFO - 'tests': ['tsvgm']}}, 18:18:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:18:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:18:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:18:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:18:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:18:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:18:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:01 INFO - 'talos_options': ['--disable-e10s'], 18:18:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:18:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:01 INFO - 'talos_options': ['--disable-e10s'], 18:18:01 INFO - 'tests': ['damp', 'tps']}, 18:18:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:01 INFO - 'tests': ['damp', 'tps']}, 18:18:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:18:01 INFO - 'tests': ['dromaeo_dom']}, 18:18:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:18:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:18:01 INFO - 'tests': ['a11yr', 18:18:01 INFO - 'ts_paint', 18:18:01 INFO - 'tpaint', 18:18:01 INFO - 'sessionrestore', 18:18:01 INFO - 'sessionrestore_no_auto_restore', 18:18:01 INFO - 'tabpaint']}, 18:18:01 INFO - 'other-e10s': {'tests': ['a11yr', 18:18:01 INFO - 'ts_paint', 18:18:01 INFO - 'tpaint', 18:18:01 INFO - 'sessionrestore', 18:18:01 INFO - 'sessionrestore_no_auto_restore', 18:18:01 INFO - 'tabpaint']}, 18:18:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:18:01 INFO - 'ts_paint', 18:18:01 INFO - 'tpaint', 18:18:01 INFO - 'sessionrestore', 18:18:01 INFO - 'sessionrestore_no_auto_restore', 18:18:01 INFO - 'tabpaint']}, 18:18:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:18:01 INFO - 'ts_paint', 18:18:01 INFO - 'tpaint', 18:18:01 INFO - 'sessionrestore', 18:18:01 INFO - 'sessionrestore_no_auto_restore', 18:18:01 INFO - 'tabpaint']}, 18:18:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:18:01 INFO - 'tests': ['a11yr', 18:18:01 INFO - 'ts_paint', 18:18:01 INFO - 'tpaint', 18:18:01 INFO - 'sessionrestore', 18:18:01 INFO - 'sessionrestore_no_auto_restore', 18:18:01 INFO - 'tabpaint']}, 18:18:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:18:01 INFO - 'tests': ['a11yr', 18:18:01 INFO - 'ts_paint', 18:18:01 INFO - 'tpaint', 18:18:01 INFO - 'sessionrestore', 18:18:01 INFO - 'sessionrestore_no_auto_restore', 18:18:01 INFO - 'tabpaint']}, 18:18:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:18:01 INFO - 'tests': ['tsvgx', 18:18:01 INFO - 'tsvgr_opacity', 18:18:01 INFO - 'tart', 18:18:01 INFO - 'tscrollx', 18:18:01 INFO - 'cart']}, 18:18:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:18:01 INFO - 'tsvgr_opacity', 18:18:01 INFO - 'tart', 18:18:01 INFO - 'tscrollx', 18:18:01 INFO - 'cart']}, 18:18:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:01 INFO - 'talos_options': ['--disable-e10s'], 18:18:01 INFO - 'tests': ['tp5o']}, 18:18:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:01 INFO - 'tests': ['tp5o']}, 18:18:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:01 INFO - 'talos_options': ['--disable-e10s', 18:18:01 INFO - '--xperf_path', 18:18:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:01 INFO - 'tests': ['tp5n']}, 18:18:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:18:01 INFO - 'talos_options': ['--xperf_path', 18:18:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:18:01 INFO - 'tests': ['tp5n']}}, 18:18:01 INFO - 'talos.zip': {'path': '', 18:18:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:18:01 INFO - Running post-action listener: _resource_record_post_action 18:18:01 INFO - ##### 18:18:01 INFO - ##### Finished populate-webroot step (success) 18:18:01 INFO - ##### 18:18:01 INFO - ##### 18:18:01 INFO - ##### Running create-virtualenv step. 18:18:01 INFO - ##### 18:18:01 INFO - Running pre-action listener: _resource_record_pre_action 18:18:01 INFO - Running main action method: create_virtualenv 18:18:01 INFO - Creating virtualenv /builds/slave/test/build/venv 18:18:01 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 18:18:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:18:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:18:01 INFO - Using real prefix '/tools/python27' 18:18:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:18:01 INFO - Installing distribute.............................................................................................................................................................................................done. 18:18:04 INFO - Installing pip.................done. 18:18:04 INFO - Return code: 0 18:18:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105beaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bf0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c85a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a8ed30>, '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 0x1058b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a85b20>, '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.Tg06xlKLnf/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.P8REpe2ORV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg06xlKLnf/Render', 18:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:04 INFO - 'HOME': '/Users/cltbld', 18:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:04 INFO - 'LOGNAME': 'cltbld', 18:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:04 INFO - 'MOZ_NO_REMOTE': '1', 18:18:04 INFO - 'NO_EM_RESTART': '1', 18:18:04 INFO - 'PAGER': '/bin/cat', 18:18:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:04 INFO - 'PWD': '/builds/slave/test', 18:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:04 INFO - 'SHELL': '/bin/bash', 18:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8REpe2ORV/Listeners', 18:18:04 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 18:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:04 INFO - 'USER': 'cltbld', 18:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:04 INFO - 'XPC_FLAGS': '0x0', 18:18:04 INFO - 'XPC_SERVICE_NAME': '0', 18:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:05 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 18:18:05 INFO - Cleaning up... 18:18:05 INFO - Return code: 0 18:18:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:18:05 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')]} 18:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:05 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')]} 18:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:05 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 0x105beaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bf0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c85a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a8ed30>, '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 0x1058b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a85b20>, '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.Tg06xlKLnf/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.P8REpe2ORV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:18:05 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 18:18:05 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 18:18:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg06xlKLnf/Render', 18:18:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:05 INFO - 'HOME': '/Users/cltbld', 18:18:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:05 INFO - 'LOGNAME': 'cltbld', 18:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:05 INFO - 'MOZ_NO_REMOTE': '1', 18:18:05 INFO - 'NO_EM_RESTART': '1', 18:18:05 INFO - 'PAGER': '/bin/cat', 18:18:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:05 INFO - 'PWD': '/builds/slave/test', 18:18:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:05 INFO - 'SHELL': '/bin/bash', 18:18:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8REpe2ORV/Listeners', 18:18:05 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 18:18:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:05 INFO - 'USER': 'cltbld', 18:18:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:05 INFO - 'XPC_FLAGS': '0x0', 18:18:05 INFO - 'XPC_SERVICE_NAME': '0', 18:18:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:05 INFO - Downloading/unpacking psutil>=3.1.1 18:18:05 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 18:18:05 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 18:18:05 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 18:18:05 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 18:18:05 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 18:18:05 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 18:18:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:18:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:08 INFO - Installing collected packages: psutil 18:18:08 INFO - Running setup.py install for psutil 18:18:08 INFO - building 'psutil._psutil_osx' extension 18:18:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:18:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:18:09 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 18:18:09 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 18:18:09 INFO - building 'psutil._psutil_posix' extension 18:18:09 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 18:18:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:18:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:18:09 INFO - ^ 18:18:09 INFO - 1 warning generated. 18:18:09 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 18:18:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:18:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:18:09 INFO - Successfully installed psutil 18:18:09 INFO - Cleaning up... 18:18:09 INFO - Return code: 0 18:18:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:10 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 0x105beaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bf0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c85a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a8ed30>, '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 0x1058b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a85b20>, '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.Tg06xlKLnf/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.P8REpe2ORV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:18:10 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 18:18:10 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 18:18:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg06xlKLnf/Render', 18:18:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:10 INFO - 'HOME': '/Users/cltbld', 18:18:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:10 INFO - 'LOGNAME': 'cltbld', 18:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:10 INFO - 'MOZ_NO_REMOTE': '1', 18:18:10 INFO - 'NO_EM_RESTART': '1', 18:18:10 INFO - 'PAGER': '/bin/cat', 18:18:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:10 INFO - 'PWD': '/builds/slave/test', 18:18:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:10 INFO - 'SHELL': '/bin/bash', 18:18:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8REpe2ORV/Listeners', 18:18:10 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 18:18:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:10 INFO - 'USER': 'cltbld', 18:18:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:10 INFO - 'XPC_FLAGS': '0x0', 18:18:10 INFO - 'XPC_SERVICE_NAME': '0', 18:18:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:18:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:18:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:18:13 INFO - Installing collected packages: mozsystemmonitor 18:18:13 INFO - Running setup.py install for mozsystemmonitor 18:18:13 INFO - Successfully installed mozsystemmonitor 18:18:13 INFO - Cleaning up... 18:18:13 INFO - Return code: 0 18:18:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:18:13 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')]} 18:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:13 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')]} 18:18:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:13 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 0x105beaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bf0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c85a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a8ed30>, '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 0x1058b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a85b20>, '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.Tg06xlKLnf/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.P8REpe2ORV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:18:13 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 18:18:13 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 18:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg06xlKLnf/Render', 18:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:13 INFO - 'HOME': '/Users/cltbld', 18:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:13 INFO - 'LOGNAME': 'cltbld', 18:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:13 INFO - 'MOZ_NO_REMOTE': '1', 18:18:13 INFO - 'NO_EM_RESTART': '1', 18:18:13 INFO - 'PAGER': '/bin/cat', 18:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:13 INFO - 'PWD': '/builds/slave/test', 18:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:13 INFO - 'SHELL': '/bin/bash', 18:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8REpe2ORV/Listeners', 18:18:13 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 18:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:13 INFO - 'USER': 'cltbld', 18:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:13 INFO - 'XPC_FLAGS': '0x0', 18:18:13 INFO - 'XPC_SERVICE_NAME': '0', 18:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:13 INFO - Downloading/unpacking blobuploader==1.2.4 18:18:13 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 18:18:13 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 18:18:13 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 18:18:13 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 18:18:13 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 18:18:13 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 18:18:16 INFO - Downloading blobuploader-1.2.4.tar.gz 18:18:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:18:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:18:16 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 18:18:16 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 18:18:16 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 18:18:16 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 18:18:16 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 18:18:16 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 18:18:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:18:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:18:17 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 18:18:17 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 18:18:17 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 18:18:17 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 18:18:17 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 18:18:17 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 18:18:17 INFO - Downloading docopt-0.6.1.tar.gz 18:18:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:18:17 INFO - Installing collected packages: blobuploader, requests, docopt 18:18:17 INFO - Running setup.py install for blobuploader 18:18:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:18:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:18:17 INFO - Running setup.py install for requests 18:18:18 INFO - Running setup.py install for docopt 18:18:18 INFO - Successfully installed blobuploader requests docopt 18:18:18 INFO - Cleaning up... 18:18:18 INFO - Return code: 0 18:18:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:18 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')]} 18:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:18 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')]} 18:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:18 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 0x105beaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bf0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c85a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a8ed30>, '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 0x1058b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a85b20>, '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.Tg06xlKLnf/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.P8REpe2ORV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:18:18 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 18:18:18 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 18:18:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg06xlKLnf/Render', 18:18:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:18 INFO - 'HOME': '/Users/cltbld', 18:18:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:18 INFO - 'LOGNAME': 'cltbld', 18:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:18 INFO - 'MOZ_NO_REMOTE': '1', 18:18:18 INFO - 'NO_EM_RESTART': '1', 18:18:18 INFO - 'PAGER': '/bin/cat', 18:18:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:18 INFO - 'PWD': '/builds/slave/test', 18:18:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:18 INFO - 'SHELL': '/bin/bash', 18:18:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8REpe2ORV/Listeners', 18:18:18 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 18:18:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:18 INFO - 'USER': 'cltbld', 18:18:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:18 INFO - 'XPC_FLAGS': '0x0', 18:18:18 INFO - 'XPC_SERVICE_NAME': '0', 18:18:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:18 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-viuLaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:18 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-jIAhNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:18 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-2f6qC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:19 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-xPdZ71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:19 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-jE6Htc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:19 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-rI3W_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:19 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-ErjNkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:19 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-oElS8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:19 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-mQcRvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:19 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-bpFj4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:19 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-Qhazea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:19 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-bmkMmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:20 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-iWjoos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:20 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-LcmQXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:20 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-C0LUuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:20 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-UyOyKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:20 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-TSJIfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:18:20 INFO - Running setup.py install for manifestparser 18:18:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:18:20 INFO - Running setup.py install for mozcrash 18:18:20 INFO - Running setup.py install for mozdebug 18:18:20 INFO - Running setup.py install for mozdevice 18:18:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:18:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:18:21 INFO - Running setup.py install for mozfile 18:18:21 INFO - Running setup.py install for mozhttpd 18:18:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:18:21 INFO - Running setup.py install for mozinfo 18:18:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:18:21 INFO - Running setup.py install for mozInstall 18:18:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:18:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:18:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:18:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:18:21 INFO - Running setup.py install for mozleak 18:18:21 INFO - Running setup.py install for mozlog 18:18:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:18:22 INFO - Running setup.py install for moznetwork 18:18:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:18:22 INFO - Running setup.py install for mozprocess 18:18:22 INFO - Running setup.py install for mozprofile 18:18:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:18:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:18:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:18:22 INFO - Running setup.py install for mozrunner 18:18:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:18:22 INFO - Running setup.py install for mozscreenshot 18:18:22 INFO - Running setup.py install for moztest 18:18:22 INFO - Running setup.py install for mozversion 18:18:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:18:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:18:23 INFO - Cleaning up... 18:18:23 INFO - Return code: 0 18:18:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18:23 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')]} 18:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:23 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')]} 18:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:23 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 0x105beaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bf0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c85a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a8ed30>, '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 0x1058b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a85b20>, '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.Tg06xlKLnf/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.P8REpe2ORV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:18:23 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 18:18:23 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 18:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg06xlKLnf/Render', 18:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:23 INFO - 'HOME': '/Users/cltbld', 18:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:23 INFO - 'LOGNAME': 'cltbld', 18:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:23 INFO - 'MOZ_NO_REMOTE': '1', 18:18:23 INFO - 'NO_EM_RESTART': '1', 18:18:23 INFO - 'PAGER': '/bin/cat', 18:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:23 INFO - 'PWD': '/builds/slave/test', 18:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:23 INFO - 'SHELL': '/bin/bash', 18:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8REpe2ORV/Listeners', 18:18:23 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 18:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:23 INFO - 'USER': 'cltbld', 18:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:23 INFO - 'XPC_FLAGS': '0x0', 18:18:23 INFO - 'XPC_SERVICE_NAME': '0', 18:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:18:23 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-xsXgHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:18:23 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)) 18:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:18:23 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-fbpS2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:18:23 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)) 18:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:18:23 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-UHQcqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:18:23 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)) 18:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:18:23 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-jariYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:18:23 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)) 18:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:18:23 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-_JZ5Sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:18:23 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)) 18:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:18:23 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-GH5l1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:18:24 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)) 18:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:18:24 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-NtRaVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:18:24 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)) 18:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:18:24 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-6f732P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:18:24 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)) 18:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:18:24 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-3Jiskx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:18:24 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)) 18:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:18:24 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-LL1HQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:18:24 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)) 18:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:18:24 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-IIMxhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:18:24 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)) 18:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:18:24 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-Tw44Zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:18:24 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)) 18:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:18:24 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-IpTXWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:18:24 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)) 18:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:18:24 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-qaHnoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:18:24 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)) 18:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:24 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-UENzKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:18:24 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)) 18:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:18:24 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-d6fOL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:18:25 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)) 18:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:18:25 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-bvTKNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:18:25 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)) 18:18:25 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)) 18:18:25 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)) 18:18:25 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)) 18:18:25 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)) 18:18:25 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)) 18:18:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:28 INFO - Downloading blessings-1.6.tar.gz 18:18:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:18:28 INFO - Installing collected packages: blessings 18:18:28 INFO - Running setup.py install for blessings 18:18:28 INFO - Successfully installed blessings 18:18:28 INFO - Cleaning up... 18:18:28 INFO - Return code: 0 18:18:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:18:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:28 INFO - Reading from file tmpfile_stdout 18:18:28 INFO - Current package versions: 18:18:28 INFO - blessings == 1.6 18:18:28 INFO - blobuploader == 1.2.4 18:18:28 INFO - docopt == 0.6.1 18:18:28 INFO - manifestparser == 1.1 18:18:28 INFO - mozInstall == 1.12 18:18:28 INFO - mozcrash == 0.17 18:18:28 INFO - mozdebug == 0.1 18:18:28 INFO - mozdevice == 0.48 18:18:28 INFO - mozfile == 1.2 18:18:28 INFO - mozhttpd == 0.7 18:18:28 INFO - mozinfo == 0.9 18:18:28 INFO - mozleak == 0.1 18:18:28 INFO - mozlog == 3.2 18:18:28 INFO - moznetwork == 0.27 18:18:28 INFO - mozprocess == 0.22 18:18:28 INFO - mozprofile == 0.28 18:18:28 INFO - mozrunner == 6.11 18:18:28 INFO - mozscreenshot == 0.1 18:18:28 INFO - mozsystemmonitor == 0.3 18:18:28 INFO - moztest == 0.7 18:18:28 INFO - mozversion == 1.4 18:18:28 INFO - psutil == 3.1.1 18:18:28 INFO - requests == 1.2.3 18:18:28 INFO - wsgiref == 0.1.2 18:18:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:28 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 0x105beaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bf0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c85a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a8ed30>, '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 0x1058b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a85b20>, '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.Tg06xlKLnf/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.P8REpe2ORV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:18:28 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 18:18:28 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 18:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg06xlKLnf/Render', 18:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:28 INFO - 'HOME': '/Users/cltbld', 18:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:28 INFO - 'LOGNAME': 'cltbld', 18:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:28 INFO - 'MOZ_NO_REMOTE': '1', 18:18:28 INFO - 'NO_EM_RESTART': '1', 18:18:28 INFO - 'PAGER': '/bin/cat', 18:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:28 INFO - 'PWD': '/builds/slave/test', 18:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:28 INFO - 'SHELL': '/bin/bash', 18:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8REpe2ORV/Listeners', 18:18:28 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 18:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:28 INFO - 'USER': 'cltbld', 18:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:28 INFO - 'XPC_FLAGS': '0x0', 18:18:28 INFO - 'XPC_SERVICE_NAME': '0', 18:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:28 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)) 18:18:28 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)) 18:18:28 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)) 18:18:28 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)) 18:18:28 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)) 18:18:28 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)) 18:18:28 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)) 18:18:28 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)) 18:18:28 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)) 18:18:28 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)) 18:18:28 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)) 18:18:28 INFO - Cleaning up... 18:18:28 INFO - Return code: 0 18:18:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:18: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')]} 18:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18: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')]} 18:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:28 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 0x105beaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bf0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c85a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a8ed30>, '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 0x1058b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a85b20>, '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.Tg06xlKLnf/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.P8REpe2ORV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:18:28 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 18:18:28 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 18:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg06xlKLnf/Render', 18:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:28 INFO - 'HOME': '/Users/cltbld', 18:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:28 INFO - 'LOGNAME': 'cltbld', 18:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:28 INFO - 'MOZ_NO_REMOTE': '1', 18:18:28 INFO - 'NO_EM_RESTART': '1', 18:18:28 INFO - 'PAGER': '/bin/cat', 18:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:28 INFO - 'PWD': '/builds/slave/test', 18:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:28 INFO - 'SHELL': '/bin/bash', 18:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8REpe2ORV/Listeners', 18:18:28 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 18:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:28 INFO - 'USER': 'cltbld', 18:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:28 INFO - 'XPC_FLAGS': '0x0', 18:18:28 INFO - 'XPC_SERVICE_NAME': '0', 18:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:29 INFO - Downloading/unpacking jsonschema 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18: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 18:18:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:18:31 INFO - Downloading/unpacking functools32 (from jsonschema) 18:18:31 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 18:18:31 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 18:18:31 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 18:18:31 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 18:18:31 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 18:18:31 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 18:18:31 INFO - Downloading functools32-3.2.3-2.tar.gz 18:18:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:18:32 INFO - warning: no files found matching '*.txt' 18:18:32 INFO - no previously-included directories found matching 'build' 18:18:32 INFO - no previously-included directories found matching 'dist' 18:18:32 INFO - no previously-included directories found matching '.git*' 18:18:32 INFO - Installing collected packages: jsonschema, functools32 18:18:32 INFO - Running setup.py install for functools32 18:18:32 INFO - warning: no files found matching '*.txt' 18:18:32 INFO - no previously-included directories found matching 'build' 18:18:32 INFO - no previously-included directories found matching 'dist' 18:18:32 INFO - no previously-included directories found matching '.git*' 18:18:32 INFO - Successfully installed jsonschema functools32 18:18:32 INFO - Cleaning up... 18:18:32 INFO - Return code: 0 18:18:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:18:32 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')]} 18:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:32 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')]} 18:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:18:32 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 0x105beaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bf0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c85a0720>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a8ed30>, '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 0x1058b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a85b20>, '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.Tg06xlKLnf/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.P8REpe2ORV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 18:18:32 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 18:18:32 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 18:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg06xlKLnf/Render', 18:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:18:32 INFO - 'HOME': '/Users/cltbld', 18:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:18:32 INFO - 'LOGNAME': 'cltbld', 18:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:18:32 INFO - 'MOZ_NO_REMOTE': '1', 18:18:32 INFO - 'NO_EM_RESTART': '1', 18:18:32 INFO - 'PAGER': '/bin/cat', 18:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:18:32 INFO - 'PWD': '/builds/slave/test', 18:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:18:32 INFO - 'SHELL': '/bin/bash', 18:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8REpe2ORV/Listeners', 18:18:32 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 18:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:18:32 INFO - 'USER': 'cltbld', 18:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:18:32 INFO - 'XPC_FLAGS': '0x0', 18:18:32 INFO - 'XPC_SERVICE_NAME': '0', 18:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:18:32 INFO - Downloading/unpacking flake8 18:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:18:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:18:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:18:35 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 18:18:35 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 18:18:35 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 18:18:35 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 18:18:35 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 18:18:35 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 18:18:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:18:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:18:35 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 18:18:35 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 18:18:35 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 18:18:35 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 18:18:35 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 18:18:35 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 18:18:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:18:36 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 18:18:36 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 18:18:36 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 18:18:36 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 18:18:36 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 18:18:36 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 18:18:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:18:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:18:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:18:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:18:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:18:36 INFO - no previously-included directories found matching 'docs/_build' 18:18:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:18:36 INFO - Running setup.py install for pep8 18:18:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:18:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:18:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:18:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:18:36 INFO - no previously-included directories found matching 'docs/_build' 18:18:36 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:18:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:18:36 INFO - Cleaning up... 18:18:36 INFO - Return code: 0 18:18:36 INFO - Running post-action listener: _resource_record_post_action 18:18:36 INFO - Running post-action listener: _start_resource_monitoring 18:18:36 INFO - Starting resource monitoring. 18:18:36 INFO - ##### 18:18:36 INFO - ##### Finished create-virtualenv step (success) 18:18:36 INFO - ##### 18:18:36 INFO - ##### 18:18:36 INFO - ##### Running install step. 18:18:36 INFO - ##### 18:18:36 INFO - Running pre-action listener: _resource_record_pre_action 18:18:36 INFO - Running main action method: install 18:18:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:18:37 INFO - Reading from file tmpfile_stdout 18:18:37 INFO - Detecting whether we're running mozinstall >=1.0... 18:18:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:18:37 INFO - Reading from file tmpfile_stdout 18:18:37 INFO - Output received: 18:18:37 INFO - Usage: mozinstall [options] installer 18:18:37 INFO - Options: 18:18:37 INFO - -h, --help show this help message and exit 18:18:37 INFO - -d DEST, --destination=DEST 18:18:37 INFO - Directory to install application into. [default: 18:18:37 INFO - "/builds/slave/test"] 18:18:37 INFO - --app=APP Application being installed. [default: firefox] 18:18:37 INFO - mkdir: /builds/slave/test/build/application 18:18:37 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'] 18:18:37 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 18:19:10 INFO - Reading from file tmpfile_stdout 18:19:10 INFO - Output received: 18:19:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:19:10 INFO - Running post-action listener: _resource_record_post_action 18:19:10 INFO - ##### 18:19:10 INFO - ##### Finished install step (success) 18:19:10 INFO - ##### 18:19:10 INFO - ##### 18:19:10 INFO - ##### Running run-tests step. 18:19:10 INFO - ##### 18:19:10 INFO - Running pre-action listener: _resource_record_pre_action 18:19:10 INFO - Running main action method: run_tests 18:19:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:19:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:19:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:19:10 INFO - 2016-05-23 18:19:10.846 screenresolution[1660:7809] starting screenresolution argv=screenresolution get 18:19:10 INFO - 2016-05-23 18:19:10.848 screenresolution[1660:7809] Display 0: 1600x1200x32@60 18:19:10 INFO - 2016-05-23 18:19:10.854 screenresolution[1661:7811] starting screenresolution argv=screenresolution list 18:19:10 INFO - Available Modes on Display 0 18:19:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:19:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:19:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:19:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:19:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:19:11 INFO - Intel Iris: 18:19:11 INFO - Chipset Model: Intel Iris 18:19:11 INFO - Type: GPU 18:19:11 INFO - Bus: Built-In 18:19:11 INFO - VRAM (Dynamic, Max): 1536 MB 18:19:11 INFO - Vendor: Intel (0x8086) 18:19:11 INFO - Device ID: 0x0a2e 18:19:11 INFO - Revision ID: 0x0009 18:19:11 INFO - Displays: 18:19:11 INFO - Display: 18:19:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:19:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:19:11 INFO - Display Serial Number: 1600x1200 60 18:19:11 INFO - Main Display: Yes 18:19:11 INFO - Mirror: Off 18:19:11 INFO - Online: Yes 18:19:11 INFO - Rotation: Supported 18:19:11 INFO - Return code: 0 18:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:19:11 INFO - Python 2.7.3 18:19:11 INFO - Return code: 0 18:19:11 INFO - grabbing minidump binary from tooltool 18:19: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')]} 18:19:11 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 0x105a8ed30>, '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 0x1058b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105a85b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:19:11 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 18:19:11 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 18:19:11 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 18:19:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:19:11 INFO - Return code: 0 18:19:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:19:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:19:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:19:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:19:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:19:12 INFO - Return code: 0 18:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0347', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0347 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Tg06xlKLnf/Render', 18:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:12 INFO - 'HOME': '/Users/cltbld', 18:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:12 INFO - 'LOGNAME': 'cltbld', 18:19:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:19:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:12 INFO - 'MOZ_NO_REMOTE': '1', 18:19:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:19:12 INFO - 'NO_EM_RESTART': '1', 18:19:12 INFO - 'PAGER': '/bin/cat', 18:19:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:12 INFO - 'PWD': '/builds/slave/test', 18:19:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:12 INFO - 'SHELL': '/bin/bash', 18:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8REpe2ORV/Listeners', 18:19:12 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 18:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:12 INFO - 'USER': 'cltbld', 18:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:12 INFO - 'XPC_FLAGS': '0x0', 18:19:12 INFO - 'XPC_SERVICE_NAME': '0', 18:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0347', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:19:12 INFO - mozversion application_buildid: 20160523172042 18:19:12 INFO - mozversion application_changeset: b3835efbf422b78ea753a5072d3e25986b7df180 18:19:12 INFO - mozversion application_display_name: Nightly 18:19:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:19:12 INFO - mozversion application_name: Firefox 18:19:12 INFO - mozversion application_remotingname: firefox 18:19:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:19:12 INFO - mozversion application_vendor: Mozilla 18:19:12 INFO - mozversion application_version: 49.0a1 18:19:12 INFO - mozversion platform_buildid: 20160523172042 18:19:12 INFO - mozversion platform_changeset: b3835efbf422b78ea753a5072d3e25986b7df180 18:19:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 18:19:12 INFO - mozversion platform_version: 49.0a1 18:19:12 INFO - using testdate: 1464052752 18:19:12 INFO - actual date: 1464052752 18:19:12 INFO - starting webserver on 'localhost:49278' 18:19:12 INFO - SUITE-START | Running 2 tests 18:19:12 INFO - TEST-START | dromaeo_css 18:19:12 INFO - operating with platform_type : mac_ 18:19:12 INFO - Initialising browser for dromaeo_css test... 18:19:12 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/tmpx2vOgP/profile http://localhost:49278/getInfo.html) 18:19:14 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 18:19:14 INFO - PROCESS | 1679 | colorDepth:24 18:19:14 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 18:19:14 INFO - PROCESS | 1679 | __metrics 18:19:14 INFO - PROCESS | 1679 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:19:14 ERROR - PROCESS | 1679 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:19:15 INFO - TEST-INFO | 1679: exit 0 18:19:15 INFO - Browser initialized. 18:19:15 INFO - Running cycle 1/1 for dromaeo_css test... 18:19:15 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/tmpx2vOgP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:19:27 INFO - PROCESS | 1681 | RSS: Main: 238993408 18:19:27 INFO - PROCESS | 1681 | 18:20:18 INFO - PROCESS | 1681 | 1464052818904 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] 18:22:28 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 18:22:30 INFO - PROCESS | 1681 | RSS: Main: 365424640 18:22:30 INFO - PROCESS | 1681 | 18:25:32 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 18:25:33 INFO - PROCESS | 1681 | RSS: Main: 374099968 18:25:33 INFO - PROCESS | 1681 | 18:28:35 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 18:28:36 INFO - PROCESS | 1681 | RSS: Main: 368926720 18:28:36 INFO - PROCESS | 1681 | 18:31:38 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 18:31:38 INFO - PROCESS | 1681 | RSS: Main: 366845952 18:31:38 INFO - PROCESS | 1681 | 18:34:42 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 18:34:42 INFO - PROCESS | 1681 | RSS: Main: 331657216 18:34:42 INFO - PROCESS | 1681 | 18:37:45 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 18:37:45 INFO - PROCESS | 1681 | RSS: Main: 378662912 18:37:45 INFO - PROCESS | 1681 | 18:37:45 INFO - PROCESS | 1681 | __start_tp_report 18:37:45 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 18:37:45 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 18:37:45 INFO - PROCESS | 1681 | |i|pagename|runs| 18:37:45 INFO - PROCESS | 1681 | |0;dojo.html;2876.3671992161726;3455.084402633301;3486.8779592714254;3496.632853550377;3501.8774342898005;5549.750261238244;5575.776968921238;5628.859278518039;5701.0878259478495;5721.799737009201;5583.302087239095;5674.460926212014;5676.971615141929;5754.5108665763355;5822.184894114827;5170.870728231786;5181.1192097343355;5189.143791274435;5235.633505654615;5236.6857988520815;4402.625776808975;5172.560332371753;5282.867928301785;5300.496452836994;5348.893022139574;4311;5296.046711591912;5324.467553244684;5346.331708536433;5375.75809088592;5114.977004599095;5118.181091025455;5158.1746920492915;5199.792008319662;5203.4796520348045;5094.4650811664615;5108.463611320795;5113.872153196163;5165.9225111623355;5249.816256431007;4554.977225113853;5551.000819852425;5577.721113944327;5587.804426845081;5648.293963254593;6068.362821903725;6101.3079194897555;6172.4136207060255;6181.165542651775;6241.032639940817;6209.968950155266;6228.906566401508;6255.968720156416;6294.527910406738;6310.684465776692;4828.517148285144;5631.465010823965;5798.768049278024;5850.385709500526;5867.237259156283;6195.287541932646;6254.2807577129215;6275.184226050585;6282.842928926722;6301.054841773771;6163.907541386883;6166.167567378437;6169.352218017133;6174.073888916699;6185.907211391833;6109.755609775648;6122.1542093545395;6212.72042758077;6221.129041934133;6243.250809902841;6104.236970378703;6135.477655820289;6143.662098584621;6194.690265486708;6204.472619827275;5564.944350556523;5803.0134877071;6168.506519478431;6180.598261113013;6222.502199824004;4838.927416088762;4890.046440944053;4948.05986862495;4954.20732682769;4962.702237865739;3724.3110024645525;4360.738355698668;4374;4398.186335527937;4443.6667249955735;5581.3302403711;5589.524890384281;5593.244911936837;5652.576056795675;5759.2512973313205;5194.831162988373;6087.208662873798;6123.801220575414;6153.8461538461;6271.592346497554;5657.6605403675085;5720.42795720433;5725.112607545837;5731.8567035824435;5746.281714785652;5359.571234301325;5378.73106344689;5408.567314614821;5420.186971954238;5426.403095659474;6190.50475961931;6201.224846894138;6265.718042687999;6319.842003949938;6331;6251.78118765841;6259;6260.718267677874;6276.811695649138;6289.371062893857;6295.779647712308;6355.078513615592;6357.396047375492;6372.681365931778;6374.426301632875;6017.187679663364;6043.8186854394435;6076.787312444043;6091.360407157362;6188;5996.130561068708;6150.2927163375;6165.810580332159;6181.443670069535;6217.316095229611;5342.679439233659;6015.608985415934;6016.849578760566;6017.308009578955;6025.487833534199;4778.37881075458;5924.46679798803;5940.762369505172;5989.460948514655;6017.217761691128;5938.61399009063;5949.672767997802;5960;5989.341172471112;6016.669083200466;6078.817635470943;6101.481374083252;6107.9921812900375;6127.303099055217;6137.478314343331;5816.098504731774;5859.794907178229;5864.413558644272;5922.437368449991;6010.849728756816 18:37:45 INFO - PROCESS | 1681 | |1;ext.html;496.31756335039313;498.4591717985983;508.34677439490264;524.7271418862192;528.6775067209002;25811.612825807606;31194.376112477737;32505.0248492545;42544.57455425445;43936;45166.77416612912;45551.77224113875;45595.08809823802;45685;45754.31368529475;9544.475053872016;9751.902480975206;9755.463449510275;9759.316847820655;9775.413475191475;8575.914240857604;9448.196903263231;11491.425428728571;11767.470463829148;11817.940910295436;11747.29516229029;11802.527898884035;11814.350210738407;11824.527018919276;11826.231294965843;8588.441751286146;11774.352410617412;11842.763144737142;11847.822282665767;11850.407479625985;11390.088792896548;11797.764044719057;11799.351035693033;11831;12031.93984030083;41716.58283417187;42259.15481690349;42270.57729422697;42273.36589951153;42392;3136.6079240094987;3180.8250546219947;3244.626867910173;3418.8803391881415;3574.1422058705984;29433.411331773244;29552.96564620228;30067;30101.397972040435;30139.849300753576;2691.125384250107;2735.849528275944;2739.643846299968;2769.75072243497;2845;518170;518435;518980;519166;519999;63844.04233936495;65325.67337163284;65365.34634653688;65397;65418.67290663516;35889.82055089708;36719;36751.4487282691;38316.61683383131;38400.61599384026;16701.49895503137;17279.790414670853;17378.13109344553;17392.739108913378;17444.47666570005;10425.791484170426;11969.700757480958;12190.2076365036;12766.553170638983;13028.609141725763;20138.39584812458;20549.794502055087;20552.794472055386;20989.89505052465;21096;7789.065312162576;7791.337736292478;7812.101608315116;7822.6088695564995;7915.604219789103;901.3870568013814;1029.25893356783;1061.2739258200313;1066.5946940162844;1149.4195431307137;29514.55728164079;30398.54402183969;30894.69105308963;31265.687343126734;32292.192695182337;1043.9373637581768;1073.9677809665723;1107.6953837694698;1148.9310641361544;1154.9191556591127;14031.368588413547;15228.7055600273;15232.93369464127;15471.91696581251;15572.766408503881;677490;682070;685109;686299;686938;61278.38721612816;62785.68607156935;62801;62824;62912.68543657344;1308.3981368570564;1354.065694670674;1430.1204759073303;1441.6251774538487;1473.2339183624456;29631.407371852874;30458.54312185275;30734.231644209074;30972.0708378749;31270.374592507567;48784;53067.46932530625;54179.45820541902;55007.17489237584;55245.723771380886;583465;598122;603851;605279;605538;23262;26069.869650651624;26268.343291417867;26706.866465667546;27793.888244469996;46805;46962.530374695816;46985.76507117443;47212;47668.52331476779;33448;38747.41878871763;38752;39379.6062039376;39453;31085.844570777;37007.88976330715;37453.6254637461;38571.64999225014;38688.83933482;37101.628983709816;37591.81204093962;37690.05774855651;38743.418848717854;38745.6125438742;32785.836070819496;32834.34331313408;33293.50059749154;33749.83125084359;33804;24737.752622473545;24807.62788558137;24992.750072499042;25608;25617.871910640326 18:37:45 INFO - PROCESS | 1681 | |2;jquery.html;383.34448093759676;385.49307660426535;386.67712460095834;397.7911596411882;401.5402364292885;15410.845891541081;15563;16153.757693634576;22462.55074898503;22571.871406429695;20447.693284600744;21595.02822372993;21840.017199226033;21889.452763680914;22323.330300091024;5316.521513063843;5748.856278593027;5944;5996.400359964013;6020;5742.942570574282;5877.118432235156;5914.172015917775;5984.281886173665;6017.368176341487;5091.7708703108265;5652.406497317764;5693.918155550453;5836.270466191726;5857.619254748449;5763.625364351304;5793.739281732311;5805.912911306334;5813.331466881321;5845.941540584582;4216.7680777557225;4979.45226025137;5058.519440653132;5092.8472145835685;5097.209932460438;23452.82735863217;24966.875165624057;25909.352266193306;25952.740472595222;26635;1877.1083735225795;2033.0647901965115;2071.2854065347433;2129.744430668314;2193.473566344082;9477.28920330978;9857.211423086208;9963.252756043326;10096;10227.744306392326;1733.7659415978808;1736.1926704082662;1738.721804511272;1740.747591599211;1768.239656947512;119100;120317;122459;123191.3840430792;125534.37232813778;11565.76868462626;11579.247348922294;11590.826137607943;11652.592159274385;11765.764684706259;14670.339834707464;14727.558173254605;14741.92629036848;14756.704865902837;14762.040467369587;7040.859182816315;7169.89245161323;8358.289545388589;8784;8808.427452215585;8492.575371231414;8614.009388920229;8654.567271636393;8655.567221638892;8754.255888249443;4426.712263702913;4524.185646583646;4534.750588717587;4576.565226303496;4633.9536604634195;1879.9624007519774;1931.3433432632971;1933.6132773445368;1935.2259096361568;1937.3316205908939;859.7721603775004;950.8383574792302;954.289054654287;971.062924278075;989.4014121456415;19854.305099321453;20223.696644550342;20231.898840505703;20407;20575.691364629543;896.3546246702364;924.778053267211;928.5078908178676;934.9953250233706;988.2242439684813;9603.37578057424;10621.415822129857;10758.94620526908;10780.353178809297;10833.349999000086;610648;614893;624984;629040;634774;72574.9113763294;133291;133717;134504;138806;830.9916900831035;921.9723408297762;935.8081593273363;940.2806852757645;943.165298710646;16287.67424651524;18438.89366638006;18465.446036618923;18517.629647407244;18526.981016044247;50000;52992.73503632457;53693.463065368844;54136;54512;58138.4186158133;78101;78760.60619696864;80100.59949700214;80437.3912521758;6843.110395648733;7327.890081648886;7348.853022939618;7388.076490438695;8039.15588863161;8974.538053238626;12346.444410001752;12452.128351015343;12532.185407948455;12710.173838700455;10488.160947124059;10615.363078215332;10725.463726813472;10754;10818.67543973682;9983.301168918106;10038.29731918788;10257.230707696803;10404.947975260075;10564.313319634506;10624.521896514521;11028.22802403824;11082.944585277022;11194.44027798623;11356.602518912128;6832;6994.601079784165;7295.015172951584;7340.853182936418;7439.665215065444;10019.749506262402;10682.465876705977;10952.835707464235;11202.49588768491;11255.549778008788 18:37:45 INFO - PROCESS | 1681 | |3;mootools.html;1406.6061502779226;1553.9976715018813;1580.2177921928646;1806.6657668331359;1955.4915721912307;7873.685052597896;8637.438566493181;8889.822203555925;8901.287896968252;8991.955040224806;8616;8865.379423440361;8872.068432814542;8877;8879.556022198896;2346.530693861226;2351.7177938647387;2359.1035406545575;2383.2373640435067;2384.642303654457;2343.5195784864063;2345.57779599672;2372;2373.074500944633;2380.214529205358;2345.718513778349;2348.6829278047503;2356.6229403295474;2371.5731168389684;2375.3111597636666;2302.861828290308;2309.3533810533077;2323.372689373868;2334.708161479815;2335.859848409101;2077.4287071055583;2158;2347.3427440316736;2349.929502114939;2351.7883390494767;8715.041345451995;8790.648374065093;8793.472391656522;8867.246284065863;8999.955000225022;5318.574514038867;5459.754311055973;5471.671699697991;5477.890442191134;5486.259354987065;3425.2122011937395;3453.1511503010115;3525.1363415962996;3586.7309951753723;3596.7662101963288;1019.1184625299137;1032.3599368391644;1035.8756949166147;1040.5681642118511;1041.9374837509774;11615.709607259887;11682.766344673144;11735.7066073348;11737.647870563897;11744.471498782576;4541.636669066466;4552.521985191574;4650.232711602615;4654.510556621916;4670.883227919329;9269.490178040138;9321.114494123047;9328.300377471582;9331.300152488591;9343.859842102374;5242.370915490165;5243.161094224905;5251.343582052244;5254.106801843619;5255.947440525546;4124.443200167939;4353.521112677603;4436.511983681792;4576.084783043405;4778.1399348116975;3797.2595343907674;3821.407681809324;3846.788426636506;3872.4384964180035;3897.2205558888336;1125.634168895096;1159.6231224851956;1164.889335513125;1165.091228841505;1171.0748508678219;835.8704400817883;845.8054647431126;887.1793590928495;891.9732408027639;950.5152372290044;5646.322441307069;5802.100674395476;5804.216430781875;5857.970710146507;5908.497777688859;938.554186761283;946.1059298962415;950.4202436513721;952.4428209497383;1004.3070281505884;3798.8860334190017;3885.630865067814;3890.105275786528;3974.7217694761666;4033.4756481657782;10463.895361046292;10499.68500944973;10847.783044339069;10871.239013268996;10886.891131088747;10464.319819211727;10669.09312708413;10800.51397687106;10820.13438924884;10837.67486975392;8068.596570171468;8238.382121341043;8250.917490825135;8255.133211012877;8265.338772898274;5624.381318054929;5642.858928526655;5735.827925162252;5751.33859606134;5765.019946609171;9298.860517092384;9945.751356216153;10192.337498062601;10207.315792894386;10215.693529194135;10052.447115408722;10109.19126469866;10187.388756674623;10438.37369757817;10477.214208934209;7037.788866334018;7049.647517624201;7091.290870912868;7104.609246491286;7199.316064973819;8204.138565450541;8246.505209687439;8306.04480484752;8400.747977560683;8411.285040771358;7490.962545187239;7501.174870764321;7561.962190189014;7578.848423031398;7677.385809135368;7330.816729581803;7350;7362.595057271901;7512.361449276654;7513.39892808563;6737.764178253738;7052.471064670068;7076.646167691491;7085.078939737801;7115.288471152843;3967.9088250730124;4167.541570427311;4852.684575502581;4900.99529596439;4949.554540091409;4868.075065737498;4978.950210497848;5021.171506701354;5028.069807085701;5200.115980283436 18:37:45 INFO - PROCESS | 1681 | |4;prototype.html;302.13137230462405;312.7451127331225;320.25061356425965;323.0164150162754;323.6795572383341;4843.927341089887;5048.444671086182;5074.670146440479;5432.538234250089;5491.176323551469;5213.244079608455;5377.897531006144;5410.675359478434;5431.891362172754;5470.91793623096;3647.55613738795;3818.5417749869916;4117.238310912476;4141.192467468844;4184.4351012613215;3311.2218628622254;3566.8216589170565;3757.5678796938428;3791.9620803792022;4156.97921510392;3176.3806057818733;3572.374834403969;3931.7051221158385;4083.5916408359076;4111.259973204822;3772.7549908530254;3948.4472173895533;4127.174565086994;4130.876073717794;4162.167566486714;3133.341998180385;3587.3542762302777;3598.1004748812798;3599.9820000899826;3602.837872295753;4539.86380408585;4748.264019077049;5197.272381866542;5206.193040078794;5255.106631872591;4603.447586289633;4616.515265897099;4626.58360747531;4642.767861606906;4644.326572646981;5020.874478138076;5647.802326918538;5655.745491452894;5658.97244973937;5709.685967271798;3574.571051473814;3622.6558476944647;3719.716697739276;3725.6833169180654;3760.567534733513;4777.976110119462;5838.678872662002;5973.910391344133;5987.850303742442;6046.999999999956;3575.874844380486;3661.176235347079;3889.649931506178;3899.2786334528205;3915;3312.585926759155;3551.4140166872694;3718.851245950186;3991.5409727881665;4020.8592699255387;3963.9405408918888;4613.5386461354265;4651.558101980307;4660.718302466848;4660.906781864343;5547.140193270049;5772.826815195551;5777.393373695785;5817.505512031525;5821.835632873443;1268.6194141757621;1313.8423389193358;1333.553259658006;1388.6389538720005;1414.6321956291233;778.5678948183812;805.9844595809323;831.4470876866936;862.5730263519473;882.2179198019963;3942.172143849766;4225.725327853741;4311.8490852820005;4380.956190438119;4429.53489883564;4478.350639157369;4564.520725323793;4624.722516649012;4738.147133515931;4935.259711043373;3967.484227050465;4093.8771836844944;4243.681723870722;4427.8524915416;4458.598726114666;2343.8124950003958;2399.7720216579396;2596.3898483856656;2635.4070334174658;2648.615950687178;4861.9027619447415;4868.050730107666;5055.53389517036;5121.0014047261175;5159.0911362796005;4552.248878934939;4579.702319349231;5050.49495050492;5065.898682026413;5108.438071812097;5124.820631277962;5566.693831839288;6174.567780255339;6178.41305076017;6226.533010024175;5452.863678407913;5634.873025394855;5908.497777688945;5952.523798096228;5970.373110823301;5179.378474583074;5346.518813306821;5453.727313634382;5575.860603484945;5889.734961926809;5061.051495174458;5464.644798088111;5972.432618901197;6170.773020480985;6262.718177681924;3883.650471457582;3909.1986142840083;3934.822932967966;3935.6654684351006;3963.8216280268034;5142.93995072931;5202.375714914235;5687.317521897398;5797.159411885337;5938.476147637323;5284.176958949156;5512.310961129859;5555.0278701227935;5774.855628609318;5780.884382312414;5031.974840125776;5065.4428012917815;5696.430356964271;5760.567957403127;5772.595918285848;5293.497117773805;5592.350256674372;5598.1322894951345;5637.46444087811;5680.630759000652;4626.190416677001;5373.668325638129;5572.662560985254;5676.545876329801;5684.403137670485;4456.977715111404;5274.498922602346;5613.859653508532;5620.437956204347;5634.2393776841245 18:37:45 INFO - PROCESS | 1681 | |5;yui.html;647.2330288608;725.4740313272874;737.9852402951941;755.1806290175159;786.449485360248;15333.769993450107;15513.767293490593;15745.134017629025;15755.527334179978;15800.84199158008;14014.299285035759;14029.017968742191;14490.927545362285;14508.347124379401;14534.418623255056;710.4777988178685;899.2895612466144;915.4324318922244;916.7158180963922;918.8897332320131;727.2327694282529;884.1291328041874;911.6353458616541;921.3550514639745;923.7967647117623;943.7310366545536;971.6016433262364;973.2846357926918;975.2880397309968;976.8632391465201;741.9443541734391;924.6994726713777;968.0948313327027;969.0842154164283;969.5636963366443;673.0476375928105;697.4873468001015;699.6257002503636;705.5801797930224;711.2141084102096;15519.767203491956;15526.456574019856;15528.14595197275;15535.45625903088;15560.844391556053;12445.688857778538;12499;12610.432530536147;12671.936640316831;12692.238465692088;11005.17461190414;11017.228793984345;11017.283876548;11138.944305278503;11547.480363383584;4022.1955608878343;4040.5151381834366;4056.3104272273786;4171.228322760299;4185;90676.54661726649;91008;93479;93779.59330610046;94533.52733236359;7541.208173141741;8607.397482176313;9171.908280917105;9255.629774809067;9423.199028082552;18416;21435.78564214369;22254.77745222527;22347.441313966956;22388.104475820786;6208.2239720034995;7175.17485489165;7454.478186527;7498.3626391756225;7547.05661792276;3966.6628336591134;3983.880483585497;3988.8205030773684;4038.2932986727205;4267.594578515036;3346.715529179998;3382.424987752088;3463.7748546344405;3476.687098161178;3478.6869181773764;619.8202521268872;621.3227581935712;623.7442648514087;631.6052467207995;632.7437387857925;476.6734786671141;515.2065818639328;547.7124509632475;574.4399210769467;575.171752676145;7825.452218344662;7887.763367098996;7970.960145199237;8033.357331413473;8087.47431416956;547.8411260734422;552.1717423864204;559.083103709916;564.5779605492392;571.7741492110594;3612.151144481045;3657.2868290683596;3667.94498082529;3674.338619048597;3695.648913353281;94228.52885735665;94655;98373;100934;102199;86726.56636716776;87297;87410.56294718485;88414.67377989335;89749;68126;68147;75265;76524.61737691276;77221;7341.449391295781;7346.889796652948;7352.852942941218;7443.81390465221;7535.246475352421;46400.53599463962;46853.2972005427;46856.908601881536;48082.75958620184;48646;66803;67220.99168512574;67591.66204168947;68211;68888.65555672189;9954.665187162382;10003.399796012263;10015.449150296512;10047.849282260626;10180.592776288866;10585.735356615838;10664.733381665521;10818.080463160404;10859.457027148768;10870.293430926964;9919.355241909252;9983.301168918106;9987.300888937705;10139.986001400097;10149.035841595036;9758.658446954323;9964;9981.650642227487;10077.697669069647;10258.179345652181;9740.366876153028;9794;10011.222224993084;10584.364938103738;10829.29609575375;8477.95761021191;8493;8723.738287851374;8757.91242087571;8891.777705557413;8409.789755256168;8522.147785221428;8533.061363249913;8557.229849313591;8640.351973601879 18:37:45 INFO - PROCESS | 1681 | __end_tp_report 18:37:45 INFO - PROCESS | 1681 | __start_cc_report 18:37:45 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1246 18:37:45 INFO - PROCESS | 1681 | __end_cc_report 18:37:45 INFO - PROCESS | 1681 | __startTimestamp1464053865757__endTimestamp 18:37:45 INFO - PROCESS | 1681 | 18:37:45 INFO - PROCESS | 1681 | ------- Summary: start ------- 18:37:45 INFO - PROCESS | 1681 | Number of tests: 6 18:37:45 INFO - PROCESS | 1681 | 18:37:45 INFO - PROCESS | 1681 | [#0] dojo.html Cycles:165 Average:5672.26 Median:5867.24 stddev:666.16 (11.4%) stddev-sans-first:631.07 18:37:45 INFO - PROCESS | 1681 | Values: 2876.4 3455.1 3486.9 3496.6 3501.9 5549.8 5575.8 5628.9 5701.1 5721.8 5583.3 5674.5 5677.0 5754.5 5822.2 5170.9 5181.1 5189.1 5235.6 5236.7 4402.6 5172.6 5282.9 5300.5 5348.9 4311.0 5296.0 5324.5 5346.3 5375.8 5115.0 5118.2 5158.2 5199.8 5203.5 5094.5 5108.5 5113.9 5165.9 5249.8 4555.0 5551.0 5577.7 5587.8 5648.3 6068.4 6101.3 6172.4 6181.2 6241.0 6210.0 6228.9 6256.0 6294.5 6310.7 4828.5 5631.5 5798.8 5850.4 5867.2 6195.3 6254.3 6275.2 6282.8 6301.1 6163.9 6166.2 6169.4 6174.1 6185.9 6109.8 6122.2 6212.7 6221.1 6243.3 6104.2 6135.5 6143.7 6194.7 6204.5 5564.9 5803.0 6168.5 6180.6 6222.5 4838.9 4890.0 4948.1 4954.2 4962.7 3724.3 4360.7 4374.0 4398.2 4443.7 5581.3 5589.5 5593.2 5652.6 5759.3 5194.8 6087.2 6123.8 6153.8 6271.6 5657.7 5720.4 5725.1 5731.9 5746.3 5359.6 5378.7 5408.6 5420.2 5426.4 6190.5 6201.2 6265.7 6319.8 6331.0 6251.8 6259.0 6260.7 6276.8 6289.4 6295.8 6355.1 6357.4 6372.7 6374.4 6017.2 6043.8 6076.8 6091.4 6188.0 5996.1 6150.3 6165.8 6181.4 6217.3 5342.7 6015.6 6016.8 6017.3 6025.5 4778.4 5924.5 5940.8 5989.5 6017.2 5938.6 5949.7 5960.0 5989.3 6016.7 6078.8 6101.5 6108.0 6127.3 6137.5 5816.1 5859.8 5864.4 5922.4 6010.8 18:37:45 INFO - PROCESS | 1681 | 18:37:45 INFO - PROCESS | 1681 | [#1] ext.html Cycles:180 Average:72676.12 Median:33370.75 stddev:161746.69 (484.7%) stddev-sans-first:162109.64 18:37:45 INFO - PROCESS | 1681 | Values: 496.3 498.5 508.3 524.7 528.7 25811.6 31194.4 32505.0 42544.6 43936.0 45166.8 45551.8 45595.1 45685.0 45754.3 9544.5 9751.9 9755.5 9759.3 9775.4 8575.9 9448.2 11491.4 11767.5 11817.9 11747.3 11802.5 11814.4 11824.5 11826.2 8588.4 11774.4 11842.8 11847.8 11850.4 11390.1 11797.8 11799.4 11831.0 12031.9 41716.6 42259.2 42270.6 42273.4 42392.0 3136.6 3180.8 3244.6 3418.9 3574.1 29433.4 29553.0 30067.0 30101.4 30139.8 2691.1 2735.8 2739.6 2769.8 2845.0 518170.0 518435.0 518980.0 519166.0 519999.0 63844.0 65325.7 65365.3 65397.0 65418.7 35889.8 36719.0 36751.4 38316.6 38400.6 16701.5 17279.8 17378.1 17392.7 17444.5 10425.8 11969.7 12190.2 12766.6 13028.6 20138.4 20549.8 20552.8 20989.9 21096.0 7789.1 7791.3 7812.1 7822.6 7915.6 901.4 1029.3 1061.3 1066.6 1149.4 29514.6 30398.5 30894.7 31265.7 32292.2 1043.9 1074.0 1107.7 1148.9 1154.9 14031.4 15228.7 15232.9 15471.9 15572.8 677490.0 682070.0 685109.0 686299.0 686938.0 61278.4 62785.7 62801.0 62824.0 62912.7 1308.4 1354.1 1430.1 1441.6 1473.2 29631.4 30458.5 30734.2 30972.1 31270.4 48784.0 53067.5 54179.5 55007.2 55245.7 583465.0 598122.0 603851.0 605279.0 605538.0 23262.0 26069.9 26268.3 26706.9 27793.9 46805.0 46962.5 46985.8 47212.0 47668.5 33448.0 38747.4 38752.0 39379.6 39453.0 31085.8 37007.9 37453.6 38571.6 38688.8 37101.6 37591.8 37690.1 38743.4 38745.6 32785.8 32834.3 33293.5 33749.8 33804.0 24737.8 24807.6 24992.8 25608.0 25617.9 18:37:45 INFO - PROCESS | 1681 | 18:37:45 INFO - PROCESS | 1681 | [#2] jquery.html Cycles:180 Average:35378.39 Median:392.23 stddev:103822.49 (26469.5%) stddev-sans-first:104080.49 18:37:45 INFO - PROCESS | 1681 | Values: 383.3 385.5 386.7 397.8 401.5 15410.8 15563.0 16153.8 22462.6 22571.9 20447.7 21595.0 21840.0 21889.5 22323.3 5316.5 5748.9 5944.0 5996.4 6020.0 5742.9 5877.1 5914.2 5984.3 6017.4 5091.8 5652.4 5693.9 5836.3 5857.6 5763.6 5793.7 5805.9 5813.3 5845.9 4216.8 4979.5 5058.5 5092.8 5097.2 23452.8 24966.9 25909.4 25952.7 26635.0 1877.1 2033.1 2071.3 2129.7 2193.5 9477.3 9857.2 9963.3 10096.0 10227.7 1733.8 1736.2 1738.7 1740.7 1768.2 119100.0 120317.0 122459.0 123191.4 125534.4 11565.8 11579.2 11590.8 11652.6 11765.8 14670.3 14727.6 14741.9 14756.7 14762.0 7040.9 7169.9 8358.3 8784.0 8808.4 8492.6 8614.0 8654.6 8655.6 8754.3 4426.7 4524.2 4534.8 4576.6 4634.0 1880.0 1931.3 1933.6 1935.2 1937.3 859.8 950.8 954.3 971.1 989.4 19854.3 20223.7 20231.9 20407.0 20575.7 896.4 924.8 928.5 935.0 988.2 9603.4 10621.4 10758.9 10780.4 10833.3 610648.0 614893.0 624984.0 629040.0 634774.0 72574.9 133291.0 133717.0 134504.0 138806.0 831.0 922.0 935.8 940.3 943.2 16287.7 18438.9 18465.4 18517.6 18527.0 50000.0 52992.7 53693.5 54136.0 54512.0 58138.4 78101.0 78760.6 80100.6 80437.4 6843.1 7327.9 7348.9 7388.1 8039.2 8974.5 12346.4 12452.1 12532.2 12710.2 10488.2 10615.4 10725.5 10754.0 10818.7 9983.3 10038.3 10257.2 10404.9 10564.3 10624.5 11028.2 11082.9 11194.4 11356.6 6832.0 6994.6 7295.0 7340.9 7439.7 10019.7 10682.5 10952.8 11202.5 11255.5 18:37:45 INFO - PROCESS | 1681 | 18:37:45 INFO - PROCESS | 1681 | [#3] mootools.html Cycles:180 Average:5669.51 Median:4815.41 stddev:3206.00 (66.6%) stddev-sans-first:3198.99 18:37:45 INFO - PROCESS | 1681 | Values: 1406.6 1554.0 1580.2 1806.7 1955.5 7873.7 8637.4 8889.8 8901.3 8992.0 8616.0 8865.4 8872.1 8877.0 8879.6 2346.5 2351.7 2359.1 2383.2 2384.6 2343.5 2345.6 2372.0 2373.1 2380.2 2345.7 2348.7 2356.6 2371.6 2375.3 2302.9 2309.4 2323.4 2334.7 2335.9 2077.4 2158.0 2347.3 2349.9 2351.8 8715.0 8790.6 8793.5 8867.2 9000.0 5318.6 5459.8 5471.7 5477.9 5486.3 3425.2 3453.2 3525.1 3586.7 3596.8 1019.1 1032.4 1035.9 1040.6 1041.9 11615.7 11682.8 11735.7 11737.6 11744.5 4541.6 4552.5 4650.2 4654.5 4670.9 9269.5 9321.1 9328.3 9331.3 9343.9 5242.4 5243.2 5251.3 5254.1 5255.9 4124.4 4353.5 4436.5 4576.1 4778.1 3797.3 3821.4 3846.8 3872.4 3897.2 1125.6 1159.6 1164.9 1165.1 1171.1 835.9 845.8 887.2 892.0 950.5 5646.3 5802.1 5804.2 5858.0 5908.5 938.6 946.1 950.4 952.4 1004.3 3798.9 3885.6 3890.1 3974.7 4033.5 10463.9 10499.7 10847.8 10871.2 10886.9 10464.3 10669.1 10800.5 10820.1 10837.7 8068.6 8238.4 8250.9 8255.1 8265.3 5624.4 5642.9 5735.8 5751.3 5765.0 9298.9 9945.8 10192.3 10207.3 10215.7 10052.4 10109.2 10187.4 10438.4 10477.2 7037.8 7049.6 7091.3 7104.6 7199.3 8204.1 8246.5 8306.0 8400.7 8411.3 7491.0 7501.2 7562.0 7578.8 7677.4 7330.8 7350.0 7362.6 7512.4 7513.4 6737.8 7052.5 7076.6 7085.1 7115.3 3967.9 4167.5 4852.7 4901.0 4949.6 4868.1 4979.0 5021.2 5028.1 5200.1 18:37:45 INFO - PROCESS | 1681 | 18:37:45 INFO - PROCESS | 1681 | [#4] prototype.html Cycles:180 Average:4435.09 Median:4810.95 stddev:1395.92 (29.0%) stddev-sans-first:1364.93 18:37:45 INFO - PROCESS | 1681 | Values: 302.1 312.7 320.3 323.0 323.7 4843.9 5048.4 5074.7 5432.5 5491.2 5213.2 5377.9 5410.7 5431.9 5470.9 3647.6 3818.5 4117.2 4141.2 4184.4 3311.2 3566.8 3757.6 3792.0 4157.0 3176.4 3572.4 3931.7 4083.6 4111.3 3772.8 3948.4 4127.2 4130.9 4162.2 3133.3 3587.4 3598.1 3600.0 3602.8 4539.9 4748.3 5197.3 5206.2 5255.1 4603.4 4616.5 4626.6 4642.8 4644.3 5020.9 5647.8 5655.7 5659.0 5709.7 3574.6 3622.7 3719.7 3725.7 3760.6 4778.0 5838.7 5973.9 5987.9 6047.0 3575.9 3661.2 3889.6 3899.3 3915.0 3312.6 3551.4 3718.9 3991.5 4020.9 3963.9 4613.5 4651.6 4660.7 4660.9 5547.1 5772.8 5777.4 5817.5 5821.8 1268.6 1313.8 1333.6 1388.6 1414.6 778.6 806.0 831.4 862.6 882.2 3942.2 4225.7 4311.8 4381.0 4429.5 4478.4 4564.5 4624.7 4738.1 4935.3 3967.5 4093.9 4243.7 4427.9 4458.6 2343.8 2399.8 2596.4 2635.4 2648.6 4861.9 4868.1 5055.5 5121.0 5159.1 4552.2 4579.7 5050.5 5065.9 5108.4 5124.8 5566.7 6174.6 6178.4 6226.5 5452.9 5634.9 5908.5 5952.5 5970.4 5179.4 5346.5 5453.7 5575.9 5889.7 5061.1 5464.6 5972.4 6170.8 6262.7 3883.7 3909.2 3934.8 3935.7 3963.8 5142.9 5202.4 5687.3 5797.2 5938.5 5284.2 5512.3 5555.0 5774.9 5780.9 5032.0 5065.4 5696.4 5760.6 5772.6 5293.5 5592.4 5598.1 5637.5 5680.6 4626.2 5373.7 5572.7 5676.5 5684.4 4457.0 5274.5 5613.9 5620.4 5634.2 18:37:45 INFO - PROCESS | 1681 | 18:37:45 INFO - PROCESS | 1681 | [#5] yui.html Cycles:180 Average:18861.96 Median:67012.00 stddev:27840.73 (41.5%) stddev-sans-first:27885.24 18:37:45 INFO - PROCESS | 1681 | Values: 647.2 725.5 738.0 755.2 786.4 15333.8 15513.8 15745.1 15755.5 15800.8 14014.3 14029.0 14490.9 14508.3 14534.4 710.5 899.3 915.4 916.7 918.9 727.2 884.1 911.6 921.4 923.8 943.7 971.6 973.3 975.3 976.9 741.9 924.7 968.1 969.1 969.6 673.0 697.5 699.6 705.6 711.2 15519.8 15526.5 15528.1 15535.5 15560.8 12445.7 12499.0 12610.4 12671.9 12692.2 11005.2 11017.2 11017.3 11138.9 11547.5 4022.2 4040.5 4056.3 4171.2 4185.0 90676.5 91008.0 93479.0 93779.6 94533.5 7541.2 8607.4 9171.9 9255.6 9423.2 18416.0 21435.8 22254.8 22347.4 22388.1 6208.2 7175.2 7454.5 7498.4 7547.1 3966.7 3983.9 3988.8 4038.3 4267.6 3346.7 3382.4 3463.8 3476.7 3478.7 619.8 621.3 623.7 631.6 632.7 476.7 515.2 547.7 574.4 575.2 7825.5 7887.8 7971.0 8033.4 8087.5 547.8 552.2 559.1 564.6 571.8 3612.2 3657.3 3667.9 3674.3 3695.6 94228.5 94655.0 98373.0 100934.0 102199.0 86726.6 87297.0 87410.6 88414.7 89749.0 68126.0 68147.0 75265.0 76524.6 77221.0 7341.4 7346.9 7352.9 7443.8 7535.2 46400.5 46853.3 46856.9 48082.8 48646.0 66803.0 67221.0 67591.7 68211.0 68888.7 9954.7 10003.4 10015.4 10047.8 10180.6 10585.7 10664.7 10818.1 10859.5 10870.3 9919.4 9983.3 9987.3 10140.0 10149.0 9758.7 9964.0 9981.7 10077.7 10258.2 9740.4 9794.0 10011.2 10584.4 10829.3 8478.0 8493.0 8723.7 8757.9 8891.8 8409.8 8522.1 8533.1 8557.2 8640.4 18:37:45 INFO - PROCESS | 1681 | -------- Summary: end -------- 18:37:45 INFO - PROCESS | 1681 | 18:37:46 INFO - TEST-INFO | 1681: exit 0 18:37:46 INFO - TEST-OK | dromaeo_css | took 1113797ms 18:37:46 INFO - TEST-START | kraken 18:37:46 INFO - operating with platform_type : mac_ 18:37:46 INFO - Initialising browser for kraken test... 18:37:46 INFO - TEST-INFO | started process 1689 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/tmpRUUR79/profile http://localhost:49278/getInfo.html) 18:37:48 INFO - PROCESS | 1689 | __metrics Screen width/height:1600/1200 18:37:48 INFO - PROCESS | 1689 | colorDepth:24 18:37:48 INFO - PROCESS | 1689 | Browser inner width/height: 1024/689 18:37:48 INFO - PROCESS | 1689 | __metrics 18:37:48 INFO - PROCESS | 1689 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:37:48 ERROR - PROCESS | 1689 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:37:49 INFO - TEST-INFO | 1689: exit 0 18:37:49 INFO - Browser initialized. 18:37:49 INFO - Running cycle 1/1 for kraken test... 18:37:49 INFO - TEST-INFO | started process 1691 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/tmpRUUR79/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:38:01 INFO - PROCESS | 1691 | RSS: Main: 239185920 18:38:01 INFO - PROCESS | 1691 | 18:38:04 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 18:38:05 INFO - PROCESS | 1691 | RSS: Main: 286965760 18:38:05 INFO - PROCESS | 1691 | 18:38:07 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 18:38:08 INFO - PROCESS | 1691 | RSS: Main: 296001536 18:38:08 INFO - PROCESS | 1691 | 18:38:12 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 18:38:12 INFO - PROCESS | 1691 | RSS: Main: 385789952 18:38:12 INFO - PROCESS | 1691 | 18:38:14 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 18:38:15 INFO - PROCESS | 1691 | RSS: Main: 285241344 18:38:15 INFO - PROCESS | 1691 | 18:38:17 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:38:18 INFO - PROCESS | 1691 | RSS: Main: 289398784 18:38:18 INFO - PROCESS | 1691 | 18:38:21 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 18:38:21 INFO - PROCESS | 1691 | RSS: Main: 328036352 18:38:21 INFO - PROCESS | 1691 | 18:38:24 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 18:38:25 INFO - PROCESS | 1691 | RSS: Main: 329723904 18:38:25 INFO - PROCESS | 1691 | 18:38:28 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 18:38:29 INFO - PROCESS | 1691 | RSS: Main: 329121792 18:38:29 INFO - PROCESS | 1691 | 18:38:30 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:38:31 INFO - PROCESS | 1691 | RSS: Main: 315277312 18:38:31 INFO - PROCESS | 1691 | 18:38:33 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:38:34 INFO - PROCESS | 1691 | RSS: Main: 322129920 18:38:34 INFO - PROCESS | 1691 | 18:38:36 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:38:37 INFO - PROCESS | 1691 | RSS: Main: 324939776 18:38:37 INFO - PROCESS | 1691 | 18:38:39 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:38:40 INFO - PROCESS | 1691 | RSS: Main: 330891264 18:38:40 INFO - PROCESS | 1691 | 18:38:43 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:38:44 INFO - PROCESS | 1691 | RSS: Main: 328531968 18:38:44 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 18:38:46 INFO - PROCESS | 1691 | RSS: Main: 375222272 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | __start_tp_report 18:38:46 INFO - PROCESS | 1691 | _x_x_mozilla_page_load 18:38:46 INFO - PROCESS | 1691 | _x_x_mozilla_page_load_details 18:38:46 INFO - PROCESS | 1691 | |i|pagename|runs| 18:38:46 INFO - PROCESS | 1691 | |0;ai-astar;112;106;110;112;113;111;113;109;160;164 18:38:46 INFO - PROCESS | 1691 | |1;audio-beat-detection;154;116;117;199;117;119;138;117;150;116 18:38:46 INFO - PROCESS | 1691 | |2;audio-dft;183;185;182;185;187;183;180;183;181;184 18:38:46 INFO - PROCESS | 1691 | |3;audio-fft;78;58;62;124;57;56;58;55;95;56 18:38:46 INFO - PROCESS | 1691 | |4;audio-oscillator;95;76;139;79;79;75;101;79;76;115 18:38:46 INFO - PROCESS | 1691 | |5;imaging-gaussian-blur;86;85;87;84;86;83;90;85;87;84 18:38:46 INFO - PROCESS | 1691 | |6;imaging-darkroom;92;92;108;150;92;93;93;92;92;93 18:38:46 INFO - PROCESS | 1691 | |7;imaging-desaturate;85;85;86;84;86;85;85;86;85;84 18:38:46 INFO - PROCESS | 1691 | |8;json-parse-financial;57;58;59;58;58;60;58;119;55;55 18:38:46 INFO - PROCESS | 1691 | |9;json-stringify-tinderbox;65;69;77;76;72;69;70;71;71;73 18:38:46 INFO - PROCESS | 1691 | |10;stanford-crypto-aes;83;86;88;84;85;84;79;86;85;85 18:38:46 INFO - PROCESS | 1691 | |11;stanford-crypto-ccm;138;137;137;136;146;144;138;135;212;142 18:38:46 INFO - PROCESS | 1691 | |12;stanford-crypto-pbkdf2;166;165;165;165;163;171;224;202;162;165 18:38:46 INFO - PROCESS | 1691 | |13;stanford-crypto-sha256-iterative;66;65;67;66;67;65;65;70;81;84 18:38:46 INFO - PROCESS | 1691 | __end_tp_report 18:38:46 INFO - PROCESS | 1691 | __start_cc_report 18:38:46 INFO - PROCESS | 1691 | _x_x_mozilla_cycle_collect,2268 18:38:46 INFO - PROCESS | 1691 | __end_cc_report 18:38:46 INFO - PROCESS | 1691 | __startTimestamp1464053926260__endTimestamp 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | ------- Summary: start ------- 18:38:46 INFO - PROCESS | 1691 | Number of tests: 14 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#0] ai-astar Cycles:10 Average:121.00 Median:112.50 stddev:21.73 (19.3%) stddev-sans-first:22.80 18:38:46 INFO - PROCESS | 1691 | Values: 112.0 106.0 110.0 112.0 113.0 111.0 113.0 109.0 160.0 164.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#1] audio-beat-detection Cycles:10 Average:134.30 Median:128.50 stddev:27.15 (21.1%) stddev-sans-first:27.85 18:38:46 INFO - PROCESS | 1691 | Values: 154.0 116.0 117.0 199.0 117.0 119.0 138.0 117.0 150.0 116.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:2.06 (1.1%) stddev-sans-first:2.18 18:38:46 INFO - PROCESS | 1691 | Values: 183.0 185.0 182.0 185.0 187.0 183.0 180.0 183.0 181.0 184.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#3] audio-fft Cycles:10 Average:69.90 Median:58.00 stddev:22.96 (39.6%) stddev-sans-first:24.16 18:38:46 INFO - PROCESS | 1691 | Values: 78.0 58.0 62.0 124.0 57.0 56.0 58.0 55.0 95.0 56.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:77.50 stddev:21.43 (27.6%) stddev-sans-first:22.69 18:38:46 INFO - PROCESS | 1691 | Values: 95.0 76.0 139.0 79.0 79.0 75.0 101.0 79.0 76.0 115.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.00 stddev:2.00 (2.3%) stddev-sans-first:2.12 18:38:46 INFO - PROCESS | 1691 | Values: 86.0 85.0 87.0 84.0 86.0 83.0 90.0 85.0 87.0 84.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:92.00 stddev:18.35 (19.9%) stddev-sans-first:19.25 18:38:46 INFO - PROCESS | 1691 | Values: 92.0 92.0 108.0 150.0 92.0 93.0 93.0 92.0 92.0 93.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:0.74 (0.9%) stddev-sans-first:0.78 18:38:46 INFO - PROCESS | 1691 | Values: 85.0 85.0 86.0 84.0 86.0 85.0 85.0 86.0 85.0 84.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:58.00 stddev:19.49 (33.6%) stddev-sans-first:20.53 18:38:46 INFO - PROCESS | 1691 | Values: 57.0 58.0 59.0 58.0 58.0 60.0 58.0 119.0 55.0 55.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.50 stddev:3.50 (4.9%) stddev-sans-first:2.87 18:38:46 INFO - PROCESS | 1691 | Values: 65.0 69.0 77.0 76.0 72.0 69.0 70.0 71.0 71.0 73.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:2.37 (2.8%) stddev-sans-first:2.45 18:38:46 INFO - PROCESS | 1691 | Values: 83.0 86.0 88.0 84.0 85.0 84.0 79.0 86.0 85.0 85.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:140.00 stddev:23.30 (16.6%) stddev-sans-first:24.51 18:38:46 INFO - PROCESS | 1691 | Values: 138.0 137.0 137.0 136.0 146.0 144.0 138.0 135.0 212.0 142.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:165.50 stddev:20.92 (12.6%) stddev-sans-first:21.95 18:38:46 INFO - PROCESS | 1691 | Values: 166.0 165.0 165.0 165.0 163.0 171.0 224.0 202.0 162.0 165.0 18:38:46 INFO - PROCESS | 1691 | 18:38:46 INFO - PROCESS | 1691 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:67.00 stddev:7.00 (10.4%) stddev-sans-first:7.30 18:38:46 INFO - PROCESS | 1691 | Values: 66.0 65.0 67.0 66.0 67.0 65.0 65.0 70.0 81.0 84.0 18:38:46 INFO - PROCESS | 1691 | -------- Summary: end -------- 18:38:46 INFO - PROCESS | 1691 | 18:38:47 INFO - TEST-INFO | 1691: exit 0 18:38:47 INFO - TEST-OK | kraken | took 60795ms 18:38:47 INFO - SUITE-END | took 1174s 18:38:47 INFO - Completed test suite (00:19:34) 18:38:47 INFO - javascript benchmark 18:38:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2876.3671992161726, 3455.084402633301, 3486.8779592714254, 3496.632853550377, 3501.8774342898005, 5549.750261238244, 5575.776968921238, 5628.859278518039, 5701.0878259478495, 5721.799737009201, 5583.302087239095, 5674.460926212014, 5676.971615141929, 5754.5108665763355, 5822.184894114827, 5170.870728231786, 5181.1192097343355, 5189.143791274435, 5235.633505654615, 5236.6857988520815, 4402.625776808975, 5172.560332371753, 5282.867928301785, 5300.496452836994, 5348.893022139574, 4311.0, 5296.046711591912, 5324.467553244684, 5346.331708536433, 5375.75809088592, 5114.977004599095, 5118.181091025455, 5158.1746920492915, 5199.792008319662, 5203.4796520348045, 5094.4650811664615, 5108.463611320795, 5113.872153196163, 5165.9225111623355, 5249.816256431007, 4554.977225113853, 5551.000819852425, 5577.721113944327, 5587.804426845081, 5648.293963254593, 6068.362821903725, 6101.3079194897555, 6172.4136207060255, 6181.165542651775, 6241.032639940817, 6209.968950155266, 6228.906566401508, 6255.968720156416, 6294.527910406738, 6310.684465776692, 4828.517148285144, 5631.465010823965, 5798.768049278024, 5850.385709500526, 5867.237259156283, 6195.287541932646, 6254.2807577129215, 6275.184226050585, 6282.842928926722, 6301.054841773771, 6163.907541386883, 6166.167567378437, 6169.352218017133, 6174.073888916699, 6185.907211391833, 6109.755609775648, 6122.1542093545395, 6212.72042758077, 6221.129041934133, 6243.250809902841, 6104.236970378703, 6135.477655820289, 6143.662098584621, 6194.690265486708, 6204.472619827275, 5564.944350556523, 5803.0134877071, 6168.506519478431, 6180.598261113013, 6222.502199824004, 4838.927416088762, 4890.046440944053, 4948.05986862495, 4954.20732682769, 4962.702237865739, 3724.3110024645525, 4360.738355698668, 4374.0, 4398.186335527937, 4443.6667249955735, 5581.3302403711, 5589.524890384281, 5593.244911936837, 5652.576056795675, 5759.2512973313205, 5194.831162988373, 6087.208662873798, 6123.801220575414, 6153.8461538461, 6271.592346497554, 5657.6605403675085, 5720.42795720433, 5725.112607545837, 5731.8567035824435, 5746.281714785652, 5359.571234301325, 5378.73106344689, 5408.567314614821, 5420.186971954238, 5426.403095659474, 6190.50475961931, 6201.224846894138, 6265.718042687999, 6319.842003949938, 6331.0, 6251.78118765841, 6259.0, 6260.718267677874, 6276.811695649138, 6289.371062893857, 6295.779647712308, 6355.078513615592, 6357.396047375492, 6372.681365931778, 6374.426301632875, 6017.187679663364, 6043.8186854394435, 6076.787312444043, 6091.360407157362, 6188.0, 5996.130561068708, 6150.2927163375, 6165.810580332159, 6181.443670069535, 6217.316095229611, 5342.679439233659, 6015.608985415934, 6016.849578760566, 6017.308009578955, 6025.487833534199, 4778.37881075458, 5924.46679798803, 5940.762369505172, 5989.460948514655, 6017.217761691128, 5938.61399009063, 5949.672767997802, 5960.0, 5989.341172471112, 6016.669083200466, 6078.817635470943, 6101.481374083252, 6107.9921812900375, 6127.303099055217, 6137.478314343331, 5816.098504731774, 5859.794907178229, 5864.413558644272, 5922.437368449991, 6010.849728756816], "value": 5630.5398088005, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.31756335039313, 498.4591717985983, 508.34677439490264, 524.7271418862192, 528.6775067209002, 25811.612825807606, 31194.376112477737, 32505.0248492545, 42544.57455425445, 43936.0, 45166.77416612912, 45551.77224113875, 45595.08809823802, 45685.0, 45754.31368529475, 9544.475053872016, 9751.902480975206, 9755.463449510275, 9759.316847820655, 9775.413475191475, 8575.914240857604, 9448.196903263231, 11491.425428728571, 11767.470463829148, 11817.940910295436, 11747.29516229029, 11802.527898884035, 11814.350210738407, 11824.527018919276, 11826.231294965843, 8588.441751286146, 11774.352410617412, 11842.763144737142, 11847.822282665767, 11850.407479625985, 11390.088792896548, 11797.764044719057, 11799.351035693033, 11831.0, 12031.93984030083, 41716.58283417187, 42259.15481690349, 42270.57729422697, 42273.36589951153, 42392.0, 3136.6079240094987, 3180.8250546219947, 3244.626867910173, 3418.8803391881415, 3574.1422058705984, 29433.411331773244, 29552.96564620228, 30067.0, 30101.397972040435, 30139.849300753576, 2691.125384250107, 2735.849528275944, 2739.643846299968, 2769.75072243497, 2845.0, 518170.0, 518435.0, 518980.0, 519166.0, 519999.0, 63844.04233936495, 65325.67337163284, 65365.34634653688, 65397.0, 65418.67290663516, 35889.82055089708, 36719.0, 36751.4487282691, 38316.61683383131, 38400.61599384026, 16701.49895503137, 17279.790414670853, 17378.13109344553, 17392.739108913378, 17444.47666570005, 10425.791484170426, 11969.700757480958, 12190.2076365036, 12766.553170638983, 13028.609141725763, 20138.39584812458, 20549.794502055087, 20552.794472055386, 20989.89505052465, 21096.0, 7789.065312162576, 7791.337736292478, 7812.101608315116, 7822.6088695564995, 7915.604219789103, 901.3870568013814, 1029.25893356783, 1061.2739258200313, 1066.5946940162844, 1149.4195431307137, 29514.55728164079, 30398.54402183969, 30894.69105308963, 31265.687343126734, 32292.192695182337, 1043.9373637581768, 1073.9677809665723, 1107.6953837694698, 1148.9310641361544, 1154.9191556591127, 14031.368588413547, 15228.7055600273, 15232.93369464127, 15471.91696581251, 15572.766408503881, 677490.0, 682070.0, 685109.0, 686299.0, 686938.0, 61278.38721612816, 62785.68607156935, 62801.0, 62824.0, 62912.68543657344, 1308.3981368570564, 1354.065694670674, 1430.1204759073303, 1441.6251774538487, 1473.2339183624456, 29631.407371852874, 30458.54312185275, 30734.231644209074, 30972.0708378749, 31270.374592507567, 48784.0, 53067.46932530625, 54179.45820541902, 55007.17489237584, 55245.723771380886, 583465.0, 598122.0, 603851.0, 605279.0, 605538.0, 23262.0, 26069.869650651624, 26268.343291417867, 26706.866465667546, 27793.888244469996, 46805.0, 46962.530374695816, 46985.76507117443, 47212.0, 47668.52331476779, 33448.0, 38747.41878871763, 38752.0, 39379.6062039376, 39453.0, 31085.844570777, 37007.88976330715, 37453.6254637461, 38571.64999225014, 38688.83933482, 37101.628983709816, 37591.81204093962, 37690.05774855651, 38743.418848717854, 38745.6125438742, 32785.836070819496, 32834.34331313408, 33293.50059749154, 33749.83125084359, 33804.0, 24737.752622473545, 24807.62788558137, 24992.750072499042, 25608.0, 25617.871910640326], "value": 20259.229486628687, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.34448093759676, 385.49307660426535, 386.67712460095834, 397.7911596411882, 401.5402364292885, 15410.845891541081, 15563.0, 16153.757693634576, 22462.55074898503, 22571.871406429695, 20447.693284600744, 21595.02822372993, 21840.017199226033, 21889.452763680914, 22323.330300091024, 5316.521513063843, 5748.856278593027, 5944.0, 5996.400359964013, 6020.0, 5742.942570574282, 5877.118432235156, 5914.172015917775, 5984.281886173665, 6017.368176341487, 5091.7708703108265, 5652.406497317764, 5693.918155550453, 5836.270466191726, 5857.619254748449, 5763.625364351304, 5793.739281732311, 5805.912911306334, 5813.331466881321, 5845.941540584582, 4216.7680777557225, 4979.45226025137, 5058.519440653132, 5092.8472145835685, 5097.209932460438, 23452.82735863217, 24966.875165624057, 25909.352266193306, 25952.740472595222, 26635.0, 1877.1083735225795, 2033.0647901965115, 2071.2854065347433, 2129.744430668314, 2193.473566344082, 9477.28920330978, 9857.211423086208, 9963.252756043326, 10096.0, 10227.744306392326, 1733.7659415978808, 1736.1926704082662, 1738.721804511272, 1740.747591599211, 1768.239656947512, 119100.0, 120317.0, 122459.0, 123191.3840430792, 125534.37232813778, 11565.76868462626, 11579.247348922294, 11590.826137607943, 11652.592159274385, 11765.764684706259, 14670.339834707464, 14727.558173254605, 14741.92629036848, 14756.704865902837, 14762.040467369587, 7040.859182816315, 7169.89245161323, 8358.289545388589, 8784.0, 8808.427452215585, 8492.575371231414, 8614.009388920229, 8654.567271636393, 8655.567221638892, 8754.255888249443, 4426.712263702913, 4524.185646583646, 4534.750588717587, 4576.565226303496, 4633.9536604634195, 1879.9624007519774, 1931.3433432632971, 1933.6132773445368, 1935.2259096361568, 1937.3316205908939, 859.7721603775004, 950.8383574792302, 954.289054654287, 971.062924278075, 989.4014121456415, 19854.305099321453, 20223.696644550342, 20231.898840505703, 20407.0, 20575.691364629543, 896.3546246702364, 924.778053267211, 928.5078908178676, 934.9953250233706, 988.2242439684813, 9603.37578057424, 10621.415822129857, 10758.94620526908, 10780.353178809297, 10833.349999000086, 610648.0, 614893.0, 624984.0, 629040.0, 634774.0, 72574.9113763294, 133291.0, 133717.0, 134504.0, 138806.0, 830.9916900831035, 921.9723408297762, 935.8081593273363, 940.2806852757645, 943.165298710646, 16287.67424651524, 18438.89366638006, 18465.446036618923, 18517.629647407244, 18526.981016044247, 50000.0, 52992.73503632457, 53693.463065368844, 54136.0, 54512.0, 58138.4186158133, 78101.0, 78760.60619696864, 80100.59949700214, 80437.3912521758, 6843.110395648733, 7327.890081648886, 7348.853022939618, 7388.076490438695, 8039.15588863161, 8974.538053238626, 12346.444410001752, 12452.128351015343, 12532.185407948455, 12710.173838700455, 10488.160947124059, 10615.363078215332, 10725.463726813472, 10754.0, 10818.67543973682, 9983.301168918106, 10038.29731918788, 10257.230707696803, 10404.947975260075, 10564.313319634506, 10624.521896514521, 11028.22802403824, 11082.944585277022, 11194.44027798623, 11356.602518912128, 6832.0, 6994.601079784165, 7295.015172951584, 7340.853182936418, 7439.665215065444, 10019.749506262402, 10682.465876705977, 10952.835707464235, 11202.49588768491, 11255.549778008788], "value": 9310.36470682808, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.6061502779226, 1553.9976715018813, 1580.2177921928646, 1806.6657668331359, 1955.4915721912307, 7873.685052597896, 8637.438566493181, 8889.822203555925, 8901.287896968252, 8991.955040224806, 8616.0, 8865.379423440361, 8872.068432814542, 8877.0, 8879.556022198896, 2346.530693861226, 2351.7177938647387, 2359.1035406545575, 2383.2373640435067, 2384.642303654457, 2343.5195784864063, 2345.57779599672, 2372.0, 2373.074500944633, 2380.214529205358, 2345.718513778349, 2348.6829278047503, 2356.6229403295474, 2371.5731168389684, 2375.3111597636666, 2302.861828290308, 2309.3533810533077, 2323.372689373868, 2334.708161479815, 2335.859848409101, 2077.4287071055583, 2158.0, 2347.3427440316736, 2349.929502114939, 2351.7883390494767, 8715.041345451995, 8790.648374065093, 8793.472391656522, 8867.246284065863, 8999.955000225022, 5318.574514038867, 5459.754311055973, 5471.671699697991, 5477.890442191134, 5486.259354987065, 3425.2122011937395, 3453.1511503010115, 3525.1363415962996, 3586.7309951753723, 3596.7662101963288, 1019.1184625299137, 1032.3599368391644, 1035.8756949166147, 1040.5681642118511, 1041.9374837509774, 11615.709607259887, 11682.766344673144, 11735.7066073348, 11737.647870563897, 11744.471498782576, 4541.636669066466, 4552.521985191574, 4650.232711602615, 4654.510556621916, 4670.883227919329, 9269.490178040138, 9321.114494123047, 9328.300377471582, 9331.300152488591, 9343.859842102374, 5242.370915490165, 5243.161094224905, 5251.343582052244, 5254.106801843619, 5255.947440525546, 4124.443200167939, 4353.521112677603, 4436.511983681792, 4576.084783043405, 4778.1399348116975, 3797.2595343907674, 3821.407681809324, 3846.788426636506, 3872.4384964180035, 3897.2205558888336, 1125.634168895096, 1159.6231224851956, 1164.889335513125, 1165.091228841505, 1171.0748508678219, 835.8704400817883, 845.8054647431126, 887.1793590928495, 891.9732408027639, 950.5152372290044, 5646.322441307069, 5802.100674395476, 5804.216430781875, 5857.970710146507, 5908.497777688859, 938.554186761283, 946.1059298962415, 950.4202436513721, 952.4428209497383, 1004.3070281505884, 3798.8860334190017, 3885.630865067814, 3890.105275786528, 3974.7217694761666, 4033.4756481657782, 10463.895361046292, 10499.68500944973, 10847.783044339069, 10871.239013268996, 10886.891131088747, 10464.319819211727, 10669.09312708413, 10800.51397687106, 10820.13438924884, 10837.67486975392, 8068.596570171468, 8238.382121341043, 8250.917490825135, 8255.133211012877, 8265.338772898274, 5624.381318054929, 5642.858928526655, 5735.827925162252, 5751.33859606134, 5765.019946609171, 9298.860517092384, 9945.751356216153, 10192.337498062601, 10207.315792894386, 10215.693529194135, 10052.447115408722, 10109.19126469866, 10187.388756674623, 10438.37369757817, 10477.214208934209, 7037.788866334018, 7049.647517624201, 7091.290870912868, 7104.609246491286, 7199.316064973819, 8204.138565450541, 8246.505209687439, 8306.04480484752, 8400.747977560683, 8411.285040771358, 7490.962545187239, 7501.174870764321, 7561.962190189014, 7578.848423031398, 7677.385809135368, 7330.816729581803, 7350.0, 7362.595057271901, 7512.361449276654, 7513.39892808563, 6737.764178253738, 7052.471064670068, 7076.646167691491, 7085.078939737801, 7115.288471152843, 3967.9088250730124, 4167.541570427311, 4852.684575502581, 4900.99529596439, 4949.554540091409, 4868.075065737498, 4978.950210497848, 5021.171506701354, 5028.069807085701, 5200.115980283436], "value": 4540.514276697181, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.13137230462405, 312.7451127331225, 320.25061356425965, 323.0164150162754, 323.6795572383341, 4843.927341089887, 5048.444671086182, 5074.670146440479, 5432.538234250089, 5491.176323551469, 5213.244079608455, 5377.897531006144, 5410.675359478434, 5431.891362172754, 5470.91793623096, 3647.55613738795, 3818.5417749869916, 4117.238310912476, 4141.192467468844, 4184.4351012613215, 3311.2218628622254, 3566.8216589170565, 3757.5678796938428, 3791.9620803792022, 4156.97921510392, 3176.3806057818733, 3572.374834403969, 3931.7051221158385, 4083.5916408359076, 4111.259973204822, 3772.7549908530254, 3948.4472173895533, 4127.174565086994, 4130.876073717794, 4162.167566486714, 3133.341998180385, 3587.3542762302777, 3598.1004748812798, 3599.9820000899826, 3602.837872295753, 4539.86380408585, 4748.264019077049, 5197.272381866542, 5206.193040078794, 5255.106631872591, 4603.447586289633, 4616.515265897099, 4626.58360747531, 4642.767861606906, 4644.326572646981, 5020.874478138076, 5647.802326918538, 5655.745491452894, 5658.97244973937, 5709.685967271798, 3574.571051473814, 3622.6558476944647, 3719.716697739276, 3725.6833169180654, 3760.567534733513, 4777.976110119462, 5838.678872662002, 5973.910391344133, 5987.850303742442, 6046.999999999956, 3575.874844380486, 3661.176235347079, 3889.649931506178, 3899.2786334528205, 3915.0, 3312.585926759155, 3551.4140166872694, 3718.851245950186, 3991.5409727881665, 4020.8592699255387, 3963.9405408918888, 4613.5386461354265, 4651.558101980307, 4660.718302466848, 4660.906781864343, 5547.140193270049, 5772.826815195551, 5777.393373695785, 5817.505512031525, 5821.835632873443, 1268.6194141757621, 1313.8423389193358, 1333.553259658006, 1388.6389538720005, 1414.6321956291233, 778.5678948183812, 805.9844595809323, 831.4470876866936, 862.5730263519473, 882.2179198019963, 3942.172143849766, 4225.725327853741, 4311.8490852820005, 4380.956190438119, 4429.53489883564, 4478.350639157369, 4564.520725323793, 4624.722516649012, 4738.147133515931, 4935.259711043373, 3967.484227050465, 4093.8771836844944, 4243.681723870722, 4427.8524915416, 4458.598726114666, 2343.8124950003958, 2399.7720216579396, 2596.3898483856656, 2635.4070334174658, 2648.615950687178, 4861.9027619447415, 4868.050730107666, 5055.53389517036, 5121.0014047261175, 5159.0911362796005, 4552.248878934939, 4579.702319349231, 5050.49495050492, 5065.898682026413, 5108.438071812097, 5124.820631277962, 5566.693831839288, 6174.567780255339, 6178.41305076017, 6226.533010024175, 5452.863678407913, 5634.873025394855, 5908.497777688945, 5952.523798096228, 5970.373110823301, 5179.378474583074, 5346.518813306821, 5453.727313634382, 5575.860603484945, 5889.734961926809, 5061.051495174458, 5464.644798088111, 5972.432618901197, 6170.773020480985, 6262.718177681924, 3883.650471457582, 3909.1986142840083, 3934.822932967966, 3935.6654684351006, 3963.8216280268034, 5142.93995072931, 5202.375714914235, 5687.317521897398, 5797.159411885337, 5938.476147637323, 5284.176958949156, 5512.310961129859, 5555.0278701227935, 5774.855628609318, 5780.884382312414, 5031.974840125776, 5065.4428012917815, 5696.430356964271, 5760.567957403127, 5772.595918285848, 5293.497117773805, 5592.350256674372, 5598.1322894951345, 5637.46444087811, 5680.630759000652, 4626.190416677001, 5373.668325638129, 5572.662560985254, 5676.545876329801, 5684.403137670485, 4456.977715111404, 5274.498922602346, 5613.859653508532, 5620.437956204347, 5634.2393776841245], "value": 3995.7039480752646, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.2330288608, 725.4740313272874, 737.9852402951941, 755.1806290175159, 786.449485360248, 15333.769993450107, 15513.767293490593, 15745.134017629025, 15755.527334179978, 15800.84199158008, 14014.299285035759, 14029.017968742191, 14490.927545362285, 14508.347124379401, 14534.418623255056, 710.4777988178685, 899.2895612466144, 915.4324318922244, 916.7158180963922, 918.8897332320131, 727.2327694282529, 884.1291328041874, 911.6353458616541, 921.3550514639745, 923.7967647117623, 943.7310366545536, 971.6016433262364, 973.2846357926918, 975.2880397309968, 976.8632391465201, 741.9443541734391, 924.6994726713777, 968.0948313327027, 969.0842154164283, 969.5636963366443, 673.0476375928105, 697.4873468001015, 699.6257002503636, 705.5801797930224, 711.2141084102096, 15519.767203491956, 15526.456574019856, 15528.14595197275, 15535.45625903088, 15560.844391556053, 12445.688857778538, 12499.0, 12610.432530536147, 12671.936640316831, 12692.238465692088, 11005.17461190414, 11017.228793984345, 11017.283876548, 11138.944305278503, 11547.480363383584, 4022.1955608878343, 4040.5151381834366, 4056.3104272273786, 4171.228322760299, 4185.0, 90676.54661726649, 91008.0, 93479.0, 93779.59330610046, 94533.52733236359, 7541.208173141741, 8607.397482176313, 9171.908280917105, 9255.629774809067, 9423.199028082552, 18416.0, 21435.78564214369, 22254.77745222527, 22347.441313966956, 22388.104475820786, 6208.2239720034995, 7175.17485489165, 7454.478186527, 7498.3626391756225, 7547.05661792276, 3966.6628336591134, 3983.880483585497, 3988.8205030773684, 4038.2932986727205, 4267.594578515036, 3346.715529179998, 3382.424987752088, 3463.7748546344405, 3476.687098161178, 3478.6869181773764, 619.8202521268872, 621.3227581935712, 623.7442648514087, 631.6052467207995, 632.7437387857925, 476.6734786671141, 515.2065818639328, 547.7124509632475, 574.4399210769467, 575.171752676145, 7825.452218344662, 7887.763367098996, 7970.960145199237, 8033.357331413473, 8087.47431416956, 547.8411260734422, 552.1717423864204, 559.083103709916, 564.5779605492392, 571.7741492110594, 3612.151144481045, 3657.2868290683596, 3667.94498082529, 3674.338619048597, 3695.648913353281, 94228.52885735665, 94655.0, 98373.0, 100934.0, 102199.0, 86726.56636716776, 87297.0, 87410.56294718485, 88414.67377989335, 89749.0, 68126.0, 68147.0, 75265.0, 76524.61737691276, 77221.0, 7341.449391295781, 7346.889796652948, 7352.852942941218, 7443.81390465221, 7535.246475352421, 46400.53599463962, 46853.2972005427, 46856.908601881536, 48082.75958620184, 48646.0, 66803.0, 67220.99168512574, 67591.66204168947, 68211.0, 68888.65555672189, 9954.665187162382, 10003.399796012263, 10015.449150296512, 10047.849282260626, 10180.592776288866, 10585.735356615838, 10664.733381665521, 10818.080463160404, 10859.457027148768, 10870.293430926964, 9919.355241909252, 9983.301168918106, 9987.300888937705, 10139.986001400097, 10149.035841595036, 9758.658446954323, 9964.0, 9981.650642227487, 10077.697669069647, 10258.179345652181, 9740.366876153028, 9794.0, 10011.222224993084, 10584.364938103738, 10829.29609575375, 8477.95761021191, 8493.0, 8723.738287851374, 8757.91242087571, 8891.777705557413, 8409.789755256168, 8522.147785221428, 8533.061363249913, 8557.229849313591, 8640.351973601879], "value": 6772.805022760625, "unit": "score"}], "name": "dromaeo_css", "value": 7122.116300200367, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 110.0, 112.0, 113.0, 111.0, 113.0, 109.0, 160.0, 164.0], "value": 121.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 116.0, 117.0, 199.0, 117.0, 119.0, 138.0, 117.0, 150.0, 116.0], "value": 134.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 182.0, 185.0, 187.0, 183.0, 180.0, 183.0, 181.0, 184.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 62.0, 124.0, 57.0, 56.0, 58.0, 55.0, 95.0, 56.0], "value": 69.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 76.0, 139.0, 79.0, 79.0, 75.0, 101.0, 79.0, 76.0, 115.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 84.0, 86.0, 83.0, 90.0, 85.0, 87.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 108.0, 150.0, 92.0, 93.0, 93.0, 92.0, 92.0, 93.0], "value": 99.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 84.0, 86.0, 85.0, 85.0, 86.0, 85.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 59.0, 58.0, 58.0, 60.0, 58.0, 119.0, 55.0, 55.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 77.0, 76.0, 72.0, 69.0, 70.0, 71.0, 71.0, 73.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 88.0, 84.0, 85.0, 84.0, 79.0, 86.0, 85.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 137.0, 136.0, 146.0, 144.0, 138.0, 135.0, 212.0, 142.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 165.0, 165.0, 163.0, 171.0, 224.0, 202.0, 162.0, 165.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 66.0, 67.0, 65.0, 65.0, 70.0, 81.0, 84.0], "value": 69.6, "unit": "score"}], "name": "kraken", "value": 1480.8, "alertThreshold": 2.0}]} 18:38:47 INFO - Return code: 0 18:38:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:38:47 ERROR - # TBPL SUCCESS # 18:38:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:38:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:38:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:38:47 INFO - 2016-05-23 18:38:47.532 screenresolution[1695:10257] starting screenresolution argv=screenresolution get 18:38:47 INFO - 2016-05-23 18:38:47.535 screenresolution[1695:10257] Display 0: 1600x1200x32@60 18:38:47 INFO - 2016-05-23 18:38:47.541 screenresolution[1696:10260] starting screenresolution argv=screenresolution list 18:38:47 INFO - Available Modes on Display 0 18:38:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:38:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:38:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:38:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:38:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:38:47 INFO - Intel Iris: 18:38:47 INFO - Chipset Model: Intel Iris 18:38:47 INFO - Type: GPU 18:38:47 INFO - Bus: Built-In 18:38:47 INFO - VRAM (Dynamic, Max): 1536 MB 18:38:47 INFO - Vendor: Intel (0x8086) 18:38:47 INFO - Device ID: 0x0a2e 18:38:47 INFO - Revision ID: 0x0009 18:38:47 INFO - Displays: 18:38:47 INFO - Display: 18:38:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:38:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:38:47 INFO - Display Serial Number: 1600x1200 60 18:38:47 INFO - Main Display: Yes 18:38:47 INFO - Mirror: Off 18:38:47 INFO - Online: Yes 18:38:47 INFO - Rotation: Supported 18:38:47 INFO - Return code: 0 18:38:47 INFO - Running post-action listener: _resource_record_post_action 18:38:47 INFO - ##### 18:38:47 INFO - ##### Finished run-tests step (success) 18:38:47 INFO - ##### 18:38:47 INFO - Running post-run listener: _resource_record_post_run 18:38:47 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 36730368; Write bytes: 695920640; Read time: 241; Write time: 1923 18:38:47 INFO - TinderboxPrint: CPU usage
54.0% 18:38:47 INFO - TinderboxPrint: I/O read bytes / time
36,730,368 / 241 18:38:47 INFO - TinderboxPrint: I/O write bytes / time
695,920,640 / 1,923 18:38:47 INFO - TinderboxPrint: CPU idle
2,227.9 (46.0%) 18:38:47 INFO - TinderboxPrint: CPU system
972.4 (20.1%) 18:38:47 INFO - TinderboxPrint: CPU user
1,641.3 (33.9%) 18:38:47 INFO - TinderboxPrint: Swap in / out
431,992,832 / 0 18:38:47 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 380848640; Write bytes: 370206208; Read time: 27899; Write time: 767 18:38:47 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30486016; Write bytes: 296555008; Read time: 185; Write time: 1082 18:38:48 INFO - Running post-run listener: _upload_blobber_files 18:38:48 INFO - Blob upload gear active. 18:38:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:38:48 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:38:48 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'] 18:38:48 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 18:38:48 INFO - (blobuploader) - INFO - Open directory for files ... 18:38:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:38:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:38:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:49 INFO - (blobuploader) - INFO - Done attempting. 18:38:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:38:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:38:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:51 INFO - (blobuploader) - INFO - Done attempting. 18:38:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:38:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:38:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:38:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:38:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:38:53 INFO - (blobuploader) - INFO - Done attempting. 18:38:53 INFO - (blobuploader) - INFO - Iteration through files over. 18:38:53 INFO - Return code: 0 18:38:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:38:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:38:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/436bfd433184b887d03b05503e4df55b17048f885c73c7d9a3dff451906074f5186a23579b02b21919a062a1efa52b19a2cf617a303c63fd338a05421afb7d02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab6b5593681ac83e5ede22e25ddc8370ab685c068ec970fd38d9b6bb36ebd4ef62ac8f0ffb8fb1954c405e4bbf932bd8fa097de134399922c1772edbc7eeda13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6337906959b70cfdda99fc7be3e33c50492a4de2a8775c7687faa826d0917138beaa0925d4d0379c8517a2b886f7cfd3d1aad2418693b009a4856ea0d494154b"} 18:38:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:38:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:38:53 INFO - Contents: 18:38:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/436bfd433184b887d03b05503e4df55b17048f885c73c7d9a3dff451906074f5186a23579b02b21919a062a1efa52b19a2cf617a303c63fd338a05421afb7d02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab6b5593681ac83e5ede22e25ddc8370ab685c068ec970fd38d9b6bb36ebd4ef62ac8f0ffb8fb1954c405e4bbf932bd8fa097de134399922c1772edbc7eeda13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6337906959b70cfdda99fc7be3e33c50492a4de2a8775c7687faa826d0917138beaa0925d4d0379c8517a2b886f7cfd3d1aad2418693b009a4856ea0d494154b"} 18:38:53 INFO - Running post-run listener: copy_logs_to_upload_dir 18:38:53 INFO - Copying logs to upload dir... 18:38:53 INFO - mkdir: /builds/slave/test/build/upload/logs 18:38:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1299.744098 ========= master_lag: 0.22 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 39 secs) (at 2016-05-23 18:38:53.619079) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 18:38:53.622415) ========= 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.Tg06xlKLnf/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.P8REpe2ORV/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/436bfd433184b887d03b05503e4df55b17048f885c73c7d9a3dff451906074f5186a23579b02b21919a062a1efa52b19a2cf617a303c63fd338a05421afb7d02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab6b5593681ac83e5ede22e25ddc8370ab685c068ec970fd38d9b6bb36ebd4ef62ac8f0ffb8fb1954c405e4bbf932bd8fa097de134399922c1772edbc7eeda13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6337906959b70cfdda99fc7be3e33c50492a4de2a8775c7687faa826d0917138beaa0925d4d0379c8517a2b886f7cfd3d1aad2418693b009a4856ea0d494154b"} build_url:https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010903 build_url: 'https://queue.taskcluster.net/v1/task/S563Jd2gTYuO9XYkvKlNnw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/436bfd433184b887d03b05503e4df55b17048f885c73c7d9a3dff451906074f5186a23579b02b21919a062a1efa52b19a2cf617a303c63fd338a05421afb7d02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab6b5593681ac83e5ede22e25ddc8370ab685c068ec970fd38d9b6bb36ebd4ef62ac8f0ffb8fb1954c405e4bbf932bd8fa097de134399922c1772edbc7eeda13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6337906959b70cfdda99fc7be3e33c50492a4de2a8775c7687faa826d0917138beaa0925d4d0379c8517a2b886f7cfd3d1aad2418693b009a4856ea0d494154b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 18:38:53.652152) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:38:53.652539) ========= 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.Tg06xlKLnf/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.P8REpe2ORV/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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.005033 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 18:38:53.700868) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 18:38:53.701213) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 18:38:53.701572) ========= ========= Total master_lag: 0.34 =========