builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0168 starttime: 1470711103.91 results: success (0) buildid: 20160808144920 builduid: 068b281844844dc6a9854f99d7c00229 revision: 54c34dbafa19ba57727f2b23921c40fdd7e160cf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:43.914734) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:43.915309) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:43.915614) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:44.059731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:44.060052) ========= 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.agpFcqkTmK/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.EzPLDGUNae/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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-08-08 19:51:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 173M=0s 2016-08-08 19:51:44 (173 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.519815 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:44.641545) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:44.641834) ========= 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.agpFcqkTmK/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.EzPLDGUNae/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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.027528 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:44.706050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:44.706358) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 54c34dbafa19ba57727f2b23921c40fdd7e160cf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 54c34dbafa19ba57727f2b23921c40fdd7e160cf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.agpFcqkTmK/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.EzPLDGUNae/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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-08-08 19:51:44,821 truncating revision to first 12 chars 2016-08-08 19:51:44,821 Setting DEBUG logging. 2016-08-08 19:51:44,821 attempt 1/10 2016-08-08 19:51:44,821 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/54c34dbafa19?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 19:51:45,145 unpacking tar archive at: autoland-54c34dbafa19/testing/mozharness/ program finished with exit code 0 elapsedTime=0.577494 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:45.354864) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:45.355344) ========= script_repo_revision: 54c34dbafa19ba57727f2b23921c40fdd7e160cf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:45.355797) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:45.356084) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 19:51:45.584424) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-08 19:51:45.584762) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.agpFcqkTmK/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.EzPLDGUNae/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:51:45 INFO - MultiFileLogger online at 20160808 19:51:45 in /builds/slave/test 19:51:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 19:51:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:51:45 INFO - {'append_to_log': False, 19:51:45 INFO - 'base_work_dir': '/builds/slave/test', 19:51:45 INFO - 'blob_upload_branch': 'Autoland', 19:51:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:51:45 INFO - 'branch': 'Autoland', 19:51:45 INFO - 'buildbot_json_path': 'buildprops.json', 19:51:45 INFO - 'config_files': ('talos/mac_config.py',), 19:51:45 INFO - 'default_actions': ('clobber', 19:51:45 INFO - 'read-buildbot-config', 19:51:45 INFO - 'download-and-extract', 19:51:45 INFO - 'populate-webroot', 19:51:45 INFO - 'create-virtualenv', 19:51:45 INFO - 'install', 19:51:45 INFO - 'run-tests'), 19:51:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:51:45 INFO - 'download_minidump_stackwalk': True, 19:51:45 INFO - 'download_symbols': 'ondemand', 19:51:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:51:45 INFO - 'tooltool.py': '/tools/tooltool.py', 19:51:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:51:45 INFO - '/tools/misc-python/virtualenv.py')}, 19:51:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:51:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:51:45 INFO - 'installer_path': 'installer.exe', 19:51:45 INFO - 'log_level': 'info', 19:51:45 INFO - 'log_name': 'talos', 19:51:45 INFO - 'log_to_console': True, 19:51:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:51:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:51:45 INFO - 'opt_config_files': (), 19:51:45 INFO - 'pip_index': False, 19:51:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:51:45 INFO - 'cmd': ('bash', 19:51:45 INFO - '-c', 19:51:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:51:45 INFO - 'enabled': True, 19:51:45 INFO - 'halt_on_failure': False, 19:51:45 INFO - 'name': 'check_screen_resolution'},), 19:51:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:51:45 INFO - 'cmd': ('bash', 19:51:45 INFO - '-c', 19:51:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:51:45 INFO - 'enabled': True, 19:51:45 INFO - 'halt_on_failure': False, 19:51:45 INFO - 'name': 'check_screen_resolution'},), 19:51:45 INFO - 'run_cmd_checks_enabled': True, 19:51:45 INFO - 'sps_profile': False, 19:51:45 INFO - 'sps_profile_interval': 0, 19:51:45 INFO - 'suite': 'dromaeojs', 19:51:45 INFO - 'system_bits': '32', 19:51:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:51:45 INFO - 'title': 't-yosemite-r7-0168', 19:51:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:51:45 INFO - 'use_talos_json': True, 19:51:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:51:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:51:45 INFO - 'work_dir': 'build'} 19:51:45 INFO - [mozharness: 2016-08-09 02:51:45.791014Z] Running clobber step. 19:51:45 INFO - Running pre-action listener: _resource_record_pre_action 19:51:45 INFO - Running main action method: clobber 19:51:45 INFO - rmtree: /builds/slave/test/build 19:51:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:51:48 INFO - Running post-action listener: _resource_record_post_action 19:51:48 INFO - [mozharness: 2016-08-09 02:51:48.001920Z] Finished clobber step (success) 19:51:48 INFO - [mozharness: 2016-08-09 02:51:48.002170Z] Running read-buildbot-config step. 19:51:48 INFO - Running pre-action listener: _resource_record_pre_action 19:51:48 INFO - Running main action method: read_buildbot_config 19:51:48 INFO - Using buildbot properties: 19:51:48 INFO - { 19:51:48 INFO - "project": "", 19:51:48 INFO - "product": "firefox", 19:51:48 INFO - "script_repo_revision": "production", 19:51:48 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 19:51:48 INFO - "repository": "", 19:51:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 19:51:48 INFO - "buildid": "20160808144920", 19:51:48 INFO - "pgo_build": "False", 19:51:48 INFO - "basedir": "/builds/slave/test", 19:51:48 INFO - "buildnumber": 92, 19:51:48 INFO - "slavename": "t-yosemite-r7-0168", 19:51:48 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:51:48 INFO - "platform": "yosemite_r7", 19:51:48 INFO - "scheduler": "tests-autoland-macosx64-talos", 19:51:48 INFO - "branch": "autoland", 19:51:48 INFO - "repo_path": "integration/autoland", 19:51:48 INFO - "revision": "54c34dbafa19ba57727f2b23921c40fdd7e160cf", 19:51:48 INFO - "stage_platform": "macosx64", 19:51:48 INFO - "builduid": "068b281844844dc6a9854f99d7c00229", 19:51:48 INFO - "slavebuilddir": "test" 19:51:48 INFO - } 19:51:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:51:48 INFO - Running post-action listener: _resource_record_post_action 19:51:48 INFO - [mozharness: 2016-08-09 02:51:48.008065Z] Finished read-buildbot-config step (success) 19:51:48 INFO - [mozharness: 2016-08-09 02:51:48.008325Z] Running download-and-extract step. 19:51:48 INFO - Running pre-action listener: _resource_record_pre_action 19:51:48 INFO - Running main action method: download_and_extract 19:51:48 INFO - mkdir: /builds/slave/test/build/tests 19:51:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:48 INFO - trying https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:51:48 INFO - Downloading https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:51:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 19:51:49 INFO - Downloaded 1199 bytes. 19:51:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:51:49 INFO - Using the following test package requirements: 19:51:49 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:51:49 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:51:49 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:51:49 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:51:49 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:51:49 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:51:49 INFO - u'jsshell-mac.zip'], 19:51:49 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:51:49 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:51:49 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:51:49 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:51:49 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:51:49 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:51:49 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:51:49 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:51:49 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:51:49 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:51:49 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:51:49 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:51:49 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:49 INFO - trying https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:51:49 INFO - Downloading https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:51:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:51:50 INFO - Downloaded 16324564 bytes. 19:51:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:51:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:51:53 INFO - Return code: 0 19:51:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:51:53 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:53 INFO - trying https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:51:53 INFO - Downloading https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:51:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:51:54 INFO - Downloaded 16324564 bytes. 19:51:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:51:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:51:57 INFO - Return code: 0 19:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:57 INFO - trying https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:51:57 INFO - Downloading https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:51:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:51:58 INFO - Downloaded 14413723 bytes. 19:51:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:51:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:51:59 INFO - Return code: 0 19:51:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:51:59 INFO - trying https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:51:59 INFO - Downloading https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:51:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 19:52:07 INFO - Downloaded 111975724 bytes. 19:52:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:52:07 INFO - mkdir: /builds/slave/test/properties 19:52:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:52:07 INFO - Writing to file /builds/slave/test/properties/build_url 19:52:07 INFO - Contents: 19:52:07 INFO - build_url:https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:52:08 INFO - Running post-action listener: _resource_record_post_action 19:52:08 INFO - Running post-action listener: set_extra_try_arguments 19:52:08 INFO - [mozharness: 2016-08-09 02:52:08.329621Z] Finished download-and-extract step (success) 19:52:08 INFO - [mozharness: 2016-08-09 02:52:08.329740Z] Running populate-webroot step. 19:52:08 INFO - Running pre-action listener: _resource_record_pre_action 19:52:08 INFO - Running main action method: populate_webroot 19:52:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:52:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:52:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:52:08 INFO - '--tppagecycles', 19:52:08 INFO - '7'], 19:52:08 INFO - 'tests': ['tsvgm']}}, 19:52:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:52:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:52:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:52:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:52:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:08 INFO - 'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:52:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:52:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:08 INFO - 'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['damp', 'tps']}, 19:52:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:08 INFO - 'tests': ['damp', 'tps']}, 19:52:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['dromaeo_dom']}, 19:52:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:52:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['basic_compositor_video']}, 19:52:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:52:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['a11yr', 19:52:08 INFO - 'ts_paint', 19:52:08 INFO - 'tpaint', 19:52:08 INFO - 'sessionrestore', 19:52:08 INFO - 'sessionrestore_no_auto_restore', 19:52:08 INFO - 'tabpaint']}, 19:52:08 INFO - 'other-e10s': {'tests': ['a11yr', 19:52:08 INFO - 'ts_paint', 19:52:08 INFO - 'tpaint', 19:52:08 INFO - 'sessionrestore', 19:52:08 INFO - 'sessionrestore_no_auto_restore', 19:52:08 INFO - 'tabpaint']}, 19:52:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:52:08 INFO - 'ts_paint', 19:52:08 INFO - 'tpaint', 19:52:08 INFO - 'sessionrestore', 19:52:08 INFO - 'sessionrestore_no_auto_restore', 19:52:08 INFO - 'tabpaint']}, 19:52:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:52:08 INFO - 'ts_paint', 19:52:08 INFO - 'tpaint', 19:52:08 INFO - 'sessionrestore', 19:52:08 INFO - 'sessionrestore_no_auto_restore', 19:52:08 INFO - 'tabpaint']}, 19:52:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['a11yr', 19:52:08 INFO - 'ts_paint', 19:52:08 INFO - 'tpaint', 19:52:08 INFO - 'sessionrestore', 19:52:08 INFO - 'sessionrestore_no_auto_restore', 19:52:08 INFO - 'tabpaint']}, 19:52:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['a11yr', 19:52:08 INFO - 'ts_paint', 19:52:08 INFO - 'tpaint', 19:52:08 INFO - 'sessionrestore', 19:52:08 INFO - 'sessionrestore_no_auto_restore', 19:52:08 INFO - 'tabpaint']}, 19:52:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['tsvgx', 19:52:08 INFO - 'tsvgr_opacity', 19:52:08 INFO - 'tart', 19:52:08 INFO - 'tscrollx', 19:52:08 INFO - 'cart']}, 19:52:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:52:08 INFO - 'tsvgr_opacity', 19:52:08 INFO - 'tart', 19:52:08 INFO - 'tscrollx', 19:52:08 INFO - 'cart']}, 19:52:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:08 INFO - 'talos_options': ['--disable-e10s'], 19:52:08 INFO - 'tests': ['tp5o']}, 19:52:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:08 INFO - 'tests': ['tp5o']}, 19:52:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:08 INFO - 'talos_options': ['--disable-e10s', 19:52:08 INFO - '--xperf_path', 19:52:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:52:08 INFO - 'tests': ['tp5n']}, 19:52:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:52:08 INFO - 'talos_options': ['--xperf_path', 19:52:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:52:08 INFO - 'tests': ['tp5n']}}, 19:52:08 INFO - 'talos.zip': {'path': '', 19:52:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:52:08 INFO - Running post-action listener: _resource_record_post_action 19:52:08 INFO - [mozharness: 2016-08-09 02:52:08.348924Z] Finished populate-webroot step (success) 19:52:08 INFO - [mozharness: 2016-08-09 02:52:08.349061Z] Running create-virtualenv step. 19:52:08 INFO - Running pre-action listener: _resource_record_pre_action 19:52:08 INFO - Running main action method: create_virtualenv 19:52:08 INFO - Creating virtualenv /builds/slave/test/build/venv 19:52:08 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 19:52:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:52:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:52:08 INFO - Using real prefix '/tools/python27' 19:52:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:52:09 INFO - Installing distribute.............................................................................................................................................................................................done. 19:52:12 INFO - Installing pip.................done. 19:52:12 INFO - Return code: 0 19:52:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:12 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 0x102643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc613568480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, '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.agpFcqkTmK/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.EzPLDGUNae/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:52:12 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 19:52:12 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 19:52:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:52:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:12 INFO - 'HOME': '/Users/cltbld', 19:52:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:12 INFO - 'LOGNAME': 'cltbld', 19:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:12 INFO - 'MOZ_NO_REMOTE': '1', 19:52:12 INFO - 'NO_EM_RESTART': '1', 19:52:12 INFO - 'PAGER': '/bin/cat', 19:52:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:12 INFO - 'PWD': '/builds/slave/test', 19:52:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:12 INFO - 'SHELL': '/bin/bash', 19:52:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:52:12 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:52:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:12 INFO - 'USER': 'cltbld', 19:52:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:12 INFO - 'XPC_FLAGS': '0x0', 19:52:12 INFO - 'XPC_SERVICE_NAME': '0', 19:52:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:12 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 19:52:12 INFO - Cleaning up... 19:52:12 INFO - Return code: 0 19:52:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:12 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 0x102643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc613568480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, '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.agpFcqkTmK/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.EzPLDGUNae/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:52:12 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 19:52:12 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 19:52:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:52:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:12 INFO - 'HOME': '/Users/cltbld', 19:52:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:12 INFO - 'LOGNAME': 'cltbld', 19:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:12 INFO - 'MOZ_NO_REMOTE': '1', 19:52:12 INFO - 'NO_EM_RESTART': '1', 19:52:12 INFO - 'PAGER': '/bin/cat', 19:52:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:12 INFO - 'PWD': '/builds/slave/test', 19:52:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:12 INFO - 'SHELL': '/bin/bash', 19:52:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:52:12 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:52:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:12 INFO - 'USER': 'cltbld', 19:52:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:12 INFO - 'XPC_FLAGS': '0x0', 19:52:12 INFO - 'XPC_SERVICE_NAME': '0', 19:52:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:12 INFO - Downloading/unpacking psutil>=3.1.1 19:52:12 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 19:52:12 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 19:52:12 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 19:52:12 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 19:52:12 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 19:52:12 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 19:52:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:52:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:52:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:52:16 INFO - Installing collected packages: psutil 19:52:16 INFO - Running setup.py install for psutil 19:52:16 INFO - building 'psutil._psutil_osx' extension 19:52:16 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 19:52:16 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 19:52:17 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 19:52:17 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 19:52:17 INFO - building 'psutil._psutil_posix' extension 19:52:17 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 19:52:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:52:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:52:17 INFO - ^ 19:52:17 INFO - 1 warning generated. 19:52:17 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 19:52:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:52:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:52:17 INFO - Successfully installed psutil 19:52:17 INFO - Cleaning up... 19:52:17 INFO - Return code: 0 19:52:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:17 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 0x102643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc613568480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, '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.agpFcqkTmK/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.EzPLDGUNae/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:52:17 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 19:52:17 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 19:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:17 INFO - 'HOME': '/Users/cltbld', 19:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:17 INFO - 'LOGNAME': 'cltbld', 19:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:17 INFO - 'MOZ_NO_REMOTE': '1', 19:52:17 INFO - 'NO_EM_RESTART': '1', 19:52:17 INFO - 'PAGER': '/bin/cat', 19:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:17 INFO - 'PWD': '/builds/slave/test', 19:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:17 INFO - 'SHELL': '/bin/bash', 19:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:52:17 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:17 INFO - 'USER': 'cltbld', 19:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:17 INFO - 'XPC_FLAGS': '0x0', 19:52:17 INFO - 'XPC_SERVICE_NAME': '0', 19:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:52:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:52:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:52:20 INFO - Installing collected packages: mozsystemmonitor 19:52:20 INFO - Running setup.py install for mozsystemmonitor 19:52:20 INFO - Successfully installed mozsystemmonitor 19:52:20 INFO - Cleaning up... 19:52:20 INFO - Return code: 0 19:52:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:52:20 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:20 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:20 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc613568480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, '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.agpFcqkTmK/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.EzPLDGUNae/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:52:20 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==2.5.1'] in /builds/slave/test/build 19:52:20 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==2.5.1 19:52:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:52:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:20 INFO - 'HOME': '/Users/cltbld', 19:52:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:20 INFO - 'LOGNAME': 'cltbld', 19:52:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:20 INFO - 'MOZ_NO_REMOTE': '1', 19:52:20 INFO - 'NO_EM_RESTART': '1', 19:52:20 INFO - 'PAGER': '/bin/cat', 19:52:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:20 INFO - 'PWD': '/builds/slave/test', 19:52:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:20 INFO - 'SHELL': '/bin/bash', 19:52:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:52:20 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:52:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:20 INFO - 'USER': 'cltbld', 19:52:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:20 INFO - 'XPC_FLAGS': '0x0', 19:52:20 INFO - 'XPC_SERVICE_NAME': '0', 19:52:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:20 INFO - Downloading/unpacking jsonschema==2.5.1 19:52:20 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 19:52:20 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 19:52:20 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 19:52:20 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 19:52:20 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 19:52:20 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 19:52:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:52:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:23 INFO - Downloading functools32-3.2.3-2.tar.gz 19:52:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:52:23 INFO - warning: no files found matching '*.txt' 19:52:23 INFO - no previously-included directories found matching 'build' 19:52:23 INFO - no previously-included directories found matching 'dist' 19:52:23 INFO - no previously-included directories found matching '.git*' 19:52:23 INFO - Installing collected packages: jsonschema, functools32 19:52:23 INFO - Running setup.py install for functools32 19:52:23 INFO - warning: no files found matching '*.txt' 19:52:23 INFO - no previously-included directories found matching 'build' 19:52:23 INFO - no previously-included directories found matching 'dist' 19:52:23 INFO - no previously-included directories found matching '.git*' 19:52:23 INFO - Successfully installed jsonschema functools32 19:52:23 INFO - Cleaning up... 19:52:23 INFO - Return code: 0 19:52:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:23 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc613568480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, '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.agpFcqkTmK/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.EzPLDGUNae/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:52:23 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:52:23 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 functools32==3.2.3-2 19:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:23 INFO - 'HOME': '/Users/cltbld', 19:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:23 INFO - 'LOGNAME': 'cltbld', 19:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:23 INFO - 'MOZ_NO_REMOTE': '1', 19:52:23 INFO - 'NO_EM_RESTART': '1', 19:52:23 INFO - 'PAGER': '/bin/cat', 19:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:23 INFO - 'PWD': '/builds/slave/test', 19:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:23 INFO - 'SHELL': '/bin/bash', 19:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:52:23 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:23 INFO - 'USER': 'cltbld', 19:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:23 INFO - 'XPC_FLAGS': '0x0', 19:52:23 INFO - 'XPC_SERVICE_NAME': '0', 19:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:52:24 INFO - Cleaning up... 19:52:24 INFO - Return code: 0 19:52:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:24 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 0x102643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc613568480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, '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.agpFcqkTmK/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.EzPLDGUNae/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:52:24 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 19:52:24 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 19:52:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:52:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:24 INFO - 'HOME': '/Users/cltbld', 19:52:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:24 INFO - 'LOGNAME': 'cltbld', 19:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:24 INFO - 'MOZ_NO_REMOTE': '1', 19:52:24 INFO - 'NO_EM_RESTART': '1', 19:52:24 INFO - 'PAGER': '/bin/cat', 19:52:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:24 INFO - 'PWD': '/builds/slave/test', 19:52:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:24 INFO - 'SHELL': '/bin/bash', 19:52:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:52:24 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:52:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:24 INFO - 'USER': 'cltbld', 19:52:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:24 INFO - 'XPC_FLAGS': '0x0', 19:52:24 INFO - 'XPC_SERVICE_NAME': '0', 19:52:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:24 INFO - Downloading/unpacking blobuploader==1.2.4 19:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:52:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:52:26 INFO - Downloading blobuploader-1.2.4.tar.gz 19:52:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:52:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:52:27 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 19:52:27 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 19:52:27 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 19:52:27 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 19:52:27 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 19:52:27 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 19:52:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:52:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:52:27 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 19:52:27 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 19:52:27 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 19:52:27 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 19:52:27 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 19:52:27 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 19:52:28 INFO - Downloading docopt-0.6.1.tar.gz 19:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:52:28 INFO - Installing collected packages: blobuploader, requests, docopt 19:52:28 INFO - Running setup.py install for blobuploader 19:52:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:52:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:52:28 INFO - Running setup.py install for requests 19:52:28 INFO - Running setup.py install for docopt 19:52:29 INFO - Successfully installed blobuploader requests docopt 19:52:29 INFO - Cleaning up... 19:52:29 INFO - Return code: 0 19:52:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:29 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 0x102643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc613568480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, '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.agpFcqkTmK/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.EzPLDGUNae/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:52:29 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 19:52:29 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 19:52:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:52:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:29 INFO - 'HOME': '/Users/cltbld', 19:52:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:29 INFO - 'LOGNAME': 'cltbld', 19:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:29 INFO - 'MOZ_NO_REMOTE': '1', 19:52:29 INFO - 'NO_EM_RESTART': '1', 19:52:29 INFO - 'PAGER': '/bin/cat', 19:52:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:29 INFO - 'PWD': '/builds/slave/test', 19:52:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:29 INFO - 'SHELL': '/bin/bash', 19:52:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:52:29 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:52:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:29 INFO - 'USER': 'cltbld', 19:52:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:29 INFO - 'XPC_FLAGS': '0x0', 19:52:29 INFO - 'XPC_SERVICE_NAME': '0', 19:52:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:52:29 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-wgfRoF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:52:29 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-BwnxfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:52:29 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-t6CIb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:52:29 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-5ykuRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:52:29 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-wqEo1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:52:29 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-85U8Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:52:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-vB0MNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:52:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Zya4yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:52:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-VljC9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:52:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-NMQG_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:52:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-YHkC1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:52:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-d0lj16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:52:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-5hjQtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:52:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-8f4zqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-b0mBsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:52:30 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-T9oLJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:52:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:52:31 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-FGQQPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:52:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:52:31 INFO - Running setup.py install for manifestparser 19:52:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:52:31 INFO - Running setup.py install for mozcrash 19:52:31 INFO - Running setup.py install for mozdebug 19:52:31 INFO - Running setup.py install for mozdevice 19:52:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:52:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:52:31 INFO - Running setup.py install for mozfile 19:52:31 INFO - Running setup.py install for mozhttpd 19:52:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:52:32 INFO - Running setup.py install for mozinfo 19:52:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:52:32 INFO - Running setup.py install for mozInstall 19:52:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:52:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:52:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:52:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:52:32 INFO - Running setup.py install for mozleak 19:52:32 INFO - Running setup.py install for mozlog 19:52:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:52:32 INFO - Running setup.py install for moznetwork 19:52:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:52:32 INFO - Running setup.py install for mozprocess 19:52:32 INFO - Running setup.py install for mozprofile 19:52:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:52:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:52:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:52:33 INFO - Running setup.py install for mozrunner 19:52:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:52:33 INFO - Running setup.py install for mozscreenshot 19:52:33 INFO - Running setup.py install for moztest 19:52:33 INFO - Running setup.py install for mozversion 19:52:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:52:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:52:33 INFO - Cleaning up... 19:52:33 INFO - Return code: 0 19:52:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:52:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:33 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 0x102643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc613568480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, '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.agpFcqkTmK/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.EzPLDGUNae/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:52:33 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 19:52:33 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 19:52:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:52:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:33 INFO - 'HOME': '/Users/cltbld', 19:52:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:33 INFO - 'LOGNAME': 'cltbld', 19:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:33 INFO - 'MOZ_NO_REMOTE': '1', 19:52:33 INFO - 'NO_EM_RESTART': '1', 19:52:33 INFO - 'PAGER': '/bin/cat', 19:52:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:33 INFO - 'PWD': '/builds/slave/test', 19:52:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:33 INFO - 'SHELL': '/bin/bash', 19:52:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:52:33 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:52:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:33 INFO - 'USER': 'cltbld', 19:52:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:33 INFO - 'XPC_FLAGS': '0x0', 19:52:33 INFO - 'XPC_SERVICE_NAME': '0', 19:52:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:52:34 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-CJvFPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:52:34 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)) 19:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:52:34 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-GMMLOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:52:34 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)) 19:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:52:34 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-GlD3Fw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:52:34 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)) 19:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:52:34 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-e5GrUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:52:34 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)) 19:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:52:34 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-0QNkaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:52:34 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)) 19:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:52:34 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Mev2mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:52:34 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)) 19:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:52:34 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-LAcw9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:52:34 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)) 19:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:52:34 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-mjVtpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:52:34 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)) 19:52:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:52:34 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-NQchMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:52:35 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)) 19:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:52:35 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-XCr3yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 19:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:52:35 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-bS3QuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:52:35 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)) 19:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:52:35 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-6A54wQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 19:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:52:35 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Q_ryr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:52:35 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)) 19:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:52:35 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-VsTCEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 19:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:35 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-LePxKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:52:35 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)) 19:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:52:35 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-tHAPoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:52:35 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)) 19:52:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:52:35 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-MtwEoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:52:35 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)) 19:52:35 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)) 19:52:35 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)) 19:52:35 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)) 19:52:35 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)) 19:52:35 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)) 19:52:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:52: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 19:52: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 19:52: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 19:52: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 19:52: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 19:52: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 19:52:38 INFO - Downloading blessings-1.6.tar.gz 19:52:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:52:38 INFO - Installing collected packages: blessings 19:52:38 INFO - Running setup.py install for blessings 19:52:38 INFO - Successfully installed blessings 19:52:38 INFO - Cleaning up... 19:52:38 INFO - Return code: 0 19:52:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:52:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:52:38 INFO - Reading from file tmpfile_stdout 19:52:38 INFO - Current package versions: 19:52:38 INFO - blessings == 1.6 19:52:38 INFO - blobuploader == 1.2.4 19:52:38 INFO - docopt == 0.6.1 19:52:38 INFO - functools32 == 3.2.3-2 19:52:38 INFO - jsonschema == 2.5.1 19:52:38 INFO - manifestparser == 1.1 19:52:38 INFO - mozInstall == 1.12 19:52:38 INFO - mozcrash == 0.17 19:52:38 INFO - mozdebug == 0.1 19:52:38 INFO - mozdevice == 0.48 19:52:38 INFO - mozfile == 1.2 19:52:38 INFO - mozhttpd == 0.7 19:52:38 INFO - mozinfo == 0.9 19:52:38 INFO - mozleak == 0.1 19:52:38 INFO - mozlog == 3.3 19:52:38 INFO - moznetwork == 0.27 19:52:38 INFO - mozprocess == 0.23 19:52:38 INFO - mozprofile == 0.28 19:52:38 INFO - mozrunner == 6.12 19:52:38 INFO - mozscreenshot == 0.1 19:52:38 INFO - mozsystemmonitor == 0.3 19:52:38 INFO - moztest == 0.7 19:52:38 INFO - mozversion == 1.4 19:52:38 INFO - psutil == 3.1.1 19:52:38 INFO - requests == 1.2.3 19:52:38 INFO - wsgiref == 0.1.2 19:52:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52:38 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 0x102643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc613568480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, '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.agpFcqkTmK/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.EzPLDGUNae/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:52:38 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 19:52:38 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 19:52:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:52:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:38 INFO - 'HOME': '/Users/cltbld', 19:52:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:38 INFO - 'LOGNAME': 'cltbld', 19:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:38 INFO - 'MOZ_NO_REMOTE': '1', 19:52:38 INFO - 'NO_EM_RESTART': '1', 19:52:38 INFO - 'PAGER': '/bin/cat', 19:52:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:38 INFO - 'PWD': '/builds/slave/test', 19:52:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:38 INFO - 'SHELL': '/bin/bash', 19:52:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:52:38 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:52:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:38 INFO - 'USER': 'cltbld', 19:52:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:38 INFO - 'XPC_FLAGS': '0x0', 19:52:38 INFO - 'XPC_SERVICE_NAME': '0', 19:52:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:39 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)) 19:52:39 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)) 19:52:39 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)) 19:52:39 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)) 19:52:39 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)) 19:52:39 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)) 19:52:39 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)) 19:52:39 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)) 19:52:39 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)) 19:52:39 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)) 19:52:39 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)) 19:52:39 INFO - Cleaning up... 19:52:39 INFO - Return code: 0 19:52:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:52:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:52:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:52:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:52: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102643a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026d8618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc613568480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, '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.agpFcqkTmK/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.EzPLDGUNae/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 19:52: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', 'jsonschema'] in /builds/slave/test/build 19:52: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 jsonschema 19:52:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:52:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:52:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:52:39 INFO - 'HOME': '/Users/cltbld', 19:52:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:52:39 INFO - 'LOGNAME': 'cltbld', 19:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:52:39 INFO - 'MOZ_NO_REMOTE': '1', 19:52:39 INFO - 'NO_EM_RESTART': '1', 19:52:39 INFO - 'PAGER': '/bin/cat', 19:52:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:52:39 INFO - 'PWD': '/builds/slave/test', 19:52:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:52:39 INFO - 'SHELL': '/bin/bash', 19:52:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:52:39 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:52:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:52:39 INFO - 'USER': 'cltbld', 19:52:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:52:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:52:39 INFO - 'XPC_FLAGS': '0x0', 19:52:39 INFO - 'XPC_SERVICE_NAME': '0', 19:52:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:52:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:52:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:52:39 INFO - Cleaning up... 19:52:39 INFO - Return code: 0 19:52:39 INFO - Running post-action listener: _resource_record_post_action 19:52:39 INFO - Running post-action listener: _start_resource_monitoring 19:52:39 INFO - Starting resource monitoring. 19:52:39 INFO - [mozharness: 2016-08-09 02:52:39.552187Z] Finished create-virtualenv step (success) 19:52:39 INFO - [mozharness: 2016-08-09 02:52:39.553212Z] Running install step. 19:52:39 INFO - Running pre-action listener: _resource_record_pre_action 19:52:39 INFO - Running main action method: install 19:52:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:52:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:52:39 INFO - Reading from file tmpfile_stdout 19:52:39 INFO - Detecting whether we're running mozinstall >=1.0... 19:52:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:52:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:52:39 INFO - Reading from file tmpfile_stdout 19:52:39 INFO - Output received: 19:52:39 INFO - Usage: mozinstall [options] installer 19:52:39 INFO - Options: 19:52:39 INFO - -h, --help show this help message and exit 19:52:39 INFO - -d DEST, --destination=DEST 19:52:39 INFO - Directory to install application into. [default: 19:52:39 INFO - "/builds/slave/test"] 19:52:39 INFO - --app=APP Application being installed. [default: firefox] 19:52:39 INFO - mkdir: /builds/slave/test/build/application 19:52:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:52:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:53:14 INFO - Reading from file tmpfile_stdout 19:53:14 INFO - Output received: 19:53:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:53:14 INFO - Running post-action listener: _resource_record_post_action 19:53:14 INFO - [mozharness: 2016-08-09 02:53:14.006065Z] Finished install step (success) 19:53:14 INFO - [mozharness: 2016-08-09 02:53:14.006250Z] Running run-tests step. 19:53:14 INFO - Running pre-action listener: _resource_record_pre_action 19:53:14 INFO - Running main action method: run_tests 19:53:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:53:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:53:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:53:14 INFO - 2016-08-08 19:53:14.020 screenresolution[1649:9709] starting screenresolution argv=screenresolution get 19:53:14 INFO - 2016-08-08 19:53:14.023 screenresolution[1649:9709] Display 0: 1600x1200x32@60 19:53:14 INFO - 2016-08-08 19:53:14.028 screenresolution[1650:9715] starting screenresolution argv=screenresolution list 19:53:14 INFO - Available Modes on Display 0 19:53:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:53:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:53:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:53:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:53:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:53:14 INFO - Intel Iris: 19:53:14 INFO - Chipset Model: Intel Iris 19:53:14 INFO - Type: GPU 19:53:14 INFO - Bus: Built-In 19:53:14 INFO - VRAM (Dynamic, Max): 1536 MB 19:53:14 INFO - Vendor: Intel (0x8086) 19:53:14 INFO - Device ID: 0x0a2e 19:53:14 INFO - Revision ID: 0x0009 19:53:14 INFO - Displays: 19:53:14 INFO - Display: 19:53:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:53:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:53:14 INFO - Display Serial Number: 1600x1200 60 19:53:14 INFO - Main Display: Yes 19:53:14 INFO - Mirror: Off 19:53:14 INFO - Online: Yes 19:53:14 INFO - Rotation: Supported 19:53:14 INFO - Return code: 0 19:53:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:53:14 INFO - Python 2.7.3 19:53:14 INFO - Return code: 0 19:53:14 INFO - grabbing minidump binary from tooltool 19:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:53:14 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 0x1025a7d30>, '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 0x10230cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10259fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:53:14 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 19:53:14 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 19:53:14 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 19:53:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:53:14 INFO - Return code: 0 19:53:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:53:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:53:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:53:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:53:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:53:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 19:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0168 --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 19:53:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agpFcqkTmK/Render', 19:53:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:53:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:53:14 INFO - 'HOME': '/Users/cltbld', 19:53:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:53:14 INFO - 'LOGNAME': 'cltbld', 19:53:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:53:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:53:14 INFO - 'MOZ_NO_REMOTE': '1', 19:53:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:53:14 INFO - 'NO_EM_RESTART': '1', 19:53:14 INFO - 'PAGER': '/bin/cat', 19:53:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:53:14 INFO - 'PWD': '/builds/slave/test', 19:53:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:53:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:53:14 INFO - 'SHELL': '/bin/bash', 19:53:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzPLDGUNae/Listeners', 19:53:14 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 19:53:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:53:14 INFO - 'USER': 'cltbld', 19:53:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:53:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:53:14 INFO - 'XPC_FLAGS': '0x0', 19:53:14 INFO - 'XPC_SERVICE_NAME': '0', 19:53:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:53:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 19:53:14 INFO - mozversion application_buildid: 20160808144920 19:53:14 INFO - mozversion application_changeset: 54c34dbafa19ba57727f2b23921c40fdd7e160cf 19:53:14 INFO - mozversion application_display_name: Nightly 19:53:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:53:14 INFO - mozversion application_name: Firefox 19:53:14 INFO - mozversion application_remotingname: firefox 19:53:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:53:14 INFO - mozversion application_vendor: Mozilla 19:53:14 INFO - mozversion application_version: 51.0a1 19:53:14 INFO - mozversion platform_buildid: 20160808144920 19:53:14 INFO - mozversion platform_changeset: 54c34dbafa19ba57727f2b23921c40fdd7e160cf 19:53:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:53:14 INFO - mozversion platform_version: 51.0a1 19:53:14 INFO - using testdate: 1470711194 19:53:14 INFO - actual date: 1470711194 19:53:14 INFO - starting webserver on 'localhost:49272' 19:53:14 INFO - SUITE-START | Running 2 tests 19:53:14 INFO - TEST-START | dromaeo_css 19:53:14 INFO - operating with platform_type : mac_ 19:53:14 INFO - Initialising browser for dromaeo_css test... 19:53:14 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmptMV2Tn/profile http://localhost:49272/getInfo.html) 19:53:17 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 19:53:17 INFO - PROCESS | 1660 | colorDepth:24 19:53:17 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 19:53:17 INFO - PROCESS | 1660 | __metrics 19:53:17 INFO - PROCESS | 1660 | 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" 19:53:17 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:53:17 INFO - PROCESS | 1660 | ************************* 19:53:17 INFO - PROCESS | 1660 | A coding exception was thrown in a Promise resolution callback. 19:53:17 INFO - PROCESS | 1660 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 19:53:17 INFO - PROCESS | 1660 | 19:53:17 ERROR - PROCESS | 1660 | Full message: TypeError: win is null 19:53:17 INFO - PROCESS | 1660 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:844:17 19:53:17 INFO - PROCESS | 1660 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 19:53:17 INFO - PROCESS | 1660 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 19:53:17 INFO - PROCESS | 1660 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 19:53:17 INFO - PROCESS | 1660 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 19:53:17 INFO - PROCESS | 1660 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 19:53:17 INFO - PROCESS | 1660 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 19:53:17 INFO - PROCESS | 1660 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 19:53:17 INFO - PROCESS | 1660 | 19:53:17 INFO - PROCESS | 1660 | ************************* 19:53:18 INFO - TEST-INFO | 1660: exit 0 19:53:18 INFO - Browser initialized. 19:53:18 INFO - Running cycle 1/1 for dromaeo_css test... 19:53:18 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmptMV2Tn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:53:30 INFO - PROCESS | 1663 | RSS: Main: 267481088 19:53:30 INFO - PROCESS | 1663 | 19:54:21 INFO - PROCESS | 1663 | 1470711261712 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] 19:56:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 19:56:35 INFO - PROCESS | 1663 | RSS: Main: 393506816 19:56:35 INFO - PROCESS | 1663 | 19:59:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 19:59:39 INFO - PROCESS | 1663 | RSS: Main: 413224960 19:59:39 INFO - PROCESS | 1663 | 20:02:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 20:02:41 INFO - PROCESS | 1663 | RSS: Main: 399810560 20:02:41 INFO - PROCESS | 1663 | 20:05:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 20:05:44 INFO - PROCESS | 1663 | RSS: Main: 385073152 20:05:44 INFO - PROCESS | 1663 | 20:08:48 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 20:08:49 INFO - PROCESS | 1663 | RSS: Main: 373440512 20:08:49 INFO - PROCESS | 1663 | 20:11:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 20:11:52 INFO - PROCESS | 1663 | RSS: Main: 529063936 20:11:52 INFO - PROCESS | 1663 | 20:11:52 INFO - PROCESS | 1663 | __start_tp_report 20:11:52 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 20:11:52 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 20:11:52 INFO - PROCESS | 1663 | |i|pagename|runs| 20:11:52 INFO - PROCESS | 1663 | |0;dojo.html;2654.4691061787635;3388;3395.3209358128365;3414.2317978454844;3446.8621255149797;5645.5483561315095;5685.061964775812;5708.942910570893;5712.171735098408;5752.395998420163;5698.259226300575;5756.971215143929;5760.107183386572;5777.0756678931375;5781.306243250816;5218.739063046852;5256.132738098209;5293.258943747878;5293.655912365703;5358.571314294866;5334.226537152111;5347.037533244014;5354.839354819362;5356.303680521527;5358;5240.868978275555;5249.737513124348;5249.973750131244;5258.368995720519;5343.091674415358;5324.973375133139;5347.385050719178;5358.115910874701;5371;5379.731013449312;5139.537441630271;5158.045761534128;5215.0873597120735;5258.237555554461;5264.42091369949;5603.663780173202;5628.802991895305;5688.203651488795;5721.513671337942;5753.280839895013;6220.377962203788;6264.404881536247;6267.686615669244;6282.183316168915;6288.748450061992;6206;6224.315325314212;6294.590851594632;6305.905411418832;6310.368963103699;5812.622179558315;5856.063029915278;5870.765169393177;5878.2358293421585;5882.970585147047;6318.8420289492215;6324.209473815773;6327.019312006554;6333.334999825044;6338.207724034496;6045.969770151209;6076.939230607726;6094.299155597075;6174.289956655042;6189.071639254058;5128.487151284916;6266.436020758062;6286.151369565142;6326.051092336094;6327.3988971047675;6211.068339748984;6250.9062364064575;6262.499000079983;6286.119943207955;6289.150964619717;5812.808374283261;6128.167033931328;6305.432511074026;6310.842228944313;6320.905186422207;4981.526754958273;4992.101421744121;4999.275105109738;5040.924386134211;5054.064997975386;4431.33529970507;4454.621357184676;4462.821487140478;4483.327500874895;4517.570830771071;5603.411641777636;5709.65742055478;5721.742521586539;5726.398728133486;5798.246227990334;6243.75024999004;6250.687465626791;6278;6284.528660350492;6371.840703982438;5722.570807189394;5723.084306510938;5730.255066841284;5731.5701322400155;5777.508911742549;5452.182172674131;5467.425920278393;5488.69812160335;5490.286262785812;5493.313335833021;6257.78097766576;6280.340564240781;6291.150694656163;6312.652804095819;6349.936500634934;6320.273168585672;6335.841603959753;6349.111124442489;6350.7459701611415;6367.840803979938;6271.592346497463;6336.651484168415;6352.68236588178;6363.013732871413;6364.236291645032;5525.834224973257;6135.967692822179;6161.630302181883;6184.628922264678;6205.503559715302;5651.971740141273;6314.336994615683;6320.5891617044745;6321.431071203614;6325.4939604832425;4900.240462728283;6119.082137679384;6191.19514463117;6211.09939058843;6221.160143380585;5092.8472145835685;5222.712750798591;5673.744681489426;5714.742836572447;5958.851028724316;5556.222128901876;5558.861028474321;5569.331680198402;5621.044422448112;5625.606207565431;5673.77304907799;5694.373618902;5695.202671625892;5701.629394089371;5713.114467257582;4927.039227350632;5919.822405327847;5977.3424923257535;6005.429484198994;6077.118817771487 20:11:52 INFO - PROCESS | 1663 | |1;ext.html;484.43805185984263;499.74762744813864;500.0399233471734;527.146023442024;529.5604648142042;30546.69453305469;30841.22896927578;32159.03522894311;32908.835455822715;32935.50596741048;33196.66803331966;40731.79634101833;42516.78741606295;43817.34273985893;44034.89912752183;9412.717618471439;9827.312088153833;9848.507574621277;9862.704118876445;9866.60533578656;9658.613655453808;9760.755981100458;9785.266105042114;9786.3638863474;9813.362131461432;9701.805963880719;9727.75680607987;9756.853647195263;9808;9853.507324633776;9708.126268635822;9762.414255144644;9763.121319081316;9780.55987480558;9802.705918822447;9625.374350667184;9669.951650241774;9717.757056073584;9752.90247097527;9762.316637835354;41199.76400707984;41463.58536414657;41507.58492415067;41582.79208603968;41745.791271043454;3395.5076513905346;3451.6893479586706;3898.961010389888;3995.5205375354853;4274.974006238512;31897;32144.196395090075;33154.668453315644;33205;33458.83270583656;3165.984170079158;3232.4666430039015;3374.476956071788;3375.459926411787;3385.7291416686608;424913;427185;432294;434320;436063.81968089956;57598.7120064397;57654;57793;58189;58285.708571456875;61786;61862.69068654628;61875.6906215466;61990.69004655038;62047.6897615509;15349.846501535065;15554.22228888551;15566;15578.844211557966;15589.532314030597;12053.879461205275;12075;12095.939520302518;12170.695732606755;12172.269663820021;15744.92127539355;16389.508314750576;16421.91789041047;16448;16453.424130155625;5890.558208134407;6002.919474494633;6350.491960643138;6447.323031081669;6486.156799616114;821.9136990615899;964.9372790768577;979.6913972098766;994;1050.485262221506;29855.850720746257;30138.54792178119;30460.69539304623;30813.22966925845;30966.845165774026;996.9512651501235;1051.6792378324685;1091.2961140064615;1107.678773155792;1139.6979800352913;12912.612621621367;14578;15622.843771562366;15677.686446271011;16141.757873631905;669076;671199;675038;675359;676710;59642.508937276914;59727.70136149292;59789;59885.10746801444;59903.400965989786;1262.8863402293655;1292.4442489729317;1336.5054929676082;1381.2610253514322;1423.9288035598179;29910.850445747634;31106.37787244197;31111.222219444695;32007.519887202176;32745.836270818494;57423;57974.42025579859;58037;58101;58115.418845812696;611663;619698;619826;621111;622204;23416.531669366857;23781;24142.517149656556;24148.87925560361;26159.476810464064;48392;48496.5150348492;49127;49501.257481137094;49601.503984961135;37634.8118259407;39324.41013384744;39405.408918866815;41177.79411102925;41289.79355103205;39120.608793912834;39787.80106099451;40470;40895;41069.58930410658;37995.62004379921;38376.6162338373;38766.80616596899;39106.41340379953;39181.60818391779;29641.851790740908;33544.32911341808;35025.474617880245;35243.64756352506;35444.5188836779;23227.3031809046;24724.13896406059;25075.49849002973;25434.74565254324;26053.348666282738 20:11:52 INFO - PROCESS | 1663 | |2;jquery.html;350.1980464735755;361.88781477741884;372.3744109895376;375.1895904845533;398.6671129606778;14717.705645887076;15439.305231264592;20772.68840967387;20991.68512472312;21218.575628487422;19642;20303.289384871532;20306.898465507653;20548.48628784281;20820.479488012807;5037.269595908591;5278.868028299304;5507.284053073115;5510.889782204354;5539.418361072069;5979.730912108966;6120.908186377208;6128.8467788305425;6129.172561704179;6142.692865356737;5055.545000949914;6070.453659170674;6072.180255665473;6084.513238940896;6127.877442451148;6159.23009623797;6202.131701561785;6210.316865144853;6220.844478888019;6231.6261024338455;5847.473727364515;5848.795292164754;6012.278526576795;6019.097135429677;6019.729112189962;13312.800307995389;13454.730905381837;13541.39063742134;25797.871010645013;25941.74058259431;2158.125958986613;2164.4588852786806;2166.8483206175574;2252.954940901173;2284.942876428086;9656.468894210815;10286.794264114676;10427.895721042769;14726.70546589073;15845.286962086619;1837.9172937217857;1869.9252029918787;1897.4023182697406;1906.3994841624844;1908.1699460734485;118290.81709182884;119356;121171;121340;128599.71400286065;11512;11576.594819181288;11665.358405287621;11688.18182727201;11900;14652.194129322776;14670.706585868224;14698.11811291326;14698.55904322872;14744.926275368554;6961.930380696128;8084.9489566356;8327.292180164633;8365.749027529184;8449.788755281044;8127.471714338667;8135.83728325442;8171.713990010321;8323.126071762377;8338.166183381612;4462.486814016364;4549.158405694957;4553.590176884097;4619;4649.930251046237;1857.7584913961098;1913.1773337465024;1922.4713203869048;1924.1533725160884;1926.1428664244277;760.7185341423709;768.3814529303852;815.0341844913678;844.8817165596821;946.1626460582435;19169.712454313198;19870.30453934134;20247.797522024885;21339;21348.78651213468;903.5211337990876;930.469632309577;944.3436811416137;969.743018100204;969.8787651543557;9735.805283894424;10675.199360048027;11303.547858085749;11698.59616845967;12090.274583525017;512506;530734;535574;625991.5838481562;627907;121101;139628;144661;147927.52072479136;148620;979.3487330924855;981.3425005246502;1013.5287091502487;1017.013496907998;1022.7085280694967;15492.147931863405;19311.420657380302;19639.70540441923;19793.208271668973;20262.89868550648;37351.62648373481;53070.73464632652;55261.7236913829;56643.433565663814;60021.69989150026;60046;79792.60103699677;83832.32335353136;85421;89078;5944.256967879015;6017.247844019497;6570.244421891543;7426.405887529093;7687.846243074995;12206.210072797223;12670.493180273055;12998.805017924928;13110.213387196798;13139;10984.01143897053;11173.994340509393;11370.43147842588;11391.405203271383;11448;9523.761905952406;10421.218408619232;11080.39057851826;11224.887751122384;11528.942355288169;11157.107431405306;11266.83099753488;11616.825747613624;11659.825102623297;11720.589779357682;6128.908066378919;6988.720451182099;7189.496735228693;7445.851082978202;7533.698652053857;9168.816623667355;10832.729181770519;10954.835677465004;11266.605668801552;11341.829872551752 20:11:52 INFO - PROCESS | 1663 | |3;mootools.html;1363.5295822941082;1503.7969874067;1530.5025866593357;1723.258998630589;1896.6680830854598;7695.461317707755;8144.837103257932;8162.795930101735;8167.019957605088;8178.263956243937;8024.799380015503;8394.202550757669;8576.271016963565;8592.140785921405;8754.95622521888;1933.3909818407158;2301.5627030864084;2326.4183954011496;2327.080803082781;2336.918207862725;2263.6151854184827;2308.849924754894;2319.246244970383;2320.118355025088;2321.5008773113777;2284.246198754407;2292.083166733312;2314.1900334882826;2333.276684227886;2336.100601268515;2257.3228031590456;2284.2804516577307;2294.036504668035;2296.3455415206586;2296.827737919646;2266.897989590472;2277.601419751537;2280.338701776483;2281.8060464860505;2284.4517315844078;8656.437331573428;8673.002604700414;8763.430377025537;8766.649334026632;8770.517621530813;5067.949320506785;5070.467600901889;5234.607404444682;5249.291345668324;5252.9737351313;3017.2456885778556;3584.336897673939;3589.138606734391;3599.9280014399565;3605.1708107135505;920.9447433154032;1055.7044027672264;1095.2005036323449;1783.2242974306218;1846.1138653446421;11469.94265028678;11506.30962142274;11595;11609.709757256052;11637.418129093596;3573.8034408107806;4045.9190816183514;4548.476925153584;4569.086182763461;4571.3600095986585;8827.779305517413;8975.865362019575;9021.774455638662;9049.502277374675;9072.95463522678;5170.560502357342;5179.948200518022;5182.015417070745;5184.766685499161;5197.116490196676;4338.58783415575;4503.7522936238165;4659.114768194032;4787.42550893888;4830.492798256132;3621.6559426854406;3679.6688298053305;3724.906877328034;3761.6238376162714;3795.013296542919;967.9516024198762;1132.8527291452058;1137.1414581990543;1152.1358980764426;1154.3650991954391;724.2178447277033;834.8205135895812;837.1293854391487;840.3529282452478;846.399056669759;5315.6279060465395;5617.43825617435;5672.064109421899;5698.259226300637;5700.201971723962;398.6432143231791;437.3658195616369;443.14694213638614;443.85796545105256;460.7926433105116;3938.369860822255;4055.1078762672164;4085.55058943516;4197.895052623709;4232.217039847638;10634.989675980923;10745.194110441747;10871.782564348669;10884.07485363753;10886.237963342648;10129.949350253202;10396.376217426827;10677.199210059121;10885.129189664807;10898.727531811768;7862.882056769153;8135.471194372347;8161.5919204039565;8311.33509319253;8336.958315208385;5349.491798279158;5965.343812180743;5970.104484327386;5970.731317090828;6172.69136543162;9641.758956025875;9816.460094694574;9826.557804898654;9977.401355918668;9994.800103998024;9280.700701901604;9518.428894266366;10099.242556808122;10104.69685909424;10107.69676909694;7031.156261248683;7053.435725142079;7411.703531858665;7553.886691699519;7700.152983171734;8275.296599788844;8531.701390451304;8546.145385461652;8552.828943421222;8554.18735220153;7458.216887226763;7532.798979144113;7617.5048621839405;7712.074551053909;7714.190010048864;7332.560046397233;7462.813429664302;7480.027596412431;7495.587742674205;7534.886976695464;5545.889082218414;7028.437724981887;7053.294670532906;7054.25930277334;7067.363937245673;4835.347228124299;4982.327385802871;5022.472640372708;5056.039352523009;5060.772265247999;4204.558521355214;4716.434027916672;4852.56326930578;4867.3915760529935;4932.136876046606 20:11:52 INFO - PROCESS | 1663 | |4;prototype.html;296.8189404463277;296.9391274788668;306.4743964101566;312.60767736490703;316.2504863473566;4705.600023997956;5091.930694554138;5132.820351287705;5194.584433245341;5230.686158830473;3976.443297938291;4111.506619205699;4166.5833416658315;4384.728428755665;5138.280640710294;3928.389360362252;3944.2900277949957;3949.565547789741;3959.5050618672594;3963.246983073207;3661.505696730947;3982.661473774718;4013.9598604014022;4019.859304924328;4089.672826173914;3581.7492775505725;3912.726109172359;3930.4693866327966;3930.6855451563806;3970.7617542947514;3911.4915061042166;3932.960670393288;3940.6847452203897;3940.862069827542;3942.546607140187;3430.588329400463;3431;3443.2080621456953;3458.3745639549625;3464.047386968604;4411.664542243603;4717.646176536773;4967.230079337708;4988.625853061035;5034.11902916988;4438.644908407319;4455;4459.219636563588;4468.798904049293;4495.527969563182;4453.421055262827;4779.617630589544;5014;5210.921836172461;5365.812196573102;3438.621751607321;3702.981485092584;3776.773393596393;3792.658660720521;3808.8286027128847;4684.601808846252;5553.640392292032;5607.158926161068;5672.4894759471845;5731.799387021476;3477.8349253000024;3656.981715091461;3813.275477659236;3814.8474061037246;3821.044738815296;2941.6175897133235;3788.3559794834946;3830.2339532093692;3836.76979381238;3842.3083844907624;3924.6067645985263;4308;4371.846985355497;4419.1382680377665;4442.600165985013;4672.906541869143;5367.973160134253;5373.408925018245;5390.19147127934;5391.272178255964;1059.2902755154066;1233.3463264469844;1259.5263541656284;1285.8328417305877;1313.3958379145508;754.4718696912183;860.5482121885934;869.6695255802755;885.4687187687465;887.3078998381272;4558.680892337571;4961.925571116364;4963.950360496421;4974.5325129087005;4984.327115839388;5281.811592391681;5515.062439385314;5521;5536.889262214733;5541.972290138524;4326.156399502067;4544.318352247189;4885.021566265597;4898.755062246873;4973.502649734997;2638.7729705686947;2856.4429936162664;2858.2711408591094;2891;2934.471795076907;4745.928811067837;5071.518205770446;5414.539764120094;5489.666891571066;5678.829635110953;4438.845900066006;5561.63849349791;5570.359408668055;5601;5606.24315717373;4736.145450912842;4919.827806026772;5563.693996830213;5653.095504719226;5655.1799989001365;5155.9742201288755;5435.483629055233;5445.428230035947;5458.836234912959;5465.9726701366235;4296.978515107405;4653.557911998355;4849.199882019428;5050.671706339076;5359.695331007704;5212.879230965209;5521.447855214607;5591.4688104629995;5603.691796951207;5632.887342253214;3545.837039405909;3708.276886007203;3753.3806921857586;3754.7747135171976;3756.9060773480883;4518.921296203652;4726.36194113804;5406.4052954175795;5449.128139497661;5463.934532766072;4137.213929353413;4787.569118779326;5291.3121294231505;5309.212663351771;5339.9295405142975;5014.022265658162;5240.633155679066;5285.947140528546;5319.042572337016;5340.519353258225;4735.573798358164;5058.54473097423;5072.721000344869;5210.140326846028;5215.947840521698;5100.832146337866;5258.474152584711;5282.1548552233;5287.312649355559;5289.3652761668845;4866.732329721899;5297.337832770903;5312.707801070978;5319.2180619491355;5326.307580014728 20:11:52 INFO - PROCESS | 1663 | |5;yui.html;695.8469136789906;713.5540287320425;737.9335859772619;739.0429393934854;761.3984951888009;14926.701465970675;16294.266757995889;16299.592510187222;16345.591360216016;16382.1979582246;14543.490977815778;14726.116433014027;14726.631834204123;14728.116313021226;14832.332545035473;588.6497533967282;676.1244188775523;694.5416025423222;823.8022874510119;845.2519520224615;821.8150916043902;836.8326334733048;849.4563479373206;851.4082712514804;853.9829203415928;899.0919171636629;901.3059943843269;902.9615941667103;903.0157128729684;903.8101998580306;894.5348418822241;895.2077411490812;895.4224525181222;896.3994123936978;897.1387468030698;534.5002422734756;644.7034364192477;666.1273731411866;666.1805978646292;672.4317951331118;14272.215028173447;14309.499167529191;14322.355494002692;14334.856651433456;14352.56942291733;13822.792658110136;14092.577222683336;14348.713025739427;14473.855261447356;14692.632684182981;12843.678908027281;12952.676183095404;12977.935110324483;13066.934665326613;13203.405846736823;4501.414816073922;4551.385562948993;4679.789409476582;4729.810807567727;4748.5014073522125;120404.19393709101;121919;122283;124646;125590;11303.434828258554;11341.489632966537;11401.48693308803;11491.655250342503;11642.68183567679;22973.62158270509;23259.534809303717;23271.53456930852;23271.650925236136;23281.534369312856;7972.2824945755165;8045.396595255379;8073.6366863491285;8089.959550202211;8202.794930126674;4322.740635561876;4353.542877997828;4354.287929774686;4514.525974772601;4524.298733696282;3465.1163953191776;3581.3195492856275;3583.8208089595937;3587.264610754789;3599.460080987873;625.3184029407967;636.4303947966544;648.7317294688872;649.4739261198445;660.3561527510735;606.6147996022557;609.7073404765648;611.3855575146949;639.6961443314482;646.5054233511368;8584.91415085841;8927.598258078402;9148.542572871329;9237.676681316256;9271.860922086174;494.36967865970456;513.5044681882002;564.3256308711051;580.2949416459022;624.6970219443566;4190.434291370687;4434.490033646172;4436.290193569013;4494.640428765691;4537.1833070047705;85530.14469855346;88612;90502;135956;170340;117825.46523604306;119580;120235.39882300707;123386;125981.37009315078;86214.99999999875;110121.44939275412;111181.33228001425;116149;116532;9634.807303854024;9848.60605575769;9918.851217231602;9972.40165590038;10016.148627366756;56066.719666401405;61322.85370012028;63069.36930630635;63708.68145659428;63813.680931595045;95276.52361738146;96720.51639741757;101390;101955;102120;11843.768580836237;12003.068004103912;12175.756484870075;12208.877911221129;12699.619011429671;14906.776398353817;15144.54566363011;15216.848081401724;15643;15726.29231684554;13675.931620342233;14120.717585648024;14155.929220353832;14547.709045819236;14686.339114740076;13820.654483637587;13873.424774754412;13877.710177820547;13896.305184740924;14135.858641413866;13649.963906105933;13686.794698079337;13777.724445511234;13991;14035.228062456663;10922.890771092187;11326;11345.773084538429;11521.251118677259;11782.469788859693;11162.218644694793;11279.492422840827;11625.59310424131;11687.707807304885;11738.354390508604 20:11:52 INFO - PROCESS | 1663 | __end_tp_report 20:11:52 INFO - PROCESS | 1663 | __start_cc_report 20:11:52 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1286 20:11:52 INFO - PROCESS | 1663 | __end_cc_report 20:11:52 INFO - PROCESS | 1663 | __startTimestamp1470712312474__endTimestamp 20:11:52 INFO - PROCESS | 1663 | 20:11:52 INFO - PROCESS | 1663 | ------- Summary: start ------- 20:11:52 INFO - PROCESS | 1663 | Number of tests: 6 20:11:52 INFO - PROCESS | 1663 | 20:11:52 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5716.10 Median:5753.28 stddev:648.42 (11.3%) stddev-sans-first:604.29 20:11:52 INFO - PROCESS | 1663 | Values: 2654.5 3388.0 3395.3 3414.2 3446.9 5645.5 5685.1 5708.9 5712.2 5752.4 5698.3 5757.0 5760.1 5777.1 5781.3 5218.7 5256.1 5293.3 5293.7 5358.6 5334.2 5347.0 5354.8 5356.3 5358.0 5240.9 5249.7 5250.0 5258.4 5343.1 5325.0 5347.4 5358.1 5371.0 5379.7 5139.5 5158.0 5215.1 5258.2 5264.4 5603.7 5628.8 5688.2 5721.5 5753.3 6220.4 6264.4 6267.7 6282.2 6288.7 6206.0 6224.3 6294.6 6305.9 6310.4 5812.6 5856.1 5870.8 5878.2 5883.0 6318.8 6324.2 6327.0 6333.3 6338.2 6046.0 6076.9 6094.3 6174.3 6189.1 5128.5 6266.4 6286.2 6326.1 6327.4 6211.1 6250.9 6262.5 6286.1 6289.2 5812.8 6128.2 6305.4 6310.8 6320.9 4981.5 4992.1 4999.3 5040.9 5054.1 4431.3 4454.6 4462.8 4483.3 4517.6 5603.4 5709.7 5721.7 5726.4 5798.2 6243.8 6250.7 6278.0 6284.5 6371.8 5722.6 5723.1 5730.3 5731.6 5777.5 5452.2 5467.4 5488.7 5490.3 5493.3 6257.8 6280.3 6291.2 6312.7 6349.9 6320.3 6335.8 6349.1 6350.7 6367.8 6271.6 6336.7 6352.7 6363.0 6364.2 5525.8 6136.0 6161.6 6184.6 6205.5 5652.0 6314.3 6320.6 6321.4 6325.5 4900.2 6119.1 6191.2 6211.1 6221.2 5092.8 5222.7 5673.7 5714.7 5958.9 5556.2 5558.9 5569.3 5621.0 5625.6 5673.8 5694.4 5695.2 5701.6 5713.1 4927.0 5919.8 5977.3 6005.4 6077.1 20:11:52 INFO - PROCESS | 1663 | 20:11:52 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:70704.82 Median:41244.78 stddev:156321.03 (379.0%) stddev-sans-first:156670.64 20:11:52 INFO - PROCESS | 1663 | Values: 484.4 499.7 500.0 527.1 529.6 30546.7 30841.2 32159.0 32908.8 32935.5 33196.7 40731.8 42516.8 43817.3 44034.9 9412.7 9827.3 9848.5 9862.7 9866.6 9658.6 9760.8 9785.3 9786.4 9813.4 9701.8 9727.8 9756.9 9808.0 9853.5 9708.1 9762.4 9763.1 9780.6 9802.7 9625.4 9670.0 9717.8 9752.9 9762.3 41199.8 41463.6 41507.6 41582.8 41745.8 3395.5 3451.7 3899.0 3995.5 4275.0 31897.0 32144.2 33154.7 33205.0 33458.8 3166.0 3232.5 3374.5 3375.5 3385.7 424913.0 427185.0 432294.0 434320.0 436063.8 57598.7 57654.0 57793.0 58189.0 58285.7 61786.0 61862.7 61875.7 61990.7 62047.7 15349.8 15554.2 15566.0 15578.8 15589.5 12053.9 12075.0 12095.9 12170.7 12172.3 15744.9 16389.5 16421.9 16448.0 16453.4 5890.6 6002.9 6350.5 6447.3 6486.2 821.9 964.9 979.7 994.0 1050.5 29855.9 30138.5 30460.7 30813.2 30966.8 997.0 1051.7 1091.3 1107.7 1139.7 12912.6 14578.0 15622.8 15677.7 16141.8 669076.0 671199.0 675038.0 675359.0 676710.0 59642.5 59727.7 59789.0 59885.1 59903.4 1262.9 1292.4 1336.5 1381.3 1423.9 29910.9 31106.4 31111.2 32007.5 32745.8 57423.0 57974.4 58037.0 58101.0 58115.4 611663.0 619698.0 619826.0 621111.0 622204.0 23416.5 23781.0 24142.5 24148.9 26159.5 48392.0 48496.5 49127.0 49501.3 49601.5 37634.8 39324.4 39405.4 41177.8 41289.8 39120.6 39787.8 40470.0 40895.0 41069.6 37995.6 38376.6 38766.8 39106.4 39181.6 29641.9 33544.3 35025.5 35243.6 35444.5 23227.3 24724.1 25075.5 25434.7 26053.3 20:11:52 INFO - PROCESS | 1663 | 20:11:52 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34372.49 Median:2268.95 stddev:95789.35 (4221.7%) stddev-sans-first:96024.00 20:11:52 INFO - PROCESS | 1663 | Values: 350.2 361.9 372.4 375.2 398.7 14717.7 15439.3 20772.7 20991.7 21218.6 19642.0 20303.3 20306.9 20548.5 20820.5 5037.3 5278.9 5507.3 5510.9 5539.4 5979.7 6120.9 6128.8 6129.2 6142.7 5055.5 6070.5 6072.2 6084.5 6127.9 6159.2 6202.1 6210.3 6220.8 6231.6 5847.5 5848.8 6012.3 6019.1 6019.7 13312.8 13454.7 13541.4 25797.9 25941.7 2158.1 2164.5 2166.8 2253.0 2284.9 9656.5 10286.8 10427.9 14726.7 15845.3 1837.9 1869.9 1897.4 1906.4 1908.2 118290.8 119356.0 121171.0 121340.0 128599.7 11512.0 11576.6 11665.4 11688.2 11900.0 14652.2 14670.7 14698.1 14698.6 14744.9 6961.9 8084.9 8327.3 8365.7 8449.8 8127.5 8135.8 8171.7 8323.1 8338.2 4462.5 4549.2 4553.6 4619.0 4649.9 1857.8 1913.2 1922.5 1924.2 1926.1 760.7 768.4 815.0 844.9 946.2 19169.7 19870.3 20247.8 21339.0 21348.8 903.5 930.5 944.3 969.7 969.9 9735.8 10675.2 11303.5 11698.6 12090.3 512506.0 530734.0 535574.0 625991.6 627907.0 121101.0 139628.0 144661.0 147927.5 148620.0 979.3 981.3 1013.5 1017.0 1022.7 15492.1 19311.4 19639.7 19793.2 20262.9 37351.6 53070.7 55261.7 56643.4 60021.7 60046.0 79792.6 83832.3 85421.0 89078.0 5944.3 6017.2 6570.2 7426.4 7687.8 12206.2 12670.5 12998.8 13110.2 13139.0 10984.0 11174.0 11370.4 11391.4 11448.0 9523.8 10421.2 11080.4 11224.9 11528.9 11157.1 11266.8 11616.8 11659.8 11720.6 6128.9 6988.7 7189.5 7445.9 7533.7 9168.8 10832.7 10954.8 11266.6 11341.8 20:11:52 INFO - PROCESS | 1663 | 20:11:52 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5588.38 Median:4899.76 stddev:3174.63 (64.8%) stddev-sans-first:3167.65 20:11:52 INFO - PROCESS | 1663 | Values: 1363.5 1503.8 1530.5 1723.3 1896.7 7695.5 8144.8 8162.8 8167.0 8178.3 8024.8 8394.2 8576.3 8592.1 8755.0 1933.4 2301.6 2326.4 2327.1 2336.9 2263.6 2308.8 2319.2 2320.1 2321.5 2284.2 2292.1 2314.2 2333.3 2336.1 2257.3 2284.3 2294.0 2296.3 2296.8 2266.9 2277.6 2280.3 2281.8 2284.5 8656.4 8673.0 8763.4 8766.6 8770.5 5067.9 5070.5 5234.6 5249.3 5253.0 3017.2 3584.3 3589.1 3599.9 3605.2 920.9 1055.7 1095.2 1783.2 1846.1 11469.9 11506.3 11595.0 11609.7 11637.4 3573.8 4045.9 4548.5 4569.1 4571.4 8827.8 8975.9 9021.8 9049.5 9073.0 5170.6 5179.9 5182.0 5184.8 5197.1 4338.6 4503.8 4659.1 4787.4 4830.5 3621.7 3679.7 3724.9 3761.6 3795.0 968.0 1132.9 1137.1 1152.1 1154.4 724.2 834.8 837.1 840.4 846.4 5315.6 5617.4 5672.1 5698.3 5700.2 398.6 437.4 443.1 443.9 460.8 3938.4 4055.1 4085.6 4197.9 4232.2 10635.0 10745.2 10871.8 10884.1 10886.2 10129.9 10396.4 10677.2 10885.1 10898.7 7862.9 8135.5 8161.6 8311.3 8337.0 5349.5 5965.3 5970.1 5970.7 6172.7 9641.8 9816.5 9826.6 9977.4 9994.8 9280.7 9518.4 10099.2 10104.7 10107.7 7031.2 7053.4 7411.7 7553.9 7700.2 8275.3 8531.7 8546.1 8552.8 8554.2 7458.2 7532.8 7617.5 7712.1 7714.2 7332.6 7462.8 7480.0 7495.6 7534.9 5545.9 7028.4 7053.3 7054.3 7067.4 4835.3 4982.3 5022.5 5056.0 5060.8 4204.6 4716.4 4852.6 4867.4 4932.1 20:11:52 INFO - PROCESS | 1663 | 20:11:52 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4317.73 Median:4741.04 stddev:1295.34 (27.3%) stddev-sans-first:1263.33 20:11:52 INFO - PROCESS | 1663 | Values: 296.8 296.9 306.5 312.6 316.3 4705.6 5091.9 5132.8 5194.6 5230.7 3976.4 4111.5 4166.6 4384.7 5138.3 3928.4 3944.3 3949.6 3959.5 3963.2 3661.5 3982.7 4014.0 4019.9 4089.7 3581.7 3912.7 3930.5 3930.7 3970.8 3911.5 3933.0 3940.7 3940.9 3942.5 3430.6 3431.0 3443.2 3458.4 3464.0 4411.7 4717.6 4967.2 4988.6 5034.1 4438.6 4455.0 4459.2 4468.8 4495.5 4453.4 4779.6 5014.0 5210.9 5365.8 3438.6 3703.0 3776.8 3792.7 3808.8 4684.6 5553.6 5607.2 5672.5 5731.8 3477.8 3657.0 3813.3 3814.8 3821.0 2941.6 3788.4 3830.2 3836.8 3842.3 3924.6 4308.0 4371.8 4419.1 4442.6 4672.9 5368.0 5373.4 5390.2 5391.3 1059.3 1233.3 1259.5 1285.8 1313.4 754.5 860.5 869.7 885.5 887.3 4558.7 4961.9 4964.0 4974.5 4984.3 5281.8 5515.1 5521.0 5536.9 5542.0 4326.2 4544.3 4885.0 4898.8 4973.5 2638.8 2856.4 2858.3 2891.0 2934.5 4745.9 5071.5 5414.5 5489.7 5678.8 4438.8 5561.6 5570.4 5601.0 5606.2 4736.1 4919.8 5563.7 5653.1 5655.2 5156.0 5435.5 5445.4 5458.8 5466.0 4297.0 4653.6 4849.2 5050.7 5359.7 5212.9 5521.4 5591.5 5603.7 5632.9 3545.8 3708.3 3753.4 3754.8 3756.9 4518.9 4726.4 5406.4 5449.1 5463.9 4137.2 4787.6 5291.3 5309.2 5339.9 5014.0 5240.6 5285.9 5319.0 5340.5 4735.6 5058.5 5072.7 5210.1 5215.9 5100.8 5258.5 5282.2 5287.3 5289.4 4866.7 5297.3 5312.7 5319.2 5326.3 20:11:52 INFO - PROCESS | 1663 | 20:11:52 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:24379.69 Median:3523.22 stddev:37903.25 (1075.8%) stddev-sans-first:37967.86 20:11:52 INFO - PROCESS | 1663 | Values: 695.8 713.6 737.9 739.0 761.4 14926.7 16294.3 16299.6 16345.6 16382.2 14543.5 14726.1 14726.6 14728.1 14832.3 588.6 676.1 694.5 823.8 845.3 821.8 836.8 849.5 851.4 854.0 899.1 901.3 903.0 903.0 903.8 894.5 895.2 895.4 896.4 897.1 534.5 644.7 666.1 666.2 672.4 14272.2 14309.5 14322.4 14334.9 14352.6 13822.8 14092.6 14348.7 14473.9 14692.6 12843.7 12952.7 12977.9 13066.9 13203.4 4501.4 4551.4 4679.8 4729.8 4748.5 120404.2 121919.0 122283.0 124646.0 125590.0 11303.4 11341.5 11401.5 11491.7 11642.7 22973.6 23259.5 23271.5 23271.7 23281.5 7972.3 8045.4 8073.6 8090.0 8202.8 4322.7 4353.5 4354.3 4514.5 4524.3 3465.1 3581.3 3583.8 3587.3 3599.5 625.3 636.4 648.7 649.5 660.4 606.6 609.7 611.4 639.7 646.5 8584.9 8927.6 9148.5 9237.7 9271.9 494.4 513.5 564.3 580.3 624.7 4190.4 4434.5 4436.3 4494.6 4537.2 85530.1 88612.0 90502.0 135956.0 170340.0 117825.5 119580.0 120235.4 123386.0 125981.4 86215.0 110121.4 111181.3 116149.0 116532.0 9634.8 9848.6 9918.9 9972.4 10016.1 56066.7 61322.9 63069.4 63708.7 63813.7 95276.5 96720.5 101390.0 101955.0 102120.0 11843.8 12003.1 12175.8 12208.9 12699.6 14906.8 15144.5 15216.8 15643.0 15726.3 13675.9 14120.7 14155.9 14547.7 14686.3 13820.7 13873.4 13877.7 13896.3 14135.9 13650.0 13686.8 13777.7 13991.0 14035.2 10922.9 11326.0 11345.8 11521.3 11782.5 11162.2 11279.5 11625.6 11687.7 11738.4 20:11:52 INFO - PROCESS | 1663 | -------- Summary: end -------- 20:11:52 INFO - PROCESS | 1663 | 20:11:53 INFO - TEST-INFO | 1663: exit 0 20:11:53 INFO - TEST-OK | dromaeo_css | took 1118500ms 20:11:53 INFO - TEST-START | kraken 20:11:53 INFO - operating with platform_type : mac_ 20:11:53 INFO - Initialising browser for kraken test... 20:11:53 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpjYchFJ/profile http://localhost:49272/getInfo.html) 20:11:55 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 20:11:55 INFO - PROCESS | 1669 | colorDepth:24 20:11:55 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 20:11:55 INFO - PROCESS | 1669 | __metrics 20:11:55 INFO - PROCESS | 1669 | 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" 20:11:55 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:11:55 INFO - PROCESS | 1669 | ************************* 20:11:55 INFO - PROCESS | 1669 | A coding exception was thrown in a Promise resolution callback. 20:11:55 INFO - PROCESS | 1669 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 20:11:55 INFO - PROCESS | 1669 | 20:11:55 ERROR - PROCESS | 1669 | Full message: TypeError: win is null 20:11:55 INFO - PROCESS | 1669 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:844:17 20:11:55 INFO - PROCESS | 1669 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 20:11:55 INFO - PROCESS | 1669 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 20:11:55 INFO - PROCESS | 1669 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 20:11:55 INFO - PROCESS | 1669 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 20:11:55 INFO - PROCESS | 1669 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 20:11:55 INFO - PROCESS | 1669 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 20:11:55 INFO - PROCESS | 1669 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 20:11:55 INFO - PROCESS | 1669 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 20:11:55 INFO - PROCESS | 1669 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 20:11:55 INFO - PROCESS | 1669 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 20:11:55 INFO - PROCESS | 1669 | Barrier/this.client.addBlocker/promise53.3% 20:12:53 INFO - TinderboxPrint: I/O read bytes / time
43,876,352 / 489 20:12:53 INFO - TinderboxPrint: I/O write bytes / time
716,056,064 / 1,987 20:12:53 INFO - TinderboxPrint: CPU idle
2,265.9 (46.7%) 20:12:53 INFO - TinderboxPrint: CPU system
969.0 (20.0%) 20:12:53 INFO - TinderboxPrint: CPU user
1,618.7 (33.4%) 20:12:53 INFO - TinderboxPrint: Swap in / out
439,672,832 / 0 20:12:53 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400632320; Write bytes: 397048320; Read time: 29502; Write time: 829 20:12:53 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 41906176; Write bytes: 303209472; Read time: 468; Write time: 1120 20:12:53 INFO - Running post-run listener: _upload_blobber_files 20:12:53 INFO - Blob upload gear active. 20:12:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:12:53 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 20:12:53 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:12:53 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:12:54 INFO - (blobuploader) - INFO - Open directory for files ... 20:12:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:12:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:12:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:54 INFO - (blobuploader) - INFO - Done attempting. 20:12:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:12:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:12:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:55 INFO - (blobuploader) - INFO - Done attempting. 20:12:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:12:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:12:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:12:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:12:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:12:58 INFO - (blobuploader) - INFO - Done attempting. 20:12:58 INFO - (blobuploader) - INFO - Iteration through files over. 20:12:58 INFO - Return code: 0 20:12:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:12:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:12:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8fba64d8fdcdc8b2b6e43f409b689b9c362986cc9d9bf1f3caa51909834ad90687120a5940bee4102902ff03fd643623e15cf9016809f23ce65c252480eaa851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04a639df6e505f017f776651931fe63b80f6ebbf53a8c64a314e5288609fbbf2dc24a5bbf4cd281ce951fe7398bfbc6d8552b0b88f4992c3f9f456b945ea4ec0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/477f089687a4cc26611946aefa4ac6679fd0790566915a91bf7893bf671d65885d6f5a60151db59c6711dc490ae3e2752ca1026d61700a5ea940ac7170bcc23f"} 20:12:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:12:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:12:58 INFO - Contents: 20:12:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8fba64d8fdcdc8b2b6e43f409b689b9c362986cc9d9bf1f3caa51909834ad90687120a5940bee4102902ff03fd643623e15cf9016809f23ce65c252480eaa851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04a639df6e505f017f776651931fe63b80f6ebbf53a8c64a314e5288609fbbf2dc24a5bbf4cd281ce951fe7398bfbc6d8552b0b88f4992c3f9f456b945ea4ec0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/477f089687a4cc26611946aefa4ac6679fd0790566915a91bf7893bf671d65885d6f5a60151db59c6711dc490ae3e2752ca1026d61700a5ea940ac7170bcc23f"} 20:12:58 INFO - Running post-run listener: copy_logs_to_upload_dir 20:12:58 INFO - Copying logs to upload dir... 20:12:58 INFO - mkdir: /builds/slave/test/build/upload/logs 20:12:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.719986 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-08 20:12:58.362020) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 20:12:58.365915) ========= 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.agpFcqkTmK/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.EzPLDGUNae/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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/Autoland/sha512/8fba64d8fdcdc8b2b6e43f409b689b9c362986cc9d9bf1f3caa51909834ad90687120a5940bee4102902ff03fd643623e15cf9016809f23ce65c252480eaa851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04a639df6e505f017f776651931fe63b80f6ebbf53a8c64a314e5288609fbbf2dc24a5bbf4cd281ce951fe7398bfbc6d8552b0b88f4992c3f9f456b945ea4ec0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/477f089687a4cc26611946aefa4ac6679fd0790566915a91bf7893bf671d65885d6f5a60151db59c6711dc490ae3e2752ca1026d61700a5ea940ac7170bcc23f"} build_url:https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/fnrtBv4VQkSmpmdqmeofMg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8fba64d8fdcdc8b2b6e43f409b689b9c362986cc9d9bf1f3caa51909834ad90687120a5940bee4102902ff03fd643623e15cf9016809f23ce65c252480eaa851", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/04a639df6e505f017f776651931fe63b80f6ebbf53a8c64a314e5288609fbbf2dc24a5bbf4cd281ce951fe7398bfbc6d8552b0b88f4992c3f9f456b945ea4ec0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/477f089687a4cc26611946aefa4ac6679fd0790566915a91bf7893bf671d65885d6f5a60151db59c6711dc490ae3e2752ca1026d61700a5ea940ac7170bcc23f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 20:12:58.406230) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 20:12:58.406549) ========= 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.agpFcqkTmK/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.EzPLDGUNae/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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.005001 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 20:12:58.467334) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 20:12:58.467666) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 20:12:58.468000) ========= ========= Total master_lag: 0.31 =========