builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0374 starttime: 1497352447.62 results: success (0) buildid: 20170613025734 builduid: cf3b517313844fa0b554723cc8526051 revision: e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:07.616403) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:07.616857) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:07.617147) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:07.643422) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:07.643824) ========= 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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 --2017-06-13 04:14:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.8M=0.001s 2017-06-13 04:14:07 (20.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124353 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:07.794095) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:07.794610) ========= 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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.026631 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:07.846206) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 04:14:07.846731) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ovm3uWgVsl/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.kHkub1P2sU/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 2017-06-13 04:14:07,944 truncating revision to first 12 chars 2017-06-13 04:14:07,944 Setting DEBUG logging. 2017-06-13 04:14:07,944 attempt 1/10 2017-06-13 04:14:07,944 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e1ebaa962af1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 04:14:08,661 unpacking tar archive at: mozilla-beta-e1ebaa962af1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.009999 ========= master_lag: 0.31 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 04:14:09.167201) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:09.167551) ========= script_repo_revision: e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:09.167937) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:09.168219) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 04:14:09.186864) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-06-13 04:14:09.187169) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ovm3uWgVsl/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.kHkub1P2sU/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:14:09 INFO - MultiFileLogger online at 20170613 04:14:09 in /builds/slave/test 04:14:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:14:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:14:09 INFO - {'append_to_log': False, 04:14:09 INFO - 'base_work_dir': '/builds/slave/test', 04:14:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:14:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:14:09 INFO - 'branch': 'Mozilla-Beta', 04:14:09 INFO - 'buildbot_json_path': 'buildprops.json', 04:14:09 INFO - 'config_files': ('talos/mac_config.py',), 04:14:09 INFO - 'default_actions': ('clobber', 04:14:09 INFO - 'read-buildbot-config', 04:14:09 INFO - 'download-and-extract', 04:14:09 INFO - 'populate-webroot', 04:14:09 INFO - 'create-virtualenv', 04:14:09 INFO - 'install', 04:14:09 INFO - 'setup-mitmproxy', 04:14:09 INFO - 'run-tests'), 04:14:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:14:09 INFO - 'download_minidump_stackwalk': True, 04:14:09 INFO - 'download_symbols': 'ondemand', 04:14:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:14:09 INFO - 'tooltool.py': '/tools/tooltool.py', 04:14:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:14:09 INFO - '/tools/misc-python/virtualenv.py')}, 04:14:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:14:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:14:09 INFO - 'gecko_profile': False, 04:14:09 INFO - 'gecko_profile_interval': 0, 04:14:09 INFO - 'installer_path': 'installer.exe', 04:14:09 INFO - 'log_level': 'info', 04:14:09 INFO - 'log_name': 'talos', 04:14:09 INFO - 'log_to_console': True, 04:14:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:14:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:14:09 INFO - 'opt_config_files': (), 04:14:09 INFO - 'pip_index': False, 04:14:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:14:09 INFO - 'cmd': ('bash', 04:14:09 INFO - '-c', 04:14:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:14:09 INFO - 'enabled': True, 04:14:09 INFO - 'halt_on_failure': False, 04:14:09 INFO - 'name': 'check_screen_resolution'},), 04:14:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:14:09 INFO - 'cmd': ('bash', 04:14:09 INFO - '-c', 04:14:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:14:09 INFO - 'enabled': True, 04:14:09 INFO - 'halt_on_failure': False, 04:14:09 INFO - 'name': 'check_screen_resolution'},), 04:14:09 INFO - 'run_cmd_checks_enabled': True, 04:14:09 INFO - 'suite': 'dromaeojs-e10s', 04:14:09 INFO - 'system_bits': '32', 04:14:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:14:09 INFO - 'title': 't-yosemite-r7-0374', 04:14:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:14:09 INFO - 'use_talos_json': True, 04:14:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:14:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:14:09 INFO - 'work_dir': 'build'} 04:14:09 INFO - [mozharness: 2017-06-13 11:14:09.393083Z] Running clobber step. 04:14:09 INFO - Running pre-action listener: _resource_record_pre_action 04:14:09 INFO - Running main action method: clobber 04:14:09 INFO - rmtree: /builds/slave/test/build 04:14:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:14:11 INFO - Running post-action listener: _resource_record_post_action 04:14:11 INFO - [mozharness: 2017-06-13 11:14:11.843607Z] Finished clobber step (success) 04:14:11 INFO - [mozharness: 2017-06-13 11:14:11.843741Z] Running read-buildbot-config step. 04:14:11 INFO - Running pre-action listener: _resource_record_pre_action 04:14:11 INFO - Running main action method: read_buildbot_config 04:14:11 INFO - Using buildbot properties: 04:14:11 INFO - { 04:14:11 INFO - "project": "", 04:14:11 INFO - "product": "firefox", 04:14:11 INFO - "script_repo_revision": "production", 04:14:11 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 04:14:11 INFO - "repository": "", 04:14:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 04:14:11 INFO - "buildid": "20170613025734", 04:14:11 INFO - "pgo_build": "False", 04:14:11 INFO - "basedir": "/builds/slave/test", 04:14:11 INFO - "buildnumber": 0, 04:14:11 INFO - "slavename": "t-yosemite-r7-0374", 04:14:11 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 04:14:11 INFO - "platform": "yosemite_r7_devedition", 04:14:11 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 04:14:11 INFO - "branch": "mozilla-beta", 04:14:11 INFO - "repo_path": "releases/mozilla-beta", 04:14:11 INFO - "revision": "e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e", 04:14:11 INFO - "stage_platform": "macosx64-devedition", 04:14:11 INFO - "builduid": "cf3b517313844fa0b554723cc8526051", 04:14:11 INFO - "slavebuilddir": "test" 04:14:11 INFO - } 04:14:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 04:14:11 INFO - Running post-action listener: _resource_record_post_action 04:14:11 INFO - [mozharness: 2017-06-13 11:14:11.847051Z] Finished read-buildbot-config step (success) 04:14:11 INFO - [mozharness: 2017-06-13 11:14:11.847186Z] Running download-and-extract step. 04:14:11 INFO - Running pre-action listener: _resource_record_pre_action 04:14:11 INFO - Running main action method: download_and_extract 04:14:11 INFO - mkdir: /builds/slave/test/build/tests 04:14:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:11 INFO - trying https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 04:14:11 INFO - Downloading https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 04:14:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 04:14:12 INFO - Downloaded 1290 bytes. 04:14:12 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 04:14:12 INFO - Using the following test package requirements: 04:14:12 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:14:12 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 04:14:12 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 04:14:12 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:14:12 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 04:14:12 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:14:12 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 04:14:12 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:14:12 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:14:12 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 04:14:12 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 04:14:12 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:14:12 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 04:14:12 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:14:12 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 04:14:12 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:14:12 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 04:14:12 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 04:14:12 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 04:14:12 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 04:14:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 04:14:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 04:14:12 INFO - Fetch https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 04:14:14 INFO - Content-Length response header: 59545199 04:14:14 INFO - Bytes received: 59545199 04:14:32 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 04:14:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 04:14:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 04:14:32 INFO - Fetch https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 04:14:34 INFO - Content-Length response header: 59545199 04:14:34 INFO - Bytes received: 59545199 04:14:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 04:14:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 04:14:52 INFO - Fetch https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 04:14:53 INFO - Content-Length response header: 14450018 04:14:53 INFO - Bytes received: 14450018 04:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:14:53 INFO - trying https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:14:53 INFO - Downloading https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 04:14:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 04:14:55 INFO - Downloaded 66876477 bytes. 04:14:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:14:55 INFO - mkdir: /builds/slave/test/properties 04:14:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:14:55 INFO - Writing to file /builds/slave/test/properties/build_url 04:14:55 INFO - Contents: 04:14:55 INFO - build_url:https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.dmg 04:14:56 INFO - Running post-action listener: _resource_record_post_action 04:14:56 INFO - Running post-action listener: set_extra_try_arguments 04:14:56 INFO - [mozharness: 2017-06-13 11:14:56.075524Z] Finished download-and-extract step (success) 04:14:56 INFO - [mozharness: 2017-06-13 11:14:56.075672Z] Running populate-webroot step. 04:14:56 INFO - Running pre-action listener: _resource_record_pre_action 04:14:56 INFO - Running main action method: populate_webroot 04:14:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:14:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:14:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:14:56 INFO - '--tppagecycles', 04:14:56 INFO - '7'], 04:14:56 INFO - 'tests': ['tsvgm']}}, 04:14:56 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:14:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:14:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:14:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:14:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:14:56 INFO - 'talos_options': ['--disable-e10s'], 04:14:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:14:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:14:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:14:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:14:56 INFO - 'talos_options': ['--disable-e10s'], 04:14:56 INFO - 'tests': ['damp', 'tps']}, 04:14:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:14:56 INFO - 'tests': ['damp', 'tps']}, 04:14:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:14:56 INFO - 'tests': ['dromaeo_dom']}, 04:14:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:14:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:14:56 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:14:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:14:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:14:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:14:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:14:56 INFO - 'tests': ['a11yr', 04:14:56 INFO - 'ts_paint', 04:14:56 INFO - 'tpaint', 04:14:56 INFO - 'sessionrestore', 04:14:56 INFO - 'sessionrestore_no_auto_restore', 04:14:56 INFO - 'tabpaint']}, 04:14:56 INFO - 'other-e10s': {'tests': ['a11yr', 04:14:56 INFO - 'ts_paint', 04:14:56 INFO - 'tpaint', 04:14:56 INFO - 'sessionrestore', 04:14:56 INFO - 'sessionrestore_no_auto_restore', 04:14:56 INFO - 'tabpaint']}, 04:14:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:14:56 INFO - 'ts_paint', 04:14:56 INFO - 'tpaint', 04:14:56 INFO - 'sessionrestore', 04:14:56 INFO - 'sessionrestore_no_auto_restore', 04:14:56 INFO - 'tabpaint']}, 04:14:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:14:56 INFO - 'ts_paint', 04:14:56 INFO - 'tpaint', 04:14:56 INFO - 'sessionrestore', 04:14:56 INFO - 'sessionrestore_no_auto_restore', 04:14:56 INFO - 'tabpaint']}, 04:14:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:14:56 INFO - 'tests': ['a11yr', 04:14:56 INFO - 'ts_paint', 04:14:56 INFO - 'tpaint', 04:14:56 INFO - 'sessionrestore', 04:14:56 INFO - 'sessionrestore_no_auto_restore', 04:14:56 INFO - 'tabpaint']}, 04:14:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:14:56 INFO - 'tests': ['a11yr', 04:14:56 INFO - 'ts_paint', 04:14:56 INFO - 'tpaint', 04:14:56 INFO - 'sessionrestore', 04:14:56 INFO - 'sessionrestore_no_auto_restore', 04:14:56 INFO - 'tabpaint']}, 04:14:56 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:14:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:14:56 INFO - 'bloom_basic_ref']}, 04:14:56 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:14:56 INFO - 'talos_options': ['--mitmproxy', 04:14:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:14:56 INFO - '--firstNonBlankPaint'], 04:14:56 INFO - 'tests': ['quantum_pageload_google', 04:14:56 INFO - 'quantum_pageload_youtube', 04:14:56 INFO - 'quantum_pageload_amazon', 04:14:56 INFO - 'quantum_pageload_facebook']}, 04:14:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:14:56 INFO - 'tests': ['tsvgx', 04:14:56 INFO - 'tsvgr_opacity', 04:14:56 INFO - 'tart', 04:14:56 INFO - 'tscrollx', 04:14:56 INFO - 'cart', 04:14:56 INFO - 'tsvg_static']}, 04:14:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:14:56 INFO - 'tsvgr_opacity', 04:14:56 INFO - 'tart', 04:14:56 INFO - 'tscrollx', 04:14:56 INFO - 'cart', 04:14:56 INFO - 'tsvg_static']}, 04:14:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:14:56 INFO - 'talos_options': ['--disable-e10s'], 04:14:56 INFO - 'tests': ['tp5o']}, 04:14:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:14:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:14:56 INFO - 'talos_options': ['--disable-e10s', 04:14:56 INFO - '--xperf_path', 04:14:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:14:56 INFO - 'tests': ['tp5n']}, 04:14:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:14:56 INFO - 'talos_options': ['--xperf_path', 04:14:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:14:56 INFO - 'tests': ['tp5n']}}, 04:14:56 INFO - 'talos.zip': {'path': '', 04:14:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:14:56 INFO - Running post-action listener: _resource_record_post_action 04:14:56 INFO - [mozharness: 2017-06-13 11:14:56.097032Z] Finished populate-webroot step (success) 04:14:56 INFO - [mozharness: 2017-06-13 11:14:56.097143Z] Running create-virtualenv step. 04:14:56 INFO - Running pre-action listener: _resource_record_pre_action 04:14:56 INFO - Running main action method: create_virtualenv 04:14:56 INFO - Creating virtualenv /builds/slave/test/build/venv 04:14:56 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 04:14:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:14:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:14:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:14:56 INFO - Using real prefix '/tools/python27' 04:14:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:14:56 INFO - Installing distribute.............................................................................................................................................................................................done. 04:14:59 INFO - Installing pip.................done. 04:14:59 INFO - Return code: 0 04:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:15:00 INFO - Reading from file tmpfile_stdout 04:15:00 INFO - Output received: 04:15:00 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:15:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:00 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/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 0x104195c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb87159a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, '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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 04:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:15:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:00 INFO - 'HOME': '/Users/cltbld', 04:15:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:00 INFO - 'LOGNAME': 'cltbld', 04:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:00 INFO - 'MOZ_NO_REMOTE': '1', 04:15:00 INFO - 'NO_EM_RESTART': '1', 04:15:00 INFO - 'PAGER': '/bin/cat', 04:15:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:00 INFO - 'PWD': '/builds/slave/test', 04:15:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:00 INFO - 'SHELL': '/bin/bash', 04:15:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:00 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:00 INFO - 'USER': 'cltbld', 04:15:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:00 INFO - 'XPC_FLAGS': '0x0', 04:15:00 INFO - 'XPC_SERVICE_NAME': '0', 04:15:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:00 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 04:15:00 INFO - Cleaning up... 04:15:00 INFO - Return code: 0 04:15:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:00 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/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 0x104195c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb87159a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, '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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 04:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:15:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:00 INFO - 'HOME': '/Users/cltbld', 04:15:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:00 INFO - 'LOGNAME': 'cltbld', 04:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:00 INFO - 'MOZ_NO_REMOTE': '1', 04:15:00 INFO - 'NO_EM_RESTART': '1', 04:15:00 INFO - 'PAGER': '/bin/cat', 04:15:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:00 INFO - 'PWD': '/builds/slave/test', 04:15:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:00 INFO - 'SHELL': '/bin/bash', 04:15:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:00 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:00 INFO - 'USER': 'cltbld', 04:15:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:00 INFO - 'XPC_FLAGS': '0x0', 04:15:00 INFO - 'XPC_SERVICE_NAME': '0', 04:15:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:00 INFO - Downloading/unpacking psutil>=3.1.1 04:15:00 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 04:15:00 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 04:15:00 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 04:15:00 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 04:15:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:15:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:15:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:15:02 INFO - Installing collected packages: psutil 04:15:02 INFO - Running setup.py install for psutil 04:15:02 INFO - building 'psutil._psutil_osx' extension 04:15:02 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 04:15:03 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 04:15:03 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 04:15:03 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 04:15:03 INFO - building 'psutil._psutil_posix' extension 04:15:03 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 04:15:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:15:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:15:03 INFO - ^ 04:15:03 INFO - 1 warning generated. 04:15:03 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 04:15:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:15:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:15:03 INFO - Successfully installed psutil 04:15:03 INFO - Cleaning up... 04:15:03 INFO - Return code: 0 04:15:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:03 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/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 0x104195c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb87159a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, '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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 04:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:03 INFO - 'HOME': '/Users/cltbld', 04:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:03 INFO - 'LOGNAME': 'cltbld', 04:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:03 INFO - 'MOZ_NO_REMOTE': '1', 04:15:03 INFO - 'NO_EM_RESTART': '1', 04:15:03 INFO - 'PAGER': '/bin/cat', 04:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:03 INFO - 'PWD': '/builds/slave/test', 04:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:03 INFO - 'SHELL': '/bin/bash', 04:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:03 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:03 INFO - 'USER': 'cltbld', 04:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:03 INFO - 'XPC_FLAGS': '0x0', 04:15:03 INFO - 'XPC_SERVICE_NAME': '0', 04:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:15:03 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 04:15:03 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 04:15:03 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 04:15:03 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 04:15:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:15:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:15:05 INFO - Installing collected packages: mozsystemmonitor 04:15:05 INFO - Running setup.py install for mozsystemmonitor 04:15:05 INFO - Successfully installed mozsystemmonitor 04:15:05 INFO - Cleaning up... 04:15:05 INFO - Return code: 0 04:15:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:15:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:06 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/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 0x104195c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb87159a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, '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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:15:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 04:15:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:15:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:06 INFO - 'HOME': '/Users/cltbld', 04:15:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:06 INFO - 'LOGNAME': 'cltbld', 04:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:06 INFO - 'MOZ_NO_REMOTE': '1', 04:15:06 INFO - 'NO_EM_RESTART': '1', 04:15:06 INFO - 'PAGER': '/bin/cat', 04:15:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:06 INFO - 'PWD': '/builds/slave/test', 04:15:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:06 INFO - 'SHELL': '/bin/bash', 04:15:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:06 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:06 INFO - 'USER': 'cltbld', 04:15:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:06 INFO - 'XPC_FLAGS': '0x0', 04:15:06 INFO - 'XPC_SERVICE_NAME': '0', 04:15:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:06 INFO - Downloading/unpacking jsonschema==2.5.1 04:15:06 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 04:15:06 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 04:15:06 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 04:15:06 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 04:15:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:15:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:15:07 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 04:15:07 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 04:15:07 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 04:15:07 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 04:15:07 INFO - Downloading functools32-3.2.3-2.tar.gz 04:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:15:07 INFO - warning: no files found matching '*.txt' 04:15:07 INFO - no previously-included directories found matching 'build' 04:15:07 INFO - no previously-included directories found matching 'dist' 04:15:07 INFO - no previously-included directories found matching '.git*' 04:15:07 INFO - Installing collected packages: jsonschema, functools32 04:15:08 INFO - Running setup.py install for functools32 04:15:08 INFO - warning: no files found matching '*.txt' 04:15:08 INFO - no previously-included directories found matching 'build' 04:15:08 INFO - no previously-included directories found matching 'dist' 04:15:08 INFO - no previously-included directories found matching '.git*' 04:15:08 INFO - Successfully installed jsonschema functools32 04:15:08 INFO - Cleaning up... 04:15:08 INFO - Return code: 0 04:15:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:08 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104195c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb87159a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, '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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:15:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:15:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:08 INFO - 'HOME': '/Users/cltbld', 04:15:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:08 INFO - 'LOGNAME': 'cltbld', 04:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:08 INFO - 'MOZ_NO_REMOTE': '1', 04:15:08 INFO - 'NO_EM_RESTART': '1', 04:15:08 INFO - 'PAGER': '/bin/cat', 04:15:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:08 INFO - 'PWD': '/builds/slave/test', 04:15:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:08 INFO - 'SHELL': '/bin/bash', 04:15:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:08 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:08 INFO - 'USER': 'cltbld', 04:15:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:08 INFO - 'XPC_FLAGS': '0x0', 04:15:08 INFO - 'XPC_SERVICE_NAME': '0', 04:15:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:15:08 INFO - Cleaning up... 04:15:08 INFO - Return code: 0 04:15:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:08 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/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 0x104195c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb87159a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, '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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:15:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 04:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:15:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:08 INFO - 'HOME': '/Users/cltbld', 04:15:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:08 INFO - 'LOGNAME': 'cltbld', 04:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:08 INFO - 'MOZ_NO_REMOTE': '1', 04:15:08 INFO - 'NO_EM_RESTART': '1', 04:15:08 INFO - 'PAGER': '/bin/cat', 04:15:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:08 INFO - 'PWD': '/builds/slave/test', 04:15:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:08 INFO - 'SHELL': '/bin/bash', 04:15:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:08 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:08 INFO - 'USER': 'cltbld', 04:15:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:08 INFO - 'XPC_FLAGS': '0x0', 04:15:08 INFO - 'XPC_SERVICE_NAME': '0', 04:15:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:08 INFO - Downloading/unpacking blobuploader==1.2.4 04:15:08 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 04:15:08 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 04:15:08 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 04:15:08 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 04:15:10 INFO - Downloading blobuploader-1.2.4.tar.gz 04:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:15:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:15:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:11 INFO - Downloading docopt-0.6.1.tar.gz 04:15:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:15:11 INFO - Installing collected packages: blobuploader, requests, docopt 04:15:11 INFO - Running setup.py install for blobuploader 04:15:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:15:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:15:11 INFO - Running setup.py install for requests 04:15:11 INFO - Running setup.py install for docopt 04:15:11 INFO - Successfully installed blobuploader requests docopt 04:15:11 INFO - Cleaning up... 04:15:11 INFO - Return code: 0 04:15:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:11 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/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 0x104195c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb87159a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, '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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:15:11 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:15:11 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:15:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:11 INFO - 'HOME': '/Users/cltbld', 04:15:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:11 INFO - 'LOGNAME': 'cltbld', 04:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:11 INFO - 'MOZ_NO_REMOTE': '1', 04:15:11 INFO - 'NO_EM_RESTART': '1', 04:15:11 INFO - 'PAGER': '/bin/cat', 04:15:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:11 INFO - 'PWD': '/builds/slave/test', 04:15:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:11 INFO - 'SHELL': '/bin/bash', 04:15:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:11 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:11 INFO - 'USER': 'cltbld', 04:15:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:11 INFO - 'XPC_FLAGS': '0x0', 04:15:11 INFO - 'XPC_SERVICE_NAME': '0', 04:15:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:15:12 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Kl2ZFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:15:12 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-FDbakT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:15:12 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-MyFuu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:15:12 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-hKbDZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:15:12 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-uPwtSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:15:12 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-nuvVez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:15:12 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-WmSflB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:15:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:15:12 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-4DaxnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:15:13 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Xkn8L9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:15:13 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-41xgpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:15:13 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ZtbsfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:15:13 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-v1MIcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:15:13 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-3BHp3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:15:13 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-yYQFQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:13 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-EUag_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:15:13 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-0kthxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:15:13 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-8OP831-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:15:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:15:13 INFO - Running setup.py install for manifestparser 04:15:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:15:14 INFO - Running setup.py install for mozcrash 04:15:14 INFO - Running setup.py install for mozdebug 04:15:14 INFO - Running setup.py install for mozdevice 04:15:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:15:14 INFO - Running setup.py install for mozfile 04:15:14 INFO - Running setup.py install for mozhttpd 04:15:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:15:14 INFO - Running setup.py install for mozinfo 04:15:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:15:14 INFO - Running setup.py install for mozInstall 04:15:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:15:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:15:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:15:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:15:15 INFO - Running setup.py install for mozleak 04:15:15 INFO - Running setup.py install for mozlog 04:15:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:15:15 INFO - Running setup.py install for moznetwork 04:15:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:15:15 INFO - Running setup.py install for mozprocess 04:15:15 INFO - Running setup.py install for mozprofile 04:15:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:15:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:15:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:15:15 INFO - Running setup.py install for mozrunner 04:15:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:15:16 INFO - Running setup.py install for mozscreenshot 04:15:16 INFO - Running setup.py install for moztest 04:15:16 INFO - Running setup.py install for mozversion 04:15:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:15:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:15:16 INFO - Cleaning up... 04:15:16 INFO - Return code: 0 04:15:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:16 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/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 0x104195c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb87159a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, '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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:15:16 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:15:16 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:15:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:16 INFO - 'HOME': '/Users/cltbld', 04:15:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:16 INFO - 'LOGNAME': 'cltbld', 04:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:16 INFO - 'MOZ_NO_REMOTE': '1', 04:15:16 INFO - 'NO_EM_RESTART': '1', 04:15:16 INFO - 'PAGER': '/bin/cat', 04:15:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:16 INFO - 'PWD': '/builds/slave/test', 04:15:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:16 INFO - 'SHELL': '/bin/bash', 04:15:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:16 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:16 INFO - 'USER': 'cltbld', 04:15:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:16 INFO - 'XPC_FLAGS': '0x0', 04:15:16 INFO - 'XPC_SERVICE_NAME': '0', 04:15:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:15:16 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-7oAYBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:15:16 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)) 04:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:15:16 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-mbs5L5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:15:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 04:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:15:16 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-W6tmJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:15:17 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)) 04:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:15:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-h6Totk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 04:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:15:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-i2zqpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:15:17 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)) 04:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:15:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-2_Tnb1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:15:17 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)) 04:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:15:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-pyyBCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 04:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:15:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ElF3Q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 04:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:15:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-zcsc5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:15:17 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)) 04:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:15:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-PcDXe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 04:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:15:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-DVWWsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:15:17 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)) 04:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:15:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-fYEdb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 04:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:15:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-UuZxxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:15:18 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)) 04:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:15:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-TdMvRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 04:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-sxQVZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:15:18 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)) 04:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:15:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-z6Ufw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 04:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:15:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-SZlGgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:15:18 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)) 04:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:15:18 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)) 04:15:18 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:15:18 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:15:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:15:18 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 04:15:18 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 04:15:18 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 04:15:18 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 04:15:19 INFO - Downloading blessings-1.6.tar.gz 04:15:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:15:20 INFO - Installing collected packages: blessings 04:15:20 INFO - Running setup.py install for blessings 04:15:20 INFO - Successfully installed blessings 04:15:20 INFO - Cleaning up... 04:15:20 INFO - Return code: 0 04:15:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:15:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:15:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:15:20 INFO - Reading from file tmpfile_stdout 04:15:20 INFO - Current package versions: 04:15:20 INFO - blessings == 1.6 04:15:20 INFO - blobuploader == 1.2.4 04:15:20 INFO - docopt == 0.6.1 04:15:20 INFO - functools32 == 3.2.3-2 04:15:20 INFO - jsonschema == 2.5.1 04:15:20 INFO - manifestparser == 1.1 04:15:20 INFO - mozInstall == 1.13 04:15:20 INFO - mozcrash == 1.0 04:15:20 INFO - mozdebug == 0.1 04:15:20 INFO - mozdevice == 0.51 04:15:20 INFO - mozfile == 1.2 04:15:20 INFO - mozhttpd == 0.7 04:15:20 INFO - mozinfo == 0.10 04:15:20 INFO - mozleak == 0.1 04:15:20 INFO - mozlog == 3.4 04:15:20 INFO - moznetwork == 0.27 04:15:20 INFO - mozprocess == 0.25 04:15:20 INFO - mozprofile == 0.28 04:15:20 INFO - mozrunner == 6.13 04:15:20 INFO - mozscreenshot == 0.1 04:15:20 INFO - mozsystemmonitor == 0.3 04:15:20 INFO - moztest == 0.8 04:15:20 INFO - mozversion == 1.4 04:15:20 INFO - psutil == 3.1.1 04:15:20 INFO - requests == 1.2.3 04:15:20 INFO - wsgiref == 0.1.2 04:15:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:20 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/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 0x104195c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb87159a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, '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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:15:20 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:15:20 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:15:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:20 INFO - 'HOME': '/Users/cltbld', 04:15:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:20 INFO - 'LOGNAME': 'cltbld', 04:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:20 INFO - 'MOZ_NO_REMOTE': '1', 04:15:20 INFO - 'NO_EM_RESTART': '1', 04:15:20 INFO - 'PAGER': '/bin/cat', 04:15:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:20 INFO - 'PWD': '/builds/slave/test', 04:15:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:20 INFO - 'SHELL': '/bin/bash', 04:15:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:20 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:20 INFO - 'USER': 'cltbld', 04:15:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:20 INFO - 'XPC_FLAGS': '0x0', 04:15:20 INFO - 'XPC_SERVICE_NAME': '0', 04:15:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:20 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)) 04:15:20 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)) 04:15:20 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)) 04:15:20 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)) 04:15:20 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)) 04:15:20 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)) 04:15:20 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)) 04:15:20 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)) 04:15:20 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)) 04:15:20 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:15:22 INFO - Downloading marionette_driver-1.4.0.tar.gz 04:15:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 04:15:22 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)) 04:15:22 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)) 04:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:15:22 INFO - Installing collected packages: marionette-driver 04:15:22 INFO - Running setup.py install for marionette-driver 04:15:22 INFO - Successfully installed marionette-driver 04:15:22 INFO - Cleaning up... 04:15:22 INFO - Return code: 0 04:15:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:15:22 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/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 0x104195c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb87159a0d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, '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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 04:15:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:15:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:15:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:22 INFO - 'HOME': '/Users/cltbld', 04:15:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:22 INFO - 'LOGNAME': 'cltbld', 04:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:22 INFO - 'MOZ_NO_REMOTE': '1', 04:15:22 INFO - 'NO_EM_RESTART': '1', 04:15:22 INFO - 'PAGER': '/bin/cat', 04:15:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:22 INFO - 'PWD': '/builds/slave/test', 04:15:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:22 INFO - 'SHELL': '/bin/bash', 04:15:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:22 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:22 INFO - 'USER': 'cltbld', 04:15:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:22 INFO - 'XPC_FLAGS': '0x0', 04:15:22 INFO - 'XPC_SERVICE_NAME': '0', 04:15:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:15:22 INFO - Cleaning up... 04:15:22 INFO - Return code: 0 04:15:22 INFO - Running post-action listener: _resource_record_post_action 04:15:22 INFO - Running post-action listener: _start_resource_monitoring 04:15:22 INFO - Starting resource monitoring. 04:15:22 INFO - [mozharness: 2017-06-13 11:15:22.830602Z] Finished create-virtualenv step (success) 04:15:22 INFO - [mozharness: 2017-06-13 11:15:22.831556Z] Running install step. 04:15:22 INFO - Running pre-action listener: _resource_record_pre_action 04:15:22 INFO - Running main action method: install 04:15:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:15:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:15:23 INFO - Reading from file tmpfile_stdout 04:15:23 INFO - Detecting whether we're running mozinstall >=1.0... 04:15:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:15:23 INFO - Reading from file tmpfile_stdout 04:15:23 INFO - Output received: 04:15:23 INFO - Usage: mozinstall [options] installer 04:15:23 INFO - Options: 04:15:23 INFO - -h, --help show this help message and exit 04:15:23 INFO - -d DEST, --destination=DEST 04:15:23 INFO - Directory to install application into. [default: 04:15:23 INFO - "/builds/slave/test"] 04:15:23 INFO - --app=APP Application being installed. [default: firefox] 04:15:23 INFO - mkdir: /builds/slave/test/build/application 04:15:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:15:44 INFO - Reading from file tmpfile_stdout 04:15:44 INFO - Output received: 04:15:44 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:15:44 INFO - Running post-action listener: _resource_record_post_action 04:15:44 INFO - [mozharness: 2017-06-13 11:15:44.905488Z] Finished install step (success) 04:15:44 INFO - [mozharness: 2017-06-13 11:15:44.905625Z] Running setup-mitmproxy step. 04:15:44 INFO - Running pre-action listener: _resource_record_pre_action 04:15:44 INFO - Running main action method: setup_mitmproxy 04:15:44 INFO - Skipping: mitmproxy is not required 04:15:44 INFO - Running post-action listener: _resource_record_post_action 04:15:44 INFO - [mozharness: 2017-06-13 11:15:44.906358Z] Finished setup-mitmproxy step (success) 04:15:44 INFO - [mozharness: 2017-06-13 11:15:44.906499Z] Running run-tests step. 04:15:44 INFO - Running pre-action listener: _resource_record_pre_action 04:15:44 INFO - Running main action method: run_tests 04:15:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:15:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:15:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:15:44 INFO - 2017-06-13 04:15:44.921 screenresolution[1625:7489] starting screenresolution argv=screenresolution get 04:15:44 INFO - 2017-06-13 04:15:44.923 screenresolution[1625:7489] Display 0: 1600x1200x32@60 04:15:44 INFO - 2017-06-13 04:15:44.929 screenresolution[1626:7492] starting screenresolution argv=screenresolution list 04:15:44 INFO - Available Modes on Display 0 04:15:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:15:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:15:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:15:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:15:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:15:45 INFO - Intel Iris: 04:15:45 INFO - Chipset Model: Intel Iris 04:15:45 INFO - Type: GPU 04:15:45 INFO - Bus: Built-In 04:15:45 INFO - VRAM (Dynamic, Max): 1536 MB 04:15:45 INFO - Vendor: Intel (0x8086) 04:15:45 INFO - Device ID: 0x0a2e 04:15:45 INFO - Revision ID: 0x0009 04:15:45 INFO - Displays: 04:15:45 INFO - Display: 04:15:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:15:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:15:45 INFO - Display Serial Number: 1600x1200 60 04:15:45 INFO - Main Display: Yes 04:15:45 INFO - Mirror: Off 04:15:45 INFO - Online: Yes 04:15:45 INFO - Rotation: Supported 04:15:45 INFO - Return code: 0 04:15:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:15:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:15:45 INFO - Python 2.7.3 04:15:45 INFO - Return code: 0 04:15:45 INFO - grabbing minidump binary from tooltool 04:15:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:15:45 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 0x103eebd30>, '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 0x103eb5cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:15:45 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 04:15:45 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 04:15:45 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 04:15:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:15:45 INFO - Return code: 0 04:15:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:15:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:15:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:15:45 INFO - ENV: RUST_BACKTRACE is now 1 04:15:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:15:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:15:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:15:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0374 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:15:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ovm3uWgVsl/Render', 04:15:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:15:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:15:45 INFO - 'HOME': '/Users/cltbld', 04:15:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:15:45 INFO - 'LOGNAME': 'cltbld', 04:15:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:15:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:15:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:15:45 INFO - 'MOZ_NO_REMOTE': '1', 04:15:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:15:45 INFO - 'NO_EM_RESTART': '1', 04:15:45 INFO - 'PAGER': '/bin/cat', 04:15:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:15:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:45 INFO - 'PWD': '/builds/slave/test', 04:15:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:15:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:15:45 INFO - 'RUST_BACKTRACE': '1', 04:15:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:15:45 INFO - 'SHELL': '/bin/bash', 04:15:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kHkub1P2sU/Listeners', 04:15:45 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 04:15:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:15:45 INFO - 'USER': 'cltbld', 04:15:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:15:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:15:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:15:45 INFO - 'XPC_FLAGS': '0x0', 04:15:45 INFO - 'XPC_SERVICE_NAME': '0', 04:15:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:15:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:15:45 INFO - mozversion application_buildid: 20170613025734 04:15:45 INFO - mozversion application_changeset: e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e 04:15:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:15:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:15:45 INFO - mozversion application_name: Firefox 04:15:45 INFO - mozversion application_remotingname: firefox-dev 04:15:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:15:45 INFO - mozversion application_vendor: Mozilla 04:15:45 INFO - mozversion application_version: 55.0 04:15:45 INFO - mozversion platform_buildid: 20170613025734 04:15:45 INFO - mozversion platform_changeset: e1ebaa962af13ad2a3d7b4cb74398b83bea7e92e 04:15:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:15:45 INFO - mozversion platform_version: 55.0 04:15:45 INFO - using testdate: 1497352545 04:15:45 INFO - actual date: 1497352545 04:15:45 INFO - starting webserver on 'localhost:49226' 04:15:45 INFO - SUITE-START | Running 2 tests 04:15:45 INFO - TEST-START | dromaeo_css 04:15:45 INFO - operating with platform_type : mac_ 04:15:45 INFO - Initialising browser for dromaeo_css test... 04:15:45 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpYKVgPh/profile 04:15:45 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 04:16:19 INFO - TEST-INFO | 1636: killed by SIGTERM 04:16:19 INFO - Browser initialized. 04:16:19 INFO - Running cycle 1/1 for dromaeo_css test... 04:16:19 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpYKVgPh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:16:20 INFO - PID 1647 | 2017-06-13 04:16:20.863 plugin-container[1648:8069] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 04:16:20 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:20 INFO - PID 1647 | 2017-06-13 04:16:20.863 plugin-container[1648:8069] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 04:16:20 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:20 INFO - PID 1647 | 2017-06-13 04:16:20.863 plugin-container[1648:8069] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:16:21 INFO - PID 1647 | 2017-06-13 04:16:21.586 plugin-container[1649:8113] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4943, name = 'com.apple.tsm.portname' 04:16:21 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:21 INFO - PID 1647 | 2017-06-13 04:16:21.586 plugin-container[1649:8113] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 04:16:21 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:16:21 INFO - PID 1647 | 2017-06-13 04:16:21.586 plugin-container[1649:8113] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:16:30 INFO - PID 1647 | RSS: Main: 214573056 04:16:30 INFO - PID 1647 | 04:19:33 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 04:19:36 INFO - PID 1647 | RSS: Main: 241090560 04:19:36 INFO - PID 1647 | 04:22:37 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 04:22:38 INFO - PID 1647 | RSS: Main: 246816768 04:22:38 INFO - PID 1647 | 04:25:39 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 04:25:40 INFO - PID 1647 | RSS: Main: 252530688 04:25:40 INFO - PID 1647 | 04:28:42 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 04:28:42 INFO - PID 1647 | RSS: Main: 254193664 04:28:42 INFO - PID 1647 | 04:31:45 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 04:31:46 INFO - PID 1647 | RSS: Main: 254750720 04:31:46 INFO - PID 1647 | 04:34:48 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 04:34:48 INFO - PID 1647 | RSS: Main: 252743680 04:34:48 INFO - PID 1647 | 04:34:48 INFO - PID 1647 | __start_tp_report 04:34:48 INFO - PID 1647 | _x_x_mozilla_page_load 04:34:48 INFO - PID 1647 | _x_x_mozilla_page_load_details 04:34:48 INFO - PID 1647 | |i|pagename|runs| 04:34:48 INFO - PID 1647 | |0;dojo.html;3534.5758508978925;3625.347437461257;3644.1982763791975;3654.1960768630893;3656.92686146277;6238.071923180688;6268.84327891803;6290.842728931773;6294.653794041325;6297.5276854236;6291.33940936202;6327.620342779436;6331.176946996896;6337.112804207414;6389.488840892729;5503.477169668885;5511.255980442629;5515.944840551603;5574.665520068788;5632.718364081779;5491.450854914497;5549.528290095326;5617.522510586605;5621.943780562204;5648.632838865481;5429.267048948421;5442.9930462864495;5501.697406642633;5543.750531226105;5602.635828671144;5459.972700136514;5470.7264636768405;5492.395836457987;5500.697461639608;5550.4727050930505;5447.754851031713;5557.916631250535;5574.16387541872;5585.190147428641;5603.383627800979;6244.438000579924;6289.559730818844;6312.147860038882;6334.936650633527;6354.968225158845;6900.171979362509;6907.033015377851;6907.516473846833;6908.1019467468905;6913.619750913697;6683.665816709194;6785;6823.147106611674;6979.965100174467;7007.089078419824;6245.188125543696;6286.371362863677;6406.4554512866;6428.939225027818;6437.130987316746;7059.82350441243;7077.92922070783;7089.929100708927;7094;7108.68010939509;6555.672216389162;6637.933620663732;6648.335166483313;6649.3018233085795;6765.1205343305055;6804.863902721918;6860.5540639858245;6930.7227710891;6970.546914450544;6974.721011159599;6802.421794147553;6805.693743781541;6820.897686534706;6830.419414349736;6834.6582670866255;6575.408213260829;6693.631850248283;6763.5941843489545;6843.486738494533;6853.691583878737;5380.3274590676165;5405.324334458192;5420.322459692538;5461.15352120422;5495.807646732345;4819.7108173509705;4900.436449808318;4907.55831975124;4985.052839960396;5003.224500202475;6279.529035322369;6386.10594516768;6440.806775796734;6455.935440645533;6478.2550006749825;6996.790096297119;7005.824854378579;7009;7020.05229294049;7028.226895041541;6242.001279795211;6446.709898054599;6454.677266136674;6456.128422662974;6484.1894763154605;5960.344362120163;5961.7615295387695;5979.103134529855;5983.730732117066;6011.158437818709;5784.421557844182;6912.861742765116;6973.93026069743;6987.6855541499735;7033.331833475811;6969.407600354027;6990.405815505739;6992.3357281058215;6996.685149168198;7002.334778196063;6006.219191505251;7055.18865330473;7108.680109394986;7123.893141602984;7127.287271272831;6802;6839.076724642109;6840.521163518705;6849.1438570178725;6855.07456493387;6752.561083529555;7002.334778196063;7011.509194356346;7013;7020.368166864802;6570.474362050929;6605.372489613479;6629.171353580802;6646.7341306349135;6735.292794256533;6673.632950187786;6728.428083612947;6751.953447215689;6761.087253220752;6774.661266936732;5998.910016349845;6357.14178585885;6449.548531602931;6654.567453115532;6661.4004739573675;6599.4720422365135;6623.2383275923885;6642.83392915181;6679;6705.731770729116;6416.550841441054;6617.305182955913;6618.437432818265;6633.270340262471;6642.933570664425 04:34:48 INFO - PID 1647 | |1;ext.html;840.3025488844258;855.9144085591441;1210.8970737487314;1238.4736486993027;1270.269594982885;37518.686845960365;38412;38474.23051538971;38513.80743096288;38553.807230963874;38558.03604909879;38867.02832429191;38976;39002.804985975105;39028.80485597575;7803.648835802403;7888.842223155533;7937.761867143966;7939.1663875293125;7941.086775020889;7996.0404751429905;8236.83526329473;8237;8245.587720613976;8251;8272.545010024447;8277.213664701874;8338.916610833874;8341.249287564118;8341.708040218593;8208;8298.958505207496;8310.127436619128;8314.875276870851;8354.958225208866;8197.426180167391;8238.711645092393;8245.83508329836;8253.917460825374;8264.628091735887;36869;36874.63125368739;36925;36959.815200924095;37075.258494830225;3616.0598244456633;3620.330238905811;3646.033801042726;3647.8723244686576;3651.8539258429632;30592.694073059207;30713.38573228523;30828.2292942676;30894;30894.382112357627;2690.5964105384155;3133.4516459619476;3145.3866496033284;3149.9370012599848;3198.98400507996;335590.32204839063;336643;337712;337973;341957;39523.60476395199;40130.59869401327;40196.598034019284;48952;49660.751696241285;41278;51672.74163629233;51765;51774.22338664923;51795.482045179066;15597.68804623901;15638.765418518731;15658.451954181777;15661.373545058297;15664.765028524582;12548.309842958543;12755.362231888368;12784.61646150617;12853.935730321475;12872.970162386986;13699.589012329647;13760.380782864595;13764.724705506034;13789.310534473236;13840.961927855451;6141.723622437001;6150.138980542728;6165.445109940127;6173.475254603409;6200.472959798378;967.6129548180784;983.4148681534475;998.9500524973868;1026.2816028779885;1050.1231471721046;22150.667739983914;22470.213542525933;23382;23557.82210889449;24653.403559571438;1105.5577768892508;1136.1024790415645;1162.8546431696038;1166.4925757295603;1166.4984056855465;11754.823677644843;12530.185537940004;12546.686332841751;12583.307918064595;12669.271744236008;327671;329098;351007;351836;352450.23774881475;55165.72417137888;55405.445945540836;55681;55721.16418253729;55721.72139139359;1285.4344088600985;1522.2617030739862;1534.6393597504577;1545.0420739141805;1555.8755299576007;22497.550048999256;22989.770102298764;23172.3048308551;23405.882970585037;24298.51402971966;39008.82973510799;39060.218795624496;39088.02279943024;39091.82724518269;39163.21673566456;303895;304386.47806760826;305514.47242764535;305517;305568;29366.55950160792;32126;33506.83246583751;34677;34782.13044673904;42959.14081718286;44737.55262447464;44782.77608611936;44810.327845083004;44824;27964.161075167776;28091.157265282076;28575.9998400055;30470;30676.539851902686;29228.26929326784;29981.55027674543;30335.69664303417;30546.694533054386;30884.69115308818;35228.82385588055;36312.45531317085;36622.450663240605;37057.444138338484;37769.244615106996;28180.577291340236;29764.404711906074;30255.09234722962;30378.228136653965;30787;16824.495265142064;23711.407214819767;24296.635550466402;24531.509369812145;24536 04:34:48 INFO - PID 1647 | |2;jquery.html;490.01506970988316;517.6505858545481;523.9554637855782;525.9053370393328;546.7621584610692;17946.371876984307;20296.441306019733;22521.774782252174;22937.77062229377;23053.884730576345;23814.047438102498;24050.63924041141;24097.879510602423;24098.638520422206;24159;6072.878542429149;6218.284897236807;6336.2396512418345;6360.109584658128;6402.711877965479;5262.868428289305;6314.336994615568;6321.399467050645;6324.968375158141;6327.4938004959495;6268.059791031359;6269.153664255336;6308.116863639071;6312;6317.24193096829;5295.205719142159;5296.576273898079;6299.937000630004;6307.369263073679;6317.589356691823;5738;5766.394528574481;5766.394528574523;5768.163616275617;5769.826905192851;27488.725112748816;29273.85363073171;29650.406991860043;29735.851320743473;30087.398252035055;2233.3746550965757;2252.6170551006367;2259.6158653029024;2272.522770218256;2274.351809734218;18300.450986470427;18634.81365186358;18811.529711757317;18828.435146945612;18860.434186974413;2180.705604743371;2221.800037996588;2254.5829021631052;2257.796798288162;2282.703248577691;166005.16997415057;172862;181072;181225;206640;12872.356382180853;13180;13864.792028119586;15010.598304271483;15357.308921098578;16288.26702798377;19982.50043748918;20036.69944950827;20071.29750458727;20276.391708248775;8586.914130858611;9111.635534578676;10053.296269261227;10280.794384112276;10310.690679279634;9397.872255329405;9681.612735490502;9742.269329800294;9767.316287859781;9819.214462842956;7635.923640763633;8152.307053900485;8442.240198382175;9059.637614495346;9073.637054517745;2187.3437968609355;2354.2584086012853;2620.0960668569314;2632.06561670608;2637.07702304192;915.2037727177266;948.1229862377411;973.732223638491;982.7837875667342;984.2716389871443;20330.433219126757;20477.180912763324;20553.122562840916;20946.162153513993;21628.89185554062;967.5065716484644;1000.2548101664306;1014.8934361892043;1027.337367398039;1040.7086015915556;8443.873341899876;10399.376037437778;10701.197410194267;12731.490740370282;14580.63548411277;553480;567591;585020;586791;600605;169455;173770.13114934598;175406.99999999744;176934;177211.11394442944;1176.664650574582;1246.5948566715854;1255.6860784803798;1263.128441375448;1269.993650031744;21525.768670563957;22437.43906402353;22544.887275563517;22846.42883927915;23265.53468930578;72127.91808122778;74117.6294118526;74487.62756186184;74660.88008679982;74785.13037174114;87498.125018749;95369.18523259269;99372;100048;100072.49963750134;11348;11682.76634467323;11882.108841836724;11927.761444770882;11994.280343179436;17464.73802892983;18152.90923545374;18426.723599145756;18459.723104153716;18673.34643287533;15042.02226855251;15075.793936485274;15143.697126057197;15222.543323700307;15275.61810954735;10605.787884242427;12975;13538.390772415069;13659;14016.789748153582;11302.604408845651;15398.307076181824;15494.992825466308;15596.922015390304;15608.375664973273;9548.761280967754;9641.325107242426;11366.715832103933;11992.460339284928;12013.978811800745;14781.704365912407;14914.403423862925;14916.62708432298;15130.016548924285;15216.695666086394 04:34:48 INFO - PID 1647 | |3;mootools.html;1401.964950876228;1457.956261312161;1480.8593183647556;1483.2731961338948;1498.8576085271898;10769.515371808267;14119.78820317696;14152;14179.574612761608;14193.219372934498;13363.06458547902;14352.856471435283;14385.568432947002;14413.063150895185;14470;2822.4355128974235;2867.885284588614;2869.368738877444;2869.727375899286;2870.067228150859;2440.2923152285816;2636.5235468137903;2801.1876555798794;2809.381935974093;2813.352928826371;2666.919992400231;2754;2762.350847550824;2801.6638003439616;2813.240425085226;2818.2390754496273;2847.786416018786;2855.0578309157927;2864.6965630638115;2869.5982562441172;2784.832910025405;2788.735070168331;2795.9440811183867;2805.4950108980374;2819.2951762059483;13481;13721.588352349445;13743.725125497434;13749.243791591458;13804.102733322403;7591.848163036763;7836.294733473957;7893.092294386104;7900.486468379594;7951.562664053475;4750.477447480775;4877.365942427497;4897.363342765453;5012.624053195988;5038.420581633122;1405.9015868889182;1408.880245179161;1418.269591160553;1436.7629341158697;1440.3590402270993;20891.373258802414;20892;20910.686339704764;21083.367498975054;21139.47151321214;5788.957987562279;6403.775867844603;6594.637294948824;6786;6822.351876571718;14332.283385830668;14410.207438590978;14423;14437.494687685881;14441.711165776625;7298.197198308182;7552.471327007057;7571.053618297713;7571.129320128256;7636.274553917369;6795.932040679629;6901.1718593769065;7010.369066783912;7048.823779405556;7051.471139664545;5089.541941225308;5198.2202669599865;5209.6874187548865;5268.894622107537;5331;1317.9868201318056;1546.806649168854;1548.1794648836135;1550.7518796992426;1551.2709026757407;804.6459557794552;812.6586833529932;815.1685281013333;836.4186890111314;839.7144970709866;6770.458363330922;7334.229905859808;7397.075365579302;7744.264294892089;8129.1870812919415;802.8474589827914;998.4658207858906;1002.0580654185043;1012.8987101289812;1051.4112097225577;3996.3805610130476;4859.95140048602;4941.48114447985;4942.382202224722;5136.5890728741615;16530.752038719427;18410.35563755262;18552.628947421246;18627.534311642317;18839.81160188408;17316.653666926843;18324.175412106488;18740.81259187418;18743.906280468782;18783.718244226347;9498.95250523743;9566.66516671913;9758.072983066739;9776.853347199798;9803.166730827817;6501;7599.696012159452;7734.845303094019;7862.803429914298;8116.513009219466;16468.258928348012;16535.421260256313;16631.16844157763;16790;17009.234584443482;13312.600621981355;16122.677546448771;16811.91594042022;17068.74396884023;17282.913585431994;10729.463526823783;10968.396738179477;10972.615958441416;10985.780284394426;10996.835047474455;12236.021118310337;12239;12420.06849486274;12491.500339986662;12537.498500060261;9320.673776417792;9438.764030899283;9802.264830137625;9841.655542055994;10137.44244066554;9630.903690963281;9859.161971232239;9958.900410996088;10045.799084018425;10207;9013.324000699842;9093.77265568366;9134.725958221265;9169.954150229207;9231.169194772503;6715.127033485615;6807.727690892309;6815.909454487258;7057;7200.279972002758;6863.450923926173;6925.3074692530345;7209.206987231296;7236.819079522844;7294.270572942663 04:34:48 INFO - PID 1647 | |4;prototype.html;415.44745488500286;428.293316028553;444.9043455657033;471.6674744305264;472.29863652476047;5455.236266922634;5721.284839395071;6609.339066093388;6654.633995130266;6728.394444499994;5139.974300128494;5290.973545132269;6188.462730280586;6408.886136388605;6432.871342573146;4424.115176964604;4848.98171384008;4867.634927380443;4868.196747536661;4879.39007624047;3909.944315034939;4770.618350531967;4801.759912004403;4811.783469743852;4825.348577941968;4322.243607368714;4592.5407459253975;4766.594839438651;4786.162421576228;4829.1307564638355;4081.530623978236;4666.673332866701;4785.712857228577;4789.401324834395;4810.374651295309;4154.459920210384;4163.3130533461945;4178.8328466861285;4179.498460184767;4198.580141985807;5648.858778530528;6258.718357673915;6283.780067697654;6317.431431171217;6327.24073111225;5721.199032135504;5742.483176514092;5759.88965958382;5766.452187042224;5784.355855810854;6344.302126766052;6346.460550853183;6778.966105169492;7001.019857219993;7062.67890238239;4045.777482238476;4224.514180869209;4509.729416235036;4807.75961201942;4855.732934688591;5933.347331793542;6095.359987201368;6739.5619284746335;7453.366463850579;7473.626318684099;4957.578605818474;4958.1323268428605;4966;4977.477364876636;4981.576565991859;5034.320366750515;5060.797568097309;5062.949370506321;5074.771635276422;5099.9490005100215;5767.36558978512;5781.624194427349;5790.739416726257;5800.4489573490455;5813.848875915938;6530.114581823807;6971.477139214579;7004.579725216503;7067.2579379164445;7075.080239568823;1409.4644035266533;1566.169929937135;1594.2985086561876;1648.2994727241114;1693.6443346897042;937.554661535765;1011.8988101189822;1045.9906190526178;1133.3596517967321;1171.9648410547697;5442.809501667422;5496.972515137399;6024.909626355608;6535.313792051861;6539.705713242915;6520.608763474207;7111.679974401164;7216.494845360774;7239.638018099074;7296.270372962661;6500.414962653384;6500.967495162588;6552.099891031643;6555.442787363032;6577.664928950249;3171.873984735432;3532.6290739472497;3573.714102871764;3721.739478236552;3773.1699026214187;6326.620402775849;6408;7089.363955655266;7397;7424.925750742532;6707.5975441473665;7413.740519081806;7421.369183619345;7424.443166762514;7447.851042979219;6731.865362692719;7372.520786148776;7379.262073792578;7397.741079062207;7428.1457632371885;6117.296510901303;6288.245410550763;6530.575512591667;7188.4968052235845;7264.382527485223;5553.500184983371;5576.052071147834;6314.958031924681;6462.870742585216;7129.536580122275;5835.708214589169;6690.962900750392;7423.777286681408;7453.440991925519;7461.253874612712;4888.853334399973;4923.433805112458;4926.832193834702;4934.420985284674;4954.504549545017;6925.89611155823;7073.752418665322;7265.854682906206;7299.927000729925;7306.159791624032;5777.584491799368;5909.8227053188475;6796.2524122347495;6912.827179320558;6938.202106757787;6028.788992385245;6358.904616430665;6409.166808314889;6943.826404339932;6990.580565165903;5392.568594512351;6828.351306625863;6829.829254268683;6829.873070943438;6849.8972515411315;6137.355577664281;6839.487038472035;6980.685869135799;7009.299070093154;7019.719211231493;5603.831885043455;5897.439743224559;6406.519511036784;6813.761518346834;6825.419839313714 04:34:48 INFO - PID 1647 | |5;yui.html;752.7290175536809;848.9957550212251;855.050893508206;872.6596627315348;877.2411863737867;16456.588585285357;17022.48932532026;17097.65804683907;17103.40138095167;17107.23017464214;13975.301234938262;13981.091229070104;14044.789328160086;16710.415135470263;17183.484495465123;981.5632043740538;994.7363948553639;995.3331268050424;1002.7994401119769;1006.4816619440994;999.4503023337172;1016.7356487313315;1017.2370721958531;1022.1720406470745;1022.8721409823772;1037.341288281939;1051.3534176481485;1052.4527245832162;1053.294292823806;1064.632701717906;1003.8042581696573;1042.6455005298196;1045.2526443592792;1045.414567842011;1046.1421634259873;635.4598591197491;765.2615226306641;766.2031487253306;767.5049593012476;769.0655853138463;14010.159390436607;17389.739153912702;17494;17592.648147036987;17631.382901598383;17232;18172;18297.176627051682;18344;18370.448886533424;14112.364943577564;14442.49451269211;14519.346629401702;14615.488457904028;14651.706965860729;3575.910602234939;3582.5521809773777;3592.766470179456;3632.4732913727626;3667.6332366763377;103174.96825031804;129141.35429322887;130255;164779;167541.16229418898;12269.07981901361;12627.305498197687;12712.364381780873;13368.33158342079;13532.93233533826;25321.113761018278;25534;25552.74447255541;25555.87222063915;26295.21114366572;8290.917090829014;8307.875381869157;8388.119247478928;8388.916110838814;8392.992840859137;3904.1020565270146;3909.5308562972054;3973.6489593538336;4178.519470260898;4217.704760666786;4743.478217396084;4777.713337199779;4817.325574419514;4828.734419606955;4829.95170048302;1007.2194049611463;1056.0864851903048;1060.9363438193734;1066.4507778494083;1072.0940805019745;529.9470052994747;584.9327327357323;590.3417689276425;596.582392325374;617.7745123030062;9001.459912405277;11697.590584329508;11714.531418743325;12037.157398981988;12520.06099542538;568.8991947963875;575.6689903305617;595.8986972214734;600.129811772931;610.7662521706129;5657;5924.318703349084;5953.255843019622;5979.701014949235;6029.849253768603;149414;153983;156666;157112;160714;123379.38310308571;123543;126268;130587;135067;87593;98164.50917745508;100604;110215;112293.43853280682;11130.721731956442;12162.270263784201;12393.380330983235;12433.689157771129;12648.407076549469;41212.58787412087;42149;45660.40188593516;46434.30348544707;46440.30339544977;56072.29065989708;59886;84477.57761211401;85884.57057714672;88458.23083538422;16993.150342483073;17438.47684569465;17797.822021780135;17921.551961201076;18248.0875956199;18602.534936626693;19054.237830487178;19250.518737031685;19289.421317360502;19326.130324135727;14639.975201735777;16827.402726970326;17028.404005859735;17673.469795906145;18081.36715214961;17608;17890.28438862431;18074.819251807312;18245.361412350503;18451.44645660632;15385.615359616098;15687.372505099669;15868.365265389717;15870.68258634844;15882.364705411654;10907.509162087566;10922.617708380169;12078.396080196131;12095.939520302341;12268.202566833128;13148.34258287101;13187.934060329637;13495.865041349462;13682.93158534201;14406.639834004234 04:34:48 INFO - PID 1647 | __end_tp_report 04:34:48 INFO - PID 1647 | __start_cc_report 04:34:48 INFO - PID 1647 | _x_x_mozilla_cycle_collect,404 04:34:48 INFO - PID 1647 | __end_cc_report 04:34:48 INFO - PID 1647 | __startTimestamp1497353688325__endTimestamp 04:34:48 INFO - PID 1647 | 04:34:48 INFO - PID 1647 | ------- Summary: start ------- 04:34:48 INFO - PID 1647 | Number of tests: 6 04:34:48 INFO - PID 1647 | 04:34:48 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6308.52 Median:6570.47 stddev:750.38 (11.4%) stddev-sans-first:720.44 04:34:48 INFO - PID 1647 | Values: 3534.6 3625.3 3644.2 3654.2 3656.9 6238.1 6268.8 6290.8 6294.7 6297.5 6291.3 6327.6 6331.2 6337.1 6389.5 5503.5 5511.3 5515.9 5574.7 5632.7 5491.5 5549.5 5617.5 5621.9 5648.6 5429.3 5443.0 5501.7 5543.8 5602.6 5460.0 5470.7 5492.4 5500.7 5550.5 5447.8 5557.9 5574.2 5585.2 5603.4 6244.4 6289.6 6312.1 6334.9 6355.0 6900.2 6907.0 6907.5 6908.1 6913.6 6683.7 6785.0 6823.1 6980.0 7007.1 6245.2 6286.4 6406.5 6428.9 6437.1 7059.8 7077.9 7089.9 7094.0 7108.7 6555.7 6637.9 6648.3 6649.3 6765.1 6804.9 6860.6 6930.7 6970.5 6974.7 6802.4 6805.7 6820.9 6830.4 6834.7 6575.4 6693.6 6763.6 6843.5 6853.7 5380.3 5405.3 5420.3 5461.2 5495.8 4819.7 4900.4 4907.6 4985.1 5003.2 6279.5 6386.1 6440.8 6455.9 6478.3 6996.8 7005.8 7009.0 7020.1 7028.2 6242.0 6446.7 6454.7 6456.1 6484.2 5960.3 5961.8 5979.1 5983.7 6011.2 5784.4 6912.9 6973.9 6987.7 7033.3 6969.4 6990.4 6992.3 6996.7 7002.3 6006.2 7055.2 7108.7 7123.9 7127.3 6802.0 6839.1 6840.5 6849.1 6855.1 6752.6 7002.3 7011.5 7013.0 7020.4 6570.5 6605.4 6629.2 6646.7 6735.3 6673.6 6728.4 6752.0 6761.1 6774.7 5998.9 6357.1 6449.5 6654.6 6661.4 6599.5 6623.2 6642.8 6679.0 6705.7 6416.6 6617.3 6618.4 6633.3 6642.9 04:34:48 INFO - PID 1647 | 04:34:48 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:47327.96 Median:34729.57 stddev:86541.36 (249.2%) stddev-sans-first:86713.74 04:34:48 INFO - PID 1647 | Values: 840.3 855.9 1210.9 1238.5 1270.3 37518.7 38412.0 38474.2 38513.8 38553.8 38558.0 38867.0 38976.0 39002.8 39028.8 7803.6 7888.8 7937.8 7939.2 7941.1 7996.0 8236.8 8237.0 8245.6 8251.0 8272.5 8277.2 8338.9 8341.2 8341.7 8208.0 8299.0 8310.1 8314.9 8355.0 8197.4 8238.7 8245.8 8253.9 8264.6 36869.0 36874.6 36925.0 36959.8 37075.3 3616.1 3620.3 3646.0 3647.9 3651.9 30592.7 30713.4 30828.2 30894.0 30894.4 2690.6 3133.5 3145.4 3149.9 3199.0 335590.3 336643.0 337712.0 337973.0 341957.0 39523.6 40130.6 40196.6 48952.0 49660.8 41278.0 51672.7 51765.0 51774.2 51795.5 15597.7 15638.8 15658.5 15661.4 15664.8 12548.3 12755.4 12784.6 12853.9 12873.0 13699.6 13760.4 13764.7 13789.3 13841.0 6141.7 6150.1 6165.4 6173.5 6200.5 967.6 983.4 999.0 1026.3 1050.1 22150.7 22470.2 23382.0 23557.8 24653.4 1105.6 1136.1 1162.9 1166.5 1166.5 11754.8 12530.2 12546.7 12583.3 12669.3 327671.0 329098.0 351007.0 351836.0 352450.2 55165.7 55405.4 55681.0 55721.2 55721.7 1285.4 1522.3 1534.6 1545.0 1555.9 22497.6 22989.8 23172.3 23405.9 24298.5 39008.8 39060.2 39088.0 39091.8 39163.2 303895.0 304386.5 305514.5 305517.0 305568.0 29366.6 32126.0 33506.8 34677.0 34782.1 42959.1 44737.6 44782.8 44810.3 44824.0 27964.2 28091.2 28576.0 30470.0 30676.5 29228.3 29981.6 30335.7 30546.7 30884.7 35228.8 36312.5 36622.5 37057.4 37769.2 28180.6 29764.4 30255.1 30378.2 30787.0 16824.5 23711.4 24296.6 24531.5 24536.0 04:34:48 INFO - PID 1647 | 04:34:48 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:40397.49 Median:12388.18 stddev:100368.45 (810.2%) stddev-sans-first:100605.28 04:34:48 INFO - PID 1647 | Values: 490.0 517.7 524.0 525.9 546.8 17946.4 20296.4 22521.8 22937.8 23053.9 23814.0 24050.6 24097.9 24098.6 24159.0 6072.9 6218.3 6336.2 6360.1 6402.7 5262.9 6314.3 6321.4 6325.0 6327.5 6268.1 6269.2 6308.1 6312.0 6317.2 5295.2 5296.6 6299.9 6307.4 6317.6 5738.0 5766.4 5766.4 5768.2 5769.8 27488.7 29273.9 29650.4 29735.9 30087.4 2233.4 2252.6 2259.6 2272.5 2274.4 18300.5 18634.8 18811.5 18828.4 18860.4 2180.7 2221.8 2254.6 2257.8 2282.7 166005.2 172862.0 181072.0 181225.0 206640.0 12872.4 13180.0 13864.8 15010.6 15357.3 16288.3 19982.5 20036.7 20071.3 20276.4 8586.9 9111.6 10053.3 10280.8 10310.7 9397.9 9681.6 9742.3 9767.3 9819.2 7635.9 8152.3 8442.2 9059.6 9073.6 2187.3 2354.3 2620.1 2632.1 2637.1 915.2 948.1 973.7 982.8 984.3 20330.4 20477.2 20553.1 20946.2 21628.9 967.5 1000.3 1014.9 1027.3 1040.7 8443.9 10399.4 10701.2 12731.5 14580.6 553480.0 567591.0 585020.0 586791.0 600605.0 169455.0 173770.1 175407.0 176934.0 177211.1 1176.7 1246.6 1255.7 1263.1 1270.0 21525.8 22437.4 22544.9 22846.4 23265.5 72127.9 74117.6 74487.6 74660.9 74785.1 87498.1 95369.2 99372.0 100048.0 100072.5 11348.0 11682.8 11882.1 11927.8 11994.3 17464.7 18152.9 18426.7 18459.7 18673.3 15042.0 15075.8 15143.7 15222.5 15275.6 10605.8 12975.0 13538.4 13659.0 14016.8 11302.6 15398.3 15495.0 15596.9 15608.4 9548.8 9641.3 11366.7 11992.5 12014.0 14781.7 14914.4 14916.6 15130.0 15216.7 04:34:48 INFO - PID 1647 | 04:34:48 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:8357.07 Median:2818.77 stddev:5549.12 (196.9%) stddev-sans-first:5540.07 04:34:48 INFO - PID 1647 | Values: 1402.0 1458.0 1480.9 1483.3 1498.9 10769.5 14119.8 14152.0 14179.6 14193.2 13363.1 14352.9 14385.6 14413.1 14470.0 2822.4 2867.9 2869.4 2869.7 2870.1 2440.3 2636.5 2801.2 2809.4 2813.4 2666.9 2754.0 2762.4 2801.7 2813.2 2818.2 2847.8 2855.1 2864.7 2869.6 2784.8 2788.7 2795.9 2805.5 2819.3 13481.0 13721.6 13743.7 13749.2 13804.1 7591.8 7836.3 7893.1 7900.5 7951.6 4750.5 4877.4 4897.4 5012.6 5038.4 1405.9 1408.9 1418.3 1436.8 1440.4 20891.4 20892.0 20910.7 21083.4 21139.5 5789.0 6403.8 6594.6 6786.0 6822.4 14332.3 14410.2 14423.0 14437.5 14441.7 7298.2 7552.5 7571.1 7571.1 7636.3 6795.9 6901.2 7010.4 7048.8 7051.5 5089.5 5198.2 5209.7 5268.9 5331.0 1318.0 1546.8 1548.2 1550.8 1551.3 804.6 812.7 815.2 836.4 839.7 6770.5 7334.2 7397.1 7744.3 8129.2 802.8 998.5 1002.1 1012.9 1051.4 3996.4 4860.0 4941.5 4942.4 5136.6 16530.8 18410.4 18552.6 18627.5 18839.8 17316.7 18324.2 18740.8 18743.9 18783.7 9499.0 9566.7 9758.1 9776.9 9803.2 6501.0 7599.7 7734.8 7862.8 8116.5 16468.3 16535.4 16631.2 16790.0 17009.2 13312.6 16122.7 16811.9 17068.7 17282.9 10729.5 10968.4 10972.6 10985.8 10996.8 12236.0 12239.0 12420.1 12491.5 12537.5 9320.7 9438.8 9802.3 9841.7 10137.4 9630.9 9859.2 9958.9 10045.8 10207.0 9013.3 9093.8 9134.7 9170.0 9231.2 6715.1 6807.7 6815.9 7057.0 7200.3 6863.5 6925.3 7209.2 7236.8 7294.3 04:34:48 INFO - PID 1647 | 04:34:48 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5554.57 Median:6026.85 stddev:1719.44 (28.5%) stddev-sans-first:1680.44 04:34:48 INFO - PID 1647 | Values: 415.4 428.3 444.9 471.7 472.3 5455.2 5721.3 6609.3 6654.6 6728.4 5140.0 5291.0 6188.5 6408.9 6432.9 4424.1 4849.0 4867.6 4868.2 4879.4 3909.9 4770.6 4801.8 4811.8 4825.3 4322.2 4592.5 4766.6 4786.2 4829.1 4081.5 4666.7 4785.7 4789.4 4810.4 4154.5 4163.3 4178.8 4179.5 4198.6 5648.9 6258.7 6283.8 6317.4 6327.2 5721.2 5742.5 5759.9 5766.5 5784.4 6344.3 6346.5 6779.0 7001.0 7062.7 4045.8 4224.5 4509.7 4807.8 4855.7 5933.3 6095.4 6739.6 7453.4 7473.6 4957.6 4958.1 4966.0 4977.5 4981.6 5034.3 5060.8 5062.9 5074.8 5099.9 5767.4 5781.6 5790.7 5800.4 5813.8 6530.1 6971.5 7004.6 7067.3 7075.1 1409.5 1566.2 1594.3 1648.3 1693.6 937.6 1011.9 1046.0 1133.4 1172.0 5442.8 5497.0 6024.9 6535.3 6539.7 6520.6 7111.7 7216.5 7239.6 7296.3 6500.4 6501.0 6552.1 6555.4 6577.7 3171.9 3532.6 3573.7 3721.7 3773.2 6326.6 6408.0 7089.4 7397.0 7424.9 6707.6 7413.7 7421.4 7424.4 7447.9 6731.9 7372.5 7379.3 7397.7 7428.1 6117.3 6288.2 6530.6 7188.5 7264.4 5553.5 5576.1 6315.0 6462.9 7129.5 5835.7 6691.0 7423.8 7453.4 7461.3 4888.9 4923.4 4926.8 4934.4 4954.5 6925.9 7073.8 7265.9 7299.9 7306.2 5777.6 5909.8 6796.3 6912.8 6938.2 6028.8 6358.9 6409.2 6943.8 6990.6 5392.6 6828.4 6829.8 6829.9 6849.9 6137.4 6839.5 6980.7 7009.3 7019.7 5603.8 5897.4 6406.5 6813.8 6825.4 04:34:48 INFO - PID 1647 | 04:34:48 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:25953.65 Median:17466.24 stddev:40565.18 (232.2%) stddev-sans-first:40634.85 04:34:48 INFO - PID 1647 | Values: 752.7 849.0 855.1 872.7 877.2 16456.6 17022.5 17097.7 17103.4 17107.2 13975.3 13981.1 14044.8 16710.4 17183.5 981.6 994.7 995.3 1002.8 1006.5 999.5 1016.7 1017.2 1022.2 1022.9 1037.3 1051.4 1052.5 1053.3 1064.6 1003.8 1042.6 1045.3 1045.4 1046.1 635.5 765.3 766.2 767.5 769.1 14010.2 17389.7 17494.0 17592.6 17631.4 17232.0 18172.0 18297.2 18344.0 18370.4 14112.4 14442.5 14519.3 14615.5 14651.7 3575.9 3582.6 3592.8 3632.5 3667.6 103175.0 129141.4 130255.0 164779.0 167541.2 12269.1 12627.3 12712.4 13368.3 13532.9 25321.1 25534.0 25552.7 25555.9 26295.2 8290.9 8307.9 8388.1 8388.9 8393.0 3904.1 3909.5 3973.6 4178.5 4217.7 4743.5 4777.7 4817.3 4828.7 4830.0 1007.2 1056.1 1060.9 1066.5 1072.1 529.9 584.9 590.3 596.6 617.8 9001.5 11697.6 11714.5 12037.2 12520.1 568.9 575.7 595.9 600.1 610.8 5657.0 5924.3 5953.3 5979.7 6029.8 149414.0 153983.0 156666.0 157112.0 160714.0 123379.4 123543.0 126268.0 130587.0 135067.0 87593.0 98164.5 100604.0 110215.0 112293.4 11130.7 12162.3 12393.4 12433.7 12648.4 41212.6 42149.0 45660.4 46434.3 46440.3 56072.3 59886.0 84477.6 85884.6 88458.2 16993.2 17438.5 17797.8 17921.6 18248.1 18602.5 19054.2 19250.5 19289.4 19326.1 14640.0 16827.4 17028.4 17673.5 18081.4 17608.0 17890.3 18074.8 18245.4 18451.4 15385.6 15687.4 15868.4 15870.7 15882.4 10907.5 10922.6 12078.4 12095.9 12268.2 13148.3 13187.9 13495.9 13682.9 14406.6 04:34:48 INFO - PID 1647 | -------- Summary: end -------- 04:34:48 INFO - PID 1647 | 04:34:48 INFO - TEST-INFO | 1647: exit 0 04:34:48 INFO - TEST-OK | dromaeo_css | took 1143320ms 04:34:48 INFO - TEST-START | kraken 04:34:48 INFO - operating with platform_type : mac_ 04:34:48 INFO - Initialising browser for kraken test... 04:34:48 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpyvA6LG/profile 04:34:48 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 04:35:22 INFO - TEST-INFO | 1659: killed by SIGTERM 04:35:22 INFO - Browser initialized. 04:35:22 INFO - Running cycle 1/1 for kraken test... 04:35:22 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpyvA6LG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:35:24 INFO - PID 1663 | 2017-06-13 04:35:24.127 plugin-container[1664:10727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x974b, name = 'com.apple.tsm.portname' 04:35:24 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:24 INFO - PID 1663 | 2017-06-13 04:35:24.127 plugin-container[1664:10727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4813, name = 'com.apple.CFPasteboardClient' 04:35:24 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:24 INFO - PID 1663 | 2017-06-13 04:35:24.127 plugin-container[1664:10727] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:35:24 INFO - PID 1663 | 2017-06-13 04:35:24.919 plugin-container[1665:10772] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9647, name = 'com.apple.tsm.portname' 04:35:24 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:24 INFO - PID 1663 | 2017-06-13 04:35:24.919 plugin-container[1665:10772] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 04:35:24 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:24 INFO - PID 1663 | 2017-06-13 04:35:24.919 plugin-container[1665:10772] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:35:34 INFO - PID 1663 | RSS: Main: 214020096 04:35:34 INFO - PID 1663 | 04:35:37 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 04:35:38 INFO - PID 1663 | RSS: Main: 228327424 04:35:38 INFO - PID 1663 | 04:35:40 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 04:35:41 INFO - PID 1663 | RSS: Main: 237371392 04:35:41 INFO - PID 1663 | 04:35:44 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 04:35:45 INFO - PID 1663 | RSS: Main: 241643520 04:35:45 INFO - PID 1663 | 04:35:47 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 04:35:47 INFO - PID 1663 | RSS: Main: 243683328 04:35:47 INFO - PID 1663 | 04:35:49 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:35:50 INFO - PID 1663 | RSS: Main: 243924992 04:35:50 INFO - PID 1663 | 04:35:53 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 04:35:53 INFO - PID 1663 | RSS: Main: 248578048 04:35:53 INFO - PID 1663 | 04:35:56 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 04:35:57 INFO - PID 1663 | RSS: Main: 248897536 04:35:57 INFO - PID 1663 | 04:36:00 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 04:36:00 INFO - PID 1663 | RSS: Main: 250392576 04:36:00 INFO - PID 1663 | 04:36:02 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:36:03 INFO - PID 1663 | RSS: Main: 249524224 04:36:03 INFO - PID 1663 | 04:36:04 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:36:05 INFO - PID 1663 | RSS: Main: 249364480 04:36:05 INFO - PID 1663 | 04:36:07 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:36:08 INFO - PID 1663 | RSS: Main: 249552896 04:36:08 INFO - PID 1663 | 04:36:10 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:36:11 INFO - PID 1663 | RSS: Main: 249393152 04:36:11 INFO - PID 1663 | 04:36:14 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:36:15 INFO - PID 1663 | RSS: Main: 249675776 04:36:15 INFO - PID 1663 | 04:36:16 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 04:36:17 INFO - PID 1663 | RSS: Main: 251232256 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | __start_tp_report 04:36:17 INFO - PID 1663 | _x_x_mozilla_page_load 04:36:17 INFO - PID 1663 | _x_x_mozilla_page_load_details 04:36:17 INFO - PID 1663 | |i|pagename|runs| 04:36:17 INFO - PID 1663 | |0;ai-astar;103;125;91;126;168;171;135;105;141;143 04:36:17 INFO - PID 1663 | |1;audio-beat-detection;128;108;144;130;108;129;113;138;143;111 04:36:17 INFO - PID 1663 | |2;audio-dft;194;200;224;232;199;195;197;196;197;197 04:36:17 INFO - PID 1663 | |3;audio-fft;63;85;75;161;62;86;85;83;120;56 04:36:17 INFO - PID 1663 | |4;audio-oscillator;68;76;113;62;97;107;143;55;82;142 04:36:17 INFO - PID 1663 | |5;imaging-gaussian-blur;89;90;89;89;91;89;88;96;89;89 04:36:17 INFO - PID 1663 | |6;imaging-darkroom;96;97;96;96;98;97;96;97;97;97 04:36:17 INFO - PID 1663 | |7;imaging-desaturate;89;91;89;92;95;91;90;94;92;92 04:36:17 INFO - PID 1663 | |8;json-parse-financial;49;49;47;50;51;52;53;53;52;52 04:36:17 INFO - PID 1663 | |9;json-stringify-tinderbox;51;55;56;63;55;70;66;59;58;56 04:36:17 INFO - PID 1663 | |10;stanford-crypto-aes;79;82;79;81;81;77;80;81;78;77 04:36:17 INFO - PID 1663 | |11;stanford-crypto-ccm;149;152;156;149;142;138;152;153;136;169 04:36:17 INFO - PID 1663 | |12;stanford-crypto-pbkdf2;176;176;189;181;176;181;175;177;181;176 04:36:17 INFO - PID 1663 | |13;stanford-crypto-sha256-iterative;61;95;90;65;66;65;70;93;63;57 04:36:17 INFO - PID 1663 | __end_tp_report 04:36:17 INFO - PID 1663 | __start_cc_report 04:36:17 INFO - PID 1663 | _x_x_mozilla_cycle_collect,1011 04:36:17 INFO - PID 1663 | __end_cc_report 04:36:17 INFO - PID 1663 | __startTimestamp1497353777130__endTimestamp 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | ------- Summary: start ------- 04:36:17 INFO - PID 1663 | Number of tests: 14 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#0] ai-astar Cycles:10 Average:130.80 Median:142.00 stddev:26.56 (18.7%) stddev-sans-first:26.20 04:36:17 INFO - PID 1663 | Values: 103.0 125.0 91.0 126.0 168.0 171.0 135.0 105.0 141.0 143.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:129.50 stddev:14.23 (11.0%) stddev-sans-first:15.05 04:36:17 INFO - PID 1663 | Values: 128.0 108.0 144.0 130.0 108.0 129.0 113.0 138.0 143.0 111.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#2] audio-dft Cycles:10 Average:203.10 Median:198.00 stddev:13.37 (6.8%) stddev-sans-first:13.77 04:36:17 INFO - PID 1663 | Values: 194.0 200.0 224.0 232.0 199.0 195.0 197.0 196.0 197.0 197.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#3] audio-fft Cycles:10 Average:87.60 Median:79.00 stddev:31.43 (39.8%) stddev-sans-first:32.05 04:36:17 INFO - PID 1663 | Values: 63.0 85.0 75.0 161.0 62.0 86.0 85.0 83.0 120.0 56.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:65.00 stddev:31.50 (48.5%) stddev-sans-first:31.92 04:36:17 INFO - PID 1663 | Values: 68.0 76.0 113.0 62.0 97.0 107.0 143.0 55.0 82.0 142.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:89.00 stddev:2.28 (2.6%) stddev-sans-first:2.40 04:36:17 INFO - PID 1663 | Values: 89.0 90.0 89.0 89.0 91.0 89.0 88.0 96.0 89.0 89.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:97.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 04:36:17 INFO - PID 1663 | Values: 96.0 97.0 96.0 96.0 98.0 97.0 96.0 97.0 97.0 97.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:92.00 stddev:1.96 (2.1%) stddev-sans-first:1.86 04:36:17 INFO - PID 1663 | Values: 89.0 91.0 89.0 92.0 95.0 91.0 90.0 94.0 92.0 92.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:52.00 stddev:1.99 (3.8%) stddev-sans-first:2.00 04:36:17 INFO - PID 1663 | Values: 49.0 49.0 47.0 50.0 51.0 52.0 53.0 53.0 52.0 52.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:58.50 stddev:5.78 (9.9%) stddev-sans-first:5.38 04:36:17 INFO - PID 1663 | Values: 51.0 55.0 56.0 63.0 55.0 70.0 66.0 59.0 58.0 56.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:80.50 stddev:1.78 (2.2%) stddev-sans-first:1.88 04:36:17 INFO - PID 1663 | Values: 79.0 82.0 79.0 81.0 81.0 77.0 80.0 81.0 78.0 77.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:152.00 stddev:9.54 (6.3%) stddev-sans-first:10.11 04:36:17 INFO - PID 1663 | Values: 149.0 152.0 156.0 149.0 142.0 138.0 152.0 153.0 136.0 169.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:179.00 stddev:4.32 (2.4%) stddev-sans-first:4.46 04:36:17 INFO - PID 1663 | Values: 176.0 176.0 189.0 181.0 176.0 181.0 175.0 177.0 181.0 176.0 04:36:17 INFO - PID 1663 | 04:36:17 INFO - PID 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:68.00 stddev:14.36 (21.1%) stddev-sans-first:14.62 04:36:17 INFO - PID 1663 | Values: 61.0 95.0 90.0 65.0 66.0 65.0 70.0 93.0 63.0 57.0 04:36:17 INFO - PID 1663 | -------- Summary: end -------- 04:36:17 INFO - PID 1663 | 04:36:18 INFO - TEST-INFO | 1663: exit 0 04:36:18 INFO - TEST-OK | kraken | took 89245ms 04:36:18 INFO - SUITE-END | took 1232s 04:36:18 INFO - Completed test suite (00:20:32) 04:36:18 INFO - javascript benchmark 04:36:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8413.211617717694, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3534.5758508978925, 3625.347437461257, 3644.1982763791975, 3654.1960768630893, 3656.92686146277, 6238.071923180688, 6268.84327891803, 6290.842728931773, 6294.653794041325, 6297.5276854236, 6291.33940936202, 6327.620342779436, 6331.176946996896, 6337.112804207414, 6389.488840892729, 5503.477169668885, 5511.255980442629, 5515.944840551603, 5574.665520068788, 5632.718364081779, 5491.450854914497, 5549.528290095326, 5617.522510586605, 5621.943780562204, 5648.632838865481, 5429.267048948421, 5442.9930462864495, 5501.697406642633, 5543.750531226105, 5602.635828671144, 5459.972700136514, 5470.7264636768405, 5492.395836457987, 5500.697461639608, 5550.4727050930505, 5447.754851031713, 5557.916631250535, 5574.16387541872, 5585.190147428641, 5603.383627800979, 6244.438000579924, 6289.559730818844, 6312.147860038882, 6334.936650633527, 6354.968225158845, 6900.171979362509, 6907.033015377851, 6907.516473846833, 6908.1019467468905, 6913.619750913697, 6683.665816709194, 6785.0, 6823.147106611674, 6979.965100174467, 7007.089078419824, 6245.188125543696, 6286.371362863677, 6406.4554512866, 6428.939225027818, 6437.130987316746, 7059.82350441243, 7077.92922070783, 7089.929100708927, 7094.0, 7108.68010939509, 6555.672216389162, 6637.933620663732, 6648.335166483313, 6649.3018233085795, 6765.1205343305055, 6804.863902721918, 6860.5540639858245, 6930.7227710891, 6970.546914450544, 6974.721011159599, 6802.421794147553, 6805.693743781541, 6820.897686534706, 6830.419414349736, 6834.6582670866255, 6575.408213260829, 6693.631850248283, 6763.5941843489545, 6843.486738494533, 6853.691583878737, 5380.3274590676165, 5405.324334458192, 5420.322459692538, 5461.15352120422, 5495.807646732345, 4819.7108173509705, 4900.436449808318, 4907.55831975124, 4985.052839960396, 5003.224500202475, 6279.529035322369, 6386.10594516768, 6440.806775796734, 6455.935440645533, 6478.2550006749825, 6996.790096297119, 7005.824854378579, 7009.0, 7020.05229294049, 7028.226895041541, 6242.001279795211, 6446.709898054599, 6454.677266136674, 6456.128422662974, 6484.1894763154605, 5960.344362120163, 5961.7615295387695, 5979.103134529855, 5983.730732117066, 6011.158437818709, 5784.421557844182, 6912.861742765116, 6973.93026069743, 6987.6855541499735, 7033.331833475811, 6969.407600354027, 6990.405815505739, 6992.3357281058215, 6996.685149168198, 7002.334778196063, 6006.219191505251, 7055.18865330473, 7108.680109394986, 7123.893141602984, 7127.287271272831, 6802.0, 6839.076724642109, 6840.521163518705, 6849.1438570178725, 6855.07456493387, 6752.561083529555, 7002.334778196063, 7011.509194356346, 7013.0, 7020.368166864802, 6570.474362050929, 6605.372489613479, 6629.171353580802, 6646.7341306349135, 6735.292794256533, 6673.632950187786, 6728.428083612947, 6751.953447215689, 6761.087253220752, 6774.661266936732, 5998.910016349845, 6357.14178585885, 6449.548531602931, 6654.567453115532, 6661.4004739573675, 6599.4720422365135, 6623.2383275923885, 6642.83392915181, 6679.0, 6705.731770729116, 6416.550841441054, 6617.305182955913, 6618.437432818265, 6633.270340262471, 6642.933570664425], "value": 6257.489790402864, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [840.3025488844258, 855.9144085591441, 1210.8970737487314, 1238.4736486993027, 1270.269594982885, 37518.686845960365, 38412.0, 38474.23051538971, 38513.80743096288, 38553.807230963874, 38558.03604909879, 38867.02832429191, 38976.0, 39002.804985975105, 39028.80485597575, 7803.648835802403, 7888.842223155533, 7937.761867143966, 7939.1663875293125, 7941.086775020889, 7996.0404751429905, 8236.83526329473, 8237.0, 8245.587720613976, 8251.0, 8272.545010024447, 8277.213664701874, 8338.916610833874, 8341.249287564118, 8341.708040218593, 8208.0, 8298.958505207496, 8310.127436619128, 8314.875276870851, 8354.958225208866, 8197.426180167391, 8238.711645092393, 8245.83508329836, 8253.917460825374, 8264.628091735887, 36869.0, 36874.63125368739, 36925.0, 36959.815200924095, 37075.258494830225, 3616.0598244456633, 3620.330238905811, 3646.033801042726, 3647.8723244686576, 3651.8539258429632, 30592.694073059207, 30713.38573228523, 30828.2292942676, 30894.0, 30894.382112357627, 2690.5964105384155, 3133.4516459619476, 3145.3866496033284, 3149.9370012599848, 3198.98400507996, 335590.32204839063, 336643.0, 337712.0, 337973.0, 341957.0, 39523.60476395199, 40130.59869401327, 40196.598034019284, 48952.0, 49660.751696241285, 41278.0, 51672.74163629233, 51765.0, 51774.22338664923, 51795.482045179066, 15597.68804623901, 15638.765418518731, 15658.451954181777, 15661.373545058297, 15664.765028524582, 12548.309842958543, 12755.362231888368, 12784.61646150617, 12853.935730321475, 12872.970162386986, 13699.589012329647, 13760.380782864595, 13764.724705506034, 13789.310534473236, 13840.961927855451, 6141.723622437001, 6150.138980542728, 6165.445109940127, 6173.475254603409, 6200.472959798378, 967.6129548180784, 983.4148681534475, 998.9500524973868, 1026.2816028779885, 1050.1231471721046, 22150.667739983914, 22470.213542525933, 23382.0, 23557.82210889449, 24653.403559571438, 1105.5577768892508, 1136.1024790415645, 1162.8546431696038, 1166.4925757295603, 1166.4984056855465, 11754.823677644843, 12530.185537940004, 12546.686332841751, 12583.307918064595, 12669.271744236008, 327671.0, 329098.0, 351007.0, 351836.0, 352450.23774881475, 55165.72417137888, 55405.445945540836, 55681.0, 55721.16418253729, 55721.72139139359, 1285.4344088600985, 1522.2617030739862, 1534.6393597504577, 1545.0420739141805, 1555.8755299576007, 22497.550048999256, 22989.770102298764, 23172.3048308551, 23405.882970585037, 24298.51402971966, 39008.82973510799, 39060.218795624496, 39088.02279943024, 39091.82724518269, 39163.21673566456, 303895.0, 304386.47806760826, 305514.47242764535, 305517.0, 305568.0, 29366.55950160792, 32126.0, 33506.83246583751, 34677.0, 34782.13044673904, 42959.14081718286, 44737.55262447464, 44782.77608611936, 44810.327845083004, 44824.0, 27964.161075167776, 28091.157265282076, 28575.9998400055, 30470.0, 30676.539851902686, 29228.26929326784, 29981.55027674543, 30335.69664303417, 30546.694533054386, 30884.69115308818, 35228.82385588055, 36312.45531317085, 36622.450663240605, 37057.444138338484, 37769.244615106996, 28180.577291340236, 29764.404711906074, 30255.09234722962, 30378.228136653965, 30787.0, 16824.495265142064, 23711.407214819767, 24296.635550466402, 24531.509369812145, 24536.0], "value": 17664.274398161633, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.01506970988316, 517.6505858545481, 523.9554637855782, 525.9053370393328, 546.7621584610692, 17946.371876984307, 20296.441306019733, 22521.774782252174, 22937.77062229377, 23053.884730576345, 23814.047438102498, 24050.63924041141, 24097.879510602423, 24098.638520422206, 24159.0, 6072.878542429149, 6218.284897236807, 6336.2396512418345, 6360.109584658128, 6402.711877965479, 5262.868428289305, 6314.336994615568, 6321.399467050645, 6324.968375158141, 6327.4938004959495, 6268.059791031359, 6269.153664255336, 6308.116863639071, 6312.0, 6317.24193096829, 5295.205719142159, 5296.576273898079, 6299.937000630004, 6307.369263073679, 6317.589356691823, 5738.0, 5766.394528574481, 5766.394528574523, 5768.163616275617, 5769.826905192851, 27488.725112748816, 29273.85363073171, 29650.406991860043, 29735.851320743473, 30087.398252035055, 2233.3746550965757, 2252.6170551006367, 2259.6158653029024, 2272.522770218256, 2274.351809734218, 18300.450986470427, 18634.81365186358, 18811.529711757317, 18828.435146945612, 18860.434186974413, 2180.705604743371, 2221.800037996588, 2254.5829021631052, 2257.796798288162, 2282.703248577691, 166005.16997415057, 172862.0, 181072.0, 181225.0, 206640.0, 12872.356382180853, 13180.0, 13864.792028119586, 15010.598304271483, 15357.308921098578, 16288.26702798377, 19982.50043748918, 20036.69944950827, 20071.29750458727, 20276.391708248775, 8586.914130858611, 9111.635534578676, 10053.296269261227, 10280.794384112276, 10310.690679279634, 9397.872255329405, 9681.612735490502, 9742.269329800294, 9767.316287859781, 9819.214462842956, 7635.923640763633, 8152.307053900485, 8442.240198382175, 9059.637614495346, 9073.637054517745, 2187.3437968609355, 2354.2584086012853, 2620.0960668569314, 2632.06561670608, 2637.07702304192, 915.2037727177266, 948.1229862377411, 973.732223638491, 982.7837875667342, 984.2716389871443, 20330.433219126757, 20477.180912763324, 20553.122562840916, 20946.162153513993, 21628.89185554062, 967.5065716484644, 1000.2548101664306, 1014.8934361892043, 1027.337367398039, 1040.7086015915556, 8443.873341899876, 10399.376037437778, 10701.197410194267, 12731.490740370282, 14580.63548411277, 553480.0, 567591.0, 585020.0, 586791.0, 600605.0, 169455.0, 173770.13114934598, 175406.99999999744, 176934.0, 177211.11394442944, 1176.664650574582, 1246.5948566715854, 1255.6860784803798, 1263.128441375448, 1269.993650031744, 21525.768670563957, 22437.43906402353, 22544.887275563517, 22846.42883927915, 23265.53468930578, 72127.91808122778, 74117.6294118526, 74487.62756186184, 74660.88008679982, 74785.13037174114, 87498.125018749, 95369.18523259269, 99372.0, 100048.0, 100072.49963750134, 11348.0, 11682.76634467323, 11882.108841836724, 11927.761444770882, 11994.280343179436, 17464.73802892983, 18152.90923545374, 18426.723599145756, 18459.723104153716, 18673.34643287533, 15042.02226855251, 15075.793936485274, 15143.697126057197, 15222.543323700307, 15275.61810954735, 10605.787884242427, 12975.0, 13538.390772415069, 13659.0, 14016.789748153582, 11302.604408845651, 15398.307076181824, 15494.992825466308, 15596.922015390304, 15608.375664973273, 9548.761280967754, 9641.325107242426, 11366.715832103933, 11992.460339284928, 12013.978811800745, 14781.704365912407, 14914.403423862925, 14916.62708432298, 15130.016548924285, 15216.695666086394], "value": 11692.346384483575, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.964950876228, 1457.956261312161, 1480.8593183647556, 1483.2731961338948, 1498.8576085271898, 10769.515371808267, 14119.78820317696, 14152.0, 14179.574612761608, 14193.219372934498, 13363.06458547902, 14352.856471435283, 14385.568432947002, 14413.063150895185, 14470.0, 2822.4355128974235, 2867.885284588614, 2869.368738877444, 2869.727375899286, 2870.067228150859, 2440.2923152285816, 2636.5235468137903, 2801.1876555798794, 2809.381935974093, 2813.352928826371, 2666.919992400231, 2754.0, 2762.350847550824, 2801.6638003439616, 2813.240425085226, 2818.2390754496273, 2847.786416018786, 2855.0578309157927, 2864.6965630638115, 2869.5982562441172, 2784.832910025405, 2788.735070168331, 2795.9440811183867, 2805.4950108980374, 2819.2951762059483, 13481.0, 13721.588352349445, 13743.725125497434, 13749.243791591458, 13804.102733322403, 7591.848163036763, 7836.294733473957, 7893.092294386104, 7900.486468379594, 7951.562664053475, 4750.477447480775, 4877.365942427497, 4897.363342765453, 5012.624053195988, 5038.420581633122, 1405.9015868889182, 1408.880245179161, 1418.269591160553, 1436.7629341158697, 1440.3590402270993, 20891.373258802414, 20892.0, 20910.686339704764, 21083.367498975054, 21139.47151321214, 5788.957987562279, 6403.775867844603, 6594.637294948824, 6786.0, 6822.351876571718, 14332.283385830668, 14410.207438590978, 14423.0, 14437.494687685881, 14441.711165776625, 7298.197198308182, 7552.471327007057, 7571.053618297713, 7571.129320128256, 7636.274553917369, 6795.932040679629, 6901.1718593769065, 7010.369066783912, 7048.823779405556, 7051.471139664545, 5089.541941225308, 5198.2202669599865, 5209.6874187548865, 5268.894622107537, 5331.0, 1317.9868201318056, 1546.806649168854, 1548.1794648836135, 1550.7518796992426, 1551.2709026757407, 804.6459557794552, 812.6586833529932, 815.1685281013333, 836.4186890111314, 839.7144970709866, 6770.458363330922, 7334.229905859808, 7397.075365579302, 7744.264294892089, 8129.1870812919415, 802.8474589827914, 998.4658207858906, 1002.0580654185043, 1012.8987101289812, 1051.4112097225577, 3996.3805610130476, 4859.95140048602, 4941.48114447985, 4942.382202224722, 5136.5890728741615, 16530.752038719427, 18410.35563755262, 18552.628947421246, 18627.534311642317, 18839.81160188408, 17316.653666926843, 18324.175412106488, 18740.81259187418, 18743.906280468782, 18783.718244226347, 9498.95250523743, 9566.66516671913, 9758.072983066739, 9776.853347199798, 9803.166730827817, 6501.0, 7599.696012159452, 7734.845303094019, 7862.803429914298, 8116.513009219466, 16468.258928348012, 16535.421260256313, 16631.16844157763, 16790.0, 17009.234584443482, 13312.600621981355, 16122.677546448771, 16811.91594042022, 17068.74396884023, 17282.913585431994, 10729.463526823783, 10968.396738179477, 10972.615958441416, 10985.780284394426, 10996.835047474455, 12236.021118310337, 12239.0, 12420.06849486274, 12491.500339986662, 12537.498500060261, 9320.673776417792, 9438.764030899283, 9802.264830137625, 9841.655542055994, 10137.44244066554, 9630.903690963281, 9859.161971232239, 9958.900410996088, 10045.799084018425, 10207.0, 9013.324000699842, 9093.77265568366, 9134.725958221265, 9169.954150229207, 9231.169194772503, 6715.127033485615, 6807.727690892309, 6815.909454487258, 7057.0, 7200.279972002758, 6863.450923926173, 6925.3074692530345, 7209.206987231296, 7236.819079522844, 7294.270572942663], "value": 6190.904058691036, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.44745488500286, 428.293316028553, 444.9043455657033, 471.6674744305264, 472.29863652476047, 5455.236266922634, 5721.284839395071, 6609.339066093388, 6654.633995130266, 6728.394444499994, 5139.974300128494, 5290.973545132269, 6188.462730280586, 6408.886136388605, 6432.871342573146, 4424.115176964604, 4848.98171384008, 4867.634927380443, 4868.196747536661, 4879.39007624047, 3909.944315034939, 4770.618350531967, 4801.759912004403, 4811.783469743852, 4825.348577941968, 4322.243607368714, 4592.5407459253975, 4766.594839438651, 4786.162421576228, 4829.1307564638355, 4081.530623978236, 4666.673332866701, 4785.712857228577, 4789.401324834395, 4810.374651295309, 4154.459920210384, 4163.3130533461945, 4178.8328466861285, 4179.498460184767, 4198.580141985807, 5648.858778530528, 6258.718357673915, 6283.780067697654, 6317.431431171217, 6327.24073111225, 5721.199032135504, 5742.483176514092, 5759.88965958382, 5766.452187042224, 5784.355855810854, 6344.302126766052, 6346.460550853183, 6778.966105169492, 7001.019857219993, 7062.67890238239, 4045.777482238476, 4224.514180869209, 4509.729416235036, 4807.75961201942, 4855.732934688591, 5933.347331793542, 6095.359987201368, 6739.5619284746335, 7453.366463850579, 7473.626318684099, 4957.578605818474, 4958.1323268428605, 4966.0, 4977.477364876636, 4981.576565991859, 5034.320366750515, 5060.797568097309, 5062.949370506321, 5074.771635276422, 5099.9490005100215, 5767.36558978512, 5781.624194427349, 5790.739416726257, 5800.4489573490455, 5813.848875915938, 6530.114581823807, 6971.477139214579, 7004.579725216503, 7067.2579379164445, 7075.080239568823, 1409.4644035266533, 1566.169929937135, 1594.2985086561876, 1648.2994727241114, 1693.6443346897042, 937.554661535765, 1011.8988101189822, 1045.9906190526178, 1133.3596517967321, 1171.9648410547697, 5442.809501667422, 5496.972515137399, 6024.909626355608, 6535.313792051861, 6539.705713242915, 6520.608763474207, 7111.679974401164, 7216.494845360774, 7239.638018099074, 7296.270372962661, 6500.414962653384, 6500.967495162588, 6552.099891031643, 6555.442787363032, 6577.664928950249, 3171.873984735432, 3532.6290739472497, 3573.714102871764, 3721.739478236552, 3773.1699026214187, 6326.620402775849, 6408.0, 7089.363955655266, 7397.0, 7424.925750742532, 6707.5975441473665, 7413.740519081806, 7421.369183619345, 7424.443166762514, 7447.851042979219, 6731.865362692719, 7372.520786148776, 7379.262073792578, 7397.741079062207, 7428.1457632371885, 6117.296510901303, 6288.245410550763, 6530.575512591667, 7188.4968052235845, 7264.382527485223, 5553.500184983371, 5576.052071147834, 6314.958031924681, 6462.870742585216, 7129.536580122275, 5835.708214589169, 6690.962900750392, 7423.777286681408, 7453.440991925519, 7461.253874612712, 4888.853334399973, 4923.433805112458, 4926.832193834702, 4934.420985284674, 4954.504549545017, 6925.89611155823, 7073.752418665322, 7265.854682906206, 7299.927000729925, 7306.159791624032, 5777.584491799368, 5909.8227053188475, 6796.2524122347495, 6912.827179320558, 6938.202106757787, 6028.788992385245, 6358.904616430665, 6409.166808314889, 6943.826404339932, 6990.580565165903, 5392.568594512351, 6828.351306625863, 6829.829254268683, 6829.873070943438, 6849.8972515411315, 6137.355577664281, 6839.487038472035, 6980.685869135799, 7009.299070093154, 7019.719211231493, 5603.831885043455, 5897.439743224559, 6406.519511036784, 6813.761518346834, 6825.419839313714], "value": 5025.925701689652, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [752.7290175536809, 848.9957550212251, 855.050893508206, 872.6596627315348, 877.2411863737867, 16456.588585285357, 17022.48932532026, 17097.65804683907, 17103.40138095167, 17107.23017464214, 13975.301234938262, 13981.091229070104, 14044.789328160086, 16710.415135470263, 17183.484495465123, 981.5632043740538, 994.7363948553639, 995.3331268050424, 1002.7994401119769, 1006.4816619440994, 999.4503023337172, 1016.7356487313315, 1017.2370721958531, 1022.1720406470745, 1022.8721409823772, 1037.341288281939, 1051.3534176481485, 1052.4527245832162, 1053.294292823806, 1064.632701717906, 1003.8042581696573, 1042.6455005298196, 1045.2526443592792, 1045.414567842011, 1046.1421634259873, 635.4598591197491, 765.2615226306641, 766.2031487253306, 767.5049593012476, 769.0655853138463, 14010.159390436607, 17389.739153912702, 17494.0, 17592.648147036987, 17631.382901598383, 17232.0, 18172.0, 18297.176627051682, 18344.0, 18370.448886533424, 14112.364943577564, 14442.49451269211, 14519.346629401702, 14615.488457904028, 14651.706965860729, 3575.910602234939, 3582.5521809773777, 3592.766470179456, 3632.4732913727626, 3667.6332366763377, 103174.96825031804, 129141.35429322887, 130255.0, 164779.0, 167541.16229418898, 12269.07981901361, 12627.305498197687, 12712.364381780873, 13368.33158342079, 13532.93233533826, 25321.113761018278, 25534.0, 25552.74447255541, 25555.87222063915, 26295.21114366572, 8290.917090829014, 8307.875381869157, 8388.119247478928, 8388.916110838814, 8392.992840859137, 3904.1020565270146, 3909.5308562972054, 3973.6489593538336, 4178.519470260898, 4217.704760666786, 4743.478217396084, 4777.713337199779, 4817.325574419514, 4828.734419606955, 4829.95170048302, 1007.2194049611463, 1056.0864851903048, 1060.9363438193734, 1066.4507778494083, 1072.0940805019745, 529.9470052994747, 584.9327327357323, 590.3417689276425, 596.582392325374, 617.7745123030062, 9001.459912405277, 11697.590584329508, 11714.531418743325, 12037.157398981988, 12520.06099542538, 568.8991947963875, 575.6689903305617, 595.8986972214734, 600.129811772931, 610.7662521706129, 5657.0, 5924.318703349084, 5953.255843019622, 5979.701014949235, 6029.849253768603, 149414.0, 153983.0, 156666.0, 157112.0, 160714.0, 123379.38310308571, 123543.0, 126268.0, 130587.0, 135067.0, 87593.0, 98164.50917745508, 100604.0, 110215.0, 112293.43853280682, 11130.721731956442, 12162.270263784201, 12393.380330983235, 12433.689157771129, 12648.407076549469, 41212.58787412087, 42149.0, 45660.40188593516, 46434.30348544707, 46440.30339544977, 56072.29065989708, 59886.0, 84477.57761211401, 85884.57057714672, 88458.23083538422, 16993.150342483073, 17438.47684569465, 17797.822021780135, 17921.551961201076, 18248.0875956199, 18602.534936626693, 19054.237830487178, 19250.518737031685, 19289.421317360502, 19326.130324135727, 14639.975201735777, 16827.402726970326, 17028.404005859735, 17673.469795906145, 18081.36715214961, 17608.0, 17890.28438862431, 18074.819251807312, 18245.361412350503, 18451.44645660632, 15385.615359616098, 15687.372505099669, 15868.365265389717, 15870.68258634844, 15882.364705411654, 10907.509162087566, 10922.617708380169, 12078.396080196131, 12095.939520302341, 12268.202566833128, 13148.34258287101, 13187.934060329637, 13495.865041349462, 13682.93158534201, 14406.639834004234], "value": 8818.117859163536, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1509.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 125.0, 91.0, 126.0, 168.0, 171.0, 135.0, 105.0, 141.0, 143.0], "value": 130.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 108.0, 144.0, 130.0, 108.0, 129.0, 113.0, 138.0, 143.0, 111.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 200.0, 224.0, 232.0, 199.0, 195.0, 197.0, 196.0, 197.0, 197.0], "value": 203.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 85.0, 75.0, 161.0, 62.0, 86.0, 85.0, 83.0, 120.0, 56.0], "value": 87.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 113.0, 62.0, 97.0, 107.0, 143.0, 55.0, 82.0, 142.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 89.0, 91.0, 89.0, 88.0, 96.0, 89.0, 89.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 98.0, 97.0, 96.0, 97.0, 97.0, 97.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 89.0, 92.0, 95.0, 91.0, 90.0, 94.0, 92.0, 92.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 47.0, 50.0, 51.0, 52.0, 53.0, 53.0, 52.0, 52.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 55.0, 56.0, 63.0, 55.0, 70.0, 66.0, 59.0, 58.0, 56.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 79.0, 81.0, 81.0, 77.0, 80.0, 81.0, 78.0, 77.0], "value": 79.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 156.0, 149.0, 142.0, 138.0, 152.0, 153.0, 136.0, 169.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 189.0, 181.0, 176.0, 181.0, 175.0, 177.0, 181.0, 176.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 95.0, 90.0, 65.0, 66.0, 65.0, 70.0, 93.0, 63.0, 57.0], "value": 72.5, "unit": "score"}]}]} 04:36:18 INFO - Return code: 0 04:36:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:36:18 INFO - # TBPL SUCCESS # 04:36:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:36:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:36:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:36:18 INFO - 2017-06-13 04:36:18.530 screenresolution[1671:11158] starting screenresolution argv=screenresolution get 04:36:18 INFO - 2017-06-13 04:36:18.532 screenresolution[1671:11158] Display 0: 1600x1200x32@60 04:36:18 INFO - 2017-06-13 04:36:18.538 screenresolution[1672:11161] starting screenresolution argv=screenresolution list 04:36:18 INFO - Available Modes on Display 0 04:36:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:36:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:36:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:36:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:36:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:36:18 INFO - Intel Iris: 04:36:18 INFO - Chipset Model: Intel Iris 04:36:18 INFO - Type: GPU 04:36:18 INFO - Bus: Built-In 04:36:18 INFO - VRAM (Dynamic, Max): 1536 MB 04:36:18 INFO - Vendor: Intel (0x8086) 04:36:18 INFO - Device ID: 0x0a2e 04:36:18 INFO - Revision ID: 0x0009 04:36:18 INFO - Displays: 04:36:18 INFO - Display: 04:36:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:36:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:36:18 INFO - Display Serial Number: 1600x1200 60 04:36:18 INFO - Main Display: Yes 04:36:18 INFO - Mirror: Off 04:36:18 INFO - Online: Yes 04:36:18 INFO - Rotation: Supported 04:36:18 INFO - Return code: 0 04:36:18 INFO - Running post-action listener: _resource_record_post_action 04:36:18 INFO - [mozharness: 2017-06-13 11:36:18.776981Z] Finished run-tests step (success) 04:36:18 INFO - Running post-run listener: _resource_record_post_run 04:36:18 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 40733184; Write bytes: 562556928; Read time: 268; Write time: 1715 04:36:18 INFO - TinderboxPrint: CPU usage
52.9% 04:36:18 INFO - TinderboxPrint: I/O read bytes / time
40,733,184 / 268 04:36:18 INFO - TinderboxPrint: I/O write bytes / time
562,556,928 / 1,715 04:36:18 INFO - TinderboxPrint: CPU idle
2,364.3 (47.1%) 04:36:18 INFO - TinderboxPrint: CPU system
1,012.5 (20.2%) 04:36:18 INFO - TinderboxPrint: CPU user
1,644.9 (32.8%) 04:36:18 INFO - TinderboxPrint: Swap in / out
284,073,984 / 0 04:36:18 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244099584; Write bytes: 246908928; Read time: 18707; Write time: 532 04:36:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:36:18 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 36547072; Write bytes: 314255360; Read time: 241; Write time: 1178 04:36:19 INFO - Running post-run listener: _upload_blobber_files 04:36:19 INFO - Blob upload gear active. 04:36:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:36:19 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 04:36:19 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:36:19 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:36:19 INFO - (blobuploader) - INFO - Open directory for files ... 04:36:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:36:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:36:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:20 INFO - (blobuploader) - INFO - Done attempting. 04:36:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:36:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:36:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:21 INFO - (blobuploader) - INFO - Done attempting. 04:36:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:36:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:36:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:22 INFO - (blobuploader) - INFO - Done attempting. 04:36:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:36:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:36:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:36:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:36:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:36:24 INFO - (blobuploader) - INFO - Done attempting. 04:36:24 INFO - (blobuploader) - INFO - Iteration through files over. 04:36:24 INFO - Return code: 0 04:36:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:36:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:36:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/31186aca6ef4d39337e336230366f039c1638b01d094a16ea898c1a1de2afc9a0b255b69ce26991496baf9e67630b6115350d593720ec5fbe681b623be078c38", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6e205a5149482a648d2f45be9d686241b0c2b2bd0715d7d273c447396ac060233bab87cc41825ee3366793ec872a8d992dfdcaa12aa43fb4b0416e14e9f2a8b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28aab1a902d5c15715119f62676b1a67b4e38a98020c7e03bead858e7dc0005c92c1cd23b89bf6f8531c6e2c93d87c8cd053f609cc733e5713aeace018c32687", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/daa9ef0188df955b5cf7daccc67bf4763c213b5645aa121821bd66c2fb105e0428fd3eb2db7fa732066168a2f77973425b2f1c9f3270de371bca3172b92d631a"} 04:36:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:36:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:36:24 INFO - Contents: 04:36:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/31186aca6ef4d39337e336230366f039c1638b01d094a16ea898c1a1de2afc9a0b255b69ce26991496baf9e67630b6115350d593720ec5fbe681b623be078c38", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6e205a5149482a648d2f45be9d686241b0c2b2bd0715d7d273c447396ac060233bab87cc41825ee3366793ec872a8d992dfdcaa12aa43fb4b0416e14e9f2a8b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28aab1a902d5c15715119f62676b1a67b4e38a98020c7e03bead858e7dc0005c92c1cd23b89bf6f8531c6e2c93d87c8cd053f609cc733e5713aeace018c32687", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/daa9ef0188df955b5cf7daccc67bf4763c213b5645aa121821bd66c2fb105e0428fd3eb2db7fa732066168a2f77973425b2f1c9f3270de371bca3172b92d631a"} 04:36:24 INFO - Running post-run listener: copy_logs_to_upload_dir 04:36:24 INFO - Copying logs to upload dir... 04:36:24 INFO - mkdir: /builds/slave/test/build/upload/logs 04:36:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1335.755705 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-06-13 04:36:24.980643) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 04:36:24.984571) ========= 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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/31186aca6ef4d39337e336230366f039c1638b01d094a16ea898c1a1de2afc9a0b255b69ce26991496baf9e67630b6115350d593720ec5fbe681b623be078c38", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6e205a5149482a648d2f45be9d686241b0c2b2bd0715d7d273c447396ac060233bab87cc41825ee3366793ec872a8d992dfdcaa12aa43fb4b0416e14e9f2a8b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28aab1a902d5c15715119f62676b1a67b4e38a98020c7e03bead858e7dc0005c92c1cd23b89bf6f8531c6e2c93d87c8cd053f609cc733e5713aeace018c32687", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/daa9ef0188df955b5cf7daccc67bf4763c213b5645aa121821bd66c2fb105e0428fd3eb2db7fa732066168a2f77973425b2f1c9f3270de371bca3172b92d631a"} build_url:https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011057 build_url: 'https://queue.taskcluster.net/v1/task/UDp5WvNrSfWxZKfds6l-8w/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/31186aca6ef4d39337e336230366f039c1638b01d094a16ea898c1a1de2afc9a0b255b69ce26991496baf9e67630b6115350d593720ec5fbe681b623be078c38", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6e205a5149482a648d2f45be9d686241b0c2b2bd0715d7d273c447396ac060233bab87cc41825ee3366793ec872a8d992dfdcaa12aa43fb4b0416e14e9f2a8b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28aab1a902d5c15715119f62676b1a67b4e38a98020c7e03bead858e7dc0005c92c1cd23b89bf6f8531c6e2c93d87c8cd053f609cc733e5713aeace018c32687", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/daa9ef0188df955b5cf7daccc67bf4763c213b5645aa121821bd66c2fb105e0428fd3eb2db7fa732066168a2f77973425b2f1c9f3270de371bca3172b92d631a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 04:36:25.017475) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:36:25.017804) ========= 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.Ovm3uWgVsl/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.kHkub1P2sU/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.005317 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 04:36:25.067220) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 04:36:25.067583) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 04:36:25.067942) ========= ========= Total master_lag: 0.47 =========