builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0221 starttime: 1464277385.79 results: success (0) buildid: 20160526074444 builduid: 4301d61e69e643b3b3aa58a33042e1d5 revision: 9cfc40ea2dbd0e258ec58028d85f4b358162e64a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:05.794046) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:05.794530) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:05.794804) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:06.006117) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:06.006477) ========= 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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-26 08:43:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-05-26 08:43:06 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102584 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:06.129055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:06.129390) ========= 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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.026109 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:06.178380) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-26 08:43:06.178751) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9cfc40ea2dbd0e258ec58028d85f4b358162e64a --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 9cfc40ea2dbd0e258ec58028d85f4b358162e64a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aGyon1DQQJ/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.FN8HTwtEnG/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-26 08:43:06,254 truncating revision to first 12 chars 2016-05-26 08:43:06,254 Setting DEBUG logging. 2016-05-26 08:43:06,254 attempt 1/10 2016-05-26 08:43:06,254 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9cfc40ea2dbd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 08:43:08,405 unpacking tar archive at: fx-team-9cfc40ea2dbd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.412491 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-26 08:43:08.611117) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:08.611447) ========= script_repo_revision: 9cfc40ea2dbd0e258ec58028d85f4b358162e64a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:08.611854) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:08.612118) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 08:43:08.628506) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-26 08:43:08.628898) ========= /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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:43:08 INFO - MultiFileLogger online at 20160526 08:43:08 in /builds/slave/test 08:43:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:43:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:43:08 INFO - {'append_to_log': False, 08:43:08 INFO - 'base_work_dir': '/builds/slave/test', 08:43:08 INFO - 'blob_upload_branch': 'Fx-Team', 08:43:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:43:08 INFO - 'branch': 'Fx-Team', 08:43:08 INFO - 'buildbot_json_path': 'buildprops.json', 08:43:08 INFO - 'config_files': ('talos/mac_config.py',), 08:43:08 INFO - 'default_actions': ('clobber', 08:43:08 INFO - 'read-buildbot-config', 08:43:08 INFO - 'download-and-extract', 08:43:08 INFO - 'populate-webroot', 08:43:08 INFO - 'create-virtualenv', 08:43:08 INFO - 'install', 08:43:08 INFO - 'run-tests'), 08:43:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:43:08 INFO - 'download_minidump_stackwalk': True, 08:43:08 INFO - 'download_symbols': 'ondemand', 08:43:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:43:08 INFO - 'tooltool.py': '/tools/tooltool.py', 08:43:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:43:08 INFO - '/tools/misc-python/virtualenv.py')}, 08:43:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:43:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:43:08 INFO - 'installer_path': 'installer.exe', 08:43:08 INFO - 'log_level': 'info', 08:43:08 INFO - 'log_name': 'talos', 08:43:08 INFO - 'log_to_console': True, 08:43:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:43:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:43:08 INFO - 'opt_config_files': (), 08:43:08 INFO - 'pip_index': False, 08:43:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:08 INFO - 'cmd': ('bash', 08:43:08 INFO - '-c', 08:43:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:08 INFO - 'enabled': True, 08:43:08 INFO - 'halt_on_failure': False, 08:43:08 INFO - 'name': 'check_screen_resolution'},), 08:43:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:08 INFO - 'cmd': ('bash', 08:43:08 INFO - '-c', 08:43:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:08 INFO - 'enabled': True, 08:43:08 INFO - 'halt_on_failure': False, 08:43:08 INFO - 'name': 'check_screen_resolution'},), 08:43:08 INFO - 'run_cmd_checks_enabled': True, 08:43:08 INFO - 'sps_profile': False, 08:43:08 INFO - 'sps_profile_interval': 0, 08:43:08 INFO - 'suite': 'dromaeojs', 08:43:08 INFO - 'system_bits': '32', 08:43:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:43:08 INFO - 'title': 't-yosemite-r7-0221', 08:43:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:43:08 INFO - 'use_talos_json': True, 08:43:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:43:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:43:08 INFO - 'work_dir': 'build'} 08:43:08 INFO - ##### 08:43:08 INFO - ##### Running clobber step. 08:43:08 INFO - ##### 08:43:08 INFO - Running pre-action listener: _resource_record_pre_action 08:43:08 INFO - Running main action method: clobber 08:43:08 INFO - rmtree: /builds/slave/test/build 08:43:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:43:11 INFO - Running post-action listener: _resource_record_post_action 08:43:11 INFO - ##### 08:43:11 INFO - ##### Finished clobber step (success) 08:43:11 INFO - ##### 08:43:11 INFO - ##### 08:43:11 INFO - ##### Running read-buildbot-config step. 08:43:11 INFO - ##### 08:43:11 INFO - Running pre-action listener: _resource_record_pre_action 08:43:11 INFO - Running main action method: read_buildbot_config 08:43:11 INFO - Using buildbot properties: 08:43:11 INFO - { 08:43:11 INFO - "project": "", 08:43:11 INFO - "product": "firefox", 08:43:11 INFO - "script_repo_revision": "production", 08:43:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:43:11 INFO - "repository": "", 08:43:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:43:11 INFO - "buildid": "20160526074444", 08:43:11 INFO - "pgo_build": "False", 08:43:11 INFO - "basedir": "/builds/slave/test", 08:43:11 INFO - "buildnumber": 830, 08:43:11 INFO - "slavename": "t-yosemite-r7-0221", 08:43:11 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:43:11 INFO - "platform": "yosemite_r7", 08:43:11 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:43:11 INFO - "branch": "fx-team", 08:43:11 INFO - "repo_path": "integration/fx-team", 08:43:11 INFO - "revision": "9cfc40ea2dbd0e258ec58028d85f4b358162e64a", 08:43:11 INFO - "stage_platform": "macosx64", 08:43:11 INFO - "builduid": "4301d61e69e643b3b3aa58a33042e1d5", 08:43:11 INFO - "slavebuilddir": "test" 08:43:11 INFO - } 08:43:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:43:11 INFO - Running post-action listener: _resource_record_post_action 08:43:11 INFO - ##### 08:43:11 INFO - ##### Finished read-buildbot-config step (success) 08:43:11 INFO - ##### 08:43:11 INFO - ##### 08:43:11 INFO - ##### Running download-and-extract step. 08:43:11 INFO - ##### 08:43:11 INFO - Running pre-action listener: _resource_record_pre_action 08:43:11 INFO - Running main action method: download_and_extract 08:43:11 INFO - mkdir: /builds/slave/test/build/tests 08:43: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')]} 08:43:11 INFO - https://queue.taskcluster.net/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:43:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:43:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:43:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/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 08:43:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/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 08:43:14 INFO - Downloaded 1199 bytes. 08:43:14 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:43:14 INFO - Using the following test package requirements: 08:43:14 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:43:14 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:43:14 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:43:14 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:43:14 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:43:14 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:43:14 INFO - u'jsshell-mac.zip'], 08:43:14 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:43:14 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:43:14 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:43:14 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:43:14 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:43:14 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:43:14 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:43:14 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:43:14 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:43:14 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:43:14 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:43:14 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:14 INFO - https://queue.taskcluster.net/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:43:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:43:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:43:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/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 08:43:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/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 08:43:15 INFO - Downloaded 18622353 bytes. 08:43:15 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'] 08:43:15 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 08:43:18 INFO - Return code: 0 08:43:18 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 08:43: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')]} 08:43:18 INFO - https://queue.taskcluster.net/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:43:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:43:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:43:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/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 08:43:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/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 08:43:21 INFO - Downloaded 18622353 bytes. 08:43:21 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'] 08:43:21 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 08:43:24 INFO - Return code: 0 08:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:24 INFO - https://queue.taskcluster.net/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:43:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:43:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:43:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/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 08:43:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/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 08:43:25 INFO - Downloaded 10834034 bytes. 08:43:25 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'] 08:43:25 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 08:43:26 INFO - Return code: 0 08:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:26 INFO - https://queue.taskcluster.net/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:43:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:43:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:43:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:43:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CbUsgjUIQciV44oHx5Etrw/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 08:43:30 INFO - Downloaded 110500652 bytes. 08:43:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:43:30 INFO - mkdir: /builds/slave/test/properties 08:43:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:43:30 INFO - Writing to file /builds/slave/test/properties/build_url 08:43:30 INFO - Contents: 08:43:30 INFO - build_url:https://queue.taskcluster.net/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:43:31 INFO - Running post-action listener: _resource_record_post_action 08:43:31 INFO - Running post-action listener: set_extra_try_arguments 08:43:31 INFO - ##### 08:43:31 INFO - ##### Finished download-and-extract step (success) 08:43:31 INFO - ##### 08:43:31 INFO - ##### 08:43:31 INFO - ##### Running populate-webroot step. 08:43:31 INFO - ##### 08:43:31 INFO - Running pre-action listener: _resource_record_pre_action 08:43:31 INFO - Running main action method: populate_webroot 08:43:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:43:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:43:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:43:31 INFO - '--tppagecycles', 08:43:31 INFO - '7'], 08:43:31 INFO - 'tests': ['tsvgm']}}, 08:43:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:43:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:43:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:43:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:43:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:43:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:43:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:31 INFO - 'talos_options': ['--disable-e10s'], 08:43:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:43:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:43:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:31 INFO - 'talos_options': ['--disable-e10s'], 08:43:31 INFO - 'tests': ['damp', 'tps']}, 08:43:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:31 INFO - 'tests': ['damp', 'tps']}, 08:43:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:43:31 INFO - 'tests': ['dromaeo_dom']}, 08:43:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:43:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:43:31 INFO - 'tests': ['a11yr', 08:43:31 INFO - 'ts_paint', 08:43:31 INFO - 'tpaint', 08:43:31 INFO - 'sessionrestore', 08:43:31 INFO - 'sessionrestore_no_auto_restore', 08:43:31 INFO - 'tabpaint']}, 08:43:31 INFO - 'other-e10s': {'tests': ['a11yr', 08:43:31 INFO - 'ts_paint', 08:43:31 INFO - 'tpaint', 08:43:31 INFO - 'sessionrestore', 08:43:31 INFO - 'sessionrestore_no_auto_restore', 08:43:31 INFO - 'tabpaint']}, 08:43:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:43:31 INFO - 'ts_paint', 08:43:31 INFO - 'tpaint', 08:43:31 INFO - 'sessionrestore', 08:43:31 INFO - 'sessionrestore_no_auto_restore', 08:43:31 INFO - 'tabpaint']}, 08:43:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:43:31 INFO - 'ts_paint', 08:43:31 INFO - 'tpaint', 08:43:31 INFO - 'sessionrestore', 08:43:31 INFO - 'sessionrestore_no_auto_restore', 08:43:31 INFO - 'tabpaint']}, 08:43:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:43:31 INFO - 'tests': ['a11yr', 08:43:31 INFO - 'ts_paint', 08:43:31 INFO - 'tpaint', 08:43:31 INFO - 'sessionrestore', 08:43:31 INFO - 'sessionrestore_no_auto_restore', 08:43:31 INFO - 'tabpaint']}, 08:43:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:43:31 INFO - 'tests': ['a11yr', 08:43:31 INFO - 'ts_paint', 08:43:31 INFO - 'tpaint', 08:43:31 INFO - 'sessionrestore', 08:43:31 INFO - 'sessionrestore_no_auto_restore', 08:43:31 INFO - 'tabpaint']}, 08:43:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:43:31 INFO - 'tests': ['tsvgx', 08:43:31 INFO - 'tsvgr_opacity', 08:43:31 INFO - 'tart', 08:43:31 INFO - 'tscrollx', 08:43:31 INFO - 'cart']}, 08:43:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:43:31 INFO - 'tsvgr_opacity', 08:43:31 INFO - 'tart', 08:43:31 INFO - 'tscrollx', 08:43:31 INFO - 'cart']}, 08:43:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:31 INFO - 'talos_options': ['--disable-e10s'], 08:43:31 INFO - 'tests': ['tp5o']}, 08:43:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:31 INFO - 'tests': ['tp5o']}, 08:43:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:31 INFO - 'talos_options': ['--disable-e10s', 08:43:31 INFO - '--xperf_path', 08:43:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:43:31 INFO - 'tests': ['tp5n']}, 08:43:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:43:31 INFO - 'talos_options': ['--xperf_path', 08:43:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:43:31 INFO - 'tests': ['tp5n']}}, 08:43:31 INFO - 'talos.zip': {'path': '', 08:43:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:43:31 INFO - Running post-action listener: _resource_record_post_action 08:43:31 INFO - ##### 08:43:31 INFO - ##### Finished populate-webroot step (success) 08:43:31 INFO - ##### 08:43:31 INFO - ##### 08:43:31 INFO - ##### Running create-virtualenv step. 08:43:31 INFO - ##### 08:43:31 INFO - Running pre-action listener: _resource_record_pre_action 08:43:31 INFO - Running main action method: create_virtualenv 08:43:31 INFO - Creating virtualenv /builds/slave/test/build/venv 08:43:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:43:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:43:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:43:31 INFO - Using real prefix '/tools/python27' 08:43:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:43:32 INFO - Installing distribute.............................................................................................................................................................................................done. 08:43:35 INFO - Installing pip.................done. 08:43:35 INFO - Return code: 0 08:43:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:35 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 0x1100d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe43489b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7bd30>, '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 0x10fd9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff72b20>, '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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:43:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:43:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aGyon1DQQJ/Render', 08:43:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:35 INFO - 'HOME': '/Users/cltbld', 08:43:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:35 INFO - 'LOGNAME': 'cltbld', 08:43:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:35 INFO - 'MOZ_NO_REMOTE': '1', 08:43:35 INFO - 'NO_EM_RESTART': '1', 08:43:35 INFO - 'PAGER': '/bin/cat', 08:43:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:35 INFO - 'PWD': '/builds/slave/test', 08:43:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:35 INFO - 'SHELL': '/bin/bash', 08:43:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FN8HTwtEnG/Listeners', 08:43:35 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 08:43:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:35 INFO - 'USER': 'cltbld', 08:43:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:35 INFO - 'XPC_FLAGS': '0x0', 08:43:35 INFO - 'XPC_SERVICE_NAME': '0', 08:43:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:43:35 INFO - Cleaning up... 08:43:35 INFO - Return code: 0 08:43:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:35 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 0x1100d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe43489b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7bd30>, '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 0x10fd9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff72b20>, '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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:35 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 08:43:35 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 08:43:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aGyon1DQQJ/Render', 08:43:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:35 INFO - 'HOME': '/Users/cltbld', 08:43:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:35 INFO - 'LOGNAME': 'cltbld', 08:43:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:35 INFO - 'MOZ_NO_REMOTE': '1', 08:43:35 INFO - 'NO_EM_RESTART': '1', 08:43:35 INFO - 'PAGER': '/bin/cat', 08:43:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:35 INFO - 'PWD': '/builds/slave/test', 08:43:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:35 INFO - 'SHELL': '/bin/bash', 08:43:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FN8HTwtEnG/Listeners', 08:43:35 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 08:43:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:35 INFO - 'USER': 'cltbld', 08:43:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:35 INFO - 'XPC_FLAGS': '0x0', 08:43:35 INFO - 'XPC_SERVICE_NAME': '0', 08:43:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:35 INFO - Downloading/unpacking psutil>=3.1.1 08:43: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 08:43: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 08:43: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 08:43: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 08:43: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 08:43: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 08:43:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:43:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:38 INFO - Installing collected packages: psutil 08:43:38 INFO - Running setup.py install for psutil 08:43:38 INFO - building 'psutil._psutil_osx' extension 08:43:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:43:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:43:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:43:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:43:39 INFO - building 'psutil._psutil_posix' extension 08:43:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:43:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:43:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:43:39 INFO - ^ 08:43:39 INFO - 1 warning generated. 08:43:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:43:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:39 INFO - Successfully installed psutil 08:43:39 INFO - Cleaning up... 08:43:39 INFO - Return code: 0 08:43:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:39 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 0x1100d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe43489b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7bd30>, '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 0x10fd9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff72b20>, '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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:39 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 08:43:39 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 08:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aGyon1DQQJ/Render', 08:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:39 INFO - 'HOME': '/Users/cltbld', 08:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:39 INFO - 'LOGNAME': 'cltbld', 08:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:39 INFO - 'MOZ_NO_REMOTE': '1', 08:43:39 INFO - 'NO_EM_RESTART': '1', 08:43:39 INFO - 'PAGER': '/bin/cat', 08:43:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:39 INFO - 'PWD': '/builds/slave/test', 08:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:39 INFO - 'SHELL': '/bin/bash', 08:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FN8HTwtEnG/Listeners', 08:43:39 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 08:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:39 INFO - 'USER': 'cltbld', 08:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:39 INFO - 'XPC_FLAGS': '0x0', 08:43:39 INFO - 'XPC_SERVICE_NAME': '0', 08:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:43:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:43:42 INFO - Installing collected packages: mozsystemmonitor 08:43:42 INFO - Running setup.py install for mozsystemmonitor 08:43:42 INFO - Successfully installed mozsystemmonitor 08:43:42 INFO - Cleaning up... 08:43:42 INFO - Return code: 0 08:43:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:43:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:42 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 0x1100d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe43489b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7bd30>, '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 0x10fd9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff72b20>, '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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:43:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:43:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aGyon1DQQJ/Render', 08:43:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:42 INFO - 'HOME': '/Users/cltbld', 08:43:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:42 INFO - 'LOGNAME': 'cltbld', 08:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:42 INFO - 'MOZ_NO_REMOTE': '1', 08:43:42 INFO - 'NO_EM_RESTART': '1', 08:43:42 INFO - 'PAGER': '/bin/cat', 08:43:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:42 INFO - 'PWD': '/builds/slave/test', 08:43:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:42 INFO - 'SHELL': '/bin/bash', 08:43:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FN8HTwtEnG/Listeners', 08:43:42 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 08:43:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:42 INFO - 'USER': 'cltbld', 08:43:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:42 INFO - 'XPC_FLAGS': '0x0', 08:43:42 INFO - 'XPC_SERVICE_NAME': '0', 08:43:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:43 INFO - Downloading/unpacking blobuploader==1.2.4 08:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:45 INFO - Downloading blobuploader-1.2.4.tar.gz 08:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:43:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:43:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:46 INFO - Downloading docopt-0.6.1.tar.gz 08:43:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:43:46 INFO - Installing collected packages: blobuploader, requests, docopt 08:43:46 INFO - Running setup.py install for blobuploader 08:43:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:43:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:43:46 INFO - Running setup.py install for requests 08:43:47 INFO - Running setup.py install for docopt 08:43:47 INFO - Successfully installed blobuploader requests docopt 08:43:47 INFO - Cleaning up... 08:43:47 INFO - Return code: 0 08:43:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:47 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 0x1100d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe43489b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7bd30>, '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 0x10fd9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff72b20>, '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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:43:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aGyon1DQQJ/Render', 08:43:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:47 INFO - 'HOME': '/Users/cltbld', 08:43:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:47 INFO - 'LOGNAME': 'cltbld', 08:43:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:47 INFO - 'MOZ_NO_REMOTE': '1', 08:43:47 INFO - 'NO_EM_RESTART': '1', 08:43:47 INFO - 'PAGER': '/bin/cat', 08:43:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:47 INFO - 'PWD': '/builds/slave/test', 08:43:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:47 INFO - 'SHELL': '/bin/bash', 08:43:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FN8HTwtEnG/Listeners', 08:43:47 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 08:43:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:47 INFO - 'USER': 'cltbld', 08:43:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:47 INFO - 'XPC_FLAGS': '0x0', 08:43:47 INFO - 'XPC_SERVICE_NAME': '0', 08:43:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:47 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-voWxlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:48 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-l7fhVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:48 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-1IliYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:48 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-lNFLVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:48 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-W4lhVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:48 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-FhnuVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:48 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-a0qa21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:48 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-czqEUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:48 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-h29epr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:48 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-pRX5yv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:48 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-OP_YH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:49 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-fqPDGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:49 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-iqnBHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:49 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-gO9I3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:49 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-f4_UKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:49 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-4wJsLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:49 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-r4Ifcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:43:49 INFO - Running setup.py install for manifestparser 08:43:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:43:49 INFO - Running setup.py install for mozcrash 08:43:49 INFO - Running setup.py install for mozdebug 08:43:50 INFO - Running setup.py install for mozdevice 08:43:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:43:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:43:50 INFO - Running setup.py install for mozfile 08:43:50 INFO - Running setup.py install for mozhttpd 08:43:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:43:50 INFO - Running setup.py install for mozinfo 08:43:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:43:50 INFO - Running setup.py install for mozInstall 08:43:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:43:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:43:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:43:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:43:50 INFO - Running setup.py install for mozleak 08:43:50 INFO - Running setup.py install for mozlog 08:43:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:43:51 INFO - Running setup.py install for moznetwork 08:43:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:43:51 INFO - Running setup.py install for mozprocess 08:43:51 INFO - Running setup.py install for mozprofile 08:43:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:43:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:43:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:43:51 INFO - Running setup.py install for mozrunner 08:43:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:43:51 INFO - Running setup.py install for mozscreenshot 08:43:51 INFO - Running setup.py install for moztest 08:43:52 INFO - Running setup.py install for mozversion 08:43:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:43:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:43:52 INFO - Cleaning up... 08:43:52 INFO - Return code: 0 08:43:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:52 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 0x1100d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe43489b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7bd30>, '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 0x10fd9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff72b20>, '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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:43:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:43:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aGyon1DQQJ/Render', 08:43:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:52 INFO - 'HOME': '/Users/cltbld', 08:43:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:52 INFO - 'LOGNAME': 'cltbld', 08:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:52 INFO - 'MOZ_NO_REMOTE': '1', 08:43:52 INFO - 'NO_EM_RESTART': '1', 08:43:52 INFO - 'PAGER': '/bin/cat', 08:43:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:52 INFO - 'PWD': '/builds/slave/test', 08:43:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:52 INFO - 'SHELL': '/bin/bash', 08:43:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FN8HTwtEnG/Listeners', 08:43:52 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 08:43:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:52 INFO - 'USER': 'cltbld', 08:43:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:52 INFO - 'XPC_FLAGS': '0x0', 08:43:52 INFO - 'XPC_SERVICE_NAME': '0', 08:43:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:52 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-DIzVrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:52 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-2RRIfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:52 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-1cX1dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:52 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-lrmqBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:52 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-NXgphO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:53 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-z_uiiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:53 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-VEPQRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:53 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-zva4Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:53 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-SAoZPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:53 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-7csWbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:53 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)) 08:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:53 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-53gsZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:53 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-iOeyJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:53 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-PvU3tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:53 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-4_yUC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:54 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-3d6qLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:54 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-uabehm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:43:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:54 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-iTHKQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:56 INFO - Downloading blessings-1.6.tar.gz 08:43:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:43:56 INFO - Installing collected packages: blessings 08:43:56 INFO - Running setup.py install for blessings 08:43:57 INFO - Successfully installed blessings 08:43:57 INFO - Cleaning up... 08:43:57 INFO - Return code: 0 08:43:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:43:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:57 INFO - Reading from file tmpfile_stdout 08:43:57 INFO - Current package versions: 08:43:57 INFO - blessings == 1.6 08:43:57 INFO - blobuploader == 1.2.4 08:43:57 INFO - docopt == 0.6.1 08:43:57 INFO - manifestparser == 1.1 08:43:57 INFO - mozInstall == 1.12 08:43:57 INFO - mozcrash == 0.17 08:43:57 INFO - mozdebug == 0.1 08:43:57 INFO - mozdevice == 0.48 08:43:57 INFO - mozfile == 1.2 08:43:57 INFO - mozhttpd == 0.7 08:43:57 INFO - mozinfo == 0.9 08:43:57 INFO - mozleak == 0.1 08:43:57 INFO - mozlog == 3.2 08:43:57 INFO - moznetwork == 0.27 08:43:57 INFO - mozprocess == 0.22 08:43:57 INFO - mozprofile == 0.28 08:43:57 INFO - mozrunner == 6.11 08:43:57 INFO - mozscreenshot == 0.1 08:43:57 INFO - mozsystemmonitor == 0.3 08:43:57 INFO - moztest == 0.7 08:43:57 INFO - mozversion == 1.4 08:43:57 INFO - psutil == 3.1.1 08:43:57 INFO - requests == 1.2.3 08:43:57 INFO - wsgiref == 0.1.2 08:43:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43: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')]} 08:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43: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')]} 08:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:57 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 0x1100d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe43489b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7bd30>, '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 0x10fd9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff72b20>, '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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:43:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:43:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aGyon1DQQJ/Render', 08:43:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:57 INFO - 'HOME': '/Users/cltbld', 08:43:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:57 INFO - 'LOGNAME': 'cltbld', 08:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:57 INFO - 'MOZ_NO_REMOTE': '1', 08:43:57 INFO - 'NO_EM_RESTART': '1', 08:43:57 INFO - 'PAGER': '/bin/cat', 08:43:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:57 INFO - 'PWD': '/builds/slave/test', 08:43:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:57 INFO - 'SHELL': '/bin/bash', 08:43:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FN8HTwtEnG/Listeners', 08:43:57 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 08:43:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:57 INFO - 'USER': 'cltbld', 08:43:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:57 INFO - 'XPC_FLAGS': '0x0', 08:43:57 INFO - 'XPC_SERVICE_NAME': '0', 08:43:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:43:57 INFO - Cleaning up... 08:43:58 INFO - Return code: 0 08:43:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:43:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:58 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 0x1100d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe43489b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7bd30>, '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 0x10fd9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff72b20>, '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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:43:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:43:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:43:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aGyon1DQQJ/Render', 08:43:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:58 INFO - 'HOME': '/Users/cltbld', 08:43:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:58 INFO - 'LOGNAME': 'cltbld', 08:43:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:58 INFO - 'MOZ_NO_REMOTE': '1', 08:43:58 INFO - 'NO_EM_RESTART': '1', 08:43:58 INFO - 'PAGER': '/bin/cat', 08:43:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:58 INFO - 'PWD': '/builds/slave/test', 08:43:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:58 INFO - 'SHELL': '/bin/bash', 08:43:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FN8HTwtEnG/Listeners', 08:43:58 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 08:43:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:58 INFO - 'USER': 'cltbld', 08:43:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:58 INFO - 'XPC_FLAGS': '0x0', 08:43:58 INFO - 'XPC_SERVICE_NAME': '0', 08:43:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:58 INFO - Downloading/unpacking jsonschema 08:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:43:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:43:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:01 INFO - Downloading/unpacking functools32 (from jsonschema) 08:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:01 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:01 INFO - warning: no files found matching '*.txt' 08:44:01 INFO - no previously-included directories found matching 'build' 08:44:01 INFO - no previously-included directories found matching 'dist' 08:44:01 INFO - no previously-included directories found matching '.git*' 08:44:01 INFO - Installing collected packages: jsonschema, functools32 08:44:01 INFO - Running setup.py install for functools32 08:44:01 INFO - warning: no files found matching '*.txt' 08:44:01 INFO - no previously-included directories found matching 'build' 08:44:01 INFO - no previously-included directories found matching 'dist' 08:44:01 INFO - no previously-included directories found matching '.git*' 08:44:01 INFO - Successfully installed jsonschema functools32 08:44:01 INFO - Cleaning up... 08:44:01 INFO - Return code: 0 08:44:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:44:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1100d7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1100dd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe43489b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ff7bd30>, '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 0x10fd9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff72b20>, '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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:44:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 08:44:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:44:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aGyon1DQQJ/Render', 08:44:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:01 INFO - 'HOME': '/Users/cltbld', 08:44:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:01 INFO - 'LOGNAME': 'cltbld', 08:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:01 INFO - 'MOZ_NO_REMOTE': '1', 08:44:01 INFO - 'NO_EM_RESTART': '1', 08:44:01 INFO - 'PAGER': '/bin/cat', 08:44:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:01 INFO - 'PWD': '/builds/slave/test', 08:44:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:01 INFO - 'SHELL': '/bin/bash', 08:44:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FN8HTwtEnG/Listeners', 08:44:01 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 08:44:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:01 INFO - 'USER': 'cltbld', 08:44:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:01 INFO - 'XPC_FLAGS': '0x0', 08:44:01 INFO - 'XPC_SERVICE_NAME': '0', 08:44:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:02 INFO - Downloading/unpacking flake8 08:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:44:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:44:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44: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 08:44:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:44:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:44:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:44:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:44:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:44:05 INFO - no previously-included directories found matching 'docs/_build' 08:44:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:44:05 INFO - Running setup.py install for pep8 08:44:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:44:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:44:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:44:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:44:06 INFO - no previously-included directories found matching 'docs/_build' 08:44:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:44:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:44:06 INFO - Cleaning up... 08:44:06 INFO - Return code: 0 08:44:06 INFO - Running post-action listener: _resource_record_post_action 08:44:06 INFO - Running post-action listener: _start_resource_monitoring 08:44:06 INFO - Starting resource monitoring. 08:44:06 INFO - ##### 08:44:06 INFO - ##### Finished create-virtualenv step (success) 08:44:06 INFO - ##### 08:44:06 INFO - ##### 08:44:06 INFO - ##### Running install step. 08:44:06 INFO - ##### 08:44:06 INFO - Running pre-action listener: _resource_record_pre_action 08:44:06 INFO - Running main action method: install 08:44:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:06 INFO - Reading from file tmpfile_stdout 08:44:06 INFO - Detecting whether we're running mozinstall >=1.0... 08:44:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:44:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:44:06 INFO - Reading from file tmpfile_stdout 08:44:06 INFO - Output received: 08:44:06 INFO - Usage: mozinstall [options] installer 08:44:06 INFO - Options: 08:44:06 INFO - -h, --help show this help message and exit 08:44:06 INFO - -d DEST, --destination=DEST 08:44:06 INFO - Directory to install application into. [default: 08:44:06 INFO - "/builds/slave/test"] 08:44:06 INFO - --app=APP Application being installed. [default: firefox] 08:44:06 INFO - mkdir: /builds/slave/test/build/application 08:44:06 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'] 08:44:06 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 08:44:40 INFO - Reading from file tmpfile_stdout 08:44:40 INFO - Output received: 08:44:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:44:40 INFO - Running post-action listener: _resource_record_post_action 08:44:40 INFO - ##### 08:44:40 INFO - ##### Finished install step (success) 08:44:40 INFO - ##### 08:44:40 INFO - ##### 08:44:40 INFO - ##### Running run-tests step. 08:44:40 INFO - ##### 08:44:40 INFO - Running pre-action listener: _resource_record_pre_action 08:44:40 INFO - Running main action method: run_tests 08:44:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:44:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:44:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:44:40 INFO - 2016-05-26 08:44:40.802 screenresolution[1645:7677] starting screenresolution argv=screenresolution get 08:44:40 INFO - 2016-05-26 08:44:40.805 screenresolution[1645:7677] Display 0: 1600x1200x32@60 08:44:40 INFO - 2016-05-26 08:44:40.811 screenresolution[1646:7679] starting screenresolution argv=screenresolution list 08:44:40 INFO - Available Modes on Display 0 08:44:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:44:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:44:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:44:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:44:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:44:41 INFO - Intel Iris: 08:44:41 INFO - Chipset Model: Intel Iris 08:44:41 INFO - Type: GPU 08:44:41 INFO - Bus: Built-In 08:44:41 INFO - VRAM (Dynamic, Max): 1536 MB 08:44:41 INFO - Vendor: Intel (0x8086) 08:44:41 INFO - Device ID: 0x0a2e 08:44:41 INFO - Revision ID: 0x0009 08:44:41 INFO - Displays: 08:44:41 INFO - Display: 08:44:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:44:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:44:41 INFO - Display Serial Number: 1600x1200 60 08:44:41 INFO - Main Display: Yes 08:44:41 INFO - Mirror: Off 08:44:41 INFO - Online: Yes 08:44:41 INFO - Rotation: Supported 08:44:41 INFO - Return code: 0 08:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:44:41 INFO - Python 2.7.3 08:44:41 INFO - Return code: 0 08:44:41 INFO - grabbing minidump binary from tooltool 08:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:44:41 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 0x10ff7bd30>, '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 0x10fd9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ff72b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:44:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:44:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:44:41 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 08:44:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:44:41 INFO - Return code: 0 08:44:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:44:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:44:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:44:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:44:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:44:42 INFO - Return code: 0 08:44:42 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/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:44:42 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/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0221 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aGyon1DQQJ/Render', 08:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:42 INFO - 'HOME': '/Users/cltbld', 08:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:42 INFO - 'LOGNAME': 'cltbld', 08:44:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:44:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:42 INFO - 'MOZ_NO_REMOTE': '1', 08:44:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:44:42 INFO - 'NO_EM_RESTART': '1', 08:44:42 INFO - 'PAGER': '/bin/cat', 08:44:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:42 INFO - 'PWD': '/builds/slave/test', 08:44:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:42 INFO - 'SHELL': '/bin/bash', 08:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FN8HTwtEnG/Listeners', 08:44:42 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 08:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:42 INFO - 'USER': 'cltbld', 08:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:42 INFO - 'XPC_FLAGS': '0x0', 08:44:42 INFO - 'XPC_SERVICE_NAME': '0', 08:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:42 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/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:44:42 INFO - mozversion application_buildid: 20160526074444 08:44:42 INFO - mozversion application_changeset: 9cfc40ea2dbd0e258ec58028d85f4b358162e64a 08:44:42 INFO - mozversion application_display_name: Nightly 08:44:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:44:42 INFO - mozversion application_name: Firefox 08:44:42 INFO - mozversion application_remotingname: firefox 08:44:42 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:44:42 INFO - mozversion application_vendor: Mozilla 08:44:42 INFO - mozversion application_version: 49.0a1 08:44:42 INFO - mozversion platform_buildid: 20160526074444 08:44:42 INFO - mozversion platform_changeset: 9cfc40ea2dbd0e258ec58028d85f4b358162e64a 08:44:42 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:44:42 INFO - mozversion platform_version: 49.0a1 08:44:42 INFO - using testdate: 1464277482 08:44:42 INFO - actual date: 1464277482 08:44:42 INFO - starting webserver on 'localhost:49283' 08:44:42 INFO - SUITE-START | Running 2 tests 08:44:42 INFO - TEST-START | dromaeo_css 08:44:42 INFO - operating with platform_type : mac_ 08:44:42 INFO - Initialising browser for dromaeo_css test... 08:44:42 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpiQbIJ8/profile http://localhost:49283/getInfo.html) 08:44:44 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 08:44:44 INFO - PROCESS | 1664 | colorDepth:24 08:44:44 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 08:44:44 INFO - PROCESS | 1664 | __metrics 08:44:44 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:44:44 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:44:45 INFO - TEST-INFO | 1664: exit 0 08:44:45 INFO - Browser initialized. 08:44:45 INFO - Running cycle 1/1 for dromaeo_css test... 08:44:45 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpiQbIJ8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:57 INFO - PROCESS | 1666 | RSS: Main: 241381376 08:44:57 INFO - PROCESS | 1666 | 08:45:48 INFO - PROCESS | 1666 | 1464277548502 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:47:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 08:48:01 INFO - PROCESS | 1666 | RSS: Main: 345444352 08:48:01 INFO - PROCESS | 1666 | 08:51:04 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 08:51:04 INFO - PROCESS | 1666 | RSS: Main: 379764736 08:51:04 INFO - PROCESS | 1666 | 08:54:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 08:54:07 INFO - PROCESS | 1666 | RSS: Main: 370163712 08:54:07 INFO - PROCESS | 1666 | 08:57:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 08:57:10 INFO - PROCESS | 1666 | RSS: Main: 371834880 08:57:10 INFO - PROCESS | 1666 | 09:00:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 09:00:14 INFO - PROCESS | 1666 | RSS: Main: 343339008 09:00:14 INFO - PROCESS | 1666 | 09:03:17 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 09:03:17 INFO - PROCESS | 1666 | RSS: Main: 380829696 09:03:17 INFO - PROCESS | 1666 | 09:03:17 INFO - PROCESS | 1666 | __start_tp_report 09:03:17 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 09:03:17 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 09:03:17 INFO - PROCESS | 1666 | |i|pagename|runs| 09:03:17 INFO - PROCESS | 1666 | |0;dojo.html;3456.8098754568496;3459.9827000865007;3475.7914525128485;3480.4083305838003;3528.8588456461744;5566.248556444879;5615.943840561598;5625.971870140654;5643.887122257553;5690.687012214332;5667.4615911488345;5673.773049078042;5701;5769;5772.769089236436;4096.9552764045275;5088.18589025756;5092.79628814847;5169.896602067956;5315.574754019669;5313.574914006889;5314.468553144693;5331.386890507602;5349.144136938091;5374.543163831079;5135.024345374367;5196.714180720059;5198.558122559587;5199.350081239845;5249.343832020997;5212.322398088224;5225.869353266199;5231.712255825928;5232.2413250078525;5236.92144617831;5169.974150129263;5180.792768289264;5201.115810312256;5218.399884013349;5229.37247530295;5520.503154716095;5562.86092847678;5565.387807341189;5591.8881622367735;5606;6178.5366097542865;6273.498120150377;6285.214348206474;6296;6298.779542715983;6129.389354848367;6199.070139479069;6262.843428914268;6341.080543321239;6344.7462101515885;5737.454941780524;5743.569232307594;5777.508911742549;5778.624389414675;5833.270841144899;6342.809715708536;6378.744850206032;6395.584287021422;6409.67951602418;6415.13395691585;5907.438793314628;6055.6063855849225;6079.240094988127;6094.908576371358;6125.418085281891;6226.688665566703;6256.0303153010445;6257.2491301044165;6266.905996410058;6276.717547710364;6191.628502289877;6196.938030619636;6211.751529938751;6222.097795819581;6225.190725205694;6080;6105.938940610537;6205.131281620576;6216.316205217422;6233.501319894398;4852.441969173519;4866.829660961849;4890.168671325883;4890.972895691873;4910.48439913811;4389.890252743707;4391.055922976575;4393.450818647669;4440.222960981815;4441.644668426518;5580.637258578179;5662.150677398341;5696.743646535916;5702.6293290935955;5707.172459993278;6158.04550294705;6190.721417536221;6211.409916057967;6243.687815609202;6320.399562041599;5650.858728531737;5658.547316214693;5663.32040155184;5669.659820410789;5698.287714035746;5257.290265814143;5296.788128474895;5339.279197308391;5351.705656188947;5358.356997160366;6285.685715714196;6307.936920630735;6333.050042493663;6349.301576826638;6357.046443033582;6206.162168107247;6221.688915554295;6224.533160012927;6239.688015599111;6247.468965137832;6275.278342990615;6328.810135695937;6363.172787537591;6365.108884756045;6376.075469057054;5291.867703307448;6008.978473659576;6075.210222671025;6133.938660613337;6144.433169541739;6120.203342909657;6174.135621013152;6211.937880621317;6215.409536094064;6218;6023.45788879003;6031.788887389096;6044.002739547926;6048.4858787003595;6054.243219597551;6015.308239552332;6027.4876635484725;6028.336882942785;6032.336442991355;6044.365341639064;4907.533784290486;5789.2763404574425;5852.122181672783;5860.120981852756;5872.911906321323;5325.680459172462;6011.338752737108;6092.181584522955;6092.508893177778;6142.262928448615;5701.030824759718;5907.232059832194;5944.791932282349;5949.494292985145;5952.672603006876 09:03:17 INFO - PROCESS | 1666 | |1;ext.html;492.716687904455;500.6595515049767;518.7509995202305;545.7871430142244;546.9453054694528;26830.06094786683;41673.58326416735;42948.57051429485;43005.56994430059;43169;44013.7799311003;44480;44565.10869782602;44611;44717.88205294869;7545.471816972814;9503.999999999982;9670.791151106112;9724.805503889918;9738.659146929858;8623.051464338918;11782.234154779955;12038;12046;12063.879361206407;11837.289762614271;11910.880891191107;11916.880831191707;11929.880701192964;11945.46245418954;8839.29285657146;11606.825897611501;11738.647840564796;11770.646880593597;11893;11787.292762434281;11882.465289061927;11894.226875253167;11895.702607434796;11922.880771192264;40525.79737101325;40843.795781020905;40901;40936.38595421071;40950.795246023874;3017.909462716122;3100.2714362124884;3106.4718997770206;3145.308032232905;3270.460374038281;29369.70630293691;29586.85206573975;29603.40793184146;29994;30083.69916300831;2503.502011475642;2688.5832695932163;2689.811713180078;2691.286808995618;2777.6944536101014;516076;517417;518328;518623.4068829669;518719;34377.312453751285;65200;65498.36254093591;65695;65739.34260657428;29885.850570747007;34918.476222856676;34959.65040349615;34962.300753984775;35009.64990350115;14852.554423367315;15209.163496007826;15211.771823422436;15211.847881521044;15213.543593691986;11678.0657547396;11850.703732406759;12715.364231788373;12883.613491595268;12913.612591622266;7897.131315555284;7909.327707144842;7920.089189743138;7982.920170798334;7994.480358776777;2886.942261154765;3254.2189874429964;3274.8690052397637;3282.7209687176855;3317;1003.8444041173484;1029.5161274201114;1061.4480470155477;1071.8955548153192;1130.553431394611;25457;29501.85249073741;29658.9920721404;29689.24413969062;29692;1045.926785125034;1063.984040239397;1127.7744451109647;1132.9830052549219;1144.2390810111349;14984.550463486114;15139.54581362561;15144.54566363011;15259.389624415122;15368.61578460525;707408;712584;713509;714006;714692;59184;61226;61278.38721612816;61340.38659613436;61362.69318653378;1114.420501339299;1344.9663758405923;1429.0853853533538;1449.2681195996165;1469.2139705257637;28797;30142.246443839078;30212.84893575518;30760.384792304478;30819.229519262197;56490.71754641201;59314;60385.39614603973;60626.78746425135;60672.39327606667;645905;651774;652559;653243;653524;34530;35582.64417355897;35608.28783424369;39980.40029399503;43268.56731432645;38546.6145338543;38984.415233770946;45769;47022.76488617535;47219.763901180275;35958.46062309015;37036.6296337044;37787.11064446712;38652.6134738649;39102.02244943899;37259.06852328713;37549.81225093966;37744;38214.80892595519;38632.227355452174;37028.44457333196;37632;38445.807770960964;38473.615263847;38674.226515470094;31691.683083168875;33155.81549436812;33294.66705333012;33498.66501335053;33782.324353513286;21564.67652985175;22205.444863878532;24476.388090297885;25123.74876251214;25449.236522904343 09:03:17 INFO - PROCESS | 1666 | |2;jquery.html;327.98360081995895;332.87849934773817;379.96770274526665;381.4849952564038;406.03363993695;19636.90181549092;20999.790002099973;21135.943202839873;21176.941152942367;21499.677504837422;20266.69599956002;20626.89686551569;20862.95685215737;21090.89454552725;21210.04554795034;5140.948590514103;5852.999999999989;6049.848753781147;6075.756969721206;6089.147519347295;4159.438475805758;4389.912201755979;5996.580239383245;6219.346968568303;6596.208454985384;6445.129907462503;6552.279249282575;6560.573562718408;6572.244191917919;6574.309697481744;5768.192453056534;6360.1413809135865;6552.574082684634;6557.475401967855;6609.173853268341;3630.364686179908;3729.6339729035208;5265.6840589564745;5708.1723150143;5740.368559458456;25904.740952590422;26139.215823525134;26330.868345658342;26595.73404265952;26605.733942660518;1845.677006523853;1933.4103098554936;1949.33722534339;2025.8480613954011;2126.425865016437;10114.494275286206;10173.59305627774;10192.694219173363;10350.9482452588;10353.585856565729;1734.3669560610413;1773.0514174916357;1786.1426515272613;1817.2276782367442;1925.1914196037699;111750;114454.42772786166;117045;117564;119222;11726.237794543327;11751.236169648946;11770.293782373084;11777.470013849397;11779.764404711857;14761.966662333534;14856.405743770345;14892.478763243234;14910.552683419515;14925.552233433014;8666.966561703479;8685.001224859096;8762.036176020632;8785;8821.676748487778;8624.741257762276;8637.222649961408;8676.91323086761;8774.692885749097;8794.38439309255;4508.819647214074;4588.105319462739;4601.838935637236;4620.19146649335;4630.398048253705;1431.6277767780243;1548.6283292009837;1721.313388252302;1890.272245185586;1911.1399870058528;824.381713714714;882.8940527136784;911.3757076402685;939.6382392778829;978.0757184460616;19418.611627767365;20113.09491072905;20243.898780506002;20327.8983605084;20577.48556286105;878.6617152396245;913.5249670171473;932.7761337279004;942.1944237676688;1047.7642530430592;9850.852237216448;10296.794064118825;10659.09397701204;10749.408782517037;10808.459577021118;582662;584450;601407;601566;604637;113350;114603;115440.39802577665;117152;117636;977.6627063663025;977.9462129582942;991.509202944547;993.7863359377768;1002.5388321372251;17601.91199044048;17690.20394082295;17873.910630447284;17925.37261195852;18190.45428637143;43739.56260437355;49716.254256185464;50941;51404.48595514146;51813.740931295106;51166;64676.67661661661;67777.66111169412;71925;72206;7699.653515591924;7935.285824275843;7998.720044798637;8129.75610731679;8373.790655233668;8470.49177049379;8774.210321071134;11577.942110289732;12615.180013299107;12624.053196010153;10709.892901070889;10837.783244335227;10924.180686448708;11010.779784404427;11170;10754.677359679223;10802.08182304513;11040.668779936294;11139.05318047975;11341.886581134084;10950.780984380428;11093.778124437305;11432.37121958267;11441.199116061796;11483.48324325424;6931.133608298963;7413.666385012795;8244.340452763645;8266.173382661686;8505.91494085076;9348.485833279236;11105.167112466435;11109.722256943642;11142;11458.77082458363 09:03:17 INFO - PROCESS | 1666 | |3;mootools.html;1414.7524183267938;1573.5594033670573;1589.5310883289428;1728.256849554691;1852.7684039495064;7802.882956755653;8541.017782954954;8764.25503832175;8803.691870784523;8947.776305592364;8568.057513673491;8790;8803.955980220106;8812.647494100243;8863.955680221607;2363.905443782251;2392.988035059831;2397.8920948557356;2398.4843258699375;2399.7240317363553;1940.8544359173118;2368.9526209475885;2383.4279772854475;2384.391980045095;2384.5111752090866;2332.4518738096535;2347.507023525062;2356.6229403295474;2370.5140446208484;2375.1805627058725;2309.8036666883336;2332.5218330242346;2344.5076533927895;2345.308134100436;2358.3278765551736;2341.824363172752;2343.2267351773876;2343.8593684378993;2346.0381243690003;2347;8556.315494760467;8699.826003479895;8708.477491350539;8796.208341249318;8805.603747831363;5266.631335806495;5405.864853378657;5415.268938693304;5418.241446197536;5424;3399.473081672345;3479.9130021749406;3548.716102711777;3554.893353199408;3556.519869817593;991.3011327014437;993.9055789699967;997.6408492942535;998.2962011781749;1001.9599216031423;11527.942360288145;11615.76768464626;11619.360935148563;11638.068954483706;11647.475863586074;4457.531959144243;4536.296873984537;4625.4911959684405;4632.675712700146;4638.884027899262;7757.26306000929;9144.634214631473;9163.266938644892;9164.404313719588;9167.404118732262;5157.200633901752;5217.217417387422;5220.947790522122;5227.686338819683;5235.424103348629;4374.300111982067;4535.455745310584;4737.8341758039;4824.372831531879;4902.705837649681;3116.766242531774;3731.4029755239576;3811.2377524495214;3857.594952529944;3921.9607803922167;964.9951750241204;1142.0292751161446;1152.2855829385835;1161.2219812725493;1163.825426186062;613.8158552434252;765.6784150656738;903.5617725403174;907.1699395053453;945.8344789661782;5620.887582248332;5819.214406055213;5921.141434491975;5966.075258334965;5967.254093238345;936.3585943628636;937.9577918993662;968.5689868008809;968.6561270748923;975.1223898491272;3680.9079773005888;3698.093966978108;3818.102745854722;3846.442265871489;3891.727579069438;10331.121854642439;10568.418736969541;10724.946375268073;10725.356478611307;10755.731106722238;10633.574657013633;10747.677569672922;10751.462426878781;10752.301100428447;10771;7787.610619469117;8005.159458256916;8217.83564328722;8219.671213151407;8225.424220304641;5607.915881261702;5616.1856530803625;5882.294124705063;5911.822645320647;5952.8809423812145;9474.810503790024;10047.547860346156;10082.495875206063;10085.083834071504;10085.747856303651;9963.405855063154;10073.647422340184;10169.084782369622;10225.079742823182;10234;7433.8141546461775;7468.551886886804;7482.214367491552;7550.433717471102;7557.168711441847;8042.3968202383885;8081.717139900075;8130.02439707239;8141.633626486704;8153;7239.203687594466;7418.851622967619;7483.588402637907;7538.547687138789;7559.697612095454;6349.936500634934;7189.604571748604;7288.927110728825;7311.122665280201;7423.665935033045;6753.628550429774;6812.114425124901;6823.829404264735;6825.692843822139;6836.794896153124;4655.674102812771;4762.761861906822;4886.218205087169;4927.630427718007;4957.677750946177;4077.4903137107863;4534.886627834198;4534.954650453453;4682.695624784378;4799.952000479951 09:03:17 INFO - PROCESS | 1666 | |4;prototype.html;297.8644716653923;298.1294619710445;314.7104663709388;316.80516482363356;319.7138560987916;5347.278117454142;5351.190624921918;5351.946480535194;5370.3287089113865;5400.810971615994;3909.6932809642303;5086.278840820162;5208.687478751278;5340.54605358544;5350.211560653213;4041.3533834586474;4094.97952510237;4097.938530922039;4110.568390319018;4116.423700681907;3178.55500229968;4083.734557253784;4090.713650044498;4103.548609652936;4232.407462955173;3309.9503507447407;3748.175401411685;3827.885163445101;4036.5559788423398;4095.5904409558952;3520.454329578894;3539.80531070791;4019.8995025124314;4041.6564592009713;4059.8782036538805;2988.610296212272;3055.4111861831943;3434.0384692286198;3530.5763308403157;3543.3090547343277;4885.487023862514;5118.667286626357;5125.7185703574105;5140.897182056376;5169.715665638389;4496.325551167319;4593.609543188833;4694.671373003891;4696.647751418623;4735.881602959919;5604.747786349583;5607.000254863669;5607.186957891124;5648.983183027053;5649.209110724501;3308.9007329780147;3456.3778519866414;3905.1994341159993;3934.429507721364;3949.664278536299;4775.856724298276;4913.31213630092;5938.079597662369;5966.420147911262;5972.999999999956;3480.965190348115;3887.8056097195026;3892.9610703893163;3901.2782635212575;3916;3947.921041579152;3950.822213000422;3959.5050618672667;3961.445397644332;3997.540282867449;3862.7682339059747;4451.998300382453;4514.9005712343915;4534.523874993144;4617.284320930261;4908.386451693538;4953.23224900141;5596.86007849807;5646.774129034875;5676.829695109153;1264.9557265495664;1302.6743314171458;1323.3647849032325;1348.548236340817;1350.156152404747;777.6889244302324;881.713443130985;910.4855756497558;912.3339961827903;918.3800934369274;4583.014651849869;4994.8251811187165;5151.4333423323405;5283.520614228022;5341.706206158621;4654.394928659252;5183.315422487632;5430.83707488776;5730.914036289459;5846.766129354864;4741.786619602126;4953.083679519301;5352.518273355339;5369.704666243394;5469.808556700496;3003.669596344444;3058.6788387219467;3058.709422604849;3065.3103051813164;3082.321889184376;4919.901601968012;5259.658122222043;5850.3564607893095;6015.909761353583;6080.8783824324155;5188.5330320270605;5460.590455715837;5934.851628709317;5973.910391344133;5984.910226346521;5094.974525127351;5401.297831281908;5795.7971470999155;5847.26909136358;6080.179175811209;5126.718030508208;5412;5827.621204621686;5844.47399734009;5896.557758168069;4858.78135483897;5070.16071000241;5704.828855134353;5706.372299047019;5760.423957604374;5746.615154710748;5844.977129002323;6059.969700151471;6093.390660933871;6117.3270940197435;3868.168343806095;3877.2665270983325;3878.5539662939123;3884.514435695538;3886.8833934981994;5101.46434624374;5137.486251374833;5804.883902321845;5811.79658711943;5818;5454.10007348783;5639.436056394327;5652.547796176378;5663.830085097454;5686.402927692533;5574.581906356958;5605.131204663284;5642.689652069175;5647.588102974257;5650.604457688087;3099.9535006975366;4873.927735898097;5396.919046214389;5430.484104010113;5566.582506311962;4527.501974782837;5019.573336266312;5624.356552420356;5631.267935168402;5635.211070450058;5246.737663116753;5331.546818520314;5455.0726376515295;5538.6122971391615;5548.19815992921 09:03:17 INFO - PROCESS | 1666 | |5;yui.html;631.352863315102;647.540246425038;679.0968012543318;693.9861202775942;743.0748717846283;14970.625734356632;15529.301181446834;15542.84457155428;15564.766528502067;15588.064716117027;13846.961477889148;13918.652033699163;14091.506797262098;14169.858301416984;14177.362018709156;878.5651102704154;880.2957633892892;896.9596368163416;897.6902968475898;899.204204279211;884.3014018925041;884.907084756101;885.6103314541581;887.2547060469204;887.7736177274786;933.2394098809449;933.7012156110047;933.9626414943394;935.7426707655416;936.7845395559026;925.4909799610222;928.8003079337907;930.5765876526165;930.9394889332206;932.0120672087575;691.6092407789582;692.1313751242209;693.1508901595555;693.9444844412485;695.4749164380911;12626.30555319457;12814.231146131262;12832.9733621311;13017.674558136028;13269.469221231138;10932.180086493545;10956.780864382747;11015.559377624966;11256.155788315828;11566.768664626745;8987.505687187202;9068.835619860303;9144.222741067017;9160.496172710498;9435.811283774356;3324.9168770780925;3329.7336213102894;3335.7498187635783;3386.72906167506;3449.741269404805;42626.78686606547;64945.3505464944;68030.63958642483;73316.63341683311;73393;7719.498232614862;9649.855252171223;9940.950295248478;9964.25268104895;10421.478926053667;22019;22298.88850555737;22338.55322893533;22388;22501.43746406353;7357.558546487228;7403.9629801851725;7483.812904677427;7575.6969721211635;7729.3430058445665;3879.7866117363815;3944.487216661873;3984.8007599619905;3994.6005399459823;4038.5759495252573;3449.6722811332884;3539.9292014160087;3541.8760343387858;3543.698785603201;3552.5559305086863;608.7717106085219;622.8909940760349;624.6096189881324;633.0693879996489;647.4626060369882;530.191458026505;562.1696388875118;575.9913601295984;593.6794131169206;602.86736917878;7732.342750866127;7934.127246002935;7967.9203207967175;8162.18378162179;8202.671893124327;571.3258355140973;582.5223316880117;583.2825624481826;585.1018686316484;590.1089355073784;3714.869979550757;3716.3496388132507;3765.359888818907;3780.4707340972286;3972.503437070366;88315;88547;89740.10259897448;89762.55118724365;89816;77794;79436;79815.8027629586;79983.20016799758;82246;69497.65251173712;70493.647531762;70548.64725676441;70584.64707676429;71204.28795711977;6544.756496265694;7113.679884405317;7420.369268612229;7579.431542634215;7861.567613781068;44582.777086114365;46115.53884461246;46122.61632151041;47474;47699.284510731675;63906.36093639004;63987.68006159939;64078.03882941666;64122;64333.67833160804;7791.4935529190425;9284.58219380143;9825.066618671215;10325.380477171393;10647.094996925038;10825.133989280996;11034.558617655204;11128.38793866345;11301.15241356918;11619;8084.110747817617;8119.39104567148;8127.715529956423;8636.82726345457;10568.418736969234;9986.800263994824;9990.550425231027;10019.248556358156;10143.137833284254;10366.326188797704;9735.318527702993;9752.171065459232;9785.36395134314;9860.506974651382;9865.556049977624;7239.782806515813;8278.586070696561;8387.874181887155;8731.91268087311;8897.955510222408;8214.26071653554;8355.874661879956;8495.95752021236;8535.786605334917;8544.91455085466 09:03:17 INFO - PROCESS | 1666 | __end_tp_report 09:03:17 INFO - PROCESS | 1666 | __start_cc_report 09:03:17 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1158 09:03:17 INFO - PROCESS | 1666 | __end_cc_report 09:03:17 INFO - PROCESS | 1666 | __startTimestamp1464278597505__endTimestamp 09:03:17 INFO - PROCESS | 1666 | 09:03:17 INFO - PROCESS | 1666 | ------- Summary: start ------- 09:03:17 INFO - PROCESS | 1666 | Number of tests: 6 09:03:17 INFO - PROCESS | 1666 | 09:03:17 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5709.74 Median:5872.91 stddev:639.34 (10.9%) stddev-sans-first:616.38 09:03:17 INFO - PROCESS | 1666 | Values: 3456.8 3460.0 3475.8 3480.4 3528.9 5566.2 5615.9 5626.0 5643.9 5690.7 5667.5 5673.8 5701.0 5769.0 5772.8 4097.0 5088.2 5092.8 5169.9 5315.6 5313.6 5314.5 5331.4 5349.1 5374.5 5135.0 5196.7 5198.6 5199.4 5249.3 5212.3 5225.9 5231.7 5232.2 5236.9 5170.0 5180.8 5201.1 5218.4 5229.4 5520.5 5562.9 5565.4 5591.9 5606.0 6178.5 6273.5 6285.2 6296.0 6298.8 6129.4 6199.1 6262.8 6341.1 6344.7 5737.5 5743.6 5777.5 5778.6 5833.3 6342.8 6378.7 6395.6 6409.7 6415.1 5907.4 6055.6 6079.2 6094.9 6125.4 6226.7 6256.0 6257.2 6266.9 6276.7 6191.6 6196.9 6211.8 6222.1 6225.2 6080.0 6105.9 6205.1 6216.3 6233.5 4852.4 4866.8 4890.2 4891.0 4910.5 4389.9 4391.1 4393.5 4440.2 4441.6 5580.6 5662.2 5696.7 5702.6 5707.2 6158.0 6190.7 6211.4 6243.7 6320.4 5650.9 5658.5 5663.3 5669.7 5698.3 5257.3 5296.8 5339.3 5351.7 5358.4 6285.7 6307.9 6333.1 6349.3 6357.0 6206.2 6221.7 6224.5 6239.7 6247.5 6275.3 6328.8 6363.2 6365.1 6376.1 5291.9 6009.0 6075.2 6133.9 6144.4 6120.2 6174.1 6211.9 6215.4 6218.0 6023.5 6031.8 6044.0 6048.5 6054.2 6015.3 6027.5 6028.3 6032.3 6044.4 4907.5 5789.3 5852.1 5860.1 5872.9 5325.7 6011.3 6092.2 6092.5 6142.3 5701.0 5907.2 5944.8 5949.5 5952.7 09:03:17 INFO - PROCESS | 1666 | 09:03:17 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:74439.23 Median:34453.66 stddev:169717.04 (492.6%) stddev-sans-first:170102.33 09:03:17 INFO - PROCESS | 1666 | Values: 492.7 500.7 518.8 545.8 546.9 26830.1 41673.6 42948.6 43005.6 43169.0 44013.8 44480.0 44565.1 44611.0 44717.9 7545.5 9504.0 9670.8 9724.8 9738.7 8623.1 11782.2 12038.0 12046.0 12063.9 11837.3 11910.9 11916.9 11929.9 11945.5 8839.3 11606.8 11738.6 11770.6 11893.0 11787.3 11882.5 11894.2 11895.7 11922.9 40525.8 40843.8 40901.0 40936.4 40950.8 3017.9 3100.3 3106.5 3145.3 3270.5 29369.7 29586.9 29603.4 29994.0 30083.7 2503.5 2688.6 2689.8 2691.3 2777.7 516076.0 517417.0 518328.0 518623.4 518719.0 34377.3 65200.0 65498.4 65695.0 65739.3 29885.9 34918.5 34959.7 34962.3 35009.6 14852.6 15209.2 15211.8 15211.8 15213.5 11678.1 11850.7 12715.4 12883.6 12913.6 7897.1 7909.3 7920.1 7982.9 7994.5 2886.9 3254.2 3274.9 3282.7 3317.0 1003.8 1029.5 1061.4 1071.9 1130.6 25457.0 29501.9 29659.0 29689.2 29692.0 1045.9 1064.0 1127.8 1133.0 1144.2 14984.6 15139.5 15144.5 15259.4 15368.6 707408.0 712584.0 713509.0 714006.0 714692.0 59184.0 61226.0 61278.4 61340.4 61362.7 1114.4 1345.0 1429.1 1449.3 1469.2 28797.0 30142.2 30212.8 30760.4 30819.2 56490.7 59314.0 60385.4 60626.8 60672.4 645905.0 651774.0 652559.0 653243.0 653524.0 34530.0 35582.6 35608.3 39980.4 43268.6 38546.6 38984.4 45769.0 47022.8 47219.8 35958.5 37036.6 37787.1 38652.6 39102.0 37259.1 37549.8 37744.0 38214.8 38632.2 37028.4 37632.0 38445.8 38473.6 38674.2 31691.7 33155.8 33294.7 33498.7 33782.3 21564.7 22205.4 24476.4 25123.7 25449.2 09:03:17 INFO - PROCESS | 1666 | 09:03:17 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34042.93 Median:26463.30 stddev:98867.27 (373.6%) stddev-sans-first:99112.21 09:03:17 INFO - PROCESS | 1666 | Values: 328.0 332.9 380.0 381.5 406.0 19636.9 20999.8 21135.9 21176.9 21499.7 20266.7 20626.9 20863.0 21090.9 21210.0 5140.9 5853.0 6049.8 6075.8 6089.1 4159.4 4389.9 5996.6 6219.3 6596.2 6445.1 6552.3 6560.6 6572.2 6574.3 5768.2 6360.1 6552.6 6557.5 6609.2 3630.4 3729.6 5265.7 5708.2 5740.4 25904.7 26139.2 26330.9 26595.7 26605.7 1845.7 1933.4 1949.3 2025.8 2126.4 10114.5 10173.6 10192.7 10350.9 10353.6 1734.4 1773.1 1786.1 1817.2 1925.2 111750.0 114454.4 117045.0 117564.0 119222.0 11726.2 11751.2 11770.3 11777.5 11779.8 14762.0 14856.4 14892.5 14910.6 14925.6 8667.0 8685.0 8762.0 8785.0 8821.7 8624.7 8637.2 8676.9 8774.7 8794.4 4508.8 4588.1 4601.8 4620.2 4630.4 1431.6 1548.6 1721.3 1890.3 1911.1 824.4 882.9 911.4 939.6 978.1 19418.6 20113.1 20243.9 20327.9 20577.5 878.7 913.5 932.8 942.2 1047.8 9850.9 10296.8 10659.1 10749.4 10808.5 582662.0 584450.0 601407.0 601566.0 604637.0 113350.0 114603.0 115440.4 117152.0 117636.0 977.7 977.9 991.5 993.8 1002.5 17601.9 17690.2 17873.9 17925.4 18190.5 43739.6 49716.3 50941.0 51404.5 51813.7 51166.0 64676.7 67777.7 71925.0 72206.0 7699.7 7935.3 7998.7 8129.8 8373.8 8470.5 8774.2 11577.9 12615.2 12624.1 10709.9 10837.8 10924.2 11010.8 11170.0 10754.7 10802.1 11040.7 11139.1 11341.9 10950.8 11093.8 11432.4 11441.2 11483.5 6931.1 7413.7 8244.3 8266.2 8505.9 9348.5 11105.2 11109.7 11142.0 11458.8 09:03:17 INFO - PROCESS | 1666 | 09:03:17 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5615.43 Median:4915.17 stddev:3173.33 (64.6%) stddev-sans-first:3166.53 09:03:17 INFO - PROCESS | 1666 | Values: 1414.8 1573.6 1589.5 1728.3 1852.8 7802.9 8541.0 8764.3 8803.7 8947.8 8568.1 8790.0 8804.0 8812.6 8864.0 2363.9 2393.0 2397.9 2398.5 2399.7 1940.9 2369.0 2383.4 2384.4 2384.5 2332.5 2347.5 2356.6 2370.5 2375.2 2309.8 2332.5 2344.5 2345.3 2358.3 2341.8 2343.2 2343.9 2346.0 2347.0 8556.3 8699.8 8708.5 8796.2 8805.6 5266.6 5405.9 5415.3 5418.2 5424.0 3399.5 3479.9 3548.7 3554.9 3556.5 991.3 993.9 997.6 998.3 1002.0 11527.9 11615.8 11619.4 11638.1 11647.5 4457.5 4536.3 4625.5 4632.7 4638.9 7757.3 9144.6 9163.3 9164.4 9167.4 5157.2 5217.2 5220.9 5227.7 5235.4 4374.3 4535.5 4737.8 4824.4 4902.7 3116.8 3731.4 3811.2 3857.6 3922.0 965.0 1142.0 1152.3 1161.2 1163.8 613.8 765.7 903.6 907.2 945.8 5620.9 5819.2 5921.1 5966.1 5967.3 936.4 938.0 968.6 968.7 975.1 3680.9 3698.1 3818.1 3846.4 3891.7 10331.1 10568.4 10724.9 10725.4 10755.7 10633.6 10747.7 10751.5 10752.3 10771.0 7787.6 8005.2 8217.8 8219.7 8225.4 5607.9 5616.2 5882.3 5911.8 5952.9 9474.8 10047.5 10082.5 10085.1 10085.7 9963.4 10073.6 10169.1 10225.1 10234.0 7433.8 7468.6 7482.2 7550.4 7557.2 8042.4 8081.7 8130.0 8141.6 8153.0 7239.2 7418.9 7483.6 7538.5 7559.7 6349.9 7189.6 7288.9 7311.1 7423.7 6753.6 6812.1 6823.8 6825.7 6836.8 4655.7 4762.8 4886.2 4927.6 4957.7 4077.5 4534.9 4535.0 4682.7 4800.0 09:03:17 INFO - PROCESS | 1666 | 09:03:17 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4523.55 Median:5110.07 stddev:1401.75 (27.4%) stddev-sans-first:1369.33 09:03:17 INFO - PROCESS | 1666 | Values: 297.9 298.1 314.7 316.8 319.7 5347.3 5351.2 5351.9 5370.3 5400.8 3909.7 5086.3 5208.7 5340.5 5350.2 4041.4 4095.0 4097.9 4110.6 4116.4 3178.6 4083.7 4090.7 4103.5 4232.4 3310.0 3748.2 3827.9 4036.6 4095.6 3520.5 3539.8 4019.9 4041.7 4059.9 2988.6 3055.4 3434.0 3530.6 3543.3 4885.5 5118.7 5125.7 5140.9 5169.7 4496.3 4593.6 4694.7 4696.6 4735.9 5604.7 5607.0 5607.2 5649.0 5649.2 3308.9 3456.4 3905.2 3934.4 3949.7 4775.9 4913.3 5938.1 5966.4 5973.0 3481.0 3887.8 3893.0 3901.3 3916.0 3947.9 3950.8 3959.5 3961.4 3997.5 3862.8 4452.0 4514.9 4534.5 4617.3 4908.4 4953.2 5596.9 5646.8 5676.8 1265.0 1302.7 1323.4 1348.5 1350.2 777.7 881.7 910.5 912.3 918.4 4583.0 4994.8 5151.4 5283.5 5341.7 4654.4 5183.3 5430.8 5730.9 5846.8 4741.8 4953.1 5352.5 5369.7 5469.8 3003.7 3058.7 3058.7 3065.3 3082.3 4919.9 5259.7 5850.4 6015.9 6080.9 5188.5 5460.6 5934.9 5973.9 5984.9 5095.0 5401.3 5795.8 5847.3 6080.2 5126.7 5412.0 5827.6 5844.5 5896.6 4858.8 5070.2 5704.8 5706.4 5760.4 5746.6 5845.0 6060.0 6093.4 6117.3 3868.2 3877.3 3878.6 3884.5 3886.9 5101.5 5137.5 5804.9 5811.8 5818.0 5454.1 5639.4 5652.5 5663.8 5686.4 5574.6 5605.1 5642.7 5647.6 5650.6 3100.0 4873.9 5396.9 5430.5 5566.6 4527.5 5019.6 5624.4 5631.3 5635.2 5246.7 5331.5 5455.1 5538.6 5548.2 09:03:17 INFO - PROCESS | 1666 | 09:03:17 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17205.82 Median:692.64 stddev:24485.27 (3535.1%) stddev-sans-first:24522.33 09:03:17 INFO - PROCESS | 1666 | Values: 631.4 647.5 679.1 694.0 743.1 14970.6 15529.3 15542.8 15564.8 15588.1 13847.0 13918.7 14091.5 14169.9 14177.4 878.6 880.3 897.0 897.7 899.2 884.3 884.9 885.6 887.3 887.8 933.2 933.7 934.0 935.7 936.8 925.5 928.8 930.6 930.9 932.0 691.6 692.1 693.2 693.9 695.5 12626.3 12814.2 12833.0 13017.7 13269.5 10932.2 10956.8 11015.6 11256.2 11566.8 8987.5 9068.8 9144.2 9160.5 9435.8 3324.9 3329.7 3335.7 3386.7 3449.7 42626.8 64945.4 68030.6 73316.6 73393.0 7719.5 9649.9 9941.0 9964.3 10421.5 22019.0 22298.9 22338.6 22388.0 22501.4 7357.6 7404.0 7483.8 7575.7 7729.3 3879.8 3944.5 3984.8 3994.6 4038.6 3449.7 3539.9 3541.9 3543.7 3552.6 608.8 622.9 624.6 633.1 647.5 530.2 562.2 576.0 593.7 602.9 7732.3 7934.1 7967.9 8162.2 8202.7 571.3 582.5 583.3 585.1 590.1 3714.9 3716.3 3765.4 3780.5 3972.5 88315.0 88547.0 89740.1 89762.6 89816.0 77794.0 79436.0 79815.8 79983.2 82246.0 69497.7 70493.6 70548.6 70584.6 71204.3 6544.8 7113.7 7420.4 7579.4 7861.6 44582.8 46115.5 46122.6 47474.0 47699.3 63906.4 63987.7 64078.0 64122.0 64333.7 7791.5 9284.6 9825.1 10325.4 10647.1 10825.1 11034.6 11128.4 11301.2 11619.0 8084.1 8119.4 8127.7 8636.8 10568.4 9986.8 9990.6 10019.2 10143.1 10366.3 9735.3 9752.2 9785.4 9860.5 9865.6 7239.8 8278.6 8387.9 8731.9 8898.0 8214.3 8355.9 8496.0 8535.8 8544.9 09:03:17 INFO - PROCESS | 1666 | -------- Summary: end -------- 09:03:17 INFO - PROCESS | 1666 | 09:03:18 INFO - TEST-INFO | 1666: exit 0 09:03:18 INFO - TEST-OK | dromaeo_css | took 1115597ms 09:03:18 INFO - TEST-START | kraken 09:03:18 INFO - operating with platform_type : mac_ 09:03:18 INFO - Initialising browser for kraken test... 09:03:18 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmp7cPgmB/profile http://localhost:49283/getInfo.html) 09:03:20 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 09:03:20 INFO - PROCESS | 1672 | colorDepth:24 09:03:20 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 09:03:20 INFO - PROCESS | 1672 | __metrics 09:03:20 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:03:20 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:03:20 INFO - TEST-INFO | 1672: exit 0 09:03:20 INFO - Browser initialized. 09:03:20 INFO - Running cycle 1/1 for kraken test... 09:03:20 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmp7cPgmB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:32 INFO - PROCESS | 1673 | RSS: Main: 241287168 09:03:32 INFO - PROCESS | 1673 | 09:03:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 09:03:36 INFO - PROCESS | 1673 | RSS: Main: 289083392 09:03:36 INFO - PROCESS | 1673 | 09:03:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 09:03:39 INFO - PROCESS | 1673 | RSS: Main: 297213952 09:03:39 INFO - PROCESS | 1673 | 09:03:43 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 09:03:44 INFO - PROCESS | 1673 | RSS: Main: 384643072 09:03:44 INFO - PROCESS | 1673 | 09:03:46 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 09:03:46 INFO - PROCESS | 1673 | RSS: Main: 285249536 09:03:46 INFO - PROCESS | 1673 | 09:03:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:03:49 INFO - PROCESS | 1673 | RSS: Main: 289824768 09:03:49 INFO - PROCESS | 1673 | 09:03:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 09:03:52 INFO - PROCESS | 1673 | RSS: Main: 327139328 09:03:52 INFO - PROCESS | 1673 | 09:03:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 09:03:56 INFO - PROCESS | 1673 | RSS: Main: 327593984 09:03:56 INFO - PROCESS | 1673 | 09:03:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 09:03:59 INFO - PROCESS | 1673 | RSS: Main: 329199616 09:03:59 INFO - PROCESS | 1673 | 09:04:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:02 INFO - PROCESS | 1673 | RSS: Main: 315064320 09:04:02 INFO - PROCESS | 1673 | 09:04:03 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:04 INFO - PROCESS | 1673 | RSS: Main: 321150976 09:04:04 INFO - PROCESS | 1673 | 09:04:06 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:07 INFO - PROCESS | 1673 | RSS: Main: 325148672 09:04:07 INFO - PROCESS | 1673 | 09:04:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:04:11 INFO - PROCESS | 1673 | RSS: Main: 329502720 09:04:11 INFO - PROCESS | 1673 | 09:04:13 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:04:14 INFO - PROCESS | 1673 | RSS: Main: 327266304 09:04:14 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 09:04:16 INFO - PROCESS | 1673 | RSS: Main: 373768192 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | __start_tp_report 09:04:16 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 09:04:16 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 09:04:16 INFO - PROCESS | 1673 | |i|pagename|runs| 09:04:16 INFO - PROCESS | 1673 | |0;ai-astar;113;108;165;101;158;166;163;113;112;113 09:04:16 INFO - PROCESS | 1673 | |1;audio-beat-detection;159;115;117;168;118;121;141;119;153;117 09:04:16 INFO - PROCESS | 1673 | |2;audio-dft;182;182;186;183;182;185;183;181;183;184 09:04:16 INFO - PROCESS | 1673 | |3;audio-fft;78;58;65;84;57;57;66;79;110;80 09:04:16 INFO - PROCESS | 1673 | |4;audio-oscillator;90;69;101;71;70;69;96;70;68;99 09:04:16 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;90;88;91;89;91;88;91;88;91;88 09:04:16 INFO - PROCESS | 1673 | |6;imaging-darkroom;95;97;96;96;96;95;97;96;96;95 09:04:16 INFO - PROCESS | 1673 | |7;imaging-desaturate;85;85;85;86;84;85;85;85;84;86 09:04:16 INFO - PROCESS | 1673 | |8;json-parse-financial;57;56;59;57;57;59;54;69;80;86 09:04:16 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;68;77;75;77;73;70;71;73;77;70 09:04:16 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;87;87;86;86;107;106;84;86;84;84 09:04:16 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;138;139;145;148;144;149;138;137;183;147 09:04:16 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;167;163;165;161;167;165;166;165;164;165 09:04:16 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;67;68;66;66;68;65;67;64;62;61 09:04:16 INFO - PROCESS | 1673 | __end_tp_report 09:04:16 INFO - PROCESS | 1673 | __start_cc_report 09:04:16 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2095 09:04:16 INFO - PROCESS | 1673 | __end_cc_report 09:04:16 INFO - PROCESS | 1673 | __startTimestamp1464278656493__endTimestamp 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | ------- Summary: start ------- 09:04:16 INFO - PROCESS | 1673 | Number of tests: 14 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:131.20 Median:135.50 stddev:27.68 (20.4%) stddev-sans-first:28.56 09:04:16 INFO - PROCESS | 1673 | Values: 113.0 108.0 165.0 101.0 158.0 166.0 163.0 113.0 112.0 113.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:131.00 stddev:20.45 (15.6%) stddev-sans-first:19.37 09:04:16 INFO - PROCESS | 1673 | Values: 159.0 115.0 117.0 168.0 118.0 121.0 141.0 119.0 153.0 117.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.00 stddev:1.52 (0.8%) stddev-sans-first:1.56 09:04:16 INFO - PROCESS | 1673 | Values: 182.0 182.0 186.0 183.0 182.0 185.0 183.0 181.0 183.0 184.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:73.40 Median:72.00 stddev:16.49 (22.9%) stddev-sans-first:17.41 09:04:16 INFO - PROCESS | 1673 | Values: 78.0 58.0 65.0 84.0 57.0 57.0 66.0 79.0 110.0 80.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:70.50 stddev:14.24 (20.2%) stddev-sans-first:14.66 09:04:16 INFO - PROCESS | 1673 | Values: 90.0 69.0 101.0 71.0 70.0 69.0 96.0 70.0 68.0 99.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:90.50 stddev:1.43 (1.6%) stddev-sans-first:1.51 09:04:16 INFO - PROCESS | 1673 | Values: 90.0 88.0 91.0 89.0 91.0 88.0 91.0 88.0 91.0 88.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 09:04:16 INFO - PROCESS | 1673 | Values: 95.0 97.0 96.0 96.0 96.0 95.0 97.0 96.0 96.0 95.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 09:04:16 INFO - PROCESS | 1673 | Values: 85.0 85.0 85.0 86.0 84.0 85.0 85.0 85.0 84.0 86.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:59.00 stddev:11.17 (18.9%) stddev-sans-first:11.60 09:04:16 INFO - PROCESS | 1673 | Values: 57.0 56.0 59.0 57.0 57.0 59.0 54.0 69.0 80.0 86.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:74.00 stddev:3.31 (4.5%) stddev-sans-first:2.96 09:04:16 INFO - PROCESS | 1673 | Values: 68.0 77.0 75.0 77.0 73.0 70.0 71.0 73.0 77.0 70.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:86.00 stddev:8.93 (10.4%) stddev-sans-first:9.42 09:04:16 INFO - PROCESS | 1673 | Values: 87.0 87.0 86.0 86.0 107.0 106.0 84.0 86.0 84.0 84.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:146.00 stddev:13.50 (9.2%) stddev-sans-first:13.94 09:04:16 INFO - PROCESS | 1673 | Values: 138.0 139.0 145.0 148.0 144.0 149.0 138.0 137.0 183.0 147.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.80 Median:165.00 stddev:1.81 (1.1%) stddev-sans-first:1.74 09:04:16 INFO - PROCESS | 1673 | Values: 167.0 163.0 165.0 161.0 167.0 165.0 166.0 165.0 164.0 165.0 09:04:16 INFO - PROCESS | 1673 | 09:04:16 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.50 stddev:2.41 (3.6%) stddev-sans-first:2.49 09:04:16 INFO - PROCESS | 1673 | Values: 67.0 68.0 66.0 66.0 68.0 65.0 67.0 64.0 62.0 61.0 09:04:16 INFO - PROCESS | 1673 | -------- Summary: end -------- 09:04:16 INFO - PROCESS | 1673 | 09:04:17 INFO - TEST-INFO | 1673: exit 0 09:04:17 INFO - TEST-OK | kraken | took 59192ms 09:04:17 INFO - SUITE-END | took 1174s 09:04:17 INFO - Completed test suite (00:19:35) 09:04:17 INFO - javascript benchmark 09:04:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3456.8098754568496, 3459.9827000865007, 3475.7914525128485, 3480.4083305838003, 3528.8588456461744, 5566.248556444879, 5615.943840561598, 5625.971870140654, 5643.887122257553, 5690.687012214332, 5667.4615911488345, 5673.773049078042, 5701.0, 5769.0, 5772.769089236436, 4096.9552764045275, 5088.18589025756, 5092.79628814847, 5169.896602067956, 5315.574754019669, 5313.574914006889, 5314.468553144693, 5331.386890507602, 5349.144136938091, 5374.543163831079, 5135.024345374367, 5196.714180720059, 5198.558122559587, 5199.350081239845, 5249.343832020997, 5212.322398088224, 5225.869353266199, 5231.712255825928, 5232.2413250078525, 5236.92144617831, 5169.974150129263, 5180.792768289264, 5201.115810312256, 5218.399884013349, 5229.37247530295, 5520.503154716095, 5562.86092847678, 5565.387807341189, 5591.8881622367735, 5606.0, 6178.5366097542865, 6273.498120150377, 6285.214348206474, 6296.0, 6298.779542715983, 6129.389354848367, 6199.070139479069, 6262.843428914268, 6341.080543321239, 6344.7462101515885, 5737.454941780524, 5743.569232307594, 5777.508911742549, 5778.624389414675, 5833.270841144899, 6342.809715708536, 6378.744850206032, 6395.584287021422, 6409.67951602418, 6415.13395691585, 5907.438793314628, 6055.6063855849225, 6079.240094988127, 6094.908576371358, 6125.418085281891, 6226.688665566703, 6256.0303153010445, 6257.2491301044165, 6266.905996410058, 6276.717547710364, 6191.628502289877, 6196.938030619636, 6211.751529938751, 6222.097795819581, 6225.190725205694, 6080.0, 6105.938940610537, 6205.131281620576, 6216.316205217422, 6233.501319894398, 4852.441969173519, 4866.829660961849, 4890.168671325883, 4890.972895691873, 4910.48439913811, 4389.890252743707, 4391.055922976575, 4393.450818647669, 4440.222960981815, 4441.644668426518, 5580.637258578179, 5662.150677398341, 5696.743646535916, 5702.6293290935955, 5707.172459993278, 6158.04550294705, 6190.721417536221, 6211.409916057967, 6243.687815609202, 6320.399562041599, 5650.858728531737, 5658.547316214693, 5663.32040155184, 5669.659820410789, 5698.287714035746, 5257.290265814143, 5296.788128474895, 5339.279197308391, 5351.705656188947, 5358.356997160366, 6285.685715714196, 6307.936920630735, 6333.050042493663, 6349.301576826638, 6357.046443033582, 6206.162168107247, 6221.688915554295, 6224.533160012927, 6239.688015599111, 6247.468965137832, 6275.278342990615, 6328.810135695937, 6363.172787537591, 6365.108884756045, 6376.075469057054, 5291.867703307448, 6008.978473659576, 6075.210222671025, 6133.938660613337, 6144.433169541739, 6120.203342909657, 6174.135621013152, 6211.937880621317, 6215.409536094064, 6218.0, 6023.45788879003, 6031.788887389096, 6044.002739547926, 6048.4858787003595, 6054.243219597551, 6015.308239552332, 6027.4876635484725, 6028.336882942785, 6032.336442991355, 6044.365341639064, 4907.533784290486, 5789.2763404574425, 5852.122181672783, 5860.120981852756, 5872.911906321323, 5325.680459172462, 6011.338752737108, 6092.181584522955, 6092.508893177778, 6142.262928448615, 5701.030824759718, 5907.232059832194, 5944.791932282349, 5949.494292985145, 5952.672603006876], "value": 5670.195388389971, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.716687904455, 500.6595515049767, 518.7509995202305, 545.7871430142244, 546.9453054694528, 26830.06094786683, 41673.58326416735, 42948.57051429485, 43005.56994430059, 43169.0, 44013.7799311003, 44480.0, 44565.10869782602, 44611.0, 44717.88205294869, 7545.471816972814, 9503.999999999982, 9670.791151106112, 9724.805503889918, 9738.659146929858, 8623.051464338918, 11782.234154779955, 12038.0, 12046.0, 12063.879361206407, 11837.289762614271, 11910.880891191107, 11916.880831191707, 11929.880701192964, 11945.46245418954, 8839.29285657146, 11606.825897611501, 11738.647840564796, 11770.646880593597, 11893.0, 11787.292762434281, 11882.465289061927, 11894.226875253167, 11895.702607434796, 11922.880771192264, 40525.79737101325, 40843.795781020905, 40901.0, 40936.38595421071, 40950.795246023874, 3017.909462716122, 3100.2714362124884, 3106.4718997770206, 3145.308032232905, 3270.460374038281, 29369.70630293691, 29586.85206573975, 29603.40793184146, 29994.0, 30083.69916300831, 2503.502011475642, 2688.5832695932163, 2689.811713180078, 2691.286808995618, 2777.6944536101014, 516076.0, 517417.0, 518328.0, 518623.4068829669, 518719.0, 34377.312453751285, 65200.0, 65498.36254093591, 65695.0, 65739.34260657428, 29885.850570747007, 34918.476222856676, 34959.65040349615, 34962.300753984775, 35009.64990350115, 14852.554423367315, 15209.163496007826, 15211.771823422436, 15211.847881521044, 15213.543593691986, 11678.0657547396, 11850.703732406759, 12715.364231788373, 12883.613491595268, 12913.612591622266, 7897.131315555284, 7909.327707144842, 7920.089189743138, 7982.920170798334, 7994.480358776777, 2886.942261154765, 3254.2189874429964, 3274.8690052397637, 3282.7209687176855, 3317.0, 1003.8444041173484, 1029.5161274201114, 1061.4480470155477, 1071.8955548153192, 1130.553431394611, 25457.0, 29501.85249073741, 29658.9920721404, 29689.24413969062, 29692.0, 1045.926785125034, 1063.984040239397, 1127.7744451109647, 1132.9830052549219, 1144.2390810111349, 14984.550463486114, 15139.54581362561, 15144.54566363011, 15259.389624415122, 15368.61578460525, 707408.0, 712584.0, 713509.0, 714006.0, 714692.0, 59184.0, 61226.0, 61278.38721612816, 61340.38659613436, 61362.69318653378, 1114.420501339299, 1344.9663758405923, 1429.0853853533538, 1449.2681195996165, 1469.2139705257637, 28797.0, 30142.246443839078, 30212.84893575518, 30760.384792304478, 30819.229519262197, 56490.71754641201, 59314.0, 60385.39614603973, 60626.78746425135, 60672.39327606667, 645905.0, 651774.0, 652559.0, 653243.0, 653524.0, 34530.0, 35582.64417355897, 35608.28783424369, 39980.40029399503, 43268.56731432645, 38546.6145338543, 38984.415233770946, 45769.0, 47022.76488617535, 47219.763901180275, 35958.46062309015, 37036.6296337044, 37787.11064446712, 38652.6134738649, 39102.02244943899, 37259.06852328713, 37549.81225093966, 37744.0, 38214.80892595519, 38632.227355452174, 37028.44457333196, 37632.0, 38445.807770960964, 38473.615263847, 38674.226515470094, 31691.683083168875, 33155.81549436812, 33294.66705333012, 33498.66501335053, 33782.324353513286, 21564.67652985175, 22205.444863878532, 24476.388090297885, 25123.74876251214, 25449.236522904343], "value": 19298.257861036273, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.98360081995895, 332.87849934773817, 379.96770274526665, 381.4849952564038, 406.03363993695, 19636.90181549092, 20999.790002099973, 21135.943202839873, 21176.941152942367, 21499.677504837422, 20266.69599956002, 20626.89686551569, 20862.95685215737, 21090.89454552725, 21210.04554795034, 5140.948590514103, 5852.999999999989, 6049.848753781147, 6075.756969721206, 6089.147519347295, 4159.438475805758, 4389.912201755979, 5996.580239383245, 6219.346968568303, 6596.208454985384, 6445.129907462503, 6552.279249282575, 6560.573562718408, 6572.244191917919, 6574.309697481744, 5768.192453056534, 6360.1413809135865, 6552.574082684634, 6557.475401967855, 6609.173853268341, 3630.364686179908, 3729.6339729035208, 5265.6840589564745, 5708.1723150143, 5740.368559458456, 25904.740952590422, 26139.215823525134, 26330.868345658342, 26595.73404265952, 26605.733942660518, 1845.677006523853, 1933.4103098554936, 1949.33722534339, 2025.8480613954011, 2126.425865016437, 10114.494275286206, 10173.59305627774, 10192.694219173363, 10350.9482452588, 10353.585856565729, 1734.3669560610413, 1773.0514174916357, 1786.1426515272613, 1817.2276782367442, 1925.1914196037699, 111750.0, 114454.42772786166, 117045.0, 117564.0, 119222.0, 11726.237794543327, 11751.236169648946, 11770.293782373084, 11777.470013849397, 11779.764404711857, 14761.966662333534, 14856.405743770345, 14892.478763243234, 14910.552683419515, 14925.552233433014, 8666.966561703479, 8685.001224859096, 8762.036176020632, 8785.0, 8821.676748487778, 8624.741257762276, 8637.222649961408, 8676.91323086761, 8774.692885749097, 8794.38439309255, 4508.819647214074, 4588.105319462739, 4601.838935637236, 4620.19146649335, 4630.398048253705, 1431.6277767780243, 1548.6283292009837, 1721.313388252302, 1890.272245185586, 1911.1399870058528, 824.381713714714, 882.8940527136784, 911.3757076402685, 939.6382392778829, 978.0757184460616, 19418.611627767365, 20113.09491072905, 20243.898780506002, 20327.8983605084, 20577.48556286105, 878.6617152396245, 913.5249670171473, 932.7761337279004, 942.1944237676688, 1047.7642530430592, 9850.852237216448, 10296.794064118825, 10659.09397701204, 10749.408782517037, 10808.459577021118, 582662.0, 584450.0, 601407.0, 601566.0, 604637.0, 113350.0, 114603.0, 115440.39802577665, 117152.0, 117636.0, 977.6627063663025, 977.9462129582942, 991.509202944547, 993.7863359377768, 1002.5388321372251, 17601.91199044048, 17690.20394082295, 17873.910630447284, 17925.37261195852, 18190.45428637143, 43739.56260437355, 49716.254256185464, 50941.0, 51404.48595514146, 51813.740931295106, 51166.0, 64676.67661661661, 67777.66111169412, 71925.0, 72206.0, 7699.653515591924, 7935.285824275843, 7998.720044798637, 8129.75610731679, 8373.790655233668, 8470.49177049379, 8774.210321071134, 11577.942110289732, 12615.180013299107, 12624.053196010153, 10709.892901070889, 10837.783244335227, 10924.180686448708, 11010.779784404427, 11170.0, 10754.677359679223, 10802.08182304513, 11040.668779936294, 11139.05318047975, 11341.886581134084, 10950.780984380428, 11093.778124437305, 11432.37121958267, 11441.199116061796, 11483.48324325424, 6931.133608298963, 7413.666385012795, 8244.340452763645, 8266.173382661686, 8505.91494085076, 9348.485833279236, 11105.167112466435, 11109.722256943642, 11142.0, 11458.77082458363], "value": 9338.322095513646, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.7524183267938, 1573.5594033670573, 1589.5310883289428, 1728.256849554691, 1852.7684039495064, 7802.882956755653, 8541.017782954954, 8764.25503832175, 8803.691870784523, 8947.776305592364, 8568.057513673491, 8790.0, 8803.955980220106, 8812.647494100243, 8863.955680221607, 2363.905443782251, 2392.988035059831, 2397.8920948557356, 2398.4843258699375, 2399.7240317363553, 1940.8544359173118, 2368.9526209475885, 2383.4279772854475, 2384.391980045095, 2384.5111752090866, 2332.4518738096535, 2347.507023525062, 2356.6229403295474, 2370.5140446208484, 2375.1805627058725, 2309.8036666883336, 2332.5218330242346, 2344.5076533927895, 2345.308134100436, 2358.3278765551736, 2341.824363172752, 2343.2267351773876, 2343.8593684378993, 2346.0381243690003, 2347.0, 8556.315494760467, 8699.826003479895, 8708.477491350539, 8796.208341249318, 8805.603747831363, 5266.631335806495, 5405.864853378657, 5415.268938693304, 5418.241446197536, 5424.0, 3399.473081672345, 3479.9130021749406, 3548.716102711777, 3554.893353199408, 3556.519869817593, 991.3011327014437, 993.9055789699967, 997.6408492942535, 998.2962011781749, 1001.9599216031423, 11527.942360288145, 11615.76768464626, 11619.360935148563, 11638.068954483706, 11647.475863586074, 4457.531959144243, 4536.296873984537, 4625.4911959684405, 4632.675712700146, 4638.884027899262, 7757.26306000929, 9144.634214631473, 9163.266938644892, 9164.404313719588, 9167.404118732262, 5157.200633901752, 5217.217417387422, 5220.947790522122, 5227.686338819683, 5235.424103348629, 4374.300111982067, 4535.455745310584, 4737.8341758039, 4824.372831531879, 4902.705837649681, 3116.766242531774, 3731.4029755239576, 3811.2377524495214, 3857.594952529944, 3921.9607803922167, 964.9951750241204, 1142.0292751161446, 1152.2855829385835, 1161.2219812725493, 1163.825426186062, 613.8158552434252, 765.6784150656738, 903.5617725403174, 907.1699395053453, 945.8344789661782, 5620.887582248332, 5819.214406055213, 5921.141434491975, 5966.075258334965, 5967.254093238345, 936.3585943628636, 937.9577918993662, 968.5689868008809, 968.6561270748923, 975.1223898491272, 3680.9079773005888, 3698.093966978108, 3818.102745854722, 3846.442265871489, 3891.727579069438, 10331.121854642439, 10568.418736969541, 10724.946375268073, 10725.356478611307, 10755.731106722238, 10633.574657013633, 10747.677569672922, 10751.462426878781, 10752.301100428447, 10771.0, 7787.610619469117, 8005.159458256916, 8217.83564328722, 8219.671213151407, 8225.424220304641, 5607.915881261702, 5616.1856530803625, 5882.294124705063, 5911.822645320647, 5952.8809423812145, 9474.810503790024, 10047.547860346156, 10082.495875206063, 10085.083834071504, 10085.747856303651, 9963.405855063154, 10073.647422340184, 10169.084782369622, 10225.079742823182, 10234.0, 7433.8141546461775, 7468.551886886804, 7482.214367491552, 7550.433717471102, 7557.168711441847, 8042.3968202383885, 8081.717139900075, 8130.02439707239, 8141.633626486704, 8153.0, 7239.203687594466, 7418.851622967619, 7483.588402637907, 7538.547687138789, 7559.697612095454, 6349.936500634934, 7189.604571748604, 7288.927110728825, 7311.122665280201, 7423.665935033045, 6753.628550429774, 6812.114425124901, 6823.829404264735, 6825.692843822139, 6836.794896153124, 4655.674102812771, 4762.761861906822, 4886.218205087169, 4927.630427718007, 4957.677750946177, 4077.4903137107863, 4534.886627834198, 4534.954650453453, 4682.695624784378, 4799.952000479951], "value": 4492.147946916779, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.8644716653923, 298.1294619710445, 314.7104663709388, 316.80516482363356, 319.7138560987916, 5347.278117454142, 5351.190624921918, 5351.946480535194, 5370.3287089113865, 5400.810971615994, 3909.6932809642303, 5086.278840820162, 5208.687478751278, 5340.54605358544, 5350.211560653213, 4041.3533834586474, 4094.97952510237, 4097.938530922039, 4110.568390319018, 4116.423700681907, 3178.55500229968, 4083.734557253784, 4090.713650044498, 4103.548609652936, 4232.407462955173, 3309.9503507447407, 3748.175401411685, 3827.885163445101, 4036.5559788423398, 4095.5904409558952, 3520.454329578894, 3539.80531070791, 4019.8995025124314, 4041.6564592009713, 4059.8782036538805, 2988.610296212272, 3055.4111861831943, 3434.0384692286198, 3530.5763308403157, 3543.3090547343277, 4885.487023862514, 5118.667286626357, 5125.7185703574105, 5140.897182056376, 5169.715665638389, 4496.325551167319, 4593.609543188833, 4694.671373003891, 4696.647751418623, 4735.881602959919, 5604.747786349583, 5607.000254863669, 5607.186957891124, 5648.983183027053, 5649.209110724501, 3308.9007329780147, 3456.3778519866414, 3905.1994341159993, 3934.429507721364, 3949.664278536299, 4775.856724298276, 4913.31213630092, 5938.079597662369, 5966.420147911262, 5972.999999999956, 3480.965190348115, 3887.8056097195026, 3892.9610703893163, 3901.2782635212575, 3916.0, 3947.921041579152, 3950.822213000422, 3959.5050618672667, 3961.445397644332, 3997.540282867449, 3862.7682339059747, 4451.998300382453, 4514.9005712343915, 4534.523874993144, 4617.284320930261, 4908.386451693538, 4953.23224900141, 5596.86007849807, 5646.774129034875, 5676.829695109153, 1264.9557265495664, 1302.6743314171458, 1323.3647849032325, 1348.548236340817, 1350.156152404747, 777.6889244302324, 881.713443130985, 910.4855756497558, 912.3339961827903, 918.3800934369274, 4583.014651849869, 4994.8251811187165, 5151.4333423323405, 5283.520614228022, 5341.706206158621, 4654.394928659252, 5183.315422487632, 5430.83707488776, 5730.914036289459, 5846.766129354864, 4741.786619602126, 4953.083679519301, 5352.518273355339, 5369.704666243394, 5469.808556700496, 3003.669596344444, 3058.6788387219467, 3058.709422604849, 3065.3103051813164, 3082.321889184376, 4919.901601968012, 5259.658122222043, 5850.3564607893095, 6015.909761353583, 6080.8783824324155, 5188.5330320270605, 5460.590455715837, 5934.851628709317, 5973.910391344133, 5984.910226346521, 5094.974525127351, 5401.297831281908, 5795.7971470999155, 5847.26909136358, 6080.179175811209, 5126.718030508208, 5412.0, 5827.621204621686, 5844.47399734009, 5896.557758168069, 4858.78135483897, 5070.16071000241, 5704.828855134353, 5706.372299047019, 5760.423957604374, 5746.615154710748, 5844.977129002323, 6059.969700151471, 6093.390660933871, 6117.3270940197435, 3868.168343806095, 3877.2665270983325, 3878.5539662939123, 3884.514435695538, 3886.8833934981994, 5101.46434624374, 5137.486251374833, 5804.883902321845, 5811.79658711943, 5818.0, 5454.10007348783, 5639.436056394327, 5652.547796176378, 5663.830085097454, 5686.402927692533, 5574.581906356958, 5605.131204663284, 5642.689652069175, 5647.588102974257, 5650.604457688087, 3099.9535006975366, 4873.927735898097, 5396.919046214389, 5430.484104010113, 5566.582506311962, 4527.501974782837, 5019.573336266312, 5624.356552420356, 5631.267935168402, 5635.211070450058, 5246.737663116753, 5331.546818520314, 5455.0726376515295, 5538.6122971391615, 5548.19815992921], "value": 4081.4801689450424, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.352863315102, 647.540246425038, 679.0968012543318, 693.9861202775942, 743.0748717846283, 14970.625734356632, 15529.301181446834, 15542.84457155428, 15564.766528502067, 15588.064716117027, 13846.961477889148, 13918.652033699163, 14091.506797262098, 14169.858301416984, 14177.362018709156, 878.5651102704154, 880.2957633892892, 896.9596368163416, 897.6902968475898, 899.204204279211, 884.3014018925041, 884.907084756101, 885.6103314541581, 887.2547060469204, 887.7736177274786, 933.2394098809449, 933.7012156110047, 933.9626414943394, 935.7426707655416, 936.7845395559026, 925.4909799610222, 928.8003079337907, 930.5765876526165, 930.9394889332206, 932.0120672087575, 691.6092407789582, 692.1313751242209, 693.1508901595555, 693.9444844412485, 695.4749164380911, 12626.30555319457, 12814.231146131262, 12832.9733621311, 13017.674558136028, 13269.469221231138, 10932.180086493545, 10956.780864382747, 11015.559377624966, 11256.155788315828, 11566.768664626745, 8987.505687187202, 9068.835619860303, 9144.222741067017, 9160.496172710498, 9435.811283774356, 3324.9168770780925, 3329.7336213102894, 3335.7498187635783, 3386.72906167506, 3449.741269404805, 42626.78686606547, 64945.3505464944, 68030.63958642483, 73316.63341683311, 73393.0, 7719.498232614862, 9649.855252171223, 9940.950295248478, 9964.25268104895, 10421.478926053667, 22019.0, 22298.88850555737, 22338.55322893533, 22388.0, 22501.43746406353, 7357.558546487228, 7403.9629801851725, 7483.812904677427, 7575.6969721211635, 7729.3430058445665, 3879.7866117363815, 3944.487216661873, 3984.8007599619905, 3994.6005399459823, 4038.5759495252573, 3449.6722811332884, 3539.9292014160087, 3541.8760343387858, 3543.698785603201, 3552.5559305086863, 608.7717106085219, 622.8909940760349, 624.6096189881324, 633.0693879996489, 647.4626060369882, 530.191458026505, 562.1696388875118, 575.9913601295984, 593.6794131169206, 602.86736917878, 7732.342750866127, 7934.127246002935, 7967.9203207967175, 8162.18378162179, 8202.671893124327, 571.3258355140973, 582.5223316880117, 583.2825624481826, 585.1018686316484, 590.1089355073784, 3714.869979550757, 3716.3496388132507, 3765.359888818907, 3780.4707340972286, 3972.503437070366, 88315.0, 88547.0, 89740.10259897448, 89762.55118724365, 89816.0, 77794.0, 79436.0, 79815.8027629586, 79983.20016799758, 82246.0, 69497.65251173712, 70493.647531762, 70548.64725676441, 70584.64707676429, 71204.28795711977, 6544.756496265694, 7113.679884405317, 7420.369268612229, 7579.431542634215, 7861.567613781068, 44582.777086114365, 46115.53884461246, 46122.61632151041, 47474.0, 47699.284510731675, 63906.36093639004, 63987.68006159939, 64078.03882941666, 64122.0, 64333.67833160804, 7791.4935529190425, 9284.58219380143, 9825.066618671215, 10325.380477171393, 10647.094996925038, 10825.133989280996, 11034.558617655204, 11128.38793866345, 11301.15241356918, 11619.0, 8084.110747817617, 8119.39104567148, 8127.715529956423, 8636.82726345457, 10568.418736969234, 9986.800263994824, 9990.550425231027, 10019.248556358156, 10143.137833284254, 10366.326188797704, 9735.318527702993, 9752.171065459232, 9785.36395134314, 9860.506974651382, 9865.556049977624, 7239.782806515813, 8278.586070696561, 8387.874181887155, 8731.91268087311, 8897.955510222408, 8214.26071653554, 8355.874661879956, 8495.95752021236, 8535.786605334917, 8544.91455085466], "value": 6516.895396696339, "unit": "score"}], "name": "dromaeo_css", "value": 7043.531686909992, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 165.0, 101.0, 158.0, 166.0, 163.0, 113.0, 112.0, 113.0], "value": 131.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 115.0, 117.0, 168.0, 118.0, 121.0, 141.0, 119.0, 153.0, 117.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 186.0, 183.0, 182.0, 185.0, 183.0, 181.0, 183.0, 184.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 65.0, 84.0, 57.0, 57.0, 66.0, 79.0, 110.0, 80.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 101.0, 71.0, 70.0, 69.0, 96.0, 70.0, 68.0, 99.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 89.0, 91.0, 88.0, 91.0, 88.0, 91.0, 88.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 96.0, 96.0, 95.0, 97.0, 96.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 86.0, 84.0, 85.0, 85.0, 85.0, 84.0, 86.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 59.0, 57.0, 57.0, 59.0, 54.0, 69.0, 80.0, 86.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 77.0, 75.0, 77.0, 73.0, 70.0, 71.0, 73.0, 77.0, 70.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 86.0, 107.0, 106.0, 84.0, 86.0, 84.0, 84.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 145.0, 148.0, 144.0, 149.0, 138.0, 137.0, 183.0, 147.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 163.0, 165.0, 161.0, 167.0, 165.0, 166.0, 165.0, 164.0, 165.0], "value": 164.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 66.0, 68.0, 65.0, 67.0, 64.0, 62.0, 61.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1474.3999999999999, "alertThreshold": 2.0}]} 09:04:17 INFO - Return code: 0 09:04:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:04:17 ERROR - # TBPL SUCCESS # 09:04:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:04:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:04:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:04:17 INFO - 2016-05-26 09:04:17.820 screenresolution[1677:9791] starting screenresolution argv=screenresolution get 09:04:17 INFO - 2016-05-26 09:04:17.823 screenresolution[1677:9791] Display 0: 1600x1200x32@60 09:04:17 INFO - 2016-05-26 09:04:17.829 screenresolution[1678:9794] starting screenresolution argv=screenresolution list 09:04:17 INFO - Available Modes on Display 0 09:04:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:04:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:04:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:04:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:04:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:04:18 INFO - Intel Iris: 09:04:18 INFO - Chipset Model: Intel Iris 09:04:18 INFO - Type: GPU 09:04:18 INFO - Bus: Built-In 09:04:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:04:18 INFO - Vendor: Intel (0x8086) 09:04:18 INFO - Device ID: 0x0a2e 09:04:18 INFO - Revision ID: 0x0009 09:04:18 INFO - Displays: 09:04:18 INFO - Display: 09:04:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:04:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:04:18 INFO - Display Serial Number: 1600x1200 60 09:04:18 INFO - Main Display: Yes 09:04:18 INFO - Mirror: Off 09:04:18 INFO - Online: Yes 09:04:18 INFO - Rotation: Supported 09:04:18 INFO - Return code: 0 09:04:18 INFO - Running post-action listener: _resource_record_post_action 09:04:18 INFO - ##### 09:04:18 INFO - ##### Finished run-tests step (success) 09:04:18 INFO - ##### 09:04:18 INFO - Running post-run listener: _resource_record_post_run 09:04:18 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36832768; Write bytes: 694246400; Read time: 237; Write time: 1934 09:04:18 INFO - TinderboxPrint: CPU usage
53.6% 09:04:18 INFO - TinderboxPrint: I/O read bytes / time
36,832,768 / 237 09:04:18 INFO - TinderboxPrint: I/O write bytes / time
694,246,400 / 1,934 09:04:18 INFO - TinderboxPrint: CPU idle
2,247.6 (46.4%) 09:04:18 INFO - TinderboxPrint: CPU system
964.6 (19.9%) 09:04:18 INFO - TinderboxPrint: CPU user
1,633.5 (33.7%) 09:04:18 INFO - TinderboxPrint: Swap in / out
434,163,712 / 0 09:04:18 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392352256; Write bytes: 380552704; Read time: 29227; Write time: 793 09:04:18 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29740544; Write bytes: 294196736; Read time: 181; Write time: 1094 09:04:18 INFO - Running post-run listener: _upload_blobber_files 09:04:18 INFO - Blob upload gear active. 09:04:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:04:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:04:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:04:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:04:18 INFO - (blobuploader) - INFO - Open directory for files ... 09:04:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:04:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:04:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:20 INFO - (blobuploader) - INFO - Done attempting. 09:04:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:04:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:04:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:21 INFO - (blobuploader) - INFO - Done attempting. 09:04:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:04:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:04:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:23 INFO - (blobuploader) - INFO - Done attempting. 09:04:23 INFO - (blobuploader) - INFO - Iteration through files over. 09:04:23 INFO - Return code: 0 09:04:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:04:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:04:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c72112a15d1ee1f8c678307aca5ca61fc062e39d3b39103c153386130613f2fe0c9091a74421f87ee77d8b3e560ade33191b4ced4b326c2f6b6fbdac8e438d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1976dd62b7fc209b5c899c2eaf8c7d87e4e598aaa4713bbae15d7c94b827e354241f9d60926927dc7fcabae578e8a2388a5decdaaadeeaf0e3a5990af2a89446", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a89e6171e274cca1ee094e5c982889adef1efe9d870360c2246835a2c7387f1410d6cb91e35fafe28016c37eea290c8ba7141a4fe18e98864e3e4ef1104f168"} 09:04:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:04:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:04:23 INFO - Contents: 09:04:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c72112a15d1ee1f8c678307aca5ca61fc062e39d3b39103c153386130613f2fe0c9091a74421f87ee77d8b3e560ade33191b4ced4b326c2f6b6fbdac8e438d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1976dd62b7fc209b5c899c2eaf8c7d87e4e598aaa4713bbae15d7c94b827e354241f9d60926927dc7fcabae578e8a2388a5decdaaadeeaf0e3a5990af2a89446", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a89e6171e274cca1ee094e5c982889adef1efe9d870360c2246835a2c7387f1410d6cb91e35fafe28016c37eea290c8ba7141a4fe18e98864e3e4ef1104f168"} 09:04:23 INFO - Running post-run listener: copy_logs_to_upload_dir 09:04:23 INFO - Copying logs to upload dir... 09:04:23 INFO - mkdir: /builds/slave/test/build/upload/logs 09:04:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.919282 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-05-26 09:04:23.596523) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 09:04:23.599724) ========= 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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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/2c72112a15d1ee1f8c678307aca5ca61fc062e39d3b39103c153386130613f2fe0c9091a74421f87ee77d8b3e560ade33191b4ced4b326c2f6b6fbdac8e438d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1976dd62b7fc209b5c899c2eaf8c7d87e4e598aaa4713bbae15d7c94b827e354241f9d60926927dc7fcabae578e8a2388a5decdaaadeeaf0e3a5990af2a89446", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a89e6171e274cca1ee094e5c982889adef1efe9d870360c2246835a2c7387f1410d6cb91e35fafe28016c37eea290c8ba7141a4fe18e98864e3e4ef1104f168"} build_url:https://queue.taskcluster.net/v1/task/CbUsgjUIQciV44oHx5Etrw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011299 build_url: 'https://queue.taskcluster.net/v1/task/CbUsgjUIQciV44oHx5Etrw/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/2c72112a15d1ee1f8c678307aca5ca61fc062e39d3b39103c153386130613f2fe0c9091a74421f87ee77d8b3e560ade33191b4ced4b326c2f6b6fbdac8e438d2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1976dd62b7fc209b5c899c2eaf8c7d87e4e598aaa4713bbae15d7c94b827e354241f9d60926927dc7fcabae578e8a2388a5decdaaadeeaf0e3a5990af2a89446", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7a89e6171e274cca1ee094e5c982889adef1efe9d870360c2246835a2c7387f1410d6cb91e35fafe28016c37eea290c8ba7141a4fe18e98864e3e4ef1104f168"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 09:04:23.630156) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 09:04:23.630470) ========= 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.aGyon1DQQJ/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.FN8HTwtEnG/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.005082 ========= master_lag: 0.23 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 09:04:23.865397) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 09:04:23.865713) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 09:04:23.866687) ========= ========= Total master_lag: 0.36 =========