builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0174 starttime: 1470230459.34 results: success (0) buildid: 20160803034441 builduid: 17a97d0ec7cc4c8783445c3d5162d0f7 revision: 8af80e4c3def3275ddef3cb965dada9aa50887d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 06:20:59.339980) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 06:20:59.340359) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 06:20:59.340636) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 06:20:59.373786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 06:20:59.374038) ========= 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.RuBusWbrLf/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.DX7XM2fNkf/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-03 06:20:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-08-03 06:20:59 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.274133 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 06:20:59.672536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 06:20:59.672864) ========= 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.RuBusWbrLf/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.DX7XM2fNkf/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.021333 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 06:20:59.720738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-03 06:20:59.721087) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8af80e4c3def3275ddef3cb965dada9aa50887d6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 8af80e4c3def3275ddef3cb965dada9aa50887d6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RuBusWbrLf/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.DX7XM2fNkf/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-03 06:20:59,798 truncating revision to first 12 chars 2016-08-03 06:20:59,799 Setting DEBUG logging. 2016-08-03 06:20:59,799 attempt 1/10 2016-08-03 06:20:59,799 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8af80e4c3def?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-03 06:21:00,773 unpacking tar archive at: fx-team-8af80e4c3def/testing/mozharness/ program finished with exit code 0 elapsedTime=1.228385 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-03 06:21:00.974237) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 06:21:00.974561) ========= script_repo_revision: 8af80e4c3def3275ddef3cb965dada9aa50887d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 06:21:00.974984) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 06:21:00.975293) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 06:21:00.993430) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-03 06:21:00.993739) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RuBusWbrLf/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.DX7XM2fNkf/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21:01 INFO - MultiFileLogger online at 20160803 06:21:01 in /builds/slave/test 06:21:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 06:21:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:21:01 INFO - {'append_to_log': False, 06:21:01 INFO - 'base_work_dir': '/builds/slave/test', 06:21:01 INFO - 'blob_upload_branch': 'Fx-Team', 06:21:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:21:01 INFO - 'branch': 'Fx-Team', 06:21:01 INFO - 'buildbot_json_path': 'buildprops.json', 06:21:01 INFO - 'config_files': ('talos/mac_config.py',), 06:21:01 INFO - 'default_actions': ('clobber', 06:21:01 INFO - 'read-buildbot-config', 06:21:01 INFO - 'download-and-extract', 06:21:01 INFO - 'populate-webroot', 06:21:01 INFO - 'create-virtualenv', 06:21:01 INFO - 'install', 06:21:01 INFO - 'run-tests'), 06:21:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:21:01 INFO - 'download_minidump_stackwalk': True, 06:21:01 INFO - 'download_symbols': 'ondemand', 06:21:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:21:01 INFO - 'tooltool.py': '/tools/tooltool.py', 06:21:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:21:01 INFO - '/tools/misc-python/virtualenv.py')}, 06:21:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:21:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:21:01 INFO - 'installer_path': 'installer.exe', 06:21:01 INFO - 'log_level': 'info', 06:21:01 INFO - 'log_name': 'talos', 06:21:01 INFO - 'log_to_console': True, 06:21:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:21:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:21:01 INFO - 'opt_config_files': (), 06:21:01 INFO - 'pip_index': False, 06:21:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:21:01 INFO - 'cmd': ('bash', 06:21:01 INFO - '-c', 06:21:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:21:01 INFO - 'enabled': True, 06:21:01 INFO - 'halt_on_failure': False, 06:21:01 INFO - 'name': 'check_screen_resolution'},), 06:21:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:21:01 INFO - 'cmd': ('bash', 06:21:01 INFO - '-c', 06:21:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:21:01 INFO - 'enabled': True, 06:21:01 INFO - 'halt_on_failure': False, 06:21:01 INFO - 'name': 'check_screen_resolution'},), 06:21:01 INFO - 'run_cmd_checks_enabled': True, 06:21:01 INFO - 'sps_profile': False, 06:21:01 INFO - 'sps_profile_interval': 0, 06:21:01 INFO - 'suite': 'dromaeojs', 06:21:01 INFO - 'system_bits': '32', 06:21:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:21:01 INFO - 'title': 't-yosemite-r7-0174', 06:21:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:21:01 INFO - 'use_talos_json': True, 06:21:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:21:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:21:01 INFO - 'work_dir': 'build'} 06:21:01 INFO - [mozharness: 2016-08-03 13:21:01.182201Z] Running clobber step. 06:21:01 INFO - Running pre-action listener: _resource_record_pre_action 06:21:01 INFO - Running main action method: clobber 06:21:01 INFO - rmtree: /builds/slave/test/build 06:21:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:21:02 INFO - Running post-action listener: _resource_record_post_action 06:21:02 INFO - [mozharness: 2016-08-03 13:21:02.104570Z] Finished clobber step (success) 06:21:02 INFO - [mozharness: 2016-08-03 13:21:02.104800Z] Running read-buildbot-config step. 06:21:02 INFO - Running pre-action listener: _resource_record_pre_action 06:21:02 INFO - Running main action method: read_buildbot_config 06:21:02 INFO - Using buildbot properties: 06:21:02 INFO - { 06:21:02 INFO - "project": "", 06:21:02 INFO - "product": "firefox", 06:21:02 INFO - "script_repo_revision": "production", 06:21:02 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 06:21:02 INFO - "repository": "", 06:21:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 06:21:02 INFO - "buildid": "20160803034441", 06:21:02 INFO - "pgo_build": "False", 06:21:02 INFO - "basedir": "/builds/slave/test", 06:21:02 INFO - "buildnumber": 119, 06:21:02 INFO - "slavename": "t-yosemite-r7-0174", 06:21:02 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 06:21:02 INFO - "platform": "yosemite_r7", 06:21:02 INFO - "scheduler": "tests-fx-team-macosx64-talos", 06:21:02 INFO - "branch": "fx-team", 06:21:02 INFO - "repo_path": "integration/fx-team", 06:21:02 INFO - "revision": "8af80e4c3def3275ddef3cb965dada9aa50887d6", 06:21:02 INFO - "stage_platform": "macosx64", 06:21:02 INFO - "builduid": "17a97d0ec7cc4c8783445c3d5162d0f7", 06:21:02 INFO - "slavebuilddir": "test" 06:21:02 INFO - } 06:21:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 06:21:02 INFO - Running post-action listener: _resource_record_post_action 06:21:02 INFO - [mozharness: 2016-08-03 13:21:02.110269Z] Finished read-buildbot-config step (success) 06:21:02 INFO - [mozharness: 2016-08-03 13:21:02.110500Z] Running download-and-extract step. 06:21:02 INFO - Running pre-action listener: _resource_record_pre_action 06:21:02 INFO - Running main action method: download_and_extract 06:21:02 INFO - mkdir: /builds/slave/test/build/tests 06:21:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:02 INFO - https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:21:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 06:21:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 06:21:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/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 06:21:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/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 06:21:04 INFO - Downloaded 1199 bytes. 06:21:04 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 06:21:04 INFO - Using the following test package requirements: 06:21:04 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 06:21:04 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:21:04 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 06:21:04 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:21:04 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 06:21:04 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:21:04 INFO - u'jsshell-mac.zip'], 06:21:04 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:21:04 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 06:21:04 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 06:21:04 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:21:04 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 06:21:04 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:21:04 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 06:21:04 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:21:04 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 06:21:04 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:21:04 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 06:21:04 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:04 INFO - https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:21:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 06:21:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 06:21:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/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 06:21:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/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 06:21:06 INFO - Downloaded 16307897 bytes. 06:21:06 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'] 06:21:06 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 06:21:09 INFO - Return code: 0 06:21:09 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 06:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:09 INFO - https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:21:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 06:21:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 06:21:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/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 06:21:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/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 06:21:11 INFO - Downloaded 16307897 bytes. 06:21:11 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'] 06:21:11 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 06:21:15 INFO - Return code: 0 06:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:15 INFO - https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:21:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 06:21:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 06:21:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/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 06:21:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/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 06:21:16 INFO - Downloaded 14413723 bytes. 06:21:16 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'] 06:21:16 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 06:21:16 INFO - Return code: 0 06:21:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:16 INFO - https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:21:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 06:21:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 06:21:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 06:21:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Huxi4jFhRmemP8FFl1-qiQ/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 06:21:19 INFO - Downloaded 111241630 bytes. 06:21:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 06:21:19 INFO - mkdir: /builds/slave/test/properties 06:21:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:21:19 INFO - Writing to file /builds/slave/test/properties/build_url 06:21:19 INFO - Contents: 06:21:19 INFO - build_url:https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 06:21:20 INFO - Running post-action listener: _resource_record_post_action 06:21:20 INFO - Running post-action listener: set_extra_try_arguments 06:21:20 INFO - [mozharness: 2016-08-03 13:21:20.747214Z] Finished download-and-extract step (success) 06:21:20 INFO - [mozharness: 2016-08-03 13:21:20.747372Z] Running populate-webroot step. 06:21:20 INFO - Running pre-action listener: _resource_record_pre_action 06:21:20 INFO - Running main action method: populate_webroot 06:21:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:21:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:21:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:21:20 INFO - '--tppagecycles', 06:21:20 INFO - '7'], 06:21:20 INFO - 'tests': ['tsvgm']}}, 06:21:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:21:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:21:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:21:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:21:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:21:20 INFO - 'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:21:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:21:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:21:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:21:20 INFO - 'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['damp', 'tps']}, 06:21:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:21:20 INFO - 'tests': ['damp', 'tps']}, 06:21:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['dromaeo_dom']}, 06:21:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:21:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['basic_compositor_video']}, 06:21:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:21:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['a11yr', 06:21:20 INFO - 'ts_paint', 06:21:20 INFO - 'tpaint', 06:21:20 INFO - 'sessionrestore', 06:21:20 INFO - 'sessionrestore_no_auto_restore', 06:21:20 INFO - 'tabpaint']}, 06:21:20 INFO - 'other-e10s': {'tests': ['a11yr', 06:21:20 INFO - 'ts_paint', 06:21:20 INFO - 'tpaint', 06:21:20 INFO - 'sessionrestore', 06:21:20 INFO - 'sessionrestore_no_auto_restore', 06:21:20 INFO - 'tabpaint']}, 06:21:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:21:20 INFO - 'ts_paint', 06:21:20 INFO - 'tpaint', 06:21:20 INFO - 'sessionrestore', 06:21:20 INFO - 'sessionrestore_no_auto_restore', 06:21:20 INFO - 'tabpaint']}, 06:21:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:21:20 INFO - 'ts_paint', 06:21:20 INFO - 'tpaint', 06:21:20 INFO - 'sessionrestore', 06:21:20 INFO - 'sessionrestore_no_auto_restore', 06:21:20 INFO - 'tabpaint']}, 06:21:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['a11yr', 06:21:20 INFO - 'ts_paint', 06:21:20 INFO - 'tpaint', 06:21:20 INFO - 'sessionrestore', 06:21:20 INFO - 'sessionrestore_no_auto_restore', 06:21:20 INFO - 'tabpaint']}, 06:21:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['a11yr', 06:21:20 INFO - 'ts_paint', 06:21:20 INFO - 'tpaint', 06:21:20 INFO - 'sessionrestore', 06:21:20 INFO - 'sessionrestore_no_auto_restore', 06:21:20 INFO - 'tabpaint']}, 06:21:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['tsvgx', 06:21:20 INFO - 'tsvgr_opacity', 06:21:20 INFO - 'tart', 06:21:20 INFO - 'tscrollx', 06:21:20 INFO - 'cart']}, 06:21:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:21:20 INFO - 'tsvgr_opacity', 06:21:20 INFO - 'tart', 06:21:20 INFO - 'tscrollx', 06:21:20 INFO - 'cart']}, 06:21:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:21:20 INFO - 'talos_options': ['--disable-e10s'], 06:21:20 INFO - 'tests': ['tp5o']}, 06:21:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:21:20 INFO - 'tests': ['tp5o']}, 06:21:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:21:20 INFO - 'talos_options': ['--disable-e10s', 06:21:20 INFO - '--xperf_path', 06:21:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:21:20 INFO - 'tests': ['tp5n']}, 06:21:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:21:20 INFO - 'talos_options': ['--xperf_path', 06:21:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:21:20 INFO - 'tests': ['tp5n']}}, 06:21:20 INFO - 'talos.zip': {'path': '', 06:21:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:21:20 INFO - Running post-action listener: _resource_record_post_action 06:21:20 INFO - [mozharness: 2016-08-03 13:21:20.766386Z] Finished populate-webroot step (success) 06:21:20 INFO - [mozharness: 2016-08-03 13:21:20.766520Z] Running create-virtualenv step. 06:21:20 INFO - Running pre-action listener: _resource_record_pre_action 06:21:20 INFO - Running main action method: create_virtualenv 06:21:20 INFO - Creating virtualenv /builds/slave/test/build/venv 06:21:20 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 06:21:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:21:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:21:20 INFO - Using real prefix '/tools/python27' 06:21:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:21:21 INFO - Installing distribute.............................................................................................................................................................................................done. 06:21:24 INFO - Installing pip.................done. 06:21:24 INFO - Return code: 0 06:21:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108465a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe693c7fc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, '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.RuBusWbrLf/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.DX7XM2fNkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21: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', 'pip>=1.5'] in /builds/slave/test/build 06:21: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 pip>=1.5 06:21:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:21:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:24 INFO - 'HOME': '/Users/cltbld', 06:21:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:24 INFO - 'LOGNAME': 'cltbld', 06:21:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:24 INFO - 'MOZ_NO_REMOTE': '1', 06:21:24 INFO - 'NO_EM_RESTART': '1', 06:21:24 INFO - 'PAGER': '/bin/cat', 06:21:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:24 INFO - 'PWD': '/builds/slave/test', 06:21:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:24 INFO - 'SHELL': '/bin/bash', 06:21:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:21:24 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:21:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:24 INFO - 'USER': 'cltbld', 06:21:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:24 INFO - 'XPC_FLAGS': '0x0', 06:21:24 INFO - 'XPC_SERVICE_NAME': '0', 06:21:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:24 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 06:21:24 INFO - Cleaning up... 06:21:24 INFO - Return code: 0 06:21:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21: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', '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 0x108465a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe693c7fc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, '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.RuBusWbrLf/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.DX7XM2fNkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21: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', 'psutil>=3.1.1'] in /builds/slave/test/build 06:21: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 psutil>=3.1.1 06:21:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:21:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:24 INFO - 'HOME': '/Users/cltbld', 06:21:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:24 INFO - 'LOGNAME': 'cltbld', 06:21:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:24 INFO - 'MOZ_NO_REMOTE': '1', 06:21:24 INFO - 'NO_EM_RESTART': '1', 06:21:24 INFO - 'PAGER': '/bin/cat', 06:21:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:24 INFO - 'PWD': '/builds/slave/test', 06:21:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:24 INFO - 'SHELL': '/bin/bash', 06:21:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:21:24 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:21:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:24 INFO - 'USER': 'cltbld', 06:21:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:24 INFO - 'XPC_FLAGS': '0x0', 06:21:24 INFO - 'XPC_SERVICE_NAME': '0', 06:21:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:25 INFO - Downloading/unpacking psutil>=3.1.1 06:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:21:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:21:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:21:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:21:27 INFO - Installing collected packages: psutil 06:21:27 INFO - Running setup.py install for psutil 06:21:28 INFO - building 'psutil._psutil_osx' extension 06:21:28 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 06:21:28 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 06:21:28 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 06:21:28 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 06:21:28 INFO - building 'psutil._psutil_posix' extension 06:21:28 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 06:21:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:21:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:21:28 INFO - ^ 06:21:28 INFO - 1 warning generated. 06:21:28 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 06:21:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:21:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:21:28 INFO - Successfully installed psutil 06:21:28 INFO - Cleaning up... 06:21:28 INFO - Return code: 0 06:21:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108465a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe693c7fc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, '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.RuBusWbrLf/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.DX7XM2fNkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:28 INFO - 'HOME': '/Users/cltbld', 06:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:28 INFO - 'LOGNAME': 'cltbld', 06:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:28 INFO - 'MOZ_NO_REMOTE': '1', 06:21:28 INFO - 'NO_EM_RESTART': '1', 06:21:28 INFO - 'PAGER': '/bin/cat', 06:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:28 INFO - 'PWD': '/builds/slave/test', 06:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:28 INFO - 'SHELL': '/bin/bash', 06:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:21:28 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:28 INFO - 'USER': 'cltbld', 06:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:28 INFO - 'XPC_FLAGS': '0x0', 06:21:28 INFO - 'XPC_SERVICE_NAME': '0', 06:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:21:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:21:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:21:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:21:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:21:31 INFO - Installing collected packages: mozsystemmonitor 06:21:31 INFO - Running setup.py install for mozsystemmonitor 06:21:31 INFO - Successfully installed mozsystemmonitor 06:21:31 INFO - Cleaning up... 06:21:31 INFO - Return code: 0 06:21:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:31 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 0x108465a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe693c7fc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, '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.RuBusWbrLf/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.DX7XM2fNkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21:31 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 06:21:31 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 06:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:31 INFO - 'HOME': '/Users/cltbld', 06:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:31 INFO - 'LOGNAME': 'cltbld', 06:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:31 INFO - 'MOZ_NO_REMOTE': '1', 06:21:31 INFO - 'NO_EM_RESTART': '1', 06:21:31 INFO - 'PAGER': '/bin/cat', 06:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:31 INFO - 'PWD': '/builds/slave/test', 06:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:31 INFO - 'SHELL': '/bin/bash', 06:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:21:31 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:31 INFO - 'USER': 'cltbld', 06:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:31 INFO - 'XPC_FLAGS': '0x0', 06:21:31 INFO - 'XPC_SERVICE_NAME': '0', 06:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:32 INFO - Downloading/unpacking jsonschema==2.5.1 06:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:21:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:21:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:21:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:21:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:21:34 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 06:21:34 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 06:21:34 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 06:21:34 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 06:21:34 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 06:21:34 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 06:21:34 INFO - Downloading functools32-3.2.3-2.tar.gz 06:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:21:34 INFO - warning: no files found matching '*.txt' 06:21:34 INFO - no previously-included directories found matching 'build' 06:21:34 INFO - no previously-included directories found matching 'dist' 06:21:34 INFO - no previously-included directories found matching '.git*' 06:21:34 INFO - Installing collected packages: jsonschema, functools32 06:21:35 INFO - Running setup.py install for functools32 06:21:35 INFO - warning: no files found matching '*.txt' 06:21:35 INFO - no previously-included directories found matching 'build' 06:21:35 INFO - no previously-included directories found matching 'dist' 06:21:35 INFO - no previously-included directories found matching '.git*' 06:21:35 INFO - Successfully installed jsonschema functools32 06:21:35 INFO - Cleaning up... 06:21:35 INFO - Return code: 0 06:21:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108465a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe693c7fc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, '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.RuBusWbrLf/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.DX7XM2fNkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:35 INFO - 'HOME': '/Users/cltbld', 06:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:35 INFO - 'LOGNAME': 'cltbld', 06:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:35 INFO - 'MOZ_NO_REMOTE': '1', 06:21:35 INFO - 'NO_EM_RESTART': '1', 06:21:35 INFO - 'PAGER': '/bin/cat', 06:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:35 INFO - 'PWD': '/builds/slave/test', 06:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:35 INFO - 'SHELL': '/bin/bash', 06:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:21:35 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:35 INFO - 'USER': 'cltbld', 06:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:35 INFO - 'XPC_FLAGS': '0x0', 06:21:35 INFO - 'XPC_SERVICE_NAME': '0', 06:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:21:35 INFO - Cleaning up... 06:21:35 INFO - Return code: 0 06:21:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108465a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe693c7fc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, '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.RuBusWbrLf/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.DX7XM2fNkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:35 INFO - 'HOME': '/Users/cltbld', 06:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:35 INFO - 'LOGNAME': 'cltbld', 06:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:35 INFO - 'MOZ_NO_REMOTE': '1', 06:21:35 INFO - 'NO_EM_RESTART': '1', 06:21:35 INFO - 'PAGER': '/bin/cat', 06:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:35 INFO - 'PWD': '/builds/slave/test', 06:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:35 INFO - 'SHELL': '/bin/bash', 06:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:21:35 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:35 INFO - 'USER': 'cltbld', 06:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:35 INFO - 'XPC_FLAGS': '0x0', 06:21:35 INFO - 'XPC_SERVICE_NAME': '0', 06:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:35 INFO - Downloading/unpacking blobuploader==1.2.4 06:21: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 06:21: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 06:21: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 06:21: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 06:21: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 06:21: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 06:21:38 INFO - Downloading blobuploader-1.2.4.tar.gz 06:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:21:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:21:38 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 06:21:38 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 06:21:38 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 06:21:38 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 06:21:38 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 06:21:38 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 06:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:21:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:21:38 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 06:21:38 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 06:21:38 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 06:21:38 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 06:21:38 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 06:21:38 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 06:21:39 INFO - Downloading docopt-0.6.1.tar.gz 06:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:21:39 INFO - Installing collected packages: blobuploader, requests, docopt 06:21:39 INFO - Running setup.py install for blobuploader 06:21:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:21:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:21:39 INFO - Running setup.py install for requests 06:21:40 INFO - Running setup.py install for docopt 06:21:40 INFO - Successfully installed blobuploader requests docopt 06:21:40 INFO - Cleaning up... 06:21:40 INFO - Return code: 0 06:21:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:40 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 0x108465a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe693c7fc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, '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.RuBusWbrLf/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.DX7XM2fNkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21:40 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 06:21:40 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 06:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:40 INFO - 'HOME': '/Users/cltbld', 06:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:40 INFO - 'LOGNAME': 'cltbld', 06:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:40 INFO - 'MOZ_NO_REMOTE': '1', 06:21:40 INFO - 'NO_EM_RESTART': '1', 06:21:40 INFO - 'PAGER': '/bin/cat', 06:21:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:40 INFO - 'PWD': '/builds/slave/test', 06:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:40 INFO - 'SHELL': '/bin/bash', 06:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:21:40 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:40 INFO - 'USER': 'cltbld', 06:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:40 INFO - 'XPC_FLAGS': '0x0', 06:21:40 INFO - 'XPC_SERVICE_NAME': '0', 06:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:21:40 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-viVJGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:21:41 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-X10J3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:21:41 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Qit4e8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:21:41 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-l4911j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:21:41 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-au7pgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:21:41 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-LBsdZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:21:41 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-WVjzhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:21:41 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-qJIKkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:21:41 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-fHneVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:21:41 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-RREwMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:21:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:21:41 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-DmvheI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:21:42 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-xfi0F6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:21:42 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-4G0gmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:21:42 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-91UBkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:21:42 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-IEqJxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:21:42 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-uL9uV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:21:42 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-rKSfXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:21:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:21:42 INFO - Running setup.py install for manifestparser 06:21:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:21:42 INFO - Running setup.py install for mozcrash 06:21:42 INFO - Running setup.py install for mozdebug 06:21:43 INFO - Running setup.py install for mozdevice 06:21:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:21:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:21:43 INFO - Running setup.py install for mozfile 06:21:43 INFO - Running setup.py install for mozhttpd 06:21:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:21:43 INFO - Running setup.py install for mozinfo 06:21:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:21:43 INFO - Running setup.py install for mozInstall 06:21:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:21:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:21:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:21:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:21:43 INFO - Running setup.py install for mozleak 06:21:43 INFO - Running setup.py install for mozlog 06:21:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:21:44 INFO - Running setup.py install for moznetwork 06:21:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:21:44 INFO - Running setup.py install for mozprocess 06:21:44 INFO - Running setup.py install for mozprofile 06:21:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:21:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:21:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:21:44 INFO - Running setup.py install for mozrunner 06:21:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:21:44 INFO - Running setup.py install for mozscreenshot 06:21:44 INFO - Running setup.py install for moztest 06:21:45 INFO - Running setup.py install for mozversion 06:21:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:21:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:21:45 INFO - Cleaning up... 06:21:45 INFO - Return code: 0 06:21:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:45 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 0x108465a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe693c7fc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, '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.RuBusWbrLf/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.DX7XM2fNkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21:45 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 06:21:45 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 06:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:45 INFO - 'HOME': '/Users/cltbld', 06:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:45 INFO - 'LOGNAME': 'cltbld', 06:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:45 INFO - 'MOZ_NO_REMOTE': '1', 06:21:45 INFO - 'NO_EM_RESTART': '1', 06:21:45 INFO - 'PAGER': '/bin/cat', 06:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:45 INFO - 'PWD': '/builds/slave/test', 06:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:45 INFO - 'SHELL': '/bin/bash', 06:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:21:45 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:45 INFO - 'USER': 'cltbld', 06:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:45 INFO - 'XPC_FLAGS': '0x0', 06:21:45 INFO - 'XPC_SERVICE_NAME': '0', 06:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:21:45 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Aj8Qf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:21:45 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)) 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:21:45 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-PDjmbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:21:45 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)) 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:21:45 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-mlkPvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:21:45 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)) 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:21:45 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-a0PZu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:21:45 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)) 06:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:21:45 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-OQVEuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:21:46 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)) 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:21:46 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ICDtJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:21:46 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)) 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:21:46 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-2Z6hGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:21:46 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)) 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:21:46 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-M1Il7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:21:46 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)) 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:21:46 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-cXGAEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:21:46 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)) 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:21:46 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-zDu6A7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:21:46 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)) 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:21:46 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-AXlHwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:21:46 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)) 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:21:46 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-T6AIxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:21:46 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)) 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:21:46 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-54UQe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:21:46 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)) 06:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:21:46 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-KcZxCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:21:47 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)) 06:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:21:47 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-d3zeZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:21:47 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)) 06:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:21:47 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-AUtn4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:21:47 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)) 06:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:21:47 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-xAkFlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:21:47 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)) 06:21:47 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)) 06:21:47 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)) 06:21:47 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)) 06:21:47 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)) 06:21:47 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)) 06:21:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:21:47 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 06:21:47 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 06:21:47 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 06:21:47 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 06:21:47 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 06:21:47 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 06:21:49 INFO - Downloading blessings-1.6.tar.gz 06:21:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:21:49 INFO - Installing collected packages: blessings 06:21:49 INFO - Running setup.py install for blessings 06:21:50 INFO - Successfully installed blessings 06:21:50 INFO - Cleaning up... 06:21:50 INFO - Return code: 0 06:21:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:21:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:21:50 INFO - Reading from file tmpfile_stdout 06:21:50 INFO - Current package versions: 06:21:50 INFO - blessings == 1.6 06:21:50 INFO - blobuploader == 1.2.4 06:21:50 INFO - docopt == 0.6.1 06:21:50 INFO - functools32 == 3.2.3-2 06:21:50 INFO - jsonschema == 2.5.1 06:21:50 INFO - manifestparser == 1.1 06:21:50 INFO - mozInstall == 1.12 06:21:50 INFO - mozcrash == 0.17 06:21:50 INFO - mozdebug == 0.1 06:21:50 INFO - mozdevice == 0.48 06:21:50 INFO - mozfile == 1.2 06:21:50 INFO - mozhttpd == 0.7 06:21:50 INFO - mozinfo == 0.9 06:21:50 INFO - mozleak == 0.1 06:21:50 INFO - mozlog == 3.3 06:21:50 INFO - moznetwork == 0.27 06:21:50 INFO - mozprocess == 0.23 06:21:50 INFO - mozprofile == 0.28 06:21:50 INFO - mozrunner == 6.12 06:21:50 INFO - mozscreenshot == 0.1 06:21:50 INFO - mozsystemmonitor == 0.3 06:21:50 INFO - moztest == 0.7 06:21:50 INFO - mozversion == 1.4 06:21:50 INFO - psutil == 3.1.1 06:21:50 INFO - requests == 1.2.3 06:21:50 INFO - wsgiref == 0.1.2 06:21:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:50 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 0x108465a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe693c7fc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, '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.RuBusWbrLf/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.DX7XM2fNkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21:50 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 06:21:50 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 06:21:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:21:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:50 INFO - 'HOME': '/Users/cltbld', 06:21:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:50 INFO - 'LOGNAME': 'cltbld', 06:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:50 INFO - 'MOZ_NO_REMOTE': '1', 06:21:50 INFO - 'NO_EM_RESTART': '1', 06:21:50 INFO - 'PAGER': '/bin/cat', 06:21:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:50 INFO - 'PWD': '/builds/slave/test', 06:21:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:50 INFO - 'SHELL': '/bin/bash', 06:21:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:21:50 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:21:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:50 INFO - 'USER': 'cltbld', 06:21:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:50 INFO - 'XPC_FLAGS': '0x0', 06:21:50 INFO - 'XPC_SERVICE_NAME': '0', 06:21:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:50 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)) 06:21:50 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)) 06:21:50 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)) 06:21:50 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)) 06:21:50 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)) 06:21:50 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)) 06:21:50 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)) 06:21:50 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)) 06:21:50 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)) 06:21:50 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)) 06:21:50 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)) 06:21:50 INFO - Cleaning up... 06:21:50 INFO - Return code: 0 06:21:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:21:50 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 0x108465a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084fa618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe693c7fc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, '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.RuBusWbrLf/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.DX7XM2fNkf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 06:21:50 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 06:21:50 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 06:21:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:21:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:21:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:21:50 INFO - 'HOME': '/Users/cltbld', 06:21:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:21:50 INFO - 'LOGNAME': 'cltbld', 06:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:21:50 INFO - 'MOZ_NO_REMOTE': '1', 06:21:50 INFO - 'NO_EM_RESTART': '1', 06:21:50 INFO - 'PAGER': '/bin/cat', 06:21:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:21:50 INFO - 'PWD': '/builds/slave/test', 06:21:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:21:50 INFO - 'SHELL': '/bin/bash', 06:21:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:21:50 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:21:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:21:50 INFO - 'USER': 'cltbld', 06:21:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:21:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:21:50 INFO - 'XPC_FLAGS': '0x0', 06:21:50 INFO - 'XPC_SERVICE_NAME': '0', 06:21:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:21:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:21:50 INFO - Cleaning up... 06:21:50 INFO - Return code: 0 06:21:50 INFO - Running post-action listener: _resource_record_post_action 06:21:50 INFO - Running post-action listener: _start_resource_monitoring 06:21:50 INFO - Starting resource monitoring. 06:21:50 INFO - [mozharness: 2016-08-03 13:21:50.948874Z] Finished create-virtualenv step (success) 06:21:50 INFO - [mozharness: 2016-08-03 13:21:50.949834Z] Running install step. 06:21:50 INFO - Running pre-action listener: _resource_record_pre_action 06:21:50 INFO - Running main action method: install 06:21:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:21:51 INFO - Reading from file tmpfile_stdout 06:21:51 INFO - Detecting whether we're running mozinstall >=1.0... 06:21:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:21:51 INFO - Reading from file tmpfile_stdout 06:21:51 INFO - Output received: 06:21:51 INFO - Usage: mozinstall [options] installer 06:21:51 INFO - Options: 06:21:51 INFO - -h, --help show this help message and exit 06:21:51 INFO - -d DEST, --destination=DEST 06:21:51 INFO - Directory to install application into. [default: 06:21:51 INFO - "/builds/slave/test"] 06:21:51 INFO - --app=APP Application being installed. [default: firefox] 06:21:51 INFO - mkdir: /builds/slave/test/build/application 06:21:51 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'] 06:21:51 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 06:22:25 INFO - Reading from file tmpfile_stdout 06:22:25 INFO - Output received: 06:22:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:22:25 INFO - Running post-action listener: _resource_record_post_action 06:22:25 INFO - [mozharness: 2016-08-03 13:22:25.348647Z] Finished install step (success) 06:22:25 INFO - [mozharness: 2016-08-03 13:22:25.348780Z] Running run-tests step. 06:22:25 INFO - Running pre-action listener: _resource_record_pre_action 06:22:25 INFO - Running main action method: run_tests 06:22:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:22:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:22:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:22:25 INFO - 2016-08-03 06:22:25.363 screenresolution[2211:19984] starting screenresolution argv=screenresolution get 06:22:25 INFO - 2016-08-03 06:22:25.365 screenresolution[2211:19984] Display 0: 1600x1200x32@60 06:22:25 INFO - 2016-08-03 06:22:25.371 screenresolution[2212:19987] starting screenresolution argv=screenresolution list 06:22:25 INFO - Available Modes on Display 0 06:22:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:22:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:22:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:22:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:22:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:22:25 INFO - Intel Iris: 06:22:25 INFO - Chipset Model: Intel Iris 06:22:25 INFO - Type: GPU 06:22:25 INFO - Bus: Built-In 06:22:25 INFO - VRAM (Dynamic, Max): 1536 MB 06:22:25 INFO - Vendor: Intel (0x8086) 06:22:25 INFO - Device ID: 0x0a2e 06:22:25 INFO - Revision ID: 0x0009 06:22:25 INFO - Displays: 06:22:25 INFO - Display: 06:22:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:22:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:22:25 INFO - Display Serial Number: 1600x1200 60 06:22:25 INFO - Main Display: Yes 06:22:25 INFO - Mirror: Off 06:22:25 INFO - Online: Yes 06:22:25 INFO - Rotation: Supported 06:22:25 INFO - Return code: 0 06:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:22:25 INFO - Python 2.7.3 06:22:25 INFO - Return code: 0 06:22:25 INFO - grabbing minidump binary from tooltool 06:22:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:22:25 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 0x1083c9d30>, '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 0x10812edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1083c1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:22:25 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 06:22:25 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 06:22:25 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 06:22:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:22:25 INFO - Return code: 0 06:22:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:22:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:22:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:22:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:22:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--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 06:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0174 --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 06:22:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RuBusWbrLf/Render', 06:22:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:22:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:22:25 INFO - 'HOME': '/Users/cltbld', 06:22:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:22:25 INFO - 'LOGNAME': 'cltbld', 06:22:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:22:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:22:25 INFO - 'MOZ_NO_REMOTE': '1', 06:22:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:22:25 INFO - 'NO_EM_RESTART': '1', 06:22:25 INFO - 'PAGER': '/bin/cat', 06:22:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:22:25 INFO - 'PWD': '/builds/slave/test', 06:22:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:22:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:22:25 INFO - 'SHELL': '/bin/bash', 06:22:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DX7XM2fNkf/Listeners', 06:22:25 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 06:22:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:22:25 INFO - 'USER': 'cltbld', 06:22:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:22:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:22:25 INFO - 'XPC_FLAGS': '0x0', 06:22:25 INFO - 'XPC_SERVICE_NAME': '0', 06:22:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:22:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--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 06:22:25 INFO - mozversion application_buildid: 20160803034441 06:22:25 INFO - mozversion application_changeset: 8af80e4c3def3275ddef3cb965dada9aa50887d6 06:22:25 INFO - mozversion application_display_name: Nightly 06:22:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:22:25 INFO - mozversion application_name: Firefox 06:22:25 INFO - mozversion application_remotingname: firefox 06:22:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:22:25 INFO - mozversion application_vendor: Mozilla 06:22:25 INFO - mozversion application_version: 51.0a1 06:22:25 INFO - mozversion platform_buildid: 20160803034441 06:22:25 INFO - mozversion platform_changeset: 8af80e4c3def3275ddef3cb965dada9aa50887d6 06:22:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:22:25 INFO - mozversion platform_version: 51.0a1 06:22:25 INFO - using testdate: 1470230545 06:22:25 INFO - actual date: 1470230545 06:22:25 INFO - starting webserver on 'localhost:50848' 06:22:25 INFO - SUITE-START | Running 2 tests 06:22:25 INFO - TEST-START | dromaeo_css 06:22:25 INFO - operating with platform_type : mac_ 06:22:25 INFO - Initialising browser for dromaeo_css test... 06:22:25 INFO - TEST-INFO | started process 2222 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpe7LGke/profile http://localhost:50848/getInfo.html) 06:22:28 INFO - PROCESS | 2222 | __metrics Screen width/height:1600/1200 06:22:28 INFO - PROCESS | 2222 | colorDepth:24 06:22:28 INFO - PROCESS | 2222 | Browser inner width/height: 1024/689 06:22:28 INFO - PROCESS | 2222 | __metrics 06:22:28 INFO - PROCESS | 2222 | 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" 06:22:28 INFO - PROCESS | 2222 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:22:28 INFO - PROCESS | 2222 | ************************* 06:22:28 INFO - PROCESS | 2222 | A coding exception was thrown in a Promise resolution callback. 06:22:28 INFO - PROCESS | 2222 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 06:22:28 INFO - PROCESS | 2222 | 06:22:28 ERROR - PROCESS | 2222 | Full message: TypeError: win is null 06:22:28 INFO - PROCESS | 2222 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:844:17 06:22:28 INFO - PROCESS | 2222 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 06:22:28 INFO - PROCESS | 2222 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 06:22:28 INFO - PROCESS | 2222 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 06:22:28 INFO - PROCESS | 2222 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 06:22:28 INFO - PROCESS | 2222 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 06:22:28 INFO - PROCESS | 2222 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 06:22:28 INFO - PROCESS | 2222 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 06:22:28 INFO - PROCESS | 2222 | 06:22:28 INFO - PROCESS | 2222 | ************************* 06:22:29 INFO - TEST-INFO | 2222: exit 0 06:22:29 INFO - Browser initialized. 06:22:29 INFO - Running cycle 1/1 for dromaeo_css test... 06:22:29 INFO - TEST-INFO | started process 2224 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpe7LGke/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:22:41 INFO - PROCESS | 2224 | RSS: Main: 256491520 06:22:41 INFO - PROCESS | 2224 | 06:23:32 INFO - PROCESS | 2224 | 1470230612590 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] 06:25:44 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:50848/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50848/tests/dromaeo/cssquery-ext.html) 06:25:46 INFO - PROCESS | 2224 | RSS: Main: 386654208 06:25:46 INFO - PROCESS | 2224 | 06:28:49 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:50848/tests/dromaeo/cssquery-ext.html (next: http://localhost:50848/tests/dromaeo/cssquery-jquery.html) 06:28:49 INFO - PROCESS | 2224 | RSS: Main: 402468864 06:28:49 INFO - PROCESS | 2224 | 06:31:52 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:50848/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50848/tests/dromaeo/cssquery-mootools.html) 06:31:52 INFO - PROCESS | 2224 | RSS: Main: 361447424 06:31:52 INFO - PROCESS | 2224 | 06:34:55 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:50848/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50848/tests/dromaeo/cssquery-prototype.html) 06:34:55 INFO - PROCESS | 2224 | RSS: Main: 364584960 06:34:55 INFO - PROCESS | 2224 | 06:37:59 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:50848/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50848/tests/dromaeo/cssquery-yui.html) 06:37:59 INFO - PROCESS | 2224 | RSS: Main: 351731712 06:37:59 INFO - PROCESS | 2224 | 06:41:03 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:50848/tests/dromaeo/cssquery-yui.html (next: http://localhost:50848/tests/dromaeo/cssquery-dojo.html) 06:41:03 INFO - PROCESS | 2224 | RSS: Main: 569966592 06:41:03 INFO - PROCESS | 2224 | 06:41:03 INFO - PROCESS | 2224 | __start_tp_report 06:41:03 INFO - PROCESS | 2224 | _x_x_mozilla_page_load 06:41:03 INFO - PROCESS | 2224 | _x_x_mozilla_page_load_details 06:41:03 INFO - PROCESS | 2224 | |i|pagename|runs| 06:41:03 INFO - PROCESS | 2224 | |0;dojo.html;3093.273080826006;3288.161518812704;3299.2411745298573;3325.9501107483375;3360.2943381889804;5540.141278101891;5588.5808564357685;5627.549796016319;5660.377358490563;5672.347680016799;5613.80351687691;5643.097104463287;5647.407022262665;5656.066748986423;5662.6036177467595;4229.34445160999;4268.914621707564;5150.124478838607;5210.713410762407;5216.7391630418515;5117.797317630354;5155.149400348938;5172.40517340505;5231.529162375386;5285.127953887604;5168;5179.637425380225;5193.1171700810955;5222.791088356461;5272.8154514592;5244.265802787613;5252.290940722992;5263.8420847374255;5264.973675131638;5305.602079844027;5076.13705670037;5083.771230294646;5144.254083157921;5164.870878228036;5184;5539.279893613845;5546.306711661043;5568.443155684439;5615.989121958046;5641.956238095965;6178.660173690445;6181.258249010102;6195.101710252034;6201.751929922798;6204.87590248197;6075.362086980892;6122.367660829195;6136.23297087864;6228.5017198624;6261;5695.487406133468;5733.111367738007;5760.8271751847515;5763.991301522216;5778.422157784272;6228.252609686776;6293.1504246927;6300.653464059521;6311.715972781236;6319.652419116993;5256.26412302278;5555.722183897727;6089.330173680892;6094.451499365078;6113.113598528189;5754.5971781975695;6121.693915304217;6190.226221722285;6234.532410069172;6252.155958945576;6141.815745527641;6162.845928851814;6168.414000669929;6170.136180934673;6178.814635560941;5913.172155898178;6151.630902145886;6193.473554747897;6197.752089916443;6208;4826.3243145958895;4859.562639362474;4881.51184881509;4901.583365413909;4913.484084171182;4364.10535840152;4367.803448844809;4371.30059190528;4380.233459144637;4409.294512877924;5012.899742005139;5486.780528778804;5618.634788738719;5636.661800291914;5668.206451096849;6088.086786981899;6106.114613381035;6113.689152827286;6122.581127188521;6164.167837341991;5562.777488900399;5565.666060036411;5577.972110139423;5582.274304340491;5620.156976453564;5285.735713214324;5308.991291654574;5309.840704778863;5315.336420322317;5369.087255166631;6158.824993776361;6218.160548325918;6249.062640603764;6250.718717657625;6268.968655156695;6262.46769024638;6267.0286105653695;6271.560990730787;6273.435390814849;6278.717457714322;6205.937940620536;6266.060090986389;6282.905756413566;6283.120363149254;6298.653574053471;5948.613340132878;6072.666003369857;6125.418085281891;6142.600730952474;6165.6608886509875;6217.626942383471;6224.37756224374;6225.252969643671;6230.190075290183;6248;5882.23530940975;6042.305134909541;6080.935836228731;6114.449699527064;6124.693765311806;5844.883102338014;5946.940530594638;5982.880342393215;6004.249468816398;6087.4521293083835;5862.179294898632;5881.911771323519;5882.470577648032;5923.318818335774;5929.85175370602;4991.475895031114;6060.424259695322;6060.727267272895;6062.939370606414;6073.423024812636;5687.800926967537;5731.971340143273;5780.826575202751;5837.970810145922;5840.503557197686 06:41:03 INFO - PROCESS | 2224 | |1;ext.html;435.30786050180217;455.82222933056096;471.41544484838766;518.6732358614073;551.9089350257208;29754.256143596394;31630.367392652162;39841.99999999996;41038.58961410385;41171.38242926358;42121.789391053004;42483.575164248345;42627.36058959118;42766.358504622374;42856.785716071376;9375.437473751563;9505.334626576143;9538.475383853885;9543.665971690993;9571.425714457155;8113.499002684493;8469.66121355145;8503;8709.825803483927;8711.215990560848;11529.250598711098;11719;11724.296542207494;11950.22323548971;11966.940165299162;11124.443777811077;11566;11657.70855728605;11663.825042624367;11666.766664666658;11582.826257606142;11655.358955257458;11663.941680291544;11673.474693638722;11678.824817627743;39406.0148496287;39882.800585997174;39908.20183596341;39926.201475970316;39969.80015099906;3298.670132986706;3520.366334059868;3592.191756854713;3875.8837234883;4130.56629053951;30966.0710178695;31946.840265798754;32164;32294.515582266285;32307;3186.2512309607223;3277.4919887417486;3314.171457135716;3336.249343897628;3355.1444381682513;312950;350364;365627;366312.1684391561;367955.1602241998;58271;58448.12327815086;58664.413355865894;58715.70642146847;58784;61648.69175654093;61747.6912615443;61767;61795.691021544604;61808.07287890685;15720.764188537181;15883.364665413254;15900.681986360209;15902.204889755465;15911.204439777965;12430.440630171664;12497.687557811127;12506.437210325557;12508.74982500345;12531;11295.943520282346;14086.788698169536;14096.50662226817;14968.101913885203;15640;5149.253358263031;5230.215467679878;5253.816116435906;5299.37680847975;6234.220722409699;899.9595018224196;972.4894430423942;1019.0675531888239;1026.3687831983277;1067.8558394616782;29844.85077574598;29976;30361.392772144434;30368.26063058985;30842.383152336828;1037.8858325584179;1068.4764465411893;1073.983890241647;1123.1632433836844;1200.2078628105407;15330.540083797503;15462.690746185239;15485;15934.804889633093;15975.360985560448;654245;655779;658736;659372;660113;50720.23919641208;51539.22691159636;51585.48414515882;59174.70412647909;59300;1354.7493713663005;1362.3324570960356;1406.5639651708004;1412.2867951684334;1412.456204361349;26918.73081269162;30318.84840575783;30335.393292134475;31604.36791264116;31767.04698859038;50616.49383506118;51198.74400627973;51282;51319.48680513147;51363.972720546124;590905;595330;624569;627841;642738;22893.084276629415;25363.492730144924;25505.362365941;25686.100986465382;25862.612060818727;46370.6088817336;46603.766981166234;46765.76617116893;46857.531424685316;46867.53132468768;38564.22871542609;38848.805755971036;38922.61077389303;39615;39722.2055558893;38141.4278785824;38875.8337249883;39384.80307598558;39423.014424639616;40084.99787505219;36891.81554092212;37015.44476832796;37120.6287937128;37588.62411375961;37945.81027094847;32409.027729168163;34462;34665.480017800255;34715.82642086875;34833.47749783806;23454.06183752699;24938.12716554912;25125.87437062803;25218.36954076163;25368.11211607585 06:41:03 INFO - PROCESS | 2224 | |2;jquery.html;353.5721776650253;372.6143441538008;373.46967306424875;380.61747943316965;402.7643828360409;15026.323815428304;15876.92061539692;20183.59632807343;20549.897250513764;20675.379738607848;20783.376498705024;20913.37259882202;21176.047077881492;21388.037538310775;21605.351839444804;5448.809291674782;5915.467607915287;5960.731767070492;6133.509319254449;6151.4463698267145;6129.356417576158;6183.041628547559;6196.16351792509;6208.906866397007;6217.036359364283;4212.473440819897;5019.573336266403;6771.221309549391;6818;6904.654767261642;6755.763548275787;6807.9319206807795;6861.48538859585;6874.621895795729;6876.277990810968;5817.185594016841;5921;5932.19915311436;5947.732352044169;5954.821355359346;25783.226503204933;26352.73647263522;26419.867900660374;26509.73490265092;26698.5995210072;2280.931572052841;2303.378087916253;2370.134900761227;2380.345405013635;3045.8781648734025;15257.396684381618;15361.539153825292;15727.685446291125;15926.76109858353;16313.83686163147;1784.5538615346163;1795.1293622593162;1801.0994502748626;1807.2590238002356;1835.9632807343778;105223;108595;117307.413462933;117385.41307293494;117798;9324.300677449219;10443.738906527398;10663.62677306306;10691.198160138021;10953.397563133945;13839.308034598229;13871.375788089561;13888.305584720723;13900.513482028282;13913.373898174606;8161.5919204039565;8424.241818236389;8436.746897592959;8624.094470080676;8636;7459.291367320096;8068.112507624157;8395.118512556215;8444.240018398374;8615.22462978335;6636.8672626547195;6721.395074443323;6744.3813484763305;6749.8650026999185;6768.289329620417;1551.2709026757407;1788.4724006418137;1814.8315544275601;1819.5451137215696;1819.7088465845402;850.1753299299669;854.4873076154257;916.2761418479326;920.769807548113;956.7464621875208;20374.083166257555;22050;22350.776492235196;22405.77594224037;22437.775622243895;918.2470374293149;931.888173419194;945.4610871803006;969.2197780786394;973.9074787895139;11513.251638643462;11677.014907465751;11871.169018168817;11927.88072119285;11968.281903085668;477457;479488;481513;605778;609123.1789138999;134054;137434.3128284352;140723;142662;143087.8456077192;936.2650319499161;995.586831464934;1006.2805094357569;1017.872765904262;1021.8671572695501;16155;18835.623287534447;19523.414297571097;19561.902190488956;19585.70621440651;23458;38887;51038.7240318995;53786.731066344415;54500.72749636226;77475;78838.60580697289;79026.60486697529;79422.20577794148;79882;7755.612219389121;8005.279524842792;8059.476134051502;8114.391420643594;8185.549794761345;11315.547378105113;12402.627921162379;12513.186642868184;12824.807627885775;12826.230426174086;9195.632174713204;10941.45292735344;11000.559977600806;11100.444977751242;11576.073914086684;10788;11007;11156;11180.217384782987;11359.432028398382;9187.632494700138;10286.794264114526;11349.54601815918;11660.06719462458;11812.645620631394;6979.581225126509;7248.420126389981;7322.2677732228485;7411.740589079356;8219.876701849596;9678.467684277432;10004.649837255662;10105.24210684187;10922.235443519196;10929.83605245906 06:41:03 INFO - PROCESS | 2224 | |3;mootools.html;1368;1511.969760604788;1564.405525900158;1752.4304601004676;1919.7984211657767;7702.922970770291;8217.301529370003;8240.83518329633;8520.74437766868;8746.300295976322;8673.352063108005;8700.825983480327;8723.4329768565;8730.738077857659;8739.606717697703;2257.887105644719;2285.0174424997244;2286.9885650571723;2287.176616418088;2290.4502919299416;2246.84272100953;2270.103309192876;2291.3813270416977;2291.4385975435957;2294.5755035318434;2241.764614715456;2269.580127676385;2278.464560828204;2287.1537531113513;2287.439577303563;2154.246013895124;2181.7818218178213;2186.365953873391;2189.2884812436023;2199.8240140788857;2253.0874995626796;2255.4474153832416;2259.3109101723967;2263.026898433673;2264.094362255095;8627.870581941275;8727.781805454852;8774.956125219396;8780.297576193889;8796.8680469793;5027.824026159104;5057.291979122926;5219.451957544479;5224.791008359661;5227.869303267411;3441.6042155151977;3515.5254040704435;3516.613172551017;3517.4899639552123;3543.061088811467;1023.6314926626409;1045.7281106912183;1047.5914393386586;1058.7088550648557;1062.1449732964961;11455.427228638619;11521.308721476738;11542.769144617145;11547.884521154765;11582.942085289604;4308.633766129851;4472;4473.172463094338;4499.685022048425;4732.296373305608;9026.86459703105;9180.586873590704;9184.586693598803;9195.862062069074;9199.356045076915;5138.948610513847;5140.742962851842;5146.6911985281;5153.020926024044;5154.9484505155215;4241.87274381769;4273.145370925828;4427.092446048552;4782.784774685148;4820.469748327681;3699.7780133192095;3708.9443658345144;3770.170562476251;3809.7142714296538;3823.483829683013;1090.1278976818514;1109.5229051507768;1118.3513562134106;1121.159130652011;1121.6915348279126;774.8062984253936;869.4001139213922;893.167270381586;926.4765407544716;939.8402271613842;5715.199872017921;5721.599488035798;5953.315368732651;6055.576109672369;6190.442860142609;836.958152092393;910.5310764956052;915.3866909170871;947.9856553487701;948.2414068744977;3856.0745421098736;3856.8842934712;3895.454636350913;3988.840446382112;4107.322291821875;9619.60515725216;10166.949165254275;10371.844422333672;10714.946425267824;10761.408122553335;10652.307600005975;10662.94668526668;10673.146148308117;10686.038256556947;10688.839667404995;7288.344049035612;8074.232947869943;8088.272055515032;8112.067112282045;8189.303909167668;4872.610191184767;5935.525157987437;5968.4926781224085;6058.091286307089;6068.4538391544975;9393;9886.950565247127;10006.09945104944;10013.299069065095;10082.193424525874;9223.58493867764;9577.23382129442;9855.704328870146;10151.390916545031;10193.74515637115;6973.5118541701595;7037.788866334018;7305.379042781423;7540.17058123595;7540.736074237595;8018.799530011796;8205.671773129247;8255.339572834038;8282.668693252443;8298.668053277801;7384.372328351936;7471.925280747123;7610;7658.8085297868;7666.501677390952;7279.59962202084;7321.414286857145;7335.706571737071;7349.30181632744;7380.704771809067;6758.222804377363;6939.306069393222;6961.512694111566;7046.718131274692;7120.60836653989;4907.607391408607;4913.828016019565;4973.676711013917;4979.228219625964;5057.822976195963;4265.040365917644;4899.1426500363;4982.576480999156;4999.575036121971;5139.974300128475 06:41:03 INFO - PROCESS | 2224 | |4;prototype.html;292.60206119677247;295.68804910819085;307.3208209856217;309.1698788752202;312.070031306706;4271.679624028198;5044.344235249417;5196.285225875461;5247.632665713397;5254.4220135785035;4271.582733812945;4334.730290663694;4825.769428795661;4951.232558953368;5161.741912904358;3949.886810556353;4009.1981603679237;4018.8995275118064;4025.657819085381;4059.4519739835187;3330.6835850594157;4015.3976903464422;4023.1752490739327;4029.254587901233;4046.976511744128;3894.435306880501;3974.701897357695;3999.3800960851113;4026.2485818668824;4031.8588849390276;3292.374448854734;3698.445233215012;3993.8617494014065;4008.659263962552;4020.7989600520004;3077.3999070181326;3301.712332190448;3480.4083305838067;3510.4032314506594;3511.753327568702;4915.877103072416;5063.873403164914;5076.771545280434;5076.949230507685;5079.085764562377;4380.189664912002;4492.730436173807;4549.658775591844;4560.247559152769;4569.360289559464;4622.468416132154;4992.925106123411;5093.4278189514425;5277.102892508283;5328.653637513549;3218.5976752905885;3844.193738570831;3849.4610754494392;3851.691864650821;3854.6530812227033;5325.547328477084;5327.573794096463;5374.978754036721;5583.664980101166;5734.455226753452;3895.4741109949796;3896.0649444133205;3926.784026878549;3935.2916475034194;3953.920921581552;3971.960280397217;3981.7411868228473;3989.162275922089;3990.2219067281603;3998.3402738547816;4195.517515485697;4362.563743625612;4410.5368936261875;4591.655625828076;4599.195140850338;4688.320193571913;4949.430815456197;5207.635465517377;5430.864228394322;5449.972750136224;1171.7813473986957;1227.551943540619;1245.4520011195045;1282.6152154353656;1353.4180302469836;855.5465603230298;885.371386315723;912.2838571721167;939.4175611121012;941.9481928493999;4548.749818759934;4971.6768410053955;5010.690805906917;5019.29729837823;5021.924671129936;4753.667243293005;5519.69641669704;5579.246801681721;5587.469190426903;5599.580031497654;4549.6360291176625;4581.954180458153;5009;5023.874403139951;5053.317802096743;2872.6409198850142;2880.0703528635863;2900.622919020514;2912.89804856829;2918.83946382951;4779.545943135397;5448.782048718086;5499.945000550023;5702.914456283159;5714.999875021855;4582.106489234568;5081.28861959326;5461.590380721462;5571.29792050855;5642.322921249476;5187.584993200459;5685.971570142123;5708.914366284509;5714.114372241991;5767.68277744728;5113;5179.766910489112;5215.895682086413;5456.499462647804;5525.723713814374;4981.775820088177;5436.700981446058;5447.945520544902;5450.492773324315;5463.726813659222;4405.7797110145;5425.538829199562;5687.3459552152035;5695.487406133468;5723.828285151452;3748.1633999339583;3838.9424158637084;3858.151206734514;3866.4393662919283;3889.5527014393706;5441.401445841034;5451.781928722965;5476.138113041585;5483.148762643483;5484.506394424521;4785.569298763128;5264.6314757966575;5359.356877174764;5369.892032595146;5374.7312634367345;4442.600165985077;4642.16441040616;5107.055194788976;5128.820491282787;5349.224362467498;4410.250257456176;4978.726170060681;5154.61340399479;5249.632525723163;5274.947250527445;4784.808607655655;4937.876553086202;5153.45888681683;5467.9453205467435;5512.02350730601;4443.511213766418;4753.952460475351;5038.470960549236;5335.33308336458;5360.839174824761 06:41:03 INFO - PROCESS | 2224 | |5;yui.html;633.9524535659825;702.2626242445434;715.5813848898391;758.9127250366209;786.0449553792141;14972.475963341272;15582.532524024298;15595.844041559596;15599.532014039569;15626.3749450022;14450.421983120663;14601.269936503186;14608.4887028954;14621.707565848677;14632;669.4744625469008;694.38546886006;821.8274162425868;839.1859895900982;858.4034096303072;841.5750046226647;845.3026253340987;845.8900342955438;852.4586887326531;853.2534032721369;917.8302014127407;918.669279059538;918.7151982885293;918.7381596245071;919.1773362840254;714.0538786108375;907.3875134284332;907.9909200907972;908.2007833106857;909.1181553892714;539.4147350125119;543.7661805423667;544.4664229055547;544.6269305525727;644.2108417188953;14489.637759056002;15024.248787560688;15025.398984040734;15027.023243489248;15038.097714137186;13485.460581576725;13680.52118175869;13727;13840.30798460073;14158.85841141596;12272.9103776166;12440;12676.366181690879;12733;13144.6713832154;3874.3219936511;4534.478534968455;4564.612007979325;4686.765661716935;4732.2428411454;90283;92893;96639.55040674466;117691;118154;10885;10945.084610193184;11297.322160670386;11411.657650270505;11503.654890353302;21606.675899861515;21634.350969470943;21636.35090947274;21700.45748856291;21728.89135554312;7926.445148839526;8282.213189746964;8284.751457456287;8363;8366.07973122956;4374.256376416017;4552.886177845514;4574.405327307429;4686.039361930796;4709.7174169549935;3405.3019131078067;3552.3960926642535;3552.6802587767224;3554.4668299754726;3554.8222588870453;623.3112410786016;626.2172284644195;639.859230969186;647.4626060369882;654.5320096131289;590.8700085981163;591.73667717866;606.4960483611245;658.6904155047121;677.1501765284536;6944.548604340803;7378.372838308804;9469.526523673789;10014.749631259278;10279.537420816081;524.5095835393843;568.3009897825698;625.0436831647586;663.8705452436825;681.3493114076045;4503.662225333048;4628.5371462853445;4666;4712.764361781898;4731.7634118293945;75804;78353;121256.39371803084;121869.39065304617;132558.33720831334;111408;118480;118510;118959;119716;99543;99763.50118249362;101210;103054.96945030603;105654;7346.37555807741;8918.152775486318;9013.369064165709;9019.864702029607;9028.59371328305;42189.312427503784;55641.16538252011;55866.72066639641;56355.71822140863;57158.428415716975;79388;88654.55672721595;89189;89350.55324723334;91692.62461062956;9652.2476675149;11493.712657183638;11662;11923.224990375598;12325.260484370967;13323.13399629021;13372.93313533426;13380.264085475392;13389;13929.164250145024;11863.525458981545;12350.62948111558;13490.392932318266;14110.153390796586;14822.851771482146;8051.102928353284;13830.65423364424;14117.294135292988;14184.148951062969;14217.857821421654;13830.515931942333;13887.236202008986;14274.000819942505;14294.124103249604;14305.356258968579;9013.864792028256;11456.541738330374;11458.312501249951;11506.539738410705;11569.942150289531;9783.217342612716;10584.947075264574;11478.196526243082;11560.13299002595;11566.768664626828 06:41:03 INFO - PROCESS | 2224 | __end_tp_report 06:41:03 INFO - PROCESS | 2224 | __start_cc_report 06:41:03 INFO - PROCESS | 2224 | _x_x_mozilla_cycle_collect,1480 06:41:03 INFO - PROCESS | 2224 | __end_cc_report 06:41:03 INFO - PROCESS | 2224 | __startTimestamp1470231663264__endTimestamp 06:41:03 INFO - PROCESS | 2224 | 06:41:03 INFO - PROCESS | 2224 | ------- Summary: start ------- 06:41:03 INFO - PROCESS | 2224 | Number of tests: 6 06:41:03 INFO - PROCESS | 2224 | 06:41:03 INFO - PROCESS | 2224 | [#0] dojo.html Cycles:165 Average:5658.87 Median:5837.97 stddev:657.12 (11.3%) stddev-sans-first:627.55 06:41:03 INFO - PROCESS | 2224 | Values: 3093.3 3288.2 3299.2 3326.0 3360.3 5540.1 5588.6 5627.5 5660.4 5672.3 5613.8 5643.1 5647.4 5656.1 5662.6 4229.3 4268.9 5150.1 5210.7 5216.7 5117.8 5155.1 5172.4 5231.5 5285.1 5168.0 5179.6 5193.1 5222.8 5272.8 5244.3 5252.3 5263.8 5265.0 5305.6 5076.1 5083.8 5144.3 5164.9 5184.0 5539.3 5546.3 5568.4 5616.0 5642.0 6178.7 6181.3 6195.1 6201.8 6204.9 6075.4 6122.4 6136.2 6228.5 6261.0 5695.5 5733.1 5760.8 5764.0 5778.4 6228.3 6293.2 6300.7 6311.7 6319.7 5256.3 5555.7 6089.3 6094.5 6113.1 5754.6 6121.7 6190.2 6234.5 6252.2 6141.8 6162.8 6168.4 6170.1 6178.8 5913.2 6151.6 6193.5 6197.8 6208.0 4826.3 4859.6 4881.5 4901.6 4913.5 4364.1 4367.8 4371.3 4380.2 4409.3 5012.9 5486.8 5618.6 5636.7 5668.2 6088.1 6106.1 6113.7 6122.6 6164.2 5562.8 5565.7 5578.0 5582.3 5620.2 5285.7 5309.0 5309.8 5315.3 5369.1 6158.8 6218.2 6249.1 6250.7 6269.0 6262.5 6267.0 6271.6 6273.4 6278.7 6205.9 6266.1 6282.9 6283.1 6298.7 5948.6 6072.7 6125.4 6142.6 6165.7 6217.6 6224.4 6225.3 6230.2 6248.0 5882.2 6042.3 6080.9 6114.4 6124.7 5844.9 5946.9 5982.9 6004.2 6087.5 5862.2 5881.9 5882.5 5923.3 5929.9 4991.5 6060.4 6060.7 6062.9 6073.4 5687.8 5732.0 5780.8 5838.0 5840.5 06:41:03 INFO - PROCESS | 2224 | 06:41:03 INFO - PROCESS | 2224 | [#1] ext.html Cycles:180 Average:67716.43 Median:365969.58 stddev:149805.62 (40.9%) stddev-sans-first:150140.69 06:41:03 INFO - PROCESS | 2224 | Values: 435.3 455.8 471.4 518.7 551.9 29754.3 31630.4 39842.0 41038.6 41171.4 42121.8 42483.6 42627.4 42766.4 42856.8 9375.4 9505.3 9538.5 9543.7 9571.4 8113.5 8469.7 8503.0 8709.8 8711.2 11529.3 11719.0 11724.3 11950.2 11966.9 11124.4 11566.0 11657.7 11663.8 11666.8 11582.8 11655.4 11663.9 11673.5 11678.8 39406.0 39882.8 39908.2 39926.2 39969.8 3298.7 3520.4 3592.2 3875.9 4130.6 30966.1 31946.8 32164.0 32294.5 32307.0 3186.3 3277.5 3314.2 3336.2 3355.1 312950.0 350364.0 365627.0 366312.2 367955.2 58271.0 58448.1 58664.4 58715.7 58784.0 61648.7 61747.7 61767.0 61795.7 61808.1 15720.8 15883.4 15900.7 15902.2 15911.2 12430.4 12497.7 12506.4 12508.7 12531.0 11295.9 14086.8 14096.5 14968.1 15640.0 5149.3 5230.2 5253.8 5299.4 6234.2 900.0 972.5 1019.1 1026.4 1067.9 29844.9 29976.0 30361.4 30368.3 30842.4 1037.9 1068.5 1074.0 1123.2 1200.2 15330.5 15462.7 15485.0 15934.8 15975.4 654245.0 655779.0 658736.0 659372.0 660113.0 50720.2 51539.2 51585.5 59174.7 59300.0 1354.7 1362.3 1406.6 1412.3 1412.5 26918.7 30318.8 30335.4 31604.4 31767.0 50616.5 51198.7 51282.0 51319.5 51364.0 590905.0 595330.0 624569.0 627841.0 642738.0 22893.1 25363.5 25505.4 25686.1 25862.6 46370.6 46603.8 46765.8 46857.5 46867.5 38564.2 38848.8 38922.6 39615.0 39722.2 38141.4 38875.8 39384.8 39423.0 40085.0 36891.8 37015.4 37120.6 37588.6 37945.8 32409.0 34462.0 34665.5 34715.8 34833.5 23454.1 24938.1 25125.9 25218.4 25368.1 06:41:03 INFO - PROCESS | 2224 | 06:41:03 INFO - PROCESS | 2224 | [#2] jquery.html Cycles:180 Average:33321.27 Median:26386.30 stddev:90066.18 (341.3%) stddev-sans-first:90284.82 06:41:03 INFO - PROCESS | 2224 | Values: 353.6 372.6 373.5 380.6 402.8 15026.3 15876.9 20183.6 20549.9 20675.4 20783.4 20913.4 21176.0 21388.0 21605.4 5448.8 5915.5 5960.7 6133.5 6151.4 6129.4 6183.0 6196.2 6208.9 6217.0 4212.5 5019.6 6771.2 6818.0 6904.7 6755.8 6807.9 6861.5 6874.6 6876.3 5817.2 5921.0 5932.2 5947.7 5954.8 25783.2 26352.7 26419.9 26509.7 26698.6 2280.9 2303.4 2370.1 2380.3 3045.9 15257.4 15361.5 15727.7 15926.8 16313.8 1784.6 1795.1 1801.1 1807.3 1836.0 105223.0 108595.0 117307.4 117385.4 117798.0 9324.3 10443.7 10663.6 10691.2 10953.4 13839.3 13871.4 13888.3 13900.5 13913.4 8161.6 8424.2 8436.7 8624.1 8636.0 7459.3 8068.1 8395.1 8444.2 8615.2 6636.9 6721.4 6744.4 6749.9 6768.3 1551.3 1788.5 1814.8 1819.5 1819.7 850.2 854.5 916.3 920.8 956.7 20374.1 22050.0 22350.8 22405.8 22437.8 918.2 931.9 945.5 969.2 973.9 11513.3 11677.0 11871.2 11927.9 11968.3 477457.0 479488.0 481513.0 605778.0 609123.2 134054.0 137434.3 140723.0 142662.0 143087.8 936.3 995.6 1006.3 1017.9 1021.9 16155.0 18835.6 19523.4 19561.9 19585.7 23458.0 38887.0 51038.7 53786.7 54500.7 77475.0 78838.6 79026.6 79422.2 79882.0 7755.6 8005.3 8059.5 8114.4 8185.5 11315.5 12402.6 12513.2 12824.8 12826.2 9195.6 10941.5 11000.6 11100.4 11576.1 10788.0 11007.0 11156.0 11180.2 11359.4 9187.6 10286.8 11349.5 11660.1 11812.6 6979.6 7248.4 7322.3 7411.7 8219.9 9678.5 10004.6 10105.2 10922.2 10929.8 06:41:03 INFO - PROCESS | 2224 | 06:41:03 INFO - PROCESS | 2224 | [#3] mootools.html Cycles:180 Average:5591.67 Median:4980.90 stddev:3148.86 (63.2%) stddev-sans-first:3141.69 06:41:03 INFO - PROCESS | 2224 | Values: 1368.0 1512.0 1564.4 1752.4 1919.8 7702.9 8217.3 8240.8 8520.7 8746.3 8673.4 8700.8 8723.4 8730.7 8739.6 2257.9 2285.0 2287.0 2287.2 2290.5 2246.8 2270.1 2291.4 2291.4 2294.6 2241.8 2269.6 2278.5 2287.2 2287.4 2154.2 2181.8 2186.4 2189.3 2199.8 2253.1 2255.4 2259.3 2263.0 2264.1 8627.9 8727.8 8775.0 8780.3 8796.9 5027.8 5057.3 5219.5 5224.8 5227.9 3441.6 3515.5 3516.6 3517.5 3543.1 1023.6 1045.7 1047.6 1058.7 1062.1 11455.4 11521.3 11542.8 11547.9 11582.9 4308.6 4472.0 4473.2 4499.7 4732.3 9026.9 9180.6 9184.6 9195.9 9199.4 5138.9 5140.7 5146.7 5153.0 5154.9 4241.9 4273.1 4427.1 4782.8 4820.5 3699.8 3708.9 3770.2 3809.7 3823.5 1090.1 1109.5 1118.4 1121.2 1121.7 774.8 869.4 893.2 926.5 939.8 5715.2 5721.6 5953.3 6055.6 6190.4 837.0 910.5 915.4 948.0 948.2 3856.1 3856.9 3895.5 3988.8 4107.3 9619.6 10166.9 10371.8 10714.9 10761.4 10652.3 10662.9 10673.1 10686.0 10688.8 7288.3 8074.2 8088.3 8112.1 8189.3 4872.6 5935.5 5968.5 6058.1 6068.5 9393.0 9887.0 10006.1 10013.3 10082.2 9223.6 9577.2 9855.7 10151.4 10193.7 6973.5 7037.8 7305.4 7540.2 7540.7 8018.8 8205.7 8255.3 8282.7 8298.7 7384.4 7471.9 7610.0 7658.8 7666.5 7279.6 7321.4 7335.7 7349.3 7380.7 6758.2 6939.3 6961.5 7046.7 7120.6 4907.6 4913.8 4973.7 4979.2 5057.8 4265.0 4899.1 4982.6 4999.6 5140.0 06:41:03 INFO - PROCESS | 2224 | 06:41:03 INFO - PROCESS | 2224 | [#4] prototype.html Cycles:180 Average:4377.69 Median:4926.88 stddev:1296.78 (26.3%) stddev-sans-first:1263.65 06:41:03 INFO - PROCESS | 2224 | Values: 292.6 295.7 307.3 309.2 312.1 4271.7 5044.3 5196.3 5247.6 5254.4 4271.6 4334.7 4825.8 4951.2 5161.7 3949.9 4009.2 4018.9 4025.7 4059.5 3330.7 4015.4 4023.2 4029.3 4047.0 3894.4 3974.7 3999.4 4026.2 4031.9 3292.4 3698.4 3993.9 4008.7 4020.8 3077.4 3301.7 3480.4 3510.4 3511.8 4915.9 5063.9 5076.8 5076.9 5079.1 4380.2 4492.7 4549.7 4560.2 4569.4 4622.5 4992.9 5093.4 5277.1 5328.7 3218.6 3844.2 3849.5 3851.7 3854.7 5325.5 5327.6 5375.0 5583.7 5734.5 3895.5 3896.1 3926.8 3935.3 3953.9 3972.0 3981.7 3989.2 3990.2 3998.3 4195.5 4362.6 4410.5 4591.7 4599.2 4688.3 4949.4 5207.6 5430.9 5450.0 1171.8 1227.6 1245.5 1282.6 1353.4 855.5 885.4 912.3 939.4 941.9 4548.7 4971.7 5010.7 5019.3 5021.9 4753.7 5519.7 5579.2 5587.5 5599.6 4549.6 4582.0 5009.0 5023.9 5053.3 2872.6 2880.1 2900.6 2912.9 2918.8 4779.5 5448.8 5499.9 5702.9 5715.0 4582.1 5081.3 5461.6 5571.3 5642.3 5187.6 5686.0 5708.9 5714.1 5767.7 5113.0 5179.8 5215.9 5456.5 5525.7 4981.8 5436.7 5447.9 5450.5 5463.7 4405.8 5425.5 5687.3 5695.5 5723.8 3748.2 3838.9 3858.2 3866.4 3889.6 5441.4 5451.8 5476.1 5483.1 5484.5 4785.6 5264.6 5359.4 5369.9 5374.7 4442.6 4642.2 5107.1 5128.8 5349.2 4410.3 4978.7 5154.6 5249.6 5274.9 4784.8 4937.9 5153.5 5467.9 5512.0 4443.5 4754.0 5038.5 5335.3 5360.8 06:41:03 INFO - PROCESS | 2224 | 06:41:03 INFO - PROCESS | 2224 | [#5] yui.html Cycles:180 Average:22513.28 Median:4543.68 stddev:34306.40 (755.0%) stddev-sans-first:34363.31 06:41:03 INFO - PROCESS | 2224 | Values: 634.0 702.3 715.6 758.9 786.0 14972.5 15582.5 15595.8 15599.5 15626.4 14450.4 14601.3 14608.5 14621.7 14632.0 669.5 694.4 821.8 839.2 858.4 841.6 845.3 845.9 852.5 853.3 917.8 918.7 918.7 918.7 919.2 714.1 907.4 908.0 908.2 909.1 539.4 543.8 544.5 544.6 644.2 14489.6 15024.2 15025.4 15027.0 15038.1 13485.5 13680.5 13727.0 13840.3 14158.9 12272.9 12440.0 12676.4 12733.0 13144.7 3874.3 4534.5 4564.6 4686.8 4732.2 90283.0 92893.0 96639.6 117691.0 118154.0 10885.0 10945.1 11297.3 11411.7 11503.7 21606.7 21634.4 21636.4 21700.5 21728.9 7926.4 8282.2 8284.8 8363.0 8366.1 4374.3 4552.9 4574.4 4686.0 4709.7 3405.3 3552.4 3552.7 3554.5 3554.8 623.3 626.2 639.9 647.5 654.5 590.9 591.7 606.5 658.7 677.2 6944.5 7378.4 9469.5 10014.7 10279.5 524.5 568.3 625.0 663.9 681.3 4503.7 4628.5 4666.0 4712.8 4731.8 75804.0 78353.0 121256.4 121869.4 132558.3 111408.0 118480.0 118510.0 118959.0 119716.0 99543.0 99763.5 101210.0 103055.0 105654.0 7346.4 8918.2 9013.4 9019.9 9028.6 42189.3 55641.2 55866.7 56355.7 57158.4 79388.0 88654.6 89189.0 89350.6 91692.6 9652.2 11493.7 11662.0 11923.2 12325.3 13323.1 13372.9 13380.3 13389.0 13929.2 11863.5 12350.6 13490.4 14110.2 14822.9 8051.1 13830.7 14117.3 14184.1 14217.9 13830.5 13887.2 14274.0 14294.1 14305.4 9013.9 11456.5 11458.3 11506.5 11569.9 9783.2 10584.9 11478.2 11560.1 11566.8 06:41:03 INFO - PROCESS | 2224 | -------- Summary: end -------- 06:41:03 INFO - PROCESS | 2224 | 06:41:03 INFO - TEST-INFO | 2224: exit 0 06:41:03 INFO - TEST-OK | dromaeo_css | took 1117992ms 06:41:03 INFO - TEST-START | kraken 06:41:03 INFO - operating with platform_type : mac_ 06:41:03 INFO - Initialising browser for kraken test... 06:41:03 INFO - TEST-INFO | started process 2230 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpYm3nFd/profile http://localhost:50848/getInfo.html) 06:41:06 INFO - PROCESS | 2230 | __metrics Screen width/height:1600/1200 06:41:06 INFO - PROCESS | 2230 | colorDepth:24 06:41:06 INFO - PROCESS | 2230 | Browser inner width/height: 1024/689 06:41:06 INFO - PROCESS | 2230 | __metrics 06:41:06 INFO - PROCESS | 2230 | 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" 06:41:06 INFO - PROCESS | 2230 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:41:06 INFO - PROCESS | 2230 | ************************* 06:41:06 INFO - PROCESS | 2230 | A coding exception was thrown in a Promise resolution callback. 06:41:06 INFO - PROCESS | 2230 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 06:41:06 INFO - PROCESS | 2230 | 06:41:06 ERROR - PROCESS | 2230 | Full message: TypeError: win is null 06:41:06 INFO - PROCESS | 2230 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:844:17 06:41:06 INFO - PROCESS | 2230 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 06:41:06 INFO - PROCESS | 2230 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 06:41:06 INFO - PROCESS | 2230 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 06:41:06 INFO - PROCESS | 2230 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 06:41:06 INFO - PROCESS | 2230 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 06:41:06 INFO - PROCESS | 2230 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 06:41:06 INFO - PROCESS | 2230 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 06:41:06 INFO - PROCESS | 2230 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 06:41:06 INFO - PROCESS | 2230 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 06:41:06 INFO - PROCESS | 2230 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 06:41:06 INFO - PROCESS | 2230 | Barrier/this.client.addBlocker/promise53.4% 06:42:04 INFO - TinderboxPrint: I/O read bytes / time
446,464 / 7 06:42:04 INFO - TinderboxPrint: I/O write bytes / time
700,632,576 / 2,046 06:42:04 INFO - TinderboxPrint: CPU idle
2,258.6 (46.6%) 06:42:04 INFO - TinderboxPrint: CPU system
969.5 (20.0%) 06:42:04 INFO - TinderboxPrint: CPU user
1,621.6 (33.4%) 06:42:04 INFO - TinderboxPrint: Swap in / out
403,550,208 / 0 06:42:04 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397320704; Write bytes: 392480768; Read time: 29640; Write time: 735 06:42:04 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 446464; Write bytes: 292263424; Read time: 7; Write time: 1274 06:42:04 INFO - Running post-run listener: _upload_blobber_files 06:42:04 INFO - Blob upload gear active. 06:42:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:42:04 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 06:42:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:42:05 INFO - (blobuploader) - INFO - Open directory for files ... 06:42:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:42:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:42:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:42:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:42:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:42:06 INFO - (blobuploader) - INFO - Done attempting. 06:42:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:42:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:42:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:42:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:42:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:42:07 INFO - (blobuploader) - INFO - Done attempting. 06:42:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:42:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:42:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:42:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:42:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:42:09 INFO - (blobuploader) - INFO - Done attempting. 06:42:09 INFO - (blobuploader) - INFO - Iteration through files over. 06:42:09 INFO - Return code: 0 06:42:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:42:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:42:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4fd00aeabb1ea6c8f705155093ac1d72c0bd7e37b2c488992d55621d655d6d27934d29c5d1028dd7f5c13c1361ce7af9656bef4ec75d274361f65015f0260262", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c992adca40157f2e1e1d256a1459d5275f52a3b62762299e0b6a9df42b61575cd01be30daf285c8e636dd0d2a5db5c5c096266d2c8da0283fdff603174cfda0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d13db0e49f81584a7ec722260ef64df86786f81aca985819fb8876428f6906ac2dfee92796f3173cebf967a102d506f0c298e25fb60eed8dd81df2815bb7d5c5"} 06:42:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:42:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:42:09 INFO - Contents: 06:42:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4fd00aeabb1ea6c8f705155093ac1d72c0bd7e37b2c488992d55621d655d6d27934d29c5d1028dd7f5c13c1361ce7af9656bef4ec75d274361f65015f0260262", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c992adca40157f2e1e1d256a1459d5275f52a3b62762299e0b6a9df42b61575cd01be30daf285c8e636dd0d2a5db5c5c096266d2c8da0283fdff603174cfda0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d13db0e49f81584a7ec722260ef64df86786f81aca985819fb8876428f6906ac2dfee92796f3173cebf967a102d506f0c298e25fb60eed8dd81df2815bb7d5c5"} 06:42:09 INFO - Running post-run listener: copy_logs_to_upload_dir 06:42:09 INFO - Copying logs to upload dir... 06:42:09 INFO - mkdir: /builds/slave/test/build/upload/logs 06:42:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.738891 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-08-03 06:42:09.774545) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 06:42:09.778529) ========= 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.RuBusWbrLf/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.DX7XM2fNkf/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4fd00aeabb1ea6c8f705155093ac1d72c0bd7e37b2c488992d55621d655d6d27934d29c5d1028dd7f5c13c1361ce7af9656bef4ec75d274361f65015f0260262", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c992adca40157f2e1e1d256a1459d5275f52a3b62762299e0b6a9df42b61575cd01be30daf285c8e636dd0d2a5db5c5c096266d2c8da0283fdff603174cfda0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d13db0e49f81584a7ec722260ef64df86786f81aca985819fb8876428f6906ac2dfee92796f3173cebf967a102d506f0c298e25fb60eed8dd81df2815bb7d5c5"} build_url:https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/Huxi4jFhRmemP8FFl1-qiQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4fd00aeabb1ea6c8f705155093ac1d72c0bd7e37b2c488992d55621d655d6d27934d29c5d1028dd7f5c13c1361ce7af9656bef4ec75d274361f65015f0260262", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c992adca40157f2e1e1d256a1459d5275f52a3b62762299e0b6a9df42b61575cd01be30daf285c8e636dd0d2a5db5c5c096266d2c8da0283fdff603174cfda0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d13db0e49f81584a7ec722260ef64df86786f81aca985819fb8876428f6906ac2dfee92796f3173cebf967a102d506f0c298e25fb60eed8dd81df2815bb7d5c5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 06:42:09.816980) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 06:42:09.817758) ========= 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.RuBusWbrLf/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.DX7XM2fNkf/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.005234 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 06:42:09.869391) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 06:42:09.869720) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 06:42:09.870072) ========= ========= Total master_lag: 0.19 =========