builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0235 starttime: 1468451796.62 results: success (0) buildid: 20160713141046 builduid: f56c373fb50a40f583c4b414d4dcca39 revision: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:36.621898) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:36.622611) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:36.622899) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:36.649770) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:36.650561) ========= 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.Egw1jaGtTe/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.VbkUXIspea/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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-07-13 16:16:36-- 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% 6.49M=0.002s 2016-07-13 16:16:37 (6.49 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.525015 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:37.199506) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:37.199962) ========= 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.Egw1jaGtTe/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.VbkUXIspea/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.020153 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:37.252352) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:37.253100) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5fd14a66be3191e5628af3cfb0a456dd70f179b8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5fd14a66be3191e5628af3cfb0a456dd70f179b8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Egw1jaGtTe/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.VbkUXIspea/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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-07-13 16:16:37,335 truncating revision to first 12 chars 2016-07-13 16:16:37,335 Setting DEBUG logging. 2016-07-13 16:16:37,335 attempt 1/10 2016-07-13 16:16:37,335 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5fd14a66be31?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 16:16:37,977 unpacking tar archive at: mozilla-central-5fd14a66be31/testing/mozharness/ program finished with exit code 0 elapsedTime=0.936813 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:38.215216) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:38.215971) ========= script_repo_revision: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:38.217004) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:38.217747) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 16:16:38.240988) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-07-13 16:16:38.241751) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Egw1jaGtTe/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.VbkUXIspea/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 16:16:38 INFO - MultiFileLogger online at 20160713 16:16:38 in /builds/slave/test 16:16:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:16:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:16:38 INFO - {'append_to_log': False, 16:16:38 INFO - 'base_work_dir': '/builds/slave/test', 16:16:38 INFO - 'blob_upload_branch': 'Firefox', 16:16:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:16:38 INFO - 'branch': 'Firefox', 16:16:38 INFO - 'buildbot_json_path': 'buildprops.json', 16:16:38 INFO - 'config_files': ('talos/mac_config.py',), 16:16:38 INFO - 'default_actions': ('clobber', 16:16:38 INFO - 'read-buildbot-config', 16:16:38 INFO - 'download-and-extract', 16:16:38 INFO - 'populate-webroot', 16:16:38 INFO - 'create-virtualenv', 16:16:38 INFO - 'install', 16:16:38 INFO - 'run-tests'), 16:16:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:16:38 INFO - 'download_minidump_stackwalk': True, 16:16:38 INFO - 'download_symbols': 'ondemand', 16:16:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:16:38 INFO - 'tooltool.py': '/tools/tooltool.py', 16:16:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:16:38 INFO - '/tools/misc-python/virtualenv.py')}, 16:16:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:16:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:16:38 INFO - 'installer_path': 'installer.exe', 16:16:38 INFO - 'log_level': 'info', 16:16:38 INFO - 'log_name': 'talos', 16:16:38 INFO - 'log_to_console': True, 16:16:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:16:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:16:38 INFO - 'opt_config_files': (), 16:16:38 INFO - 'pip_index': False, 16:16:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:16:38 INFO - 'cmd': ('bash', 16:16:38 INFO - '-c', 16:16:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:16:38 INFO - 'enabled': True, 16:16:38 INFO - 'halt_on_failure': False, 16:16:38 INFO - 'name': 'check_screen_resolution'},), 16:16:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:16:38 INFO - 'cmd': ('bash', 16:16:38 INFO - '-c', 16:16:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:16:38 INFO - 'enabled': True, 16:16:38 INFO - 'halt_on_failure': False, 16:16:38 INFO - 'name': 'check_screen_resolution'},), 16:16:38 INFO - 'run_cmd_checks_enabled': True, 16:16:38 INFO - 'sps_profile': False, 16:16:38 INFO - 'sps_profile_interval': 0, 16:16:38 INFO - 'suite': 'dromaeojs', 16:16:38 INFO - 'system_bits': '32', 16:16:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:16:38 INFO - 'title': 't-yosemite-r7-0235', 16:16:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:16:38 INFO - 'use_talos_json': True, 16:16:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:16:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:16:38 INFO - 'work_dir': 'build'} 16:16:38 INFO - ##### 16:16:38 INFO - ##### Running clobber step. 16:16:38 INFO - ##### 16:16:38 INFO - Running pre-action listener: _resource_record_pre_action 16:16:38 INFO - Running main action method: clobber 16:16:38 INFO - rmtree: /builds/slave/test/build 16:16:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:16:39 INFO - Running post-action listener: _resource_record_post_action 16:16:39 INFO - ##### 16:16:39 INFO - ##### Finished clobber step (success) 16:16:39 INFO - ##### 16:16:39 INFO - ##### 16:16:39 INFO - ##### Running read-buildbot-config step. 16:16:39 INFO - ##### 16:16:39 INFO - Running pre-action listener: _resource_record_pre_action 16:16:39 INFO - Running main action method: read_buildbot_config 16:16:39 INFO - Using buildbot properties: 16:16:39 INFO - { 16:16:39 INFO - "project": "", 16:16:39 INFO - "product": "firefox", 16:16:39 INFO - "script_repo_revision": "production", 16:16:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:16:39 INFO - "repository": "", 16:16:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:16:39 INFO - "buildid": "20160713141046", 16:16:39 INFO - "pgo_build": "False", 16:16:39 INFO - "basedir": "/builds/slave/test", 16:16:39 INFO - "buildnumber": 293, 16:16:39 INFO - "slavename": "t-yosemite-r7-0235", 16:16:39 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:16:39 INFO - "platform": "yosemite_r7", 16:16:39 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:16:39 INFO - "branch": "mozilla-central", 16:16:39 INFO - "repo_path": "mozilla-central", 16:16:39 INFO - "revision": "5fd14a66be3191e5628af3cfb0a456dd70f179b8", 16:16:39 INFO - "stage_platform": "macosx64", 16:16:39 INFO - "builduid": "f56c373fb50a40f583c4b414d4dcca39", 16:16:39 INFO - "slavebuilddir": "test" 16:16:39 INFO - } 16:16:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 16:16:39 INFO - Running post-action listener: _resource_record_post_action 16:16:39 INFO - ##### 16:16:39 INFO - ##### Finished read-buildbot-config step (success) 16:16:39 INFO - ##### 16:16:39 INFO - ##### 16:16:39 INFO - ##### Running download-and-extract step. 16:16:39 INFO - ##### 16:16:39 INFO - Running pre-action listener: _resource_record_pre_action 16:16:39 INFO - Running main action method: download_and_extract 16:16:39 INFO - mkdir: /builds/slave/test/build/tests 16:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:39 INFO - https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:16:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:16:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:16:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:16:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 16:17:01 INFO - Downloaded 1199 bytes. 16:17:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:17:01 INFO - Using the following test package requirements: 16:17:01 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:17:01 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:01 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 16:17:01 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:01 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 16:17:01 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:01 INFO - u'jsshell-mac.zip'], 16:17:01 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:01 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 16:17:01 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:17:01 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:01 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 16:17:01 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:01 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 16:17:01 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:01 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 16:17:01 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:17:01 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 16:17:01 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:01 INFO - https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:17:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:17:07 INFO - Downloaded 18827271 bytes. 16:17:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:17:10 INFO - Return code: 0 16:17:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:10 INFO - https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:17:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:17:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:17:14 INFO - Downloaded 18827271 bytes. 16:17:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:17:17 INFO - Return code: 0 16:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:17:17 INFO - https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:17:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:17:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:17:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:17:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:17:18 INFO - Downloaded 14403317 bytes. 16:17:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:17:19 INFO - Return code: 0 16:17:19 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')]} 16:17:19 INFO - https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:17:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:17:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:17:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 16:17:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 16:17:22 INFO - Downloaded 112779863 bytes. 16:17:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:17:22 INFO - mkdir: /builds/slave/test/properties 16:17:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:17:22 INFO - Writing to file /builds/slave/test/properties/build_url 16:17:22 INFO - Contents: 16:17:22 INFO - build_url:https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:17:23 INFO - Running post-action listener: _resource_record_post_action 16:17:23 INFO - Running post-action listener: set_extra_try_arguments 16:17:23 INFO - ##### 16:17:23 INFO - ##### Finished download-and-extract step (success) 16:17:23 INFO - ##### 16:17:23 INFO - ##### 16:17:23 INFO - ##### Running populate-webroot step. 16:17:23 INFO - ##### 16:17:23 INFO - Running pre-action listener: _resource_record_pre_action 16:17:23 INFO - Running main action method: populate_webroot 16:17:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:17:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:17:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:17:23 INFO - '--tppagecycles', 16:17:23 INFO - '7'], 16:17:23 INFO - 'tests': ['tsvgm']}}, 16:17:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:17:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:17:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:17:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:17:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:23 INFO - 'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:23 INFO - 'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['damp', 'tps']}, 16:17:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:23 INFO - 'tests': ['damp', 'tps']}, 16:17:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['dromaeo_dom']}, 16:17:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:17:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['basic_compositor_video']}, 16:17:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:17:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['a11yr', 16:17:23 INFO - 'ts_paint', 16:17:23 INFO - 'tpaint', 16:17:23 INFO - 'sessionrestore', 16:17:23 INFO - 'sessionrestore_no_auto_restore', 16:17:23 INFO - 'tabpaint']}, 16:17:23 INFO - 'other-e10s': {'tests': ['a11yr', 16:17:23 INFO - 'ts_paint', 16:17:23 INFO - 'tpaint', 16:17:23 INFO - 'sessionrestore', 16:17:23 INFO - 'sessionrestore_no_auto_restore', 16:17:23 INFO - 'tabpaint']}, 16:17:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:17:23 INFO - 'ts_paint', 16:17:23 INFO - 'tpaint', 16:17:23 INFO - 'sessionrestore', 16:17:23 INFO - 'sessionrestore_no_auto_restore', 16:17:23 INFO - 'tabpaint']}, 16:17:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:17:23 INFO - 'ts_paint', 16:17:23 INFO - 'tpaint', 16:17:23 INFO - 'sessionrestore', 16:17:23 INFO - 'sessionrestore_no_auto_restore', 16:17:23 INFO - 'tabpaint']}, 16:17:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['a11yr', 16:17:23 INFO - 'ts_paint', 16:17:23 INFO - 'tpaint', 16:17:23 INFO - 'sessionrestore', 16:17:23 INFO - 'sessionrestore_no_auto_restore', 16:17:23 INFO - 'tabpaint']}, 16:17:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['a11yr', 16:17:23 INFO - 'ts_paint', 16:17:23 INFO - 'tpaint', 16:17:23 INFO - 'sessionrestore', 16:17:23 INFO - 'sessionrestore_no_auto_restore', 16:17:23 INFO - 'tabpaint']}, 16:17:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['tsvgx', 16:17:23 INFO - 'tsvgr_opacity', 16:17:23 INFO - 'tart', 16:17:23 INFO - 'tscrollx', 16:17:23 INFO - 'cart']}, 16:17:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:17:23 INFO - 'tsvgr_opacity', 16:17:23 INFO - 'tart', 16:17:23 INFO - 'tscrollx', 16:17:23 INFO - 'cart']}, 16:17:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:23 INFO - 'talos_options': ['--disable-e10s'], 16:17:23 INFO - 'tests': ['tp5o']}, 16:17:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:23 INFO - 'tests': ['tp5o']}, 16:17:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:23 INFO - 'talos_options': ['--disable-e10s', 16:17:23 INFO - '--xperf_path', 16:17:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:23 INFO - 'tests': ['tp5n']}, 16:17:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:23 INFO - 'talos_options': ['--xperf_path', 16:17:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:23 INFO - 'tests': ['tp5n']}}, 16:17:23 INFO - 'talos.zip': {'path': '', 16:17:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:17:23 INFO - Running post-action listener: _resource_record_post_action 16:17:23 INFO - ##### 16:17:23 INFO - ##### Finished populate-webroot step (success) 16:17:23 INFO - ##### 16:17:23 INFO - ##### 16:17:23 INFO - ##### Running create-virtualenv step. 16:17:23 INFO - ##### 16:17:23 INFO - Running pre-action listener: _resource_record_pre_action 16:17:23 INFO - Running main action method: create_virtualenv 16:17:23 INFO - Creating virtualenv /builds/slave/test/build/venv 16:17:23 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 16:17:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:17:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:17:23 INFO - Using real prefix '/tools/python27' 16:17:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:17:24 INFO - Installing distribute.............................................................................................................................................................................................done. 16:17:26 INFO - Installing pip.................done. 16:17:26 INFO - Return code: 0 16:17:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102861a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa472555e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c5d30>, '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 0x10252adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027bdb20>, '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.Egw1jaGtTe/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.VbkUXIspea/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 16:17:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:17:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:17:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Egw1jaGtTe/Render', 16:17:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:26 INFO - 'HOME': '/Users/cltbld', 16:17:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:26 INFO - 'LOGNAME': 'cltbld', 16:17:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:26 INFO - 'MOZ_NO_REMOTE': '1', 16:17:26 INFO - 'NO_EM_RESTART': '1', 16:17:26 INFO - 'PAGER': '/bin/cat', 16:17:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:26 INFO - 'PWD': '/builds/slave/test', 16:17:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:26 INFO - 'SHELL': '/bin/bash', 16:17:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VbkUXIspea/Listeners', 16:17:26 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 16:17:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:26 INFO - 'USER': 'cltbld', 16:17:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:26 INFO - 'XPC_FLAGS': '0x0', 16:17:26 INFO - 'XPC_SERVICE_NAME': '0', 16:17:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:27 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 16:17:27 INFO - Cleaning up... 16:17:27 INFO - Return code: 0 16:17:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:17:27 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')]} 16:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:27 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')]} 16:17:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:27 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 0x102861a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa472555e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c5d30>, '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 0x10252adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027bdb20>, '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.Egw1jaGtTe/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.VbkUXIspea/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 16:17:27 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 16:17:27 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 16:17:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Egw1jaGtTe/Render', 16:17:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:27 INFO - 'HOME': '/Users/cltbld', 16:17:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:27 INFO - 'LOGNAME': 'cltbld', 16:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:27 INFO - 'MOZ_NO_REMOTE': '1', 16:17:27 INFO - 'NO_EM_RESTART': '1', 16:17:27 INFO - 'PAGER': '/bin/cat', 16:17:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:27 INFO - 'PWD': '/builds/slave/test', 16:17:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:27 INFO - 'SHELL': '/bin/bash', 16:17:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VbkUXIspea/Listeners', 16:17:27 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 16:17:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:27 INFO - 'USER': 'cltbld', 16:17:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:27 INFO - 'XPC_FLAGS': '0x0', 16:17:27 INFO - 'XPC_SERVICE_NAME': '0', 16:17:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:27 INFO - Downloading/unpacking psutil>=3.1.1 16:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:17:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:30 INFO - Installing collected packages: psutil 16:17:30 INFO - Running setup.py install for psutil 16:17:30 INFO - building 'psutil._psutil_osx' extension 16:17:30 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 16:17:31 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 16:17:31 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 16:17:31 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 16:17:31 INFO - building 'psutil._psutil_posix' extension 16:17:31 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 16:17:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:17:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:17:31 INFO - ^ 16:17:31 INFO - 1 warning generated. 16:17:31 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 16:17:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:31 INFO - Successfully installed psutil 16:17:31 INFO - Cleaning up... 16:17:31 INFO - Return code: 0 16:17:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:17: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')]} 16:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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')]} 16:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102861a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa472555e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c5d30>, '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 0x10252adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027bdb20>, '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.Egw1jaGtTe/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.VbkUXIspea/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 16:17: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:17: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 mozsystemmonitor==0.3 16:17:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Egw1jaGtTe/Render', 16:17:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:31 INFO - 'HOME': '/Users/cltbld', 16:17:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:31 INFO - 'LOGNAME': 'cltbld', 16:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:31 INFO - 'MOZ_NO_REMOTE': '1', 16:17:31 INFO - 'NO_EM_RESTART': '1', 16:17:31 INFO - 'PAGER': '/bin/cat', 16:17:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:31 INFO - 'PWD': '/builds/slave/test', 16:17:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:31 INFO - 'SHELL': '/bin/bash', 16:17:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VbkUXIspea/Listeners', 16:17:31 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 16:17:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:31 INFO - 'USER': 'cltbld', 16:17:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:31 INFO - 'XPC_FLAGS': '0x0', 16:17:31 INFO - 'XPC_SERVICE_NAME': '0', 16:17:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:17:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:17:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:17:34 INFO - Installing collected packages: mozsystemmonitor 16:17:34 INFO - Running setup.py install for mozsystemmonitor 16:17:34 INFO - Successfully installed mozsystemmonitor 16:17:34 INFO - Cleaning up... 16:17:34 INFO - Return code: 0 16:17:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:17:34 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')]} 16:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:34 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')]} 16:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:34 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 0x102861a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa472555e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c5d30>, '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 0x10252adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027bdb20>, '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.Egw1jaGtTe/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.VbkUXIspea/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 16:17:34 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 16:17:34 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 16:17:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Egw1jaGtTe/Render', 16:17:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:34 INFO - 'HOME': '/Users/cltbld', 16:17:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:34 INFO - 'LOGNAME': 'cltbld', 16:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:34 INFO - 'MOZ_NO_REMOTE': '1', 16:17:34 INFO - 'NO_EM_RESTART': '1', 16:17:34 INFO - 'PAGER': '/bin/cat', 16:17:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:34 INFO - 'PWD': '/builds/slave/test', 16:17:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:34 INFO - 'SHELL': '/bin/bash', 16:17:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VbkUXIspea/Listeners', 16:17:34 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 16:17:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:34 INFO - 'USER': 'cltbld', 16:17:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:34 INFO - 'XPC_FLAGS': '0x0', 16:17:34 INFO - 'XPC_SERVICE_NAME': '0', 16:17:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:34 INFO - Downloading/unpacking jsonschema==2.5.1 16:17: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 16:17: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 16:17: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 16:17: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 16:17: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 16:17: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 16:17:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:17:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:17:37 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 16:17:37 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 16:17:37 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 16:17:37 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 16:17:37 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 16:17:37 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 16:17:37 INFO - Downloading functools32-3.2.3-2.tar.gz 16:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:17:37 INFO - warning: no files found matching '*.txt' 16:17:37 INFO - no previously-included directories found matching 'build' 16:17:37 INFO - no previously-included directories found matching 'dist' 16:17:37 INFO - no previously-included directories found matching '.git*' 16:17:37 INFO - Installing collected packages: jsonschema, functools32 16:17:37 INFO - Running setup.py install for functools32 16:17:38 INFO - warning: no files found matching '*.txt' 16:17:38 INFO - no previously-included directories found matching 'build' 16:17:38 INFO - no previously-included directories found matching 'dist' 16:17:38 INFO - no previously-included directories found matching '.git*' 16:17:38 INFO - Successfully installed jsonschema functools32 16:17:38 INFO - Cleaning up... 16:17:38 INFO - Return code: 0 16:17:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:17:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:38 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 0x102861a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa472555e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c5d30>, '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 0x10252adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027bdb20>, '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.Egw1jaGtTe/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.VbkUXIspea/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 16:17:38 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 16:17:38 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 16:17:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Egw1jaGtTe/Render', 16:17:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:38 INFO - 'HOME': '/Users/cltbld', 16:17:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:38 INFO - 'LOGNAME': 'cltbld', 16:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:38 INFO - 'MOZ_NO_REMOTE': '1', 16:17:38 INFO - 'NO_EM_RESTART': '1', 16:17:38 INFO - 'PAGER': '/bin/cat', 16:17:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:38 INFO - 'PWD': '/builds/slave/test', 16:17:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:38 INFO - 'SHELL': '/bin/bash', 16:17:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VbkUXIspea/Listeners', 16:17:38 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 16:17:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:38 INFO - 'USER': 'cltbld', 16:17:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:38 INFO - 'XPC_FLAGS': '0x0', 16:17:38 INFO - 'XPC_SERVICE_NAME': '0', 16:17:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:38 INFO - Downloading/unpacking blobuploader==1.2.4 16:17: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 16:17: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 16:17: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 16:17: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 16:17: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 16:17: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 16:17:40 INFO - Downloading blobuploader-1.2.4.tar.gz 16:17:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:17:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:17:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:17:41 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 16:17:41 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 16:17:41 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 16:17:41 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 16:17:41 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 16:17:41 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 16:17:41 INFO - Downloading docopt-0.6.1.tar.gz 16:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:17:42 INFO - Installing collected packages: blobuploader, requests, docopt 16:17:42 INFO - Running setup.py install for blobuploader 16:17:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:17:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:17:42 INFO - Running setup.py install for requests 16:17:42 INFO - Running setup.py install for docopt 16:17:42 INFO - Successfully installed blobuploader requests docopt 16:17:42 INFO - Cleaning up... 16:17:43 INFO - Return code: 0 16:17:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:43 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')]} 16:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:43 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')]} 16:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:43 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 0x102861a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa472555e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c5d30>, '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 0x10252adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027bdb20>, '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.Egw1jaGtTe/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.VbkUXIspea/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 16:17:43 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 16:17:43 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 16:17:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Egw1jaGtTe/Render', 16:17:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:43 INFO - 'HOME': '/Users/cltbld', 16:17:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:43 INFO - 'LOGNAME': 'cltbld', 16:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:43 INFO - 'MOZ_NO_REMOTE': '1', 16:17:43 INFO - 'NO_EM_RESTART': '1', 16:17:43 INFO - 'PAGER': '/bin/cat', 16:17:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:43 INFO - 'PWD': '/builds/slave/test', 16:17:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:43 INFO - 'SHELL': '/bin/bash', 16:17:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VbkUXIspea/Listeners', 16:17:43 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 16:17:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:43 INFO - 'USER': 'cltbld', 16:17:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:43 INFO - 'XPC_FLAGS': '0x0', 16:17:43 INFO - 'XPC_SERVICE_NAME': '0', 16:17:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:43 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-sglqT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:43 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-N1ia25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:43 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-koTdQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:43 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-FGTfM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:43 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-O4MJTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:43 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-3IYyXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:43 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-5AF6gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:44 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-4fGvyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:44 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-I6JFvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:44 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-NMKjXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:44 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-H67TAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:44 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-NhlBJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:44 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-rcnvAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:44 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-A1NK60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:44 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-HsR6yK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:44 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-_wLwOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:44 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-LBOX3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:17:45 INFO - Running setup.py install for manifestparser 16:17:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:17:45 INFO - Running setup.py install for mozcrash 16:17:45 INFO - Running setup.py install for mozdebug 16:17:45 INFO - Running setup.py install for mozdevice 16:17:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:17:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:17:45 INFO - Running setup.py install for mozfile 16:17:45 INFO - Running setup.py install for mozhttpd 16:17:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:17:45 INFO - Running setup.py install for mozinfo 16:17:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:17:46 INFO - Running setup.py install for mozInstall 16:17:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:17:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:17:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:17:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:17:46 INFO - Running setup.py install for mozleak 16:17:46 INFO - Running setup.py install for mozlog 16:17:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:17:46 INFO - Running setup.py install for moznetwork 16:17:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:17:46 INFO - Running setup.py install for mozprocess 16:17:46 INFO - Running setup.py install for mozprofile 16:17:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:17:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:17:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:17:47 INFO - Running setup.py install for mozrunner 16:17:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:17:47 INFO - Running setup.py install for mozscreenshot 16:17:47 INFO - Running setup.py install for moztest 16:17:47 INFO - Running setup.py install for mozversion 16:17:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:17:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:17:47 INFO - Cleaning up... 16:17:47 INFO - Return code: 0 16:17:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:47 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 0x102861a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa472555e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c5d30>, '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 0x10252adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027bdb20>, '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.Egw1jaGtTe/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.VbkUXIspea/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 16:17:47 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 16:17:47 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 16:17:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Egw1jaGtTe/Render', 16:17:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:47 INFO - 'HOME': '/Users/cltbld', 16:17:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:47 INFO - 'LOGNAME': 'cltbld', 16:17:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:47 INFO - 'MOZ_NO_REMOTE': '1', 16:17:47 INFO - 'NO_EM_RESTART': '1', 16:17:47 INFO - 'PAGER': '/bin/cat', 16:17:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:47 INFO - 'PWD': '/builds/slave/test', 16:17:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:47 INFO - 'SHELL': '/bin/bash', 16:17:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VbkUXIspea/Listeners', 16:17:47 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 16:17:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:47 INFO - 'USER': 'cltbld', 16:17:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:47 INFO - 'XPC_FLAGS': '0x0', 16:17:47 INFO - 'XPC_SERVICE_NAME': '0', 16:17:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:47 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-rYsE5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:48 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)) 16:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:48 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-oTPclw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:48 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)) 16:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:48 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-Onfed5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:48 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)) 16:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:48 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-HHp4HU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:48 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)) 16:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:48 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-Tz29ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:48 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)) 16:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:48 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-vfqfQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:48 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)) 16:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:48 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-ifH4s3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:48 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)) 16:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:48 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-zsTL2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:48 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)) 16:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:48 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-HN0xzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:48 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)) 16:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:48 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-6KEPuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:17:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:49 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-DwDmGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:49 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)) 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:49 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-kkEN4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:49 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)) 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:49 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-2vX8pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:49 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)) 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:49 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-138Z3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:49 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)) 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:49 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-kRzUYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:49 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)) 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:49 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-EcATKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:49 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)) 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:49 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-GHPvM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:49 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)) 16:17:49 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)) 16:17:49 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)) 16:17:49 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)) 16:17:49 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)) 16:17:49 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)) 16:17:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:17:49 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 16:17:49 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 16:17:49 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 16:17:49 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 16:17:49 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 16:17:49 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 16:17:52 INFO - Downloading blessings-1.6.tar.gz 16:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:17:52 INFO - Installing collected packages: blessings 16:17:52 INFO - Running setup.py install for blessings 16:17:52 INFO - Successfully installed blessings 16:17:52 INFO - Cleaning up... 16:17:52 INFO - Return code: 0 16:17:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:17:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:52 INFO - Reading from file tmpfile_stdout 16:17:52 INFO - Current package versions: 16:17:52 INFO - blessings == 1.6 16:17:52 INFO - blobuploader == 1.2.4 16:17:52 INFO - docopt == 0.6.1 16:17:52 INFO - functools32 == 3.2.3-2 16:17:52 INFO - jsonschema == 2.5.1 16:17:52 INFO - manifestparser == 1.1 16:17:52 INFO - mozInstall == 1.12 16:17:52 INFO - mozcrash == 0.17 16:17:52 INFO - mozdebug == 0.1 16:17:52 INFO - mozdevice == 0.48 16:17:52 INFO - mozfile == 1.2 16:17:52 INFO - mozhttpd == 0.7 16:17:52 INFO - mozinfo == 0.9 16:17:52 INFO - mozleak == 0.1 16:17:52 INFO - mozlog == 3.2 16:17:52 INFO - moznetwork == 0.27 16:17:52 INFO - mozprocess == 0.23 16:17:52 INFO - mozprofile == 0.28 16:17:52 INFO - mozrunner == 6.12 16:17:52 INFO - mozscreenshot == 0.1 16:17:52 INFO - mozsystemmonitor == 0.3 16:17:52 INFO - moztest == 0.7 16:17:52 INFO - mozversion == 1.4 16:17:52 INFO - psutil == 3.1.1 16:17:52 INFO - requests == 1.2.3 16:17:52 INFO - wsgiref == 0.1.2 16:17:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:52 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 0x102861a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa472555e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c5d30>, '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 0x10252adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027bdb20>, '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.Egw1jaGtTe/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.VbkUXIspea/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 16:17:52 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 16:17:52 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 16:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Egw1jaGtTe/Render', 16:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:52 INFO - 'HOME': '/Users/cltbld', 16:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:52 INFO - 'LOGNAME': 'cltbld', 16:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:52 INFO - 'MOZ_NO_REMOTE': '1', 16:17:52 INFO - 'NO_EM_RESTART': '1', 16:17:52 INFO - 'PAGER': '/bin/cat', 16:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:52 INFO - 'PWD': '/builds/slave/test', 16:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:52 INFO - 'SHELL': '/bin/bash', 16:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VbkUXIspea/Listeners', 16:17:52 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 16:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:52 INFO - 'USER': 'cltbld', 16:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:52 INFO - 'XPC_FLAGS': '0x0', 16:17:52 INFO - 'XPC_SERVICE_NAME': '0', 16:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:53 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)) 16:17:53 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)) 16:17:53 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)) 16:17:53 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)) 16:17:53 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)) 16:17:53 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)) 16:17:53 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)) 16:17:53 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)) 16:17:53 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)) 16:17:53 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)) 16:17:53 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)) 16:17:53 INFO - Cleaning up... 16:17:53 INFO - Return code: 0 16:17:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:53 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 0x102861a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028f6618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa472555e90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1027c5d30>, '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 0x10252adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027bdb20>, '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.Egw1jaGtTe/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.VbkUXIspea/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 16:17:53 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 16:17:53 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 16:17:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Egw1jaGtTe/Render', 16:17:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:53 INFO - 'HOME': '/Users/cltbld', 16:17:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:53 INFO - 'LOGNAME': 'cltbld', 16:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:53 INFO - 'MOZ_NO_REMOTE': '1', 16:17:53 INFO - 'NO_EM_RESTART': '1', 16:17:53 INFO - 'PAGER': '/bin/cat', 16:17:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:53 INFO - 'PWD': '/builds/slave/test', 16:17:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:53 INFO - 'SHELL': '/bin/bash', 16:17:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VbkUXIspea/Listeners', 16:17:53 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 16:17:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:53 INFO - 'USER': 'cltbld', 16:17:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:53 INFO - 'XPC_FLAGS': '0x0', 16:17:53 INFO - 'XPC_SERVICE_NAME': '0', 16:17:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:17:53 INFO - Cleaning up... 16:17:53 INFO - Return code: 0 16:17:53 INFO - Running post-action listener: _resource_record_post_action 16:17:53 INFO - Running post-action listener: _start_resource_monitoring 16:17:53 INFO - Starting resource monitoring. 16:17:53 INFO - ##### 16:17:53 INFO - ##### Finished create-virtualenv step (success) 16:17:53 INFO - ##### 16:17:53 INFO - ##### 16:17:53 INFO - ##### Running install step. 16:17:53 INFO - ##### 16:17:53 INFO - Running pre-action listener: _resource_record_pre_action 16:17:53 INFO - Running main action method: install 16:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:53 INFO - Reading from file tmpfile_stdout 16:17:53 INFO - Detecting whether we're running mozinstall >=1.0... 16:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:17:53 INFO - Reading from file tmpfile_stdout 16:17:53 INFO - Output received: 16:17:53 INFO - Usage: mozinstall [options] installer 16:17:53 INFO - Options: 16:17:53 INFO - -h, --help show this help message and exit 16:17:53 INFO - -d DEST, --destination=DEST 16:17:53 INFO - Directory to install application into. [default: 16:17:53 INFO - "/builds/slave/test"] 16:17:53 INFO - --app=APP Application being installed. [default: firefox] 16:17:53 INFO - mkdir: /builds/slave/test/build/application 16:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:18:27 INFO - Reading from file tmpfile_stdout 16:18:27 INFO - Output received: 16:18:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:18:27 INFO - Running post-action listener: _resource_record_post_action 16:18:27 INFO - ##### 16:18:27 INFO - ##### Finished install step (success) 16:18:27 INFO - ##### 16:18:27 INFO - ##### 16:18:27 INFO - ##### Running run-tests step. 16:18:27 INFO - ##### 16:18:27 INFO - Running pre-action listener: _resource_record_pre_action 16:18:27 INFO - Running main action method: run_tests 16:18:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:18:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:18:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:18:27 INFO - 2016-07-13 16:18:27.618 screenresolution[2165:18028] starting screenresolution argv=screenresolution get 16:18:27 INFO - 2016-07-13 16:18:27.621 screenresolution[2165:18028] Display 0: 1600x1200x32@60 16:18:27 INFO - 2016-07-13 16:18:27.627 screenresolution[2166:18030] starting screenresolution argv=screenresolution list 16:18:27 INFO - Available Modes on Display 0 16:18:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:18:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:18:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:18:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:18:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:18:27 INFO - Intel Iris: 16:18:27 INFO - Chipset Model: Intel Iris 16:18:27 INFO - Type: GPU 16:18:27 INFO - Bus: Built-In 16:18:27 INFO - VRAM (Dynamic, Max): 1536 MB 16:18:27 INFO - Vendor: Intel (0x8086) 16:18:27 INFO - Device ID: 0x0a2e 16:18:27 INFO - Revision ID: 0x0009 16:18:27 INFO - Displays: 16:18:27 INFO - Display: 16:18:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:18:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:18:27 INFO - Display Serial Number: 1600x1200 60 16:18:27 INFO - Main Display: Yes 16:18:27 INFO - Mirror: Off 16:18:27 INFO - Online: Yes 16:18:27 INFO - Rotation: Supported 16:18:27 INFO - Return code: 0 16:18:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:18:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:18:27 INFO - Python 2.7.3 16:18:27 INFO - Return code: 0 16:18:27 INFO - grabbing minidump binary from tooltool 16:18:27 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')]} 16:18:27 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 0x1027c5d30>, '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 0x10252adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1027bdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:18:27 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 16:18:27 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 16:18:27 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 16:18:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:18:27 INFO - Return code: 0 16:18:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:18:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:18:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:18:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:18:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:18:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 16:18:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0235 --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 16:18:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Egw1jaGtTe/Render', 16:18:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:27 INFO - 'HOME': '/Users/cltbld', 16:18:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:27 INFO - 'LOGNAME': 'cltbld', 16:18:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:27 INFO - 'MOZ_NO_REMOTE': '1', 16:18:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:27 INFO - 'NO_EM_RESTART': '1', 16:18:27 INFO - 'PAGER': '/bin/cat', 16:18:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:27 INFO - 'PWD': '/builds/slave/test', 16:18:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:27 INFO - 'SHELL': '/bin/bash', 16:18:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VbkUXIspea/Listeners', 16:18:27 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 16:18:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:27 INFO - 'USER': 'cltbld', 16:18:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:27 INFO - 'XPC_FLAGS': '0x0', 16:18:27 INFO - 'XPC_SERVICE_NAME': '0', 16:18:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 16:18:28 INFO - mozversion application_buildid: 20160713141046 16:18:28 INFO - mozversion application_changeset: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 16:18:28 INFO - mozversion application_display_name: Nightly 16:18:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:18:28 INFO - mozversion application_name: Firefox 16:18:28 INFO - mozversion application_remotingname: firefox 16:18:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:18:28 INFO - mozversion application_vendor: Mozilla 16:18:28 INFO - mozversion application_version: 50.0a1 16:18:28 INFO - mozversion platform_buildid: 20160713141046 16:18:28 INFO - mozversion platform_changeset: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 16:18:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:18:28 INFO - mozversion platform_version: 50.0a1 16:18:28 INFO - using testdate: 1468451908 16:18:28 INFO - actual date: 1468451908 16:18:28 INFO - starting webserver on 'localhost:54475' 16:18:28 INFO - SUITE-START | Running 2 tests 16:18:28 INFO - TEST-START | dromaeo_css 16:18:28 INFO - operating with platform_type : mac_ 16:18:28 INFO - Initialising browser for dromaeo_css test... 16:18:28 INFO - TEST-INFO | started process 2177 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpzKHAFE/profile http://localhost:54475/getInfo.html) 16:18:30 INFO - PROCESS | 2177 | __metrics Screen width/height:1600/1200 16:18:30 INFO - PROCESS | 2177 | colorDepth:24 16:18:30 INFO - PROCESS | 2177 | Browser inner width/height: 1024/689 16:18:30 INFO - PROCESS | 2177 | __metrics 16:18:30 INFO - PROCESS | 2177 | 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" 16:18:30 INFO - PROCESS | 2177 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:18:30 INFO - PROCESS | 2177 | ************************* 16:18:30 INFO - PROCESS | 2177 | A coding exception was thrown in a Promise resolution callback. 16:18:30 INFO - PROCESS | 2177 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 16:18:30 INFO - PROCESS | 2177 | 16:18:30 ERROR - PROCESS | 2177 | Full message: TypeError: win is null 16:18:30 INFO - PROCESS | 2177 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:855:17 16:18:30 INFO - PROCESS | 2177 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 16:18:30 INFO - PROCESS | 2177 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 16:18:30 INFO - PROCESS | 2177 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 16:18:30 INFO - PROCESS | 2177 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 16:18:30 INFO - PROCESS | 2177 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 16:18:30 INFO - PROCESS | 2177 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 16:18:30 INFO - PROCESS | 2177 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 16:18:30 INFO - PROCESS | 2177 | postMessage@resource://gre/modules/PromiseWorker.jsm:292:9 16:18:30 INFO - PROCESS | 2177 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 16:18:30 INFO - PROCESS | 2177 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 16:18:30 INFO - PROCESS | 2177 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 16:18:30 INFO - PROCESS | 2177 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 16:18:30 INFO - PROCESS | 2177 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 16:18:30 INFO - PROCESS | 2177 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5 16:18:30 INFO - PROCESS | 2177 | this.DeferredSave.prototype._deferredSave@resource://gre/modules/DeferredSave.jsm:220:5 16:18:30 INFO - PROCESS | 2177 | this.DeferredSave.prototype._startTimer/<@resource://gre/modules/DeferredSave.jsm:175:40 16:18:30 INFO - PROCESS | 2177 | syncLoadManifestFromFile@resource://gre/modules/addons/XPIProvider.jsm:1515:5 16:18:30 INFO - PROCESS | 2177 | addMetadata@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:1647:21 16:18:30 INFO - PROCESS | 2177 | processFileChanges@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2011:23 16:18:30 INFO - PROCESS | 2177 | this.XPIProvider.checkForChanges@resource://gre/modules/addons/XPIProvider.jsm:3726:34 16:18:30 INFO - PROCESS | 2177 | this.XPIProvider.startup@resource://gre/modules/addons/XPIProvider.jsm:2715:25 16:18:30 INFO - PROCESS | 2177 | callProvider@resource://gre/modules/AddonManager.jsm:227:12 16:18:30 INFO - PROCESS | 2177 | _startProvider@resource://gre/modules/AddonManager.jsm:779:5 16:18:30 INFO - PROCESS | 2177 | AddonManagerInternal.startup@resource://gre/modules/AddonManager.jsm:963:9 16:18:30 INFO - PROCESS | 2177 | this.AddonManagerPrivate.startup@resource://gre/modules/AddonManager.jsm:2985:5 16:18:30 INFO - PROCESS | 2177 | amManager.prototype.observe@resource://gre/components/addonManager.js:71:9 16:18:30 INFO - PROCESS | 2177 | 16:18:30 INFO - PROCESS | 2177 | ************************* 16:18:31 INFO - TEST-INFO | 2177: exit 0 16:18:31 INFO - Browser initialized. 16:18:31 INFO - Running cycle 1/1 for dromaeo_css test... 16:18:31 INFO - TEST-INFO | started process 2179 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpzKHAFE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:18:43 INFO - PROCESS | 2179 | RSS: Main: 268013568 16:18:43 INFO - PROCESS | 2179 | 16:19:35 INFO - PROCESS | 2179 | 1468451975208 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] 16:21:45 INFO - PROCESS | 2179 | Cycle 1(1): loaded http://localhost:54475/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54475/tests/dromaeo/cssquery-ext.html) 16:21:46 INFO - PROCESS | 2179 | RSS: Main: 375238656 16:21:46 INFO - PROCESS | 2179 | 16:24:49 INFO - PROCESS | 2179 | Cycle 1(1): loaded http://localhost:54475/tests/dromaeo/cssquery-ext.html (next: http://localhost:54475/tests/dromaeo/cssquery-jquery.html) 16:24:50 INFO - PROCESS | 2179 | RSS: Main: 404271104 16:24:50 INFO - PROCESS | 2179 | 16:27:52 INFO - PROCESS | 2179 | Cycle 1(1): loaded http://localhost:54475/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54475/tests/dromaeo/cssquery-mootools.html) 16:27:53 INFO - PROCESS | 2179 | RSS: Main: 393576448 16:27:53 INFO - PROCESS | 2179 | 16:30:55 INFO - PROCESS | 2179 | Cycle 1(1): loaded http://localhost:54475/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54475/tests/dromaeo/cssquery-prototype.html) 16:30:56 INFO - PROCESS | 2179 | RSS: Main: 399704064 16:30:56 INFO - PROCESS | 2179 | 16:33:59 INFO - PROCESS | 2179 | Cycle 1(1): loaded http://localhost:54475/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54475/tests/dromaeo/cssquery-yui.html) 16:33:59 INFO - PROCESS | 2179 | RSS: Main: 364568576 16:33:59 INFO - PROCESS | 2179 | 16:37:02 INFO - PROCESS | 2179 | Cycle 1(1): loaded http://localhost:54475/tests/dromaeo/cssquery-yui.html (next: http://localhost:54475/tests/dromaeo/cssquery-dojo.html) 16:37:03 INFO - PROCESS | 2179 | RSS: Main: 419835904 16:37:03 INFO - PROCESS | 2179 | 16:37:03 INFO - PROCESS | 2179 | __start_tp_report 16:37:03 INFO - PROCESS | 2179 | _x_x_mozilla_page_load 16:37:03 INFO - PROCESS | 2179 | _x_x_mozilla_page_load_details 16:37:03 INFO - PROCESS | 2179 | |i|pagename|runs| 16:37:03 INFO - PROCESS | 2179 | |0;dojo.html;3155.5266709993507;3352.1284466038824;3375.3924293627138;3410.795352278862;3443.362977849098;5632.35227948786;5690.4025077366905;5720.027595308797;5764.193012978181;5764.971175144124;5713.914291285634;5722.79970201043;5819.592628516006;5856.824295271138;5872.70636468176;5105.923411148836;5146.202338637517;5221.634485586011;5235.528802407783;5314.202869569557;5300;5336.759845806948;5338;5349.491798279177;5390.78436862527;5130.256112863613;5166.4575219601775;5180;5230.110881150175;5264.341957255343;5194.792208311663;5209.765560549747;5236.423993360727;5253.711045892474;5345;5206.817761378372;5230.056603208518;5238.60710446718;5303.681779093228;5318.095923692981;5646.322441307028;5705.4579814917515;5782.88434231313;5784.739686714108;5855.795047173371;6235.719392627343;6293.590916590453;6318.968405157991;6333.7466501339895;6357.968210158966;6317.305096439388;6336.52476064297;6369.235691716977;6381.234251889757;6388.041793730978;5708.628939118944;5751.597388182787;5757.596968212269;5846.9707651461895;5924.259467566554;6335.841603959846;6346.143270658402;6357.300696923335;6359.077933699587;6362.2683391409655;6090.1887508868385;6129.724162412613;6142.959235178615;6184.598001130003;6238.407351301619;6303.086052522359;6362.650054247061;6370.012648039561;6374.426301632875;6374.490040796725;6051.0923361496125;6060.818175454743;6315.242170939517;6319.747210111636;6333.334999824952;6195.287541932646;6284.214473190851;6300.495960323163;6322;6348.269948955837;4988.7505624718615;5030.698158110525;5038.874028149252;5043.268726034705;5077.26379674945;4455.019895622958;4457.3091170868565;4533;4538.863834084983;4560.1335746208115;5734.77060917567;5786.566007549451;5789.94210057894;5805.796797112165;5860.736266867916;6323.873522529524;6372.58578192416;6376.011718183689;6376.330485298977;6382.680865956683;5515.917261241085;5551.250581171571;5809.477147056785;5809.883802324014;5860.062390017577;5441.918371224435;5520.53075488588;5525.944740552543;5538;5544.750486228129;5290.867728306746;6320.873582528416;6371.649559274192;6375.330590288046;6386.9361306386345;6159.322474527708;6177.907331390123;6316.936830631635;6353.841153971188;6380.298167201697;6305.905411418924;6317.652529110943;6326.051092336186;6334.873302534015;6351.428371446407;6159.630422174684;6177.2587289525545;6177.96911015442;6177.96911015442;6188.752449901954;4768.38011058574;5859.121131830259;5869.295684517885;6141.969290153699;6247.219097612799;5743.310802703702;5766.221560089334;5771;5773.422657734193;5800.738966746591;5688.431156884279;5748.6838223898085;5759.136129580596;5765.625234359753;5772.336181339032;4948.703077815343;5607.467290607386;5863.882722345615;5932.317783454785;5981.850453738692;6171.290301615371;6181.041938499539;6183.752649893954;6191.473724733268;6202.34875338083;5920.37836027211;5949.762009519571;5967.343592204941;5986.16193732869;6040.426159515015 16:37:03 INFO - PROCESS | 2179 | |1;ext.html;493.9975300123499;515.2400209690707;525.2620068803333;532.8534652970434;537.4383768961435;26605.334866628345;42896;44214.33678494825;44316.778416107874;44327;45013;45314.867128321726;45444.31833522492;45519.544804551944;45519.772401138034;9752.951235243814;9934.00659934008;9940.502974851264;9960.352577082504;9989.400635961829;9864.802703945918;9960.950195249014;9968.900310996905;9971.05274998874;9978;9937;9990.200783937267;9996.950015249913;10009.349392289478;10009.349392289514;9916.405015699082;9985.850212246822;9987.250956178243;9995.400275983391;10004.649837255698;9759.219262458986;9849.507524623812;9850.802983940353;9852.852207216898;9859.161971232383;42887.35668964968;43312.56687433148;43340;43409.13181736379;43409.782951085355;3420.435628121357;3554.893353199408;3638.490611314418;3699.667029967316;3988.202359528077;29960.70039299601;30728.385432291452;32775.6722432775;32884.01347959565;33582.65669373222;3301.1747063234193;3321.7674762766615;3323.3685599736214;3334.61651910031;3358.899233023013;549255;550592;551381;551394.2430287863;551937.2403137998;65237.67381163064;65877;65904;66058;66102.66948665322;35856.74501392491;36194.81902590523;67842;67890.98163527552;68126;14907.552773416815;16905.802245708226;16940.068296243826;17317.393891213746;17412.825871741374;13162;13271.270080145685;13274.336283185801;13274.336283185996;13274.933625332005;20307;20498.79501204969;20529.2814751483;20537.79462205359;20552.07515661799;7826.530408175528;7877.803054923673;7903.841923161504;7906;7938.166492518318;951.8905325887612;994.3785134291068;997.8852431970416;1025.661531694554;1031.9638812641522;29909.551356729666;31249;31423.528647070314;31488.685113149033;32231.677683222868;894.9060348663427;1060.0088916862758;1139.2936379444798;1201.6094769200045;1206.7827790997528;14651.780223296659;14670.853291466949;15922.76115858263;16038.278277477542;16141.677166456606;703615;715995;722127;722314;723273;62264.06603900945;62709.37290627127;62766.781820090364;62881.68559157266;63219.36780632227;1279.3475327583;1393.8327400712183;1408.3732738931078;1434.8349939756645;1442.4879167895456;31000.996015936256;32324;32598.34803303968;32699.182520437178;33173.17067073342;52568.47431525789;52934.94130117299;53460.73269633627;53595.732021339645;53604.92790144097;548480;552591;552622;553024;554741;22145.557088858455;24567;24911.128110516045;26037.479250415265;27624.895004199607;50170.7491462555;50696.74651626718;51156.23265650944;51734.22398664098;51759.74120129375;39092.413613795245;40274.59725402708;41126.38310425286;42027.36958945679;42063.579364205965;38215;41021;41342.96642583839;41645.58354416417;42546;39448.605513944494;39798.801005994785;39800.60199397969;40272;40858.591414086666;35340.46989295214;35902.820485897406;36518.08904686591;36573.817130914176;37108;25196.24411267665;26303;26511.469770604093;26615.93536258528;26986 16:37:03 INFO - PROCESS | 2179 | |2;jquery.html;370.4461829564798;392.4682055814372;395.778364116095;398.2632130558466;416.7561976243897;14600.70798584029;20641.587168256625;20960.26639067633;21614.783852161472;21686.24098156565;17338.739918901225;20171.09230084646;20939.371818845422;21463.356099317007;22289.777102228974;5879.7648094076185;5912.4974377178;6046.939530604682;6074.787382441616;6085.543584231178;5731.885362292752;5842.415758424145;5893.057110862263;5911.231539899807;5916.2900451945825;5759.942400576004;5839.854003649922;5847.239858818348;5871.677057761843;5878.382769809173;5690.60165788395;5814.680192589406;5852.707364631751;5868.325142608612;5901.704914754267;4989.126902791997;5126.974365128188;5142.408623008327;5307.177387504943;5308.761105750251;12984.28586427746;13235.602931912059;13268.40292186854;21604.243851465282;25766.840492177897;2160.1359456217483;2162.7080344153496;2277.487565297811;2579.3164811325014;2593.9351516212055;10125.544350504246;10154.695359139238;10203.744906377326;10293.948530257301;10295.536700848479;1830.7803063632316;1849.5653521422455;1884.7738271407518;1916.7699876014829;1951.6584597695346;74713.70848526627;76164.61917690375;82234.58882705549;117855;118688.21967670406;9489.762755931157;10954.068904143078;11114.777704446027;11125.94437027826;11514.36670983104;14131.78802317966;14215.786763198561;14221.786673199911;14222.857771422154;14234.078399416154;8357.247847693738;8541.359398045171;8582.270507006975;8628.439151455135;8694.043655197924;8628.827423451496;8639;8666.436681615674;8768.254698350584;8794.692185773596;3946.565877753445;4432.401625780542;4503.572160644733;4558.4529856416775;4566.70316429431;1735.6094878652455;1739.5303268117536;1916.559191385989;1917.472695008856;1920.6062757135032;881.625309243569;913.7715571107224;939.1876027236527;988.8961659025699;989.1789814453986;21193.36419907405;22082.88958555197;22124;22225;22241.221557245743;963.0706368354571;967.8209531236744;970.7670159161751;993.4833886379042;1007.2848277722752;11055;11475.77048459026;11577.189596728149;11784.292942423483;11786.410679465993;555702;558011;561005;567876.4092693065;570260;123749;126389;127288;132957;134506.96479105644;950.7337945375168;989.3816364772017;992.5731935267904;995.875515560555;1027.445179603006;15751;18901.716474252622;18994.620107597493;19143;19429.319973800848;49732.25937092184;50324;50378;52918.94162116659;53262;76859.84710229239;77176.84234736371;78234.6088269555;78298.6085069571;79475;5565.972170139124;7864.134945155914;8005.159458257032;8111.107778144517;8242.587870606565;10594.417307048187;12416.565420210249;12793.744125117631;13234.007449441138;13252.271125088213;5467.261919640798;9388.718338449857;11138.721531961766;11145.331280122895;11325;10414.271001029856;10911.345319280868;10948.671539853816;11078.279911805706;11354.489047992694;9043.547822608713;11347.20569560123;11362;11438.542458301576;11576.536938522364;8049.476784009021;8202.220789025034;8248.628811703607;8263.256306932404;8411.116832732476;10323.225758068325;10958.835617465586;11010.614628487965;11095.833562496407;11145.832812507657 16:37:03 INFO - PROCESS | 2179 | |3;mootools.html;1408.7605107131787;1569.2702893154685;1599.3362754456898;1816.8364847163753;1956.5206524401524;7913.802154946129;8810.735677929657;9093.408928419649;9097.272218222543;9175.17423431891;7366.963165184166;8838.955805220965;9029.097090290968;9032.819343613124;9050.1854833065;2116.904739286736;2337.731160916491;2381.785639292463;2418.625113107471;2424.612062070069;2413.227767114524;2452.2030340139436;2456.3736247256925;2456.7174774900845;2458.483718419134;2375.95248095038;2401.5317013182434;2405.050005247926;2420.2376251480728;2421.5762241607736;2101.495641046153;2305.204704376987;2373.0270589058578;2376.2871138658334;2390.020091762378;2385.689860318165;2406.1818981546357;2407.0973384980634;2419.0928401849305;2421.1646981791255;9058.592363343665;9116.31627627924;9117.361784675075;9136.908630913673;9137.634494620208;5230.816921407771;5231.633785635007;5359.410464848864;5383.865403364908;5399.9730001350135;3574.088607405121;3631.473436351714;3705.8332375043187;3711.888643340704;3716.9256614867822;874.7987962768535;1066.1470823341297;1072.3994563044641;1088.1566785741113;1088.5917780832187;12033.217840840318;12035.157538972275;12048.698782530508;12050.879491205063;12070.69823254417;4009.3384591542067;4629.490756016836;4728.3143944127905;4865.781039853145;4872.610191184696;10081.294309398272;10092;10095.899041009643;10102.949485252526;10141;5403.216533602685;5403.5136837684795;5411.675299481965;5415.512603865671;5420.620556561082;3490.877819276313;4362.650987920959;4599.747013914267;4893.241547560134;4957.975210123998;3972.801359931992;3973.1854969731135;4040.2727509048655;4091.85678501251;4097.733647312915;1295.5077070713257;1296.5850927703045;1302.8306320178506;1307.2875282970804;1315.3160356614699;851.8211175653182;929.4887811703536;948.7153853843819;957.0764212534938;997.665781963036;6017.3681763414215;6039.969800151059;6110.847228819226;6196.690165491797;6220.657863817443;450.6665067849833;460.2199272233532;461.3886600254676;473.16722568279386;474.2885671492761;3603.72972027099;3656.9634303657153;3988.4017397390044;4034.596540345942;4194.161167766398;10510.789784204273;11073.114150867912;11079.833802492969;11094.44527773608;11132.942370474791;10887.401192934465;10994.615188468366;10995.450227488594;11015.284006539548;11047.72380690473;8400.201980811813;8490.023647280519;8495.70265040733;8498.74503764888;8512.574371281411;5911.379305173067;6022.668753218615;6051.4553690168095;6139.2325959255095;6265;9921.900780992097;10306.536205870603;10341;10418.791624167627;10425.374477531372;10403.895961040293;10427.478626068818;10440.53017614194;10453.163746900382;10468.842967355344;7633.084029916446;7649.502782319131;7650.961745191238;7720.922790772021;7757.7672669819995;8339.12439193876;8421.62102705392;8493.278071364004;8500.44747091437;8500.97988241415;6548.705308261235;7493.250674932463;7653.57905315213;7682.884756728766;7795.922040779747;7441.255874412515;7491.43814213947;7491.737789177353;7630.656620451982;7678.001859758196;7037.894431583428;7051.294870513113;7072.186698529735;7094.858102838018;7100.396466300423;4980.5019498049905;5028.974855125701;5080;5145.228215767665;5158.509941555546;5163.767630456714;5192.558632516128;5193.246979187921;5270.631055826056;5352.759125839269 16:37:03 INFO - PROCESS | 2179 | |4;prototype.html;293.53767815690287;304.31680876432415;305.3435114503818;323.74747696796493;324.438721012648;4332.80502377393;4823.528823708767;5030.465707959071;5250.55370293525;5280.128778751507;4527.261007090704;4995.175795993657;5311.601629877765;5376.548331950371;5376.623636345457;3758.5113935188388;4023.425007994871;4079.388091786226;4145.481814773153;4179.038821071148;3904.3752999520084;3976.1848820991772;4146.419501269825;4151.564085770996;4152.979235103835;3657.305112028706;3703.241085391374;3970.4441378207075;3995.1011022519992;4175.1858387614275;3440.9483857742152;4047.846363786336;4073.9185216295655;4081.3265811141127;4147.6889233307475;2970.1831996201004;3566.340227057977;3606.314800187982;3606.8376923038527;3613.512175856278;4084.4485994390675;4183.874483765492;5132.538071573539;5146.356705411824;5210.635255532115;4655.2784318430695;4667.136579732726;4677.766111694402;4724.881877953078;4733.881652958669;4618.699784513996;5400;5503.624093976506;5555;5608.298962629671;3500.3874321993803;3790.5261842269715;3869.3228684980018;3869.690424766012;3899.74651647642;5083.678243656639;5422.783088676449;5591.077472217039;5601.271834661508;5823.330317013555;3755.774653520743;3838.251540949544;3852.57621661617;3859.8842034739005;3872.7800742766503;3318.435865902802;3526.2947410517486;3809.761827406104;3957.782321972262;3962.385830196324;4052.3313653246882;4320.595806362945;4603.562661547147;4603.861884143481;4626.352310676508;5364.973175134178;5499.50504454601;5539.279893613804;5568.777248910079;5640.407757185437;1010.9696709098739;1312.835895513061;1315.9013074019488;1324.198859689893;1340.376724822962;695.2352412346379;702.7891632510226;716.8458781362003;783.4202690009351;811.8579248631465;3901.3757798752063;4021.04453226878;4167.4788702124115;4202.642775364128;4366.223216917344;4210.5578914214175;4271.466066741657;4715.882102947385;4728.574428301469;4741.762911854394;4252.574742525722;4274.793904571571;4326.680362489408;4336.373859802574;4356.978215108904;2304.4238940264936;2365.3968238099174;2454.6686197363483;2467.4201562632766;2475.588914318821;4348.847790327323;4826.855194344175;4921.901561968741;4924.4090709115135;4989.351384320015;4297.226499230106;4570.405847239837;4570.840020599263;4776;4867.586255168279;4567.6802623816675;4891.853244402673;4947.381577302837;5020.623453240948;5055.646104772631;4223.944013996501;4312.374705667723;4570.771461426849;4741.312509686008;4762.714237145782;3924.941125883171;4010.057636455372;4461.420015397977;4767.33257343979;4794.568488836021;4271.658267338667;4847.903521524307;4923.704577725348;5129.38424396314;5310.9468905312;3155.7002084801907;3194.211241704653;3786.261678972574;3831.5593706724085;3841.366174581163;5200.8274952279835;5234.560495863668;5673.687947162945;5727.599068065196;5730;5325.046858044725;5327.549327309481;5497.47773961473;5523.420040895809;5552.888942221214;4926.581240594446;5428.525743524069;5457.4622800398;5505.944940550543;5513.448655134454;5133.666311689728;5431.270010139341;5446.400895901527;5449.863753406196;5484.808031718871;4634.957134644678;4664.626829853672;5161.277421160966;5483.972580137074;5564.1066645345;4484.596386325246;5383.569314454912;5426.639411855697;5448.237246785532;5451.727413629382 16:37:03 INFO - PROCESS | 2179 | |5;yui.html;742.5544673196081;753.2204168685405;762.7955209444702;779.8401327727817;853.3727710133052;14900.254987250635;16207.91896040518;16234.83765162348;16240.75638865418;16242.269097890594;14569.635759106028;14759.704805903903;14780.965332426735;14811.333489992949;14813.963022588423;710.8116349167475;732.9340359367656;901.9458832470073;919.6597259014175;920.2867777472445;888.3337496877342;917.2111983694018;919.0533750237233;922;928.9303302252324;945.3902233059672;960.3709570231474;962.1196605106319;962.4129281138535;965.0446058402166;615.215600109859;942.189716843514;953.5422996961428;954.398728800858;957.4973139102027;726.8146622611254;727.1274470635259;727.6943683652826;727.9599622020787;741.525423728814;15234.238288085551;15246.23768811555;15312.464063757716;15323.923380383138;15351.232438378036;14240.715185696332;14470.782938255934;14493;14568.198749068797;14923.403063877431;13160.736785264242;13244.735105297936;13297.86702132976;13320.933395333059;13432.059755817098;4648.558386953234;4702.8118875244945;4725.362076119749;4750.311204875274;4758.976205118952;119613;123929.76070239273;124713.37643311816;126041;132538;11425.428728563538;11483.392180266412;11518.36648984297;11640.185187036996;11708.88291117095;23433.41416464568;23455;23463.88268058649;23465.648015279785;23515.647265291034;7963.044434667877;8016.839663206703;8142;8148.307393871469;8350.498970061815;4264.765437900884;4450.287953927356;4511.233090374644;4687.39063921688;4691.5308469152815;3412.1128506588466;3534.9469757953652;3540.522029525981;3545.432730763117;3554.9690589729207;654.3325807676143;667.2993356975156;669.0499490723184;682.0451368084622;686.6120641837446;628.9245290565161;630.9558330916792;644.2655372874926;648.7145655911384;649.1787888321277;8766.649334026695;8859;8958.283337332998;8966.910330896608;9170;627.8179327994922;637.1366797988751;643.1831573901213;651.4169818012947;656.875193713193;3652.324320000755;4395.890102747457;4424.911501769947;4483.983996421522;4544.636429085731;83660.58169709235;97057.5147124274;160388;162723;171071;76800.6775270268;100973.49513252387;107445;114076.42961785138;114206.42896785463;99568;102139;102826;103441;105505.47247263715;9380.48185845523;9501.952490237505;9826.803463930539;9864.605415783575;9884.604615815288;49549.761255968886;57094;61882.071768924405;62467.750644985936;65565.67217163884;71662;74570;85051.5747421259;86189;91419;11836.881631183922;13441.865581344062;13583.308798211561;13593.59219223425;13931.58205253844;15259.313330899915;15387.153706546247;15507.76738348903;15520.84479155194;15785.131817750134;13139.671508212372;14140.080894741808;14331.710146086902;14377.137371757728;14734.336954836843;12427.502899883903;14427.206503642401;14431.3505892233;14455.566333010027;14514.564563063124;13932.512362067278;13979.930100349433;14005.789913151515;14053.36759845789;14084.718305633625;9780.510974451392;11492.540298388305;11560.826587601361;11696.649100526998;12110.333931633844;10854.565817367216;10987.230893837355;11151.386673733023;11370.886291136983;11798.469068891749 16:37:03 INFO - PROCESS | 2179 | __end_tp_report 16:37:03 INFO - PROCESS | 2179 | __start_cc_report 16:37:03 INFO - PROCESS | 2179 | _x_x_mozilla_cycle_collect,1172 16:37:03 INFO - PROCESS | 2179 | __end_cc_report 16:37:03 INFO - PROCESS | 2179 | __startTimestamp1468453023152__endTimestamp 16:37:03 INFO - PROCESS | 2179 | 16:37:03 INFO - PROCESS | 2179 | ------- Summary: start ------- 16:37:03 INFO - PROCESS | 2179 | Number of tests: 6 16:37:03 INFO - PROCESS | 2179 | 16:37:03 INFO - PROCESS | 2179 | [#0] dojo.html Cycles:165 Average:5747.91 Median:5846.97 stddev:642.34 (11.0%) stddev-sans-first:611.27 16:37:03 INFO - PROCESS | 2179 | Values: 3155.5 3352.1 3375.4 3410.8 3443.4 5632.4 5690.4 5720.0 5764.2 5765.0 5713.9 5722.8 5819.6 5856.8 5872.7 5105.9 5146.2 5221.6 5235.5 5314.2 5300.0 5336.8 5338.0 5349.5 5390.8 5130.3 5166.5 5180.0 5230.1 5264.3 5194.8 5209.8 5236.4 5253.7 5345.0 5206.8 5230.1 5238.6 5303.7 5318.1 5646.3 5705.5 5782.9 5784.7 5855.8 6235.7 6293.6 6319.0 6333.7 6358.0 6317.3 6336.5 6369.2 6381.2 6388.0 5708.6 5751.6 5757.6 5847.0 5924.3 6335.8 6346.1 6357.3 6359.1 6362.3 6090.2 6129.7 6143.0 6184.6 6238.4 6303.1 6362.7 6370.0 6374.4 6374.5 6051.1 6060.8 6315.2 6319.7 6333.3 6195.3 6284.2 6300.5 6322.0 6348.3 4988.8 5030.7 5038.9 5043.3 5077.3 4455.0 4457.3 4533.0 4538.9 4560.1 5734.8 5786.6 5789.9 5805.8 5860.7 6323.9 6372.6 6376.0 6376.3 6382.7 5515.9 5551.3 5809.5 5809.9 5860.1 5441.9 5520.5 5525.9 5538.0 5544.8 5290.9 6320.9 6371.6 6375.3 6386.9 6159.3 6177.9 6316.9 6353.8 6380.3 6305.9 6317.7 6326.1 6334.9 6351.4 6159.6 6177.3 6178.0 6178.0 6188.8 4768.4 5859.1 5869.3 6142.0 6247.2 5743.3 5766.2 5771.0 5773.4 5800.7 5688.4 5748.7 5759.1 5765.6 5772.3 4948.7 5607.5 5863.9 5932.3 5981.9 6171.3 6181.0 6183.8 6191.5 6202.3 5920.4 5949.8 5967.3 5986.2 6040.4 16:37:03 INFO - PROCESS | 2179 | 16:37:03 INFO - PROCESS | 2179 | [#1] ext.html Cycles:180 Average:74376.49 Median:42304.79 stddev:163684.42 (386.9%) stddev-sans-first:164049.60 16:37:03 INFO - PROCESS | 2179 | Values: 494.0 515.2 525.3 532.9 537.4 26605.3 42896.0 44214.3 44316.8 44327.0 45013.0 45314.9 45444.3 45519.5 45519.8 9753.0 9934.0 9940.5 9960.4 9989.4 9864.8 9961.0 9968.9 9971.1 9978.0 9937.0 9990.2 9997.0 10009.3 10009.3 9916.4 9985.9 9987.3 9995.4 10004.6 9759.2 9849.5 9850.8 9852.9 9859.2 42887.4 43312.6 43340.0 43409.1 43409.8 3420.4 3554.9 3638.5 3699.7 3988.2 29960.7 30728.4 32775.7 32884.0 33582.7 3301.2 3321.8 3323.4 3334.6 3358.9 549255.0 550592.0 551381.0 551394.2 551937.2 65237.7 65877.0 65904.0 66058.0 66102.7 35856.7 36194.8 67842.0 67891.0 68126.0 14907.6 16905.8 16940.1 17317.4 17412.8 13162.0 13271.3 13274.3 13274.3 13274.9 20307.0 20498.8 20529.3 20537.8 20552.1 7826.5 7877.8 7903.8 7906.0 7938.2 951.9 994.4 997.9 1025.7 1032.0 29909.6 31249.0 31423.5 31488.7 32231.7 894.9 1060.0 1139.3 1201.6 1206.8 14651.8 14670.9 15922.8 16038.3 16141.7 703615.0 715995.0 722127.0 722314.0 723273.0 62264.1 62709.4 62766.8 62881.7 63219.4 1279.3 1393.8 1408.4 1434.8 1442.5 31001.0 32324.0 32598.3 32699.2 33173.2 52568.5 52934.9 53460.7 53595.7 53604.9 548480.0 552591.0 552622.0 553024.0 554741.0 22145.6 24567.0 24911.1 26037.5 27624.9 50170.7 50696.7 51156.2 51734.2 51759.7 39092.4 40274.6 41126.4 42027.4 42063.6 38215.0 41021.0 41343.0 41645.6 42546.0 39448.6 39798.8 39800.6 40272.0 40858.6 35340.5 35902.8 36518.1 36573.8 37108.0 25196.2 26303.0 26511.5 26615.9 26986.0 16:37:03 INFO - PROCESS | 2179 | 16:37:03 INFO - PROCESS | 2179 | [#2] jquery.html Cycles:180 Average:33062.23 Median:381.46 stddev:93860.51 (24605.8%) stddev-sans-first:94091.72 16:37:03 INFO - PROCESS | 2179 | Values: 370.4 392.5 395.8 398.3 416.8 14600.7 20641.6 20960.3 21614.8 21686.2 17338.7 20171.1 20939.4 21463.4 22289.8 5879.8 5912.5 6046.9 6074.8 6085.5 5731.9 5842.4 5893.1 5911.2 5916.3 5759.9 5839.9 5847.2 5871.7 5878.4 5690.6 5814.7 5852.7 5868.3 5901.7 4989.1 5127.0 5142.4 5307.2 5308.8 12984.3 13235.6 13268.4 21604.2 25766.8 2160.1 2162.7 2277.5 2579.3 2593.9 10125.5 10154.7 10203.7 10293.9 10295.5 1830.8 1849.6 1884.8 1916.8 1951.7 74713.7 76164.6 82234.6 117855.0 118688.2 9489.8 10954.1 11114.8 11125.9 11514.4 14131.8 14215.8 14221.8 14222.9 14234.1 8357.2 8541.4 8582.3 8628.4 8694.0 8628.8 8639.0 8666.4 8768.3 8794.7 3946.6 4432.4 4503.6 4558.5 4566.7 1735.6 1739.5 1916.6 1917.5 1920.6 881.6 913.8 939.2 988.9 989.2 21193.4 22082.9 22124.0 22225.0 22241.2 963.1 967.8 970.8 993.5 1007.3 11055.0 11475.8 11577.2 11784.3 11786.4 555702.0 558011.0 561005.0 567876.4 570260.0 123749.0 126389.0 127288.0 132957.0 134507.0 950.7 989.4 992.6 995.9 1027.4 15751.0 18901.7 18994.6 19143.0 19429.3 49732.3 50324.0 50378.0 52918.9 53262.0 76859.8 77176.8 78234.6 78298.6 79475.0 5566.0 7864.1 8005.2 8111.1 8242.6 10594.4 12416.6 12793.7 13234.0 13252.3 5467.3 9388.7 11138.7 11145.3 11325.0 10414.3 10911.3 10948.7 11078.3 11354.5 9043.5 11347.2 11362.0 11438.5 11576.5 8049.5 8202.2 8248.6 8263.3 8411.1 10323.2 10958.8 11010.6 11095.8 11145.8 16:37:03 INFO - PROCESS | 2179 | 16:37:03 INFO - PROCESS | 2179 | [#3] mootools.html Cycles:180 Average:5814.94 Median:4678.90 stddev:3318.19 (70.9%) stddev-sans-first:3310.98 16:37:03 INFO - PROCESS | 2179 | Values: 1408.8 1569.3 1599.3 1816.8 1956.5 7913.8 8810.7 9093.4 9097.3 9175.2 7367.0 8839.0 9029.1 9032.8 9050.2 2116.9 2337.7 2381.8 2418.6 2424.6 2413.2 2452.2 2456.4 2456.7 2458.5 2376.0 2401.5 2405.1 2420.2 2421.6 2101.5 2305.2 2373.0 2376.3 2390.0 2385.7 2406.2 2407.1 2419.1 2421.2 9058.6 9116.3 9117.4 9136.9 9137.6 5230.8 5231.6 5359.4 5383.9 5400.0 3574.1 3631.5 3705.8 3711.9 3716.9 874.8 1066.1 1072.4 1088.2 1088.6 12033.2 12035.2 12048.7 12050.9 12070.7 4009.3 4629.5 4728.3 4865.8 4872.6 10081.3 10092.0 10095.9 10102.9 10141.0 5403.2 5403.5 5411.7 5415.5 5420.6 3490.9 4362.7 4599.7 4893.2 4958.0 3972.8 3973.2 4040.3 4091.9 4097.7 1295.5 1296.6 1302.8 1307.3 1315.3 851.8 929.5 948.7 957.1 997.7 6017.4 6040.0 6110.8 6196.7 6220.7 450.7 460.2 461.4 473.2 474.3 3603.7 3657.0 3988.4 4034.6 4194.2 10510.8 11073.1 11079.8 11094.4 11132.9 10887.4 10994.6 10995.5 11015.3 11047.7 8400.2 8490.0 8495.7 8498.7 8512.6 5911.4 6022.7 6051.5 6139.2 6265.0 9921.9 10306.5 10341.0 10418.8 10425.4 10403.9 10427.5 10440.5 10453.2 10468.8 7633.1 7649.5 7651.0 7720.9 7757.8 8339.1 8421.6 8493.3 8500.4 8501.0 6548.7 7493.3 7653.6 7682.9 7795.9 7441.3 7491.4 7491.7 7630.7 7678.0 7037.9 7051.3 7072.2 7094.9 7100.4 4980.5 5029.0 5080.0 5145.2 5158.5 5163.8 5192.6 5193.2 5270.6 5352.8 16:37:03 INFO - PROCESS | 2179 | 16:37:03 INFO - PROCESS | 2179 | [#4] prototype.html Cycles:180 Average:4236.82 Median:4587.20 stddev:1284.79 (28.0%) stddev-sans-first:1253.84 16:37:03 INFO - PROCESS | 2179 | Values: 293.5 304.3 305.3 323.7 324.4 4332.8 4823.5 5030.5 5250.6 5280.1 4527.3 4995.2 5311.6 5376.5 5376.6 3758.5 4023.4 4079.4 4145.5 4179.0 3904.4 3976.2 4146.4 4151.6 4153.0 3657.3 3703.2 3970.4 3995.1 4175.2 3440.9 4047.8 4073.9 4081.3 4147.7 2970.2 3566.3 3606.3 3606.8 3613.5 4084.4 4183.9 5132.5 5146.4 5210.6 4655.3 4667.1 4677.8 4724.9 4733.9 4618.7 5400.0 5503.6 5555.0 5608.3 3500.4 3790.5 3869.3 3869.7 3899.7 5083.7 5422.8 5591.1 5601.3 5823.3 3755.8 3838.3 3852.6 3859.9 3872.8 3318.4 3526.3 3809.8 3957.8 3962.4 4052.3 4320.6 4603.6 4603.9 4626.4 5365.0 5499.5 5539.3 5568.8 5640.4 1011.0 1312.8 1315.9 1324.2 1340.4 695.2 702.8 716.8 783.4 811.9 3901.4 4021.0 4167.5 4202.6 4366.2 4210.6 4271.5 4715.9 4728.6 4741.8 4252.6 4274.8 4326.7 4336.4 4357.0 2304.4 2365.4 2454.7 2467.4 2475.6 4348.8 4826.9 4921.9 4924.4 4989.4 4297.2 4570.4 4570.8 4776.0 4867.6 4567.7 4891.9 4947.4 5020.6 5055.6 4223.9 4312.4 4570.8 4741.3 4762.7 3924.9 4010.1 4461.4 4767.3 4794.6 4271.7 4847.9 4923.7 5129.4 5310.9 3155.7 3194.2 3786.3 3831.6 3841.4 5200.8 5234.6 5673.7 5727.6 5730.0 5325.0 5327.5 5497.5 5523.4 5552.9 4926.6 5428.5 5457.5 5505.9 5513.4 5133.7 5431.3 5446.4 5449.9 5484.8 4635.0 4664.6 5161.3 5484.0 5564.1 4484.6 5383.6 5426.6 5448.2 5451.7 16:37:03 INFO - PROCESS | 2179 | 16:37:03 INFO - PROCESS | 2179 | [#5] yui.html Cycles:180 Average:23891.82 Median:3958.54 stddev:37262.84 (941.3%) stddev-sans-first:37326.84 16:37:03 INFO - PROCESS | 2179 | Values: 742.6 753.2 762.8 779.8 853.4 14900.3 16207.9 16234.8 16240.8 16242.3 14569.6 14759.7 14781.0 14811.3 14814.0 710.8 732.9 901.9 919.7 920.3 888.3 917.2 919.1 922.0 928.9 945.4 960.4 962.1 962.4 965.0 615.2 942.2 953.5 954.4 957.5 726.8 727.1 727.7 728.0 741.5 15234.2 15246.2 15312.5 15323.9 15351.2 14240.7 14470.8 14493.0 14568.2 14923.4 13160.7 13244.7 13297.9 13320.9 13432.1 4648.6 4702.8 4725.4 4750.3 4759.0 119613.0 123929.8 124713.4 126041.0 132538.0 11425.4 11483.4 11518.4 11640.2 11708.9 23433.4 23455.0 23463.9 23465.6 23515.6 7963.0 8016.8 8142.0 8148.3 8350.5 4264.8 4450.3 4511.2 4687.4 4691.5 3412.1 3534.9 3540.5 3545.4 3555.0 654.3 667.3 669.0 682.0 686.6 628.9 631.0 644.3 648.7 649.2 8766.6 8859.0 8958.3 8966.9 9170.0 627.8 637.1 643.2 651.4 656.9 3652.3 4395.9 4424.9 4484.0 4544.6 83660.6 97057.5 160388.0 162723.0 171071.0 76800.7 100973.5 107445.0 114076.4 114206.4 99568.0 102139.0 102826.0 103441.0 105505.5 9380.5 9502.0 9826.8 9864.6 9884.6 49549.8 57094.0 61882.1 62467.8 65565.7 71662.0 74570.0 85051.6 86189.0 91419.0 11836.9 13441.9 13583.3 13593.6 13931.6 15259.3 15387.2 15507.8 15520.8 15785.1 13139.7 14140.1 14331.7 14377.1 14734.3 12427.5 14427.2 14431.4 14455.6 14514.6 13932.5 13979.9 14005.8 14053.4 14084.7 9780.5 11492.5 11560.8 11696.6 12110.3 10854.6 10987.2 11151.4 11370.9 11798.5 16:37:03 INFO - PROCESS | 2179 | -------- Summary: end -------- 16:37:03 INFO - PROCESS | 2179 | 16:37:03 INFO - TEST-INFO | 2179: exit 0 16:37:03 INFO - TEST-OK | dromaeo_css | took 1115707ms 16:37:03 INFO - TEST-START | kraken 16:37:03 INFO - operating with platform_type : mac_ 16:37:03 INFO - Initialising browser for kraken test... 16:37:03 INFO - TEST-INFO | started process 2294 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpyGiZew/profile http://localhost:54475/getInfo.html) 16:37:06 INFO - PROCESS | 2294 | __metrics Screen width/height:1600/1200 16:37:06 INFO - PROCESS | 2294 | colorDepth:24 16:37:06 INFO - PROCESS | 2294 | Browser inner width/height: 1024/689 16:37:06 INFO - PROCESS | 2294 | __metrics 16:37:06 INFO - PROCESS | 2294 | 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" 16:37:06 INFO - PROCESS | 2294 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:37:06 INFO - PROCESS | 2294 | ************************* 16:37:06 INFO - PROCESS | 2294 | A coding exception was thrown in a Promise resolution callback. 16:37:06 INFO - PROCESS | 2294 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 16:37:06 INFO - PROCESS | 2294 | 16:37:06 ERROR - PROCESS | 2294 | Full message: TypeError: win is null 16:37:06 INFO - PROCESS | 2294 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:855:17 16:37:06 INFO - PROCESS | 2294 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 16:37:06 INFO - PROCESS | 2294 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 16:37:06 INFO - PROCESS | 2294 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 16:37:06 INFO - PROCESS | 2294 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 16:37:06 INFO - PROCESS | 2294 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 16:37:06 INFO - PROCESS | 2294 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 16:37:06 INFO - PROCESS | 2294 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 16:37:06 INFO - PROCESS | 2294 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 16:37:06 INFO - PROCESS | 2294 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 16:37:06 INFO - PROCESS | 2294 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 16:37:06 INFO - PROCESS | 2294 | Barrier/this.client.addBlocker/promise53.7% 16:38:03 INFO - TinderboxPrint: I/O read bytes / time
802,816 / 7 16:38:03 INFO - TinderboxPrint: I/O write bytes / time
708,596,736 / 1,942 16:38:03 INFO - TinderboxPrint: CPU idle
2,239.2 (46.3%) 16:38:03 INFO - TinderboxPrint: CPU system
969.3 (20.0%) 16:38:03 INFO - TinderboxPrint: CPU user
1,629.1 (33.7%) 16:38:03 INFO - TinderboxPrint: Swap in / out
407,429,120 / 0 16:38:03 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404978176; Write bytes: 399967232; Read time: 29835; Write time: 815 16:38:03 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 765952; Write bytes: 296947712; Read time: 6; Write time: 1093 16:38:04 INFO - Running post-run listener: _upload_blobber_files 16:38:04 INFO - Blob upload gear active. 16:38:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38: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 16:38: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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:38: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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:38:04 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:38:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:38:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:05 INFO - (blobuploader) - INFO - Done attempting. 16:38:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:38:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:38:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:06 INFO - (blobuploader) - INFO - Done attempting. 16:38:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:38:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:38:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:09 INFO - (blobuploader) - INFO - Done attempting. 16:38:09 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:09 INFO - Return code: 0 16:38:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73e5ddaa2b060d28cddf2eba6d35d34787ee9dbfb5953ee85df85bd5e56f05f94539612002d6326b029120658a7faea8a665c6ffa3eeacea1bd0a01fed897890", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd8fa453e7d0c597133253eb46925c567f5b5d4f05d8ad6c5dc4fd47fa4a5e7f69b560299a74ca12a13e3af4758a443915bb9ba8dce5f86f7cfdd4648f7ee2ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/559a5606949ccf982567df747fe073237b4a7ed5f33f0a158fcab69596cafddec0793363a10108575fdb6915f8626e38e145f33d515cc99718bfcc74f6a746f6"} 16:38:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:09 INFO - Contents: 16:38:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73e5ddaa2b060d28cddf2eba6d35d34787ee9dbfb5953ee85df85bd5e56f05f94539612002d6326b029120658a7faea8a665c6ffa3eeacea1bd0a01fed897890", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd8fa453e7d0c597133253eb46925c567f5b5d4f05d8ad6c5dc4fd47fa4a5e7f69b560299a74ca12a13e3af4758a443915bb9ba8dce5f86f7cfdd4648f7ee2ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/559a5606949ccf982567df747fe073237b4a7ed5f33f0a158fcab69596cafddec0793363a10108575fdb6915f8626e38e145f33d515cc99718bfcc74f6a746f6"} 16:38:09 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:09 INFO - Copying logs to upload dir... 16:38:09 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.875084 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-07-13 16:38:09.161068) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 16:38:09.164463) ========= 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.Egw1jaGtTe/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.VbkUXIspea/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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/Firefox/sha512/73e5ddaa2b060d28cddf2eba6d35d34787ee9dbfb5953ee85df85bd5e56f05f94539612002d6326b029120658a7faea8a665c6ffa3eeacea1bd0a01fed897890", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd8fa453e7d0c597133253eb46925c567f5b5d4f05d8ad6c5dc4fd47fa4a5e7f69b560299a74ca12a13e3af4758a443915bb9ba8dce5f86f7cfdd4648f7ee2ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/559a5606949ccf982567df747fe073237b4a7ed5f33f0a158fcab69596cafddec0793363a10108575fdb6915f8626e38e145f33d515cc99718bfcc74f6a746f6"} build_url:https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011205 build_url: 'https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73e5ddaa2b060d28cddf2eba6d35d34787ee9dbfb5953ee85df85bd5e56f05f94539612002d6326b029120658a7faea8a665c6ffa3eeacea1bd0a01fed897890", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cd8fa453e7d0c597133253eb46925c567f5b5d4f05d8ad6c5dc4fd47fa4a5e7f69b560299a74ca12a13e3af4758a443915bb9ba8dce5f86f7cfdd4648f7ee2ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/559a5606949ccf982567df747fe073237b4a7ed5f33f0a158fcab69596cafddec0793363a10108575fdb6915f8626e38e145f33d515cc99718bfcc74f6a746f6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 16:38:09.197370) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:38:09.197711) ========= 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.Egw1jaGtTe/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.VbkUXIspea/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.005107 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 16:38:09.246001) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 16:38:09.246357) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 16:38:09.246735) ========= ========= Total master_lag: 0.19 =========