builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0348 starttime: 1495645151.79 results: success (0) buildid: 20170524084320 builduid: c3d2f40f83344e1db21b6ed6e6b8c173 revision: b3b1b2125fc0b242026a926b1db8c7f23330cc99 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:11.793941) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:11.794433) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:11.794722) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:11.818386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:11.818760) ========= 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.9Kgz32bsnX/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.6wuCm8EERm/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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-05-24 09:59:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-05-24 09:59:11 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:11.943617) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:11.943952) ========= 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.9Kgz32bsnX/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.6wuCm8EERm/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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.022014 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:11.995131) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:11.995730) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b3b1b2125fc0b242026a926b1db8c7f23330cc99 --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 b3b1b2125fc0b242026a926b1db8c7f23330cc99 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9Kgz32bsnX/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.6wuCm8EERm/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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-05-24 09:59:11,997 truncating revision to first 12 chars 2017-05-24 09:59:11,998 Setting DEBUG logging. 2017-05-24 09:59:11,998 attempt 1/10 2017-05-24 09:59:11,998 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b3b1b2125fc0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 09:59:12,696 unpacking tar archive at: mozilla-beta-b3b1b2125fc0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.963706 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:12.982049) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:12.982371) ========= script_repo_revision: b3b1b2125fc0b242026a926b1db8c7f23330cc99 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:12.982761) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:12.983027) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 09:59:13.001620) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-05-24 09:59:13.001905) ========= /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.9Kgz32bsnX/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.6wuCm8EERm/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 09:59:13 INFO - MultiFileLogger online at 20170524 09:59:13 in /builds/slave/test 09:59:13 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 09:59:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:59:13 INFO - {'append_to_log': False, 09:59:13 INFO - 'base_work_dir': '/builds/slave/test', 09:59:13 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:59:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:59:13 INFO - 'branch': 'Mozilla-Beta', 09:59:13 INFO - 'buildbot_json_path': 'buildprops.json', 09:59:13 INFO - 'config_files': ('talos/mac_config.py',), 09:59:13 INFO - 'default_actions': ('clobber', 09:59:13 INFO - 'read-buildbot-config', 09:59:13 INFO - 'download-and-extract', 09:59:13 INFO - 'populate-webroot', 09:59:13 INFO - 'create-virtualenv', 09:59:13 INFO - 'install', 09:59:13 INFO - 'run-tests'), 09:59:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:59:13 INFO - 'download_minidump_stackwalk': True, 09:59:13 INFO - 'download_symbols': 'ondemand', 09:59:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:59:13 INFO - 'tooltool.py': '/tools/tooltool.py', 09:59:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:59:13 INFO - '/tools/misc-python/virtualenv.py')}, 09:59:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:59:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:59:13 INFO - 'gecko_profile': False, 09:59:13 INFO - 'gecko_profile_interval': 0, 09:59:13 INFO - 'installer_path': 'installer.exe', 09:59:13 INFO - 'log_level': 'info', 09:59:13 INFO - 'log_name': 'talos', 09:59:13 INFO - 'log_to_console': True, 09:59:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:59:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:59:13 INFO - 'opt_config_files': (), 09:59:13 INFO - 'pip_index': False, 09:59:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:59:13 INFO - 'cmd': ('bash', 09:59:13 INFO - '-c', 09:59:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:59:13 INFO - 'enabled': True, 09:59:13 INFO - 'halt_on_failure': False, 09:59:13 INFO - 'name': 'check_screen_resolution'},), 09:59:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:59:13 INFO - 'cmd': ('bash', 09:59:13 INFO - '-c', 09:59:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:59:13 INFO - 'enabled': True, 09:59:13 INFO - 'halt_on_failure': False, 09:59:13 INFO - 'name': 'check_screen_resolution'},), 09:59:13 INFO - 'run_cmd_checks_enabled': True, 09:59:13 INFO - 'suite': 'dromaeojs-e10s', 09:59:13 INFO - 'system_bits': '32', 09:59:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:59:13 INFO - 'title': 't-yosemite-r7-0348', 09:59:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:59:13 INFO - 'use_talos_json': True, 09:59:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:59:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:59:13 INFO - 'work_dir': 'build'} 09:59:13 INFO - [mozharness: 2017-05-24 16:59:13.129892Z] Running clobber step. 09:59:13 INFO - Running pre-action listener: _resource_record_pre_action 09:59:13 INFO - Running main action method: clobber 09:59:13 INFO - rmtree: /builds/slave/test/build 09:59:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:59:13 INFO - Running post-action listener: _resource_record_post_action 09:59:13 INFO - [mozharness: 2017-05-24 16:59:13.456203Z] Finished clobber step (success) 09:59:13 INFO - [mozharness: 2017-05-24 16:59:13.456394Z] Running read-buildbot-config step. 09:59:13 INFO - Running pre-action listener: _resource_record_pre_action 09:59:13 INFO - Running main action method: read_buildbot_config 09:59:13 INFO - Using buildbot properties: 09:59:13 INFO - { 09:59:13 INFO - "project": "", 09:59:13 INFO - "product": "firefox", 09:59:13 INFO - "script_repo_revision": "production", 09:59:13 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:59:13 INFO - "repository": "", 09:59:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:59:13 INFO - "buildid": "20170524084320", 09:59:13 INFO - "pgo_build": "False", 09:59:13 INFO - "basedir": "/builds/slave/test", 09:59:13 INFO - "buildnumber": 2, 09:59:13 INFO - "slavename": "t-yosemite-r7-0348", 09:59:13 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:59:13 INFO - "platform": "yosemite_r7", 09:59:13 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:59:13 INFO - "branch": "mozilla-beta", 09:59:13 INFO - "repo_path": "releases/mozilla-beta", 09:59:13 INFO - "revision": "b3b1b2125fc0b242026a926b1db8c7f23330cc99", 09:59:13 INFO - "stage_platform": "macosx64", 09:59:13 INFO - "builduid": "c3d2f40f83344e1db21b6ed6e6b8c173", 09:59:13 INFO - "slavebuilddir": "test" 09:59:13 INFO - } 09:59:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:59:13 INFO - Running post-action listener: _resource_record_post_action 09:59:13 INFO - [mozharness: 2017-05-24 16:59:13.460019Z] Finished read-buildbot-config step (success) 09:59:13 INFO - [mozharness: 2017-05-24 16:59:13.460156Z] Running download-and-extract step. 09:59:13 INFO - Running pre-action listener: _resource_record_pre_action 09:59:13 INFO - Running main action method: download_and_extract 09:59:13 INFO - mkdir: /builds/slave/test/build/tests 09:59:13 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')]} 09:59:13 INFO - trying https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:59:13 INFO - Downloading https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:59:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 09:59:14 INFO - Downloaded 1287 bytes. 09:59:14 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:59:14 INFO - Using the following test package requirements: 09:59:14 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:59:14 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:59:14 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:59:14 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:59:14 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:59:14 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:59:14 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:59:14 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:59:14 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:59:14 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:59:14 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:59:14 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:59:14 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:59:14 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:59:14 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:59:14 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:59:14 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:59:14 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:59:14 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:59:14 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:59:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:59:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:59:14 INFO - Fetch https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:59:15 INFO - Expected file size: 51397724 09:59:15 INFO - Obtained file size: 51397724 09:59:31 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:59:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:59:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:59:31 INFO - Fetch https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:59:33 INFO - Expected file size: 51397724 09:59:33 INFO - Obtained file size: 51397724 09:59:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:59:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:59:48 INFO - Fetch https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:59:49 INFO - Expected file size: 14404449 09:59:49 INFO - Obtained file size: 14404449 09:59:49 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')]} 09:59:49 INFO - trying https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:59:49 INFO - Downloading https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:59:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 09:59:51 INFO - Downloaded 59645093 bytes. 09:59:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:59:51 INFO - mkdir: /builds/slave/test/properties 09:59:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:59:51 INFO - Writing to file /builds/slave/test/properties/build_url 09:59:51 INFO - Contents: 09:59:51 INFO - build_url:https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:59:52 INFO - Running post-action listener: _resource_record_post_action 09:59:52 INFO - Running post-action listener: set_extra_try_arguments 09:59:52 INFO - [mozharness: 2017-05-24 16:59:52.384232Z] Finished download-and-extract step (success) 09:59:52 INFO - [mozharness: 2017-05-24 16:59:52.384393Z] Running populate-webroot step. 09:59:52 INFO - Running pre-action listener: _resource_record_pre_action 09:59:52 INFO - Running main action method: populate_webroot 09:59:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:59:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:59:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:59:52 INFO - '--tppagecycles', 09:59:52 INFO - '7'], 09:59:52 INFO - 'tests': ['tsvgm']}}, 09:59:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:59:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:59:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:59:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:59:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['damp', 'tps']}, 09:59:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'tests': ['damp', 'tps']}, 09:59:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['dromaeo_dom']}, 09:59:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:59:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['basic_compositor_video']}, 09:59:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:59:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'other-e10s': {'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['a11yr', 09:59:52 INFO - 'ts_paint', 09:59:52 INFO - 'tpaint', 09:59:52 INFO - 'sessionrestore', 09:59:52 INFO - 'sessionrestore_no_auto_restore', 09:59:52 INFO - 'tabpaint']}, 09:59:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['tsvgx', 09:59:52 INFO - 'tsvgr_opacity', 09:59:52 INFO - 'tart', 09:59:52 INFO - 'tscrollx', 09:59:52 INFO - 'cart', 09:59:52 INFO - 'tsvg_static']}, 09:59:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:59:52 INFO - 'tsvgr_opacity', 09:59:52 INFO - 'tart', 09:59:52 INFO - 'tscrollx', 09:59:52 INFO - 'cart', 09:59:52 INFO - 'tsvg_static']}, 09:59:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'talos_options': ['--disable-e10s'], 09:59:52 INFO - 'tests': ['tp5o']}, 09:59:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:59:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'talos_options': ['--disable-e10s', 09:59:52 INFO - '--xperf_path', 09:59:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:52 INFO - 'tests': ['tp5n']}, 09:59:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:59:52 INFO - 'talos_options': ['--xperf_path', 09:59:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:52 INFO - 'tests': ['tp5n']}}, 09:59:52 INFO - 'talos.zip': {'path': '', 09:59:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:59:52 INFO - Running post-action listener: _resource_record_post_action 09:59:52 INFO - [mozharness: 2017-05-24 16:59:52.403109Z] Finished populate-webroot step (success) 09:59:52 INFO - [mozharness: 2017-05-24 16:59:52.403243Z] Running create-virtualenv step. 09:59:52 INFO - Running pre-action listener: _resource_record_pre_action 09:59:52 INFO - Running main action method: create_virtualenv 09:59:52 INFO - Creating virtualenv /builds/slave/test/build/venv 09:59:52 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 09:59:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:59:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:59:52 INFO - Using real prefix '/tools/python27' 09:59:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:59:53 INFO - Installing distribute.............................................................................................................................................................................................done. 09:59:56 INFO - Installing pip.................done. 09:59:56 INFO - Return code: 0 09:59:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:59:56 INFO - Reading from file tmpfile_stdout 09:59:56 INFO - Output received: 09:59:56 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) 09:59:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:59:56 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')]} 09:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:56 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')]} 09:59:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:56 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 0x1032fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8789cc8850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, '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.9Kgz32bsnX/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.6wuCm8EERm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 09:59:56 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 09:59:56 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 09:59:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 09:59:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:56 INFO - 'HOME': '/Users/cltbld', 09:59:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:56 INFO - 'LOGNAME': 'cltbld', 09:59:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:56 INFO - 'MOZ_NO_REMOTE': '1', 09:59:56 INFO - 'NO_EM_RESTART': '1', 09:59:56 INFO - 'PAGER': '/bin/cat', 09:59:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:56 INFO - 'PWD': '/builds/slave/test', 09:59:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:56 INFO - 'SHELL': '/bin/bash', 09:59:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 09:59:56 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 09:59:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:56 INFO - 'USER': 'cltbld', 09:59:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:56 INFO - 'XPC_FLAGS': '0x0', 09:59:56 INFO - 'XPC_SERVICE_NAME': '0', 09:59:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:56 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 09:59:56 INFO - Cleaning up... 09:59:56 INFO - Return code: 0 09:59:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:59:56 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')]} 09:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:56 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')]} 09:59:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:56 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 0x1032fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8789cc8850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, '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.9Kgz32bsnX/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.6wuCm8EERm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 09:59:56 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 09:59:56 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 09:59:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 09:59:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:56 INFO - 'HOME': '/Users/cltbld', 09:59:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:56 INFO - 'LOGNAME': 'cltbld', 09:59:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:56 INFO - 'MOZ_NO_REMOTE': '1', 09:59:56 INFO - 'NO_EM_RESTART': '1', 09:59:56 INFO - 'PAGER': '/bin/cat', 09:59:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:56 INFO - 'PWD': '/builds/slave/test', 09:59:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:56 INFO - 'SHELL': '/bin/bash', 09:59:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 09:59:56 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 09:59:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:56 INFO - 'USER': 'cltbld', 09:59:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:56 INFO - 'XPC_FLAGS': '0x0', 09:59:56 INFO - 'XPC_SERVICE_NAME': '0', 09:59:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:56 INFO - Downloading/unpacking psutil>=3.1.1 09:59:56 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 09:59:56 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 09:59:56 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 09:59:56 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 09:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:59:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:58 INFO - Installing collected packages: psutil 09:59:58 INFO - Running setup.py install for psutil 09:59:59 INFO - building 'psutil._psutil_osx' extension 09:59:59 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 09:59:59 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 09:59:59 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 09:59:59 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 09:59:59 INFO - building 'psutil._psutil_posix' extension 09:59:59 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 09:59:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:59:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:59:59 INFO - ^ 09:59:59 INFO - 1 warning generated. 09:59:59 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 09:59:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:59 INFO - Successfully installed psutil 09:59:59 INFO - Cleaning up... 09:59:59 INFO - Return code: 0 09:59:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:59:59 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')]} 09:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:59 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')]} 09:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:59 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 0x1032fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8789cc8850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, '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.9Kgz32bsnX/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.6wuCm8EERm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 09:59:59 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 09:59:59 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 09:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 09:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:59 INFO - 'HOME': '/Users/cltbld', 09:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:59 INFO - 'LOGNAME': 'cltbld', 09:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:59 INFO - 'MOZ_NO_REMOTE': '1', 09:59:59 INFO - 'NO_EM_RESTART': '1', 09:59:59 INFO - 'PAGER': '/bin/cat', 09:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:59 INFO - 'PWD': '/builds/slave/test', 09:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:59 INFO - 'SHELL': '/bin/bash', 09:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 09:59:59 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 09:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:59 INFO - 'USER': 'cltbld', 09:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:59 INFO - 'XPC_FLAGS': '0x0', 09:59:59 INFO - 'XPC_SERVICE_NAME': '0', 09:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:00: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 10:00: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 10:00: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 10:00: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 10:00:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:00:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:00:01 INFO - Installing collected packages: mozsystemmonitor 10:00:01 INFO - Running setup.py install for mozsystemmonitor 10:00:01 INFO - Successfully installed mozsystemmonitor 10:00:01 INFO - Cleaning up... 10:00:01 INFO - Return code: 0 10:00:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:00:01 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')]} 10:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:01 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')]} 10:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1032fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8789cc8850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, '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.9Kgz32bsnX/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.6wuCm8EERm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 10:00:01 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 10:00:01 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 10:00:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 10:00:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:01 INFO - 'HOME': '/Users/cltbld', 10:00:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:01 INFO - 'LOGNAME': 'cltbld', 10:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:01 INFO - 'MOZ_NO_REMOTE': '1', 10:00:01 INFO - 'NO_EM_RESTART': '1', 10:00:01 INFO - 'PAGER': '/bin/cat', 10:00:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:01 INFO - 'PWD': '/builds/slave/test', 10:00:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:01 INFO - 'SHELL': '/bin/bash', 10:00:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 10:00:01 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 10:00:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:01 INFO - 'USER': 'cltbld', 10:00:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:01 INFO - 'XPC_FLAGS': '0x0', 10:00:01 INFO - 'XPC_SERVICE_NAME': '0', 10:00:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:01 INFO - Downloading/unpacking jsonschema==2.5.1 10:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:00:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:00: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 10:00: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 10:00: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 10:00: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 10:00:03 INFO - Downloading functools32-3.2.3-2.tar.gz 10:00:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:00:03 INFO - warning: no files found matching '*.txt' 10:00:03 INFO - no previously-included directories found matching 'build' 10:00:03 INFO - no previously-included directories found matching 'dist' 10:00:03 INFO - no previously-included directories found matching '.git*' 10:00:03 INFO - Installing collected packages: jsonschema, functools32 10:00:03 INFO - Running setup.py install for functools32 10:00:03 INFO - warning: no files found matching '*.txt' 10:00:03 INFO - no previously-included directories found matching 'build' 10:00:03 INFO - no previously-included directories found matching 'dist' 10:00:03 INFO - no previously-included directories found matching '.git*' 10:00:03 INFO - Successfully installed jsonschema functools32 10:00:03 INFO - Cleaning up... 10:00:03 INFO - Return code: 0 10:00:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:00: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')]} 10:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00: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')]} 10:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00: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', '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 0x1032fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8789cc8850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, '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.9Kgz32bsnX/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.6wuCm8EERm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 10:00: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:00: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 functools32==3.2.3-2 10:00:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 10:00:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:03 INFO - 'HOME': '/Users/cltbld', 10:00:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:03 INFO - 'LOGNAME': 'cltbld', 10:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:03 INFO - 'MOZ_NO_REMOTE': '1', 10:00:03 INFO - 'NO_EM_RESTART': '1', 10:00:03 INFO - 'PAGER': '/bin/cat', 10:00:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:03 INFO - 'PWD': '/builds/slave/test', 10:00:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:03 INFO - 'SHELL': '/bin/bash', 10:00:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 10:00:03 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 10:00:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:03 INFO - 'USER': 'cltbld', 10:00:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:03 INFO - 'XPC_FLAGS': '0x0', 10:00:03 INFO - 'XPC_SERVICE_NAME': '0', 10:00:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:00:04 INFO - Cleaning up... 10:00:04 INFO - Return code: 0 10:00:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:00:04 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')]} 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:04 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')]} 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:04 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 0x1032fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8789cc8850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, '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.9Kgz32bsnX/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.6wuCm8EERm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 10:00:04 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 10:00:04 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 10:00:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 10:00:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:04 INFO - 'HOME': '/Users/cltbld', 10:00:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:04 INFO - 'LOGNAME': 'cltbld', 10:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:04 INFO - 'MOZ_NO_REMOTE': '1', 10:00:04 INFO - 'NO_EM_RESTART': '1', 10:00:04 INFO - 'PAGER': '/bin/cat', 10:00:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:04 INFO - 'PWD': '/builds/slave/test', 10:00:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:04 INFO - 'SHELL': '/bin/bash', 10:00:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 10:00:04 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 10:00:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:04 INFO - 'USER': 'cltbld', 10:00:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:04 INFO - 'XPC_FLAGS': '0x0', 10:00:04 INFO - 'XPC_SERVICE_NAME': '0', 10:00:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:04 INFO - Downloading/unpacking blobuploader==1.2.4 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:05 INFO - Downloading blobuploader-1.2.4.tar.gz 10:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:00:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:00:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:00: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 10:00: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 10:00: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 10:00: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 10:00:06 INFO - Downloading docopt-0.6.1.tar.gz 10:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:00:06 INFO - Installing collected packages: blobuploader, requests, docopt 10:00:06 INFO - Running setup.py install for blobuploader 10:00:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:00:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:00:06 INFO - Running setup.py install for requests 10:00:07 INFO - Running setup.py install for docopt 10:00:07 INFO - Successfully installed blobuploader requests docopt 10:00:07 INFO - Cleaning up... 10:00:07 INFO - Return code: 0 10:00:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:00:07 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')]} 10:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00: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')]} 10:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:08 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 0x1032fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8789cc8850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, '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.9Kgz32bsnX/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.6wuCm8EERm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 10:00:08 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 10:00:08 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 10:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 10:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:08 INFO - 'HOME': '/Users/cltbld', 10:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:08 INFO - 'LOGNAME': 'cltbld', 10:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:08 INFO - 'MOZ_NO_REMOTE': '1', 10:00:08 INFO - 'NO_EM_RESTART': '1', 10:00:08 INFO - 'PAGER': '/bin/cat', 10:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:08 INFO - 'PWD': '/builds/slave/test', 10:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:08 INFO - 'SHELL': '/bin/bash', 10:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 10:00:08 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 10:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:08 INFO - 'USER': 'cltbld', 10:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:08 INFO - 'XPC_FLAGS': '0x0', 10:00:08 INFO - 'XPC_SERVICE_NAME': '0', 10:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:00:09 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-sA1XY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:00:09 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-pcVfgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:00:09 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-f0Airh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:00:09 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-dAyd53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:00:09 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-QsbXUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:00:09 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-xe4kIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:00:09 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-EXpYGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:00:09 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-_xMK5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:00:09 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-mPSFXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:00:10 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-PIeRjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:00:10 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-loixVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:00:10 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-J8c88E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:00:10 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-nLG7RB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:00:10 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-7i7eVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:10 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-CVhoWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:00:10 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-0x0v5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:00:10 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-PF0VUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:00:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:00:10 INFO - Running setup.py install for manifestparser 10:00:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:00:11 INFO - Running setup.py install for mozcrash 10:00:11 INFO - Running setup.py install for mozdebug 10:00:11 INFO - Running setup.py install for mozdevice 10:00:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:00:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:00:11 INFO - Running setup.py install for mozfile 10:00:11 INFO - Running setup.py install for mozhttpd 10:00:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:00:11 INFO - Running setup.py install for mozinfo 10:00:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:00:11 INFO - Running setup.py install for mozInstall 10:00:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:00:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:00:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:00:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:00:12 INFO - Running setup.py install for mozleak 10:00:12 INFO - Running setup.py install for mozlog 10:00:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:00:12 INFO - Running setup.py install for moznetwork 10:00:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:00:12 INFO - Running setup.py install for mozprocess 10:00:12 INFO - Running setup.py install for mozprofile 10:00:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:00:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:00:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:00:12 INFO - Running setup.py install for mozrunner 10:00:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:00:13 INFO - Running setup.py install for mozscreenshot 10:00:13 INFO - Running setup.py install for moztest 10:00:13 INFO - Running setup.py install for mozversion 10:00:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:00:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:00:13 INFO - Cleaning up... 10:00:13 INFO - Return code: 0 10:00:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:00:13 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')]} 10:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:13 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')]} 10:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:13 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 0x1032fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8789cc8850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, '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.9Kgz32bsnX/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.6wuCm8EERm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 10:00:13 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 10:00:13 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 10:00:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 10:00:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:13 INFO - 'HOME': '/Users/cltbld', 10:00:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:13 INFO - 'LOGNAME': 'cltbld', 10:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:13 INFO - 'MOZ_NO_REMOTE': '1', 10:00:13 INFO - 'NO_EM_RESTART': '1', 10:00:13 INFO - 'PAGER': '/bin/cat', 10:00:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:13 INFO - 'PWD': '/builds/slave/test', 10:00:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:13 INFO - 'SHELL': '/bin/bash', 10:00:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 10:00:13 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 10:00:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:13 INFO - 'USER': 'cltbld', 10:00:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:13 INFO - 'XPC_FLAGS': '0x0', 10:00:13 INFO - 'XPC_SERVICE_NAME': '0', 10:00:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:00:14 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-V_HlA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:00:14 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-xWnNaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:00:14 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-KA2fIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:00:14 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-xDQ09T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 10:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:00:14 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-oUCVk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:00:14 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-7cX1bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:00:14 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)) 10:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:00:14 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-M2smDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:00:14 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-PBnO3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:00:15 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-_Dlsdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:00:15 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)) 10:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:00:15 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-Mao0Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:00:15 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)) 10:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:00:15 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-aOCWQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:00:15 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)) 10:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:00:15 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-P7_PCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:00:15 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)) 10:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:00:15 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-dCkhPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:00:15 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)) 10:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:00:15 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-JAWnBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:00:15 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)) 10:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:15 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-On1fIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:00:15 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)) 10:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:00:15 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-KcrQtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:00:15 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)) 10:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:00:15 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-5cd2Lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:00:16 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)) 10:00:16 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)) 10:00:16 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)) 10:00:16 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)) 10:00:16 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:00:16 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:00:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:00:16 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 10:00:16 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 10:00:16 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 10:00:16 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 10:00:17 INFO - Downloading blessings-1.6.tar.gz 10:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:00:17 INFO - Installing collected packages: blessings 10:00:17 INFO - Running setup.py install for blessings 10:00:18 INFO - Successfully installed blessings 10:00:18 INFO - Cleaning up... 10:00:18 INFO - Return code: 0 10:00:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:00:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:00:18 INFO - Reading from file tmpfile_stdout 10:00:18 INFO - Current package versions: 10:00:18 INFO - blessings == 1.6 10:00:18 INFO - blobuploader == 1.2.4 10:00:18 INFO - docopt == 0.6.1 10:00:18 INFO - functools32 == 3.2.3-2 10:00:18 INFO - jsonschema == 2.5.1 10:00:18 INFO - manifestparser == 1.1 10:00:18 INFO - mozInstall == 1.12 10:00:18 INFO - mozcrash == 1.0 10:00:18 INFO - mozdebug == 0.1 10:00:18 INFO - mozdevice == 0.49 10:00:18 INFO - mozfile == 1.2 10:00:18 INFO - mozhttpd == 0.7 10:00:18 INFO - mozinfo == 0.9 10:00:18 INFO - mozleak == 0.1 10:00:18 INFO - mozlog == 3.4 10:00:18 INFO - moznetwork == 0.27 10:00:18 INFO - mozprocess == 0.25 10:00:18 INFO - mozprofile == 0.28 10:00:18 INFO - mozrunner == 6.13 10:00:18 INFO - mozscreenshot == 0.1 10:00:18 INFO - mozsystemmonitor == 0.3 10:00:18 INFO - moztest == 0.8 10:00:18 INFO - mozversion == 1.4 10:00:18 INFO - psutil == 3.1.1 10:00:18 INFO - requests == 1.2.3 10:00:18 INFO - wsgiref == 0.1.2 10:00:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:00:18 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')]} 10:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:18 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')]} 10:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:18 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 0x1032fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8789cc8850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, '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.9Kgz32bsnX/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.6wuCm8EERm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 10:00:18 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 10:00:18 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 10:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 10:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:18 INFO - 'HOME': '/Users/cltbld', 10:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:18 INFO - 'LOGNAME': 'cltbld', 10:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:18 INFO - 'MOZ_NO_REMOTE': '1', 10:00:18 INFO - 'NO_EM_RESTART': '1', 10:00:18 INFO - 'PAGER': '/bin/cat', 10:00:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:18 INFO - 'PWD': '/builds/slave/test', 10:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:18 INFO - 'SHELL': '/bin/bash', 10:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 10:00:18 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 10:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:18 INFO - 'USER': 'cltbld', 10:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:18 INFO - 'XPC_FLAGS': '0x0', 10:00:18 INFO - 'XPC_SERVICE_NAME': '0', 10:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:18 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)) 10:00:18 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)) 10:00:18 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)) 10:00:18 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)) 10:00:18 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)) 10:00:18 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)) 10:00:18 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)) 10:00:18 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)) 10:00:18 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)) 10:00:18 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)) 10:00:18 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)) 10:00:18 INFO - Cleaning up... 10:00:18 INFO - Return code: 0 10:00:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:00:18 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')]} 10:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:00:18 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')]} 10:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:00:18 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 0x1032fcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1032ff768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8789cc8850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, '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.9Kgz32bsnX/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.6wuCm8EERm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 10:00:18 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 10:00:18 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 10:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 10:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:18 INFO - 'HOME': '/Users/cltbld', 10:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:18 INFO - 'LOGNAME': 'cltbld', 10:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:18 INFO - 'MOZ_NO_REMOTE': '1', 10:00:18 INFO - 'NO_EM_RESTART': '1', 10:00:18 INFO - 'PAGER': '/bin/cat', 10:00:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:18 INFO - 'PWD': '/builds/slave/test', 10:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:18 INFO - 'SHELL': '/bin/bash', 10:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 10:00:18 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 10:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:18 INFO - 'USER': 'cltbld', 10:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:18 INFO - 'XPC_FLAGS': '0x0', 10:00:18 INFO - 'XPC_SERVICE_NAME': '0', 10:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:00:18 INFO - Cleaning up... 10:00:18 INFO - Return code: 0 10:00:18 INFO - Running post-action listener: _resource_record_post_action 10:00:18 INFO - Running post-action listener: _start_resource_monitoring 10:00:18 INFO - Starting resource monitoring. 10:00:18 INFO - [mozharness: 2017-05-24 17:00:18.974437Z] Finished create-virtualenv step (success) 10:00:18 INFO - [mozharness: 2017-05-24 17:00:18.975457Z] Running install step. 10:00:18 INFO - Running pre-action listener: _resource_record_pre_action 10:00:18 INFO - Running main action method: install 10:00:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:00:19 INFO - Reading from file tmpfile_stdout 10:00:19 INFO - Detecting whether we're running mozinstall >=1.0... 10:00:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:00:19 INFO - Reading from file tmpfile_stdout 10:00:19 INFO - Output received: 10:00:19 INFO - Usage: mozinstall [options] installer 10:00:19 INFO - Options: 10:00:19 INFO - -h, --help show this help message and exit 10:00:19 INFO - -d DEST, --destination=DEST 10:00:19 INFO - Directory to install application into. [default: 10:00:19 INFO - "/builds/slave/test"] 10:00:19 INFO - --app=APP Application being installed. [default: firefox] 10:00:19 INFO - mkdir: /builds/slave/test/build/application 10:00:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:00:34 INFO - Reading from file tmpfile_stdout 10:00:34 INFO - Output received: 10:00:34 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:00:34 INFO - Running post-action listener: _resource_record_post_action 10:00:34 INFO - [mozharness: 2017-05-24 17:00:34.534709Z] Finished install step (success) 10:00:34 INFO - [mozharness: 2017-05-24 17:00:34.534877Z] Running run-tests step. 10:00:34 INFO - Running pre-action listener: _resource_record_pre_action 10:00:34 INFO - Running main action method: run_tests 10:00:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:00:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:00:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:00:34 INFO - 2017-05-24 10:00:34.549 screenresolution[1986:19537] starting screenresolution argv=screenresolution get 10:00:34 INFO - 2017-05-24 10:00:34.552 screenresolution[1986:19537] Display 0: 1600x1200x32@60 10:00:34 INFO - 2017-05-24 10:00:34.558 screenresolution[1987:19539] starting screenresolution argv=screenresolution list 10:00:34 INFO - Available Modes on Display 0 10:00:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:00:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:00:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:00:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:00:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:00:34 INFO - Intel Iris: 10:00:34 INFO - Chipset Model: Intel Iris 10:00:34 INFO - Type: GPU 10:00:34 INFO - Bus: Built-In 10:00:34 INFO - VRAM (Dynamic, Max): 1536 MB 10:00:34 INFO - Vendor: Intel (0x8086) 10:00:34 INFO - Device ID: 0x0a2e 10:00:34 INFO - Revision ID: 0x0009 10:00:34 INFO - Displays: 10:00:34 INFO - Display: 10:00:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:00:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:00:34 INFO - Display Serial Number: 1600x1200 60 10:00:34 INFO - Main Display: Yes 10:00:34 INFO - Mirror: Off 10:00:34 INFO - Online: Yes 10:00:34 INFO - Rotation: Supported 10:00:34 INFO - Return code: 0 10:00:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:00:34 INFO - Python 2.7.3 10:00:34 INFO - Return code: 0 10:00:34 INFO - grabbing minidump binary from tooltool 10:00:34 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')]} 10:00:34 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 0x1030dad30>, '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 0x103001db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030cfbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:00:34 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 10:00:34 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 10:00:34 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 10:00:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:00:34 INFO - Return code: 0 10:00:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:00:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:00:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:00:34 INFO - ENV: RUST_BACKTRACE is now 1 10:00:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:00:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:00:34 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/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0348', '--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 10:00:34 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/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0348 --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 10:00:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9Kgz32bsnX/Render', 10:00:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:34 INFO - 'HOME': '/Users/cltbld', 10:00:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:34 INFO - 'LOGNAME': 'cltbld', 10:00:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:00:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:34 INFO - 'MOZ_NO_REMOTE': '1', 10:00:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:00:34 INFO - 'NO_EM_RESTART': '1', 10:00:34 INFO - 'PAGER': '/bin/cat', 10:00:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:34 INFO - 'PWD': '/builds/slave/test', 10:00:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:00:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:34 INFO - 'RUST_BACKTRACE': '1', 10:00:34 INFO - 'SHELL': '/bin/bash', 10:00:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wuCm8EERm/Listeners', 10:00:34 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 10:00:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:34 INFO - 'USER': 'cltbld', 10:00:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:34 INFO - 'XPC_FLAGS': '0x0', 10:00:34 INFO - 'XPC_SERVICE_NAME': '0', 10:00:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:34 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/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0348', '--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 10:00:35 INFO - mozversion application_buildid: 20170524084320 10:00:35 INFO - mozversion application_changeset: b3b1b2125fc0b242026a926b1db8c7f23330cc99 10:00:35 INFO - mozversion application_display_name: Firefox 10:00:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:00:35 INFO - mozversion application_name: Firefox 10:00:35 INFO - mozversion application_remotingname: firefox 10:00:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:00:35 INFO - mozversion application_vendor: Mozilla 10:00:35 INFO - mozversion application_version: 54.0 10:00:35 INFO - mozversion platform_buildid: 20170524084320 10:00:35 INFO - mozversion platform_changeset: b3b1b2125fc0b242026a926b1db8c7f23330cc99 10:00:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:00:35 INFO - mozversion platform_version: 54.0 10:00:35 INFO - using testdate: 1495645235 10:00:35 INFO - actual date: 1495645235 10:00:35 INFO - starting webserver on 'localhost:49264' 10:00:35 INFO - SUITE-START | Running 2 tests 10:00:35 INFO - TEST-START | dromaeo_css 10:00:35 INFO - operating with platform_type : mac_ 10:00:35 INFO - Initialising browser for dromaeo_css test... 10:00:35 INFO - TEST-INFO | started process 1997 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmpY6xXrO/profile http://localhost:49264/getInfo.html) 10:00:38 INFO - PROCESS | 1997 | 2017-05-24 10:00:38.018 plugin-container[2000:19709] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 10:00:38 INFO - PROCESS | 1997 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:38 INFO - PROCESS | 1997 | 2017-05-24 10:00:38.018 plugin-container[2000:19709] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9303, name = 'com.apple.CFPasteboardClient' 10:00:38 INFO - PROCESS | 1997 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:38 INFO - PROCESS | 1997 | 2017-05-24 10:00:38.018 plugin-container[2000:19709] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:00:38 INFO - PROCESS | 1997 | __metrics Screen width/height:1600/1200 10:00:38 INFO - PROCESS | 1997 | colorDepth:24 10:00:38 INFO - PROCESS | 1997 | Browser inner width/height: 1024/689 10:00:38 INFO - PROCESS | 1997 | __metrics 10:00:38 ERROR - PROCESS | 1997 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:00:38 INFO - TEST-INFO | 1997: exit 0 10:00:38 INFO - Browser initialized. 10:00:38 INFO - Running cycle 1/1 for dromaeo_css test... 10:00:38 INFO - TEST-INFO | started process 2001 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmpY6xXrO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:41 INFO - PROCESS | 2001 | 2017-05-24 10:00:41.076 plugin-container[2002:19842] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843b, name = 'com.apple.tsm.portname' 10:00:41 INFO - PROCESS | 2001 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:41 INFO - PROCESS | 2001 | 2017-05-24 10:00:41.076 plugin-container[2002:19842] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 10:00:41 INFO - PROCESS | 2001 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:41 INFO - PROCESS | 2001 | 2017-05-24 10:00:41.077 plugin-container[2002:19842] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:00:50 INFO - PROCESS | 2001 | RSS: Main: 174706688 10:00:50 INFO - PROCESS | 2001 | 10:03:53 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49264/tests/dromaeo/cssquery-ext.html) 10:03:55 INFO - PROCESS | 2001 | RSS: Main: 192790528 10:03:55 INFO - PROCESS | 2001 | 10:06:57 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-ext.html (next: http://localhost:49264/tests/dromaeo/cssquery-jquery.html) 10:06:57 INFO - PROCESS | 2001 | RSS: Main: 207572992 10:06:57 INFO - PROCESS | 2001 | 10:09:59 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49264/tests/dromaeo/cssquery-mootools.html) 10:10:00 INFO - PROCESS | 2001 | RSS: Main: 209121280 10:10:00 INFO - PROCESS | 2001 | 10:13:02 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49264/tests/dromaeo/cssquery-prototype.html) 10:13:03 INFO - PROCESS | 2001 | RSS: Main: 213135360 10:13:03 INFO - PROCESS | 2001 | 10:16:05 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49264/tests/dromaeo/cssquery-yui.html) 10:16:06 INFO - PROCESS | 2001 | RSS: Main: 215883776 10:16:06 INFO - PROCESS | 2001 | 10:19:09 INFO - PROCESS | 2001 | Cycle 1(1): loaded http://localhost:49264/tests/dromaeo/cssquery-yui.html (next: http://localhost:49264/tests/dromaeo/cssquery-dojo.html) 10:19:09 INFO - PROCESS | 2001 | RSS: Main: 213913600 10:19:09 INFO - PROCESS | 2001 | 10:19:09 INFO - PROCESS | 2001 | __start_tp_report 10:19:09 INFO - PROCESS | 2001 | _x_x_mozilla_page_load 10:19:09 INFO - PROCESS | 2001 | _x_x_mozilla_page_load_details 10:19:09 INFO - PROCESS | 2001 | |i|pagename|runs| 10:19:09 INFO - PROCESS | 2001 | |0;dojo.html;2959.437706835701;2972.509535926571;3639.1811842335496;3645.5807582128064;3645.981770091149;6149.415805498476;6152.692365381729;6169.722362493687;6185.628862268256;6215.440610345079;5103.6937783733;6129.81610551683;6150.507959363252;6157.322694503601;6174.012158054712;5640.887182256362;5696.77212911483;5719.199312096289;5771.076627739562;5781.103928891029;5745;5757.337906140785;5761.4814666679995;5762.031952815479;5798.971005144968;4697.107549565589;5328.786848526054;5617.101263797793;5745.712714364265;5749.022666146765;5727.11229759388;5737.770489180428;5765.682887441189;5776.682282474462;5827.300723913115;4605.769711514411;5570.275864137677;5655.604107712462;5680.6591604503865;5702.344230413472;4572.702774319659;5453.2092846537425;5799.855003624901;6016.789412370546;6200.906986395207;6337.176167098248;6401.967990160066;6420.165378500812;6547.247066587356;6601.93398066018;6715.697793599251;6722.899156512656;6741.460683145337;6743.393094621459;6748.055272261888;5736.3403208631125;5881.823635272963;6138.232720909887;6152.969235153885;6232.813015609494;6653.833654158588;6727.394534491919;6812.114425124702;6838.897416538756;6840.042394064835;5878.030125029322;6377;6454.677266136674;6457.5479716419395;6475.028745688184;5707.828765137053;5979.431953964367;6121.908171377433;6308.495320374359;6414.652922886153;6211.875762484725;6353.777617783356;6369.172007638977;6450;6487.3836985486305;6044.123602077674;6265.749370025239;6569.770058047945;6597.901031484532;6612.735490580323;5239.214117882272;5242.501962313574;5254.369475662868;5256.737163141904;5264.263003179558;4783.545563171493;4792.95207047925;4794.304825800239;4797.352357431771;4809.975950120227;6130.816075517741;6150.692465376714;6277.52918531112;6287.968560157261;6303.936960630335;6441.677916104176;6521.576097553644;6566.179227596551;6647.268800431949;6653.667316634149;6282.2147231596055;6288.811335659938;6296.65368404733;6298.7795427160745;6308.558400911892;5601;5846.94153058464;5853.092770620476;5865.35481097079;5932.644041357532;6514.478841692653;6534.607923524509;6734.831629209309;6760.357766012221;6803.081583986296;6662.200535935719;6696.1629796275465;6737.461003119641;6744.0895479109695;6745.730170793309;6887.483438742014;6910.101686780687;6917;6923.2038315594355;6935.687894044881;5464.672119672832;6286.402791734778;6317.96841015792;6487.740490380521;6611.471082313307;5414.53132336351;6298.803227386782;6681.76613818514;6717.1267735194115;6781.6270105144695;6327.240731112296;6460.25707043696;6464.773732919325;6475.061116138039;6482.805515834532;6189.978653522118;6242.781502647385;6335.809925702236;6485.3514648534765;6502.154719886385;6109.252844142398;6272.18461599989;6290.716917738804;6342.90485642725;6354.142190804368;6433.710483028369;6436.871262574816;6451.516136289703;6467.320931302145;6471.838204044937;6164.8802487409985;6212.006079027334;6239.0953311768635;6258.812235632939;6279.96860015697 10:19:09 INFO - PROCESS | 2001 | |1;ext.html;784.9686012559498;839.0979696825913;856.3748463621554;977.8337682593958;980.0052946259548;40518.79740601296;41109.588904110984;42840.57159428405;42890;42989;41277.58722412775;41657.791711041406;41702.748917532525;41713.791431042875;41764.37353439693;8338;8456.365772567051;11226.887731122706;11543.076553875711;11709.180357374988;11298.265612735146;11349.886501135006;11384.886151138508;11392.373419461926;11401.600943966963;9422.717318480456;11370.317780933128;11372.601958931438;11376.317420954769;11398.943005285004;11302.095832333434;11319.434028298552;11334.376609286486;11343.035841953444;11349.262297950607;9514.762130946712;11101.278416902875;11186;11194.160437967184;11206.327620342805;35397;35546.64453355485;35625.28749425023;35629.93110206698;35652.82173589141;3901.7854018028997;3939.6454319111417;3950.6839452843706;3961.1483531040676;3969.960300396988;21260.152670233245;26272.86863565689;26370.86814565934;27004.594931076048;27606.447871042466;3236.6116066072223;3247.512873069035;3256.3161736035463;3269.346130773855;3287.2603664175367;313077;316655;418534;428599.85700071615;431425;44738.77630611891;45037;45055.32417013747;45112.097758044656;45253.868653283935;46854;46857.531424686;46864.5313546867;47008.294875576896;47208.76395618069;14275.500357487646;14280.42878284857;14354.928225359015;14393;14415.855841441451;10252.538635761408;12462.688432789253;12480.063995200215;12517.624471265877;12518;12197.268163910194;12258.509659613694;12259.387030648431;12315.261084334968;12405.937970310271;5902.970485147633;5930.733117009659;5943.10853371989;5962.360350903449;5972.432618901197;1137.8634563852224;1151.78691941991;1163.3078318400537;1170.4674373160192;1209.3832145605804;23110.537789244456;26631.733682662925;27130;27172.59241111385;27260.727392726214;1244.8319476870506;1275.43880692495;1276.5914907229783;1308.3981368570373;1318.4001279417841;13517.59447216585;13905.513307034205;14163.504277350243;14234.715305694035;14252.572422827332;437978.81010595383;628372;644420;667731;668522;51623;51839.74080129575;51914.48085519097;52081.47918520766;52299;1301.576987479073;1491.4183468447313;1513.2055670772713;1528.2664321125922;1530.5867415797672;27415.17754467369;29037.264755935637;29311.974080907064;29441.116766497038;29443.263918402212;43212.783936080115;43528;43542;43626.78186609047;43734.56265437432;522364;524404;527547;529118;529832;25907.870460647577;26369.340766480993;30951.380972380877;31691.84154079215;31945.36109277848;39081.80459097686;39521.407178892914;40879.38680919729;40984.38523422211;41018.58981410267;28068.578971315037;28432.85783571069;28880.85559572273;29076.27309317284;29301.560476592444;22987.65518517257;28641;28857.845686172317;29419.55870661899;29801.850990744908;32419.513707294882;33187.33625327528;33519.664803351654;33884.81403150878;33888;28138.01516946979;28860.27849303784;28927.276818079717;29248.56127158137;29720.554191686708;22459.32622021342;23387.76612233924;23630.409239768454;23772.762272377746;23963.281101566285 10:19:09 INFO - PROCESS | 2001 | |2;jquery.html;411.88055463915464;428.8036378503974;446.74758761299864;450.53819834669457;454.82593204482566;20559.897200514013;21290.787092129056;21910.671339929893;22055.11779528817;22173.2239371622;21679.241226557075;22288.442788930246;22859.314220573367;22869.656955145685;23199.304020879357;5413.83602525457;6810.148731408586;7076;7081.822954426155;7082.220955694869;6020.277566692003;7031.859362812741;7038.718451261943;7039.683214255345;7070.964645176793;6798.728050877959;6944;6944.47916406269;7027.47293952953;7050.823729406755;6644.568103073333;6894.896576551356;6899.275576064516;6963.895541566855;6973.058637083979;6789.490788190856;6790.728370865159;6791.117154769898;6801.4898882584;6803.319668033157;26888.46223075547;26939.865300673566;27097.593536096974;27249.727502725116;27384.863075684694;2912.8397938113394;2931.618889544367;3018.381231847488;3172.9206769830707;3491.5286436331025;16090.712742980533;16621.584460388465;16803.15984200785;17041.318347266093;17364.91317543404;2079.8856062916534;2158.298552970291;2167.2847960173103;2172.1528603844513;2175.1516908405574;154688;158173.41826581702;172960;180518.19481805144;181117.18882811136;19300.613987720448;20961.790382095984;21023.107920287246;21150;21171.68242476364;32266;32772.508412373834;32909.84815531445;32932.3413531728;32945.34109317848;10125.949370253102;10817.296875703054;10880.619178328721;10973.945130274456;11050;9229.833422247868;9316.02181770918;9339.066093390607;9448.433094014381;9482.431054136774;8063.677452901936;9202.539873006323;9441.339106262632;9540.809183816285;9558.091981261769;3350.6649335066786;3964.671834935329;4232.555581663943;4269.039466120098;4270.871873843789;928.9024652411534;1039.9064084232455;1074.5755426606447;1076.860008198929;1092.5036292072575;21672.78327216739;21710.89144554267;21890.781092189194;21937.561248775255;21946.780532194793;1077.5743581285349;1079.875814281352;1104.100158370925;1114.7658991611852;1117.1788735279563;10743.78512429747;11128.276662017104;11362.659120226406;11386.544538218379;11415.258008229439;751712;777816;779565;791662;795643.0217848874;122812;168048.1597592004;169383.45924811138;169883;171656;1057.9259451838298;1231.0459393969745;1231.6489800406841;1261.9305938173304;1264.7533730922567;13611.52359667407;18172;19289.614207716048;19301.90349048246;19434.61130777405;40090.79954600208;63220.73558528896;63304.36695633169;63444;64621.67689161524;87061.12938870784;88400;88691;89066;89969.55015224882;9946;11407.714807129889;11425.371604561496;11525.250858694159;11707.590234341757;12839.293838838954;12852.228866268053;12929.418176182282;13217.867821321664;13320.93339533296;8728.214460698568;9790.118889299712;10980.835287470534;11457.417033186792;11739.002184814386;9799.65701200483;10066.295359324777;10489.632862849763;10611.946940265248;10628.468576570986;11355.091592672732;11422.714432139262;11662.950334469939;11757.353345566076;11770.470328835052;10359.896401035892;11515.481803319039;11674.357910315015;11729.648110556696;11801.822972655245;11131.944340278247;11311.095112390823;11473.4263286837;11521.366324851879;11558.653240402802 10:19:09 INFO - PROCESS | 2001 | |3;mootools.html;1517.5826647671888;1600.1119378744806;1630.2500849609187;1636.885418020739;1638.3692278472784;8444.66221351146;9860.556274967626;11388.943055284733;11534.942325288372;11557.479913403897;11436.771264574703;11596.594119205829;11606;11606.709832254199;11627.767444651103;2130.648443006906;2450.0444826517582;2465.4206261528525;2471.9011239550396;2474.6782918220606;2432.8540287582714;2489.8879550420183;2506.711728151268;2516.5973444248925;2518;2465.1988103866224;2474.2948259745976;2498.150628786212;2508.3227528567277;2509.5608268553024;2433.0267892842826;2443.2059580636364;2469.086438017927;2470.493548822487;2475.591527397977;2444.511097780451;2473.418746594549;2473.777360037587;2476.5418397596322;2481.10680155144;11270.661880143609;11436.714082148013;11466.48400821965;11502.942485287604;11521.423928803526;5945.108233764927;6079.331273559905;6120.938790612081;6123.387661233885;6125.724342404603;3713.8700145494777;3795.6773674237725;3839.4240863870364;3854.9229015419814;3889.708271879592;1297.2800095946745;1922.6827573450364;1936.6707659697886;1986.1658103596524;2013.1846602126163;14248.002639815217;14528.491502797457;15756.763648545166;16068.276927538172;16103.838961610469;4270.701050926467;4672.182368085571;4702.976485117552;4908.2637604358915;4914.90170196594;11980.58067967617;12012.459439325252;12082.818757718642;12115.575954841539;12123.211991220542;5967.76128954837;5980;5981.760729570856;5982.970085149634;5985.191999080069;5387.973060134753;5430.294061772023;5475.753591088411;5483.177523371526;5556.583256255797;3828.578856325802;3881.1849511602313;3905.453236546886;3929.332013557702;3950.906019809506;1073.8174510333263;1076.9192310576739;1081.5079138991898;1085.7991271614776;1091.0943916549245;932.529072818236;944.9149576538007;948.5115165689675;962.2638681408728;1013.7668336282549;5600;6507.251666058369;6568.211814582281;6606.933930660728;6738.629375384303;884.9601767920459;1007.7531004903846;1024.7395703285001;1043.2540733375672;1082.2532452607675;4087.202995415865;4151.771652559078;4160.167966406731;4215.220184265921;4369.47566292047;11765.823512647317;13467.730645387037;13883.305834708224;14092.647683807987;14102.435902563988;13428;13926.442942282196;13948.023638345421;13965.860341396456;13971.301434928213;9057.864132038025;9937.205023598095;10170.08469237772;10179.389236645826;10193.184545236363;5913.438223368773;5995.550333725076;6511.902321465276;6778.796636100925;6910.896336555056;11858.703532411759;12030.63908082759;12133.939330303292;12147.574834880737;12341.13612047184;12567.811482827949;12828.294443805307;12829.743405131658;12845.908097811416;12851.485940562647;8874.467531947845;8887.955560222157;8976.820463590822;9079.636814527345;9083.500407477388;9822.901770982198;9841.015898410387;9881.357711748713;9884.011598840058;9914.256430767866;7020.473464490082;8294.170582941657;8522.659093636434;8569.571521424028;8587.699430519902;8301.543415112226;8370.330373569977;8387.454815436977;8392.580370981304;8529.9764028317;7388.335049845539;7840.411969102226;8111.107778144517;8148.674053037812;8244.340452763885;5131.820386286462;5869.148973398748;5956.821295361146;6082.178905847654;6088.695565221633;5917.260342457193;6014.609050411709;6048.266661356901;6056.66688332146;6170.012797952306 10:19:09 INFO - PROCESS | 2001 | |4;prototype.html;380.6193806193806;398.9840406383745;399.7801209334866;403.7012610668107;408.7874305361211;5251.186066159751;5424.64028147756;5484.6983415912155;5499.890002199953;5545.500904918556;4817.494163112875;5263.710495922723;5275.815346462875;5337.4662533746605;5460.590455715827;4046.231216068952;4370.6503479721705;4766.713997160165;4771.141194584973;4824.975875120619;4063.756174629532;4675.919215948904;4682.484926658065;4811.473976162508;4835;4014.558398576154;4480.686351955364;4489.595936365727;4681.565585895332;4764.714117152982;3860;4748.928766068511;4759.262314341283;4780.569748722614;4810.494898035691;4212.9157416851795;4295.367760251116;4518.706284091523;4544.8863778405475;4607.35497030416;4937.358143441365;5285.180796976437;5285.339332583427;5329.573634109262;5356.35723713153;4842.975785121087;5283.86790330241;5382.219578161184;5418.539424148952;5566.916496252559;5125.743712814345;5671.461211184931;5699.430056994309;5923.5853490255695;5930.970345148246;4308.9784551077355;4607.055553611502;4632.3051542268595;4644.8374306899095;4650.139724151009;5153.871153221162;5222.712750798704;5642.4170651636505;5873.765049397976;6008.609440386361;4594.448666160082;4638.5593368629925;4642.3500709900645;4644.930326045112;4647.697899636512;4129.256733787887;4164.18917230874;4529.456465224194;4672.415948006499;4746.795912758874;4774.856754297377;4871.906339474459;5067.214581739836;5109.241009854637;5142.845714628567;4690.835820746258;5041.621878359065;5089.770960306795;5730.882477916766;5794.362620111784;1383.3221721356463;1614.144503413193;1615.967680646404;1621.7810595569683;1631.404537343861;1037.818381783185;1114.5844777132995;1123.702218911989;1195.396324855942;1248.2073883084306;4678.461976872635;4978.4025916890205;5354.598405119554;5358.526405238591;5395.78416863321;5215.461438875541;5547.972260138754;6048.939510604926;6076.453119219292;6078.419610901109;4869.756512174448;4997.90004199914;5039.748012599355;5152.945289285075;5439.3200849893765;2714.8099633025895;3083.383323335342;3107.564940908275;3126.3747250549527;3133.85897634607;5656.406077361818;5900.64596124234;6150;6168.784092556739;6280.623162610258;5139.974300128475;5764.452377024176;6052.636841789506;6057.57596968208;6144.446999770043;4943.901121977612;5415.972920135374;5660.518855897295;6211.81364559064;6216.844578885383;5612.588765054708;5800.954498888117;5809.618056596944;5875.94124058771;6015.383308260921;5158.509941555546;5233.790648374023;5465.2238022642005;5633.295838020247;5665.348484924121;5227.320448341843;5455.972720136374;5843.444872737083;5875.122419203305;6185.195924529782;4141.426258021932;4183.974926143114;4223.640990515841;4320.179165958458;4670.929936050894;5037.524108365216;5213.418419288448;5304.363476382704;5793.449622285876;6074.479091177112;5504.146857237135;5505.6421332614855;5711.8000869969355;5721.8569535761935;5869.354371019099;4575.99328147807;5547.694876781654;5762.493315407811;5834.7082645866685;5949.434803693643;5149.433562308068;5623.718814059363;5651.2935883014625;5720.656760594377;5749.885002299847;5724.942750572441;5738.971305143447;5780.393058728773;5811.418858114154;5836.678982655994;5060.898782024413;5516.724163791875;5668.291463567054;5730.656160630376;5752 10:19:09 INFO - PROCESS | 2001 | |5;yui.html;688.869114868175;749.6514120933765;751.7481643649373;789.8499285135824;899.9460032398055;15353.385864565418;15953.44162954294;16533.008019518824;16578.00531968081;16685.998840069573;15301;15544.300506477208;15649.904506684537;15899.761503577427;15921.04473731577;998.5805961496189;1033.7415646088477;1037.9481025948671;1038.3873514626368;1039.433508737736;999.2355847776421;1027.6351895077253;1045.6497073480393;1054.1092776603757;1055.2665897201448;1078.2075174746556;1088.2382332367335;1090.4602221900132;1090.907272881806;1092.2790957967743;1081.4268437727978;1082.3884505254507;1083.5015892689373;1084.3060441317564;1084.528230219853;804.798800299925;808.0465051239535;808.0626473290954;810.222186700768;813.5932033983008;14723.484678036217;17304.480865574053;17305.221265043103;17821.10894455285;18064.729029064576;14632.268386580736;14746.557603271918;14826.703465930728;15132.319045642973;15142.772858407132;9727.610895564168;11435.196058345271;12134;12174.695632609168;12408.317542535156;4006.8797936061965;4582.633389328879;4630.259158534652;4738.976305118486;4886.413630364379;88423.55788221082;105641.41537876938;116549.4789731085;121189.39405303005;123917;9659.758506037264;9915.454649994319;10545.789084218426;11075.170662009721;11386.430678466044;22088.33734987953;22281.55436891253;22383.888080559493;22393.992270347873;22426.663600046013;7857.803554911173;7978.760637180894;8032.437729359006;8115.3507719382305;8224.054233763194;4556.612687921497;4598.793054312564;4615.861524154281;4627.9074418511445;4646.312151129333;3458.1354661334667;3550.0592343029116;3552.715782737375;3555.6444355564236;3571.5892672342493;635.1362147479424;640.1166390381337;646.1374065622352;646.2051676438008;662.9038789375513;515.569499467941;544.7370252189436;552.6352607279176;581.8719881626035;604.6372176693949;7880.921190788019;9758.268129890286;9827.26295527824;9957.402555846673;10351.378917264989;537.8329026013482;546.2352706211336;565.9886802264014;602.3284038297267;603.7464265008707;4044.4539987101953;4717.410323709536;5027.673201241981;5071.290019397287;5084.6440749147205;109404;110362;112384;112471;114464;92724;98756;99288;101040;102567.97432025585;59944.40055599388;74693;82431.7635235472;83865.58067209747;85710.57144714237;10914.563417463212;11155.553777848796;11482.655520334403;11486.712832179262;11541.884581154081;51113.744431277606;51395;51400.48599514107;52484;52995.41013769593;60991.69504152628;64838;65965.34034659591;66258;67644.98532521918;13152.671183220496;13299.867001329863;13347.563286720046;13617.04680672383;13884.028118031641;15203.239838008276;15348.76976845326;15465.072095674295;15630.06219626826;16276.51170464888;13870.029097963046;14089.506867259597;14359;14503.637409064857;14659;14310.141391516543;14499.492517761828;14709.92645036768;15008.47470338533;15034.548963531111;13487.460501579826;13620.18278903269;13668.589942301747;14070.437182512585;14963;9225.031371705874;10215.080642742189;10426.374417534973;10427.322224055411;10526.473676316307;11009.755855989762;11943.223690460092;11946.76106477883;12083.972862306802;12399.566015189424 10:19:09 INFO - PROCESS | 2001 | __end_tp_report 10:19:09 INFO - PROCESS | 2001 | __start_cc_report 10:19:09 INFO - PROCESS | 2001 | _x_x_mozilla_cycle_collect,390 10:19:09 INFO - PROCESS | 2001 | __end_cc_report 10:19:09 INFO - PROCESS | 2001 | __startTimestamp1495646349628__endTimestamp 10:19:09 INFO - PROCESS | 2001 | 10:19:09 INFO - PROCESS | 2001 | ------- Summary: start ------- 10:19:09 INFO - PROCESS | 2001 | Number of tests: 6 10:19:09 INFO - PROCESS | 2001 | 10:19:09 INFO - PROCESS | 2001 | [#0] dojo.html Cycles:165 Average:6062.77 Median:6265.75 stddev:707.05 (11.3%) stddev-sans-first:665.99 10:19:09 INFO - PROCESS | 2001 | Values: 2959.4 2972.5 3639.2 3645.6 3646.0 6149.4 6152.7 6169.7 6185.6 6215.4 5103.7 6129.8 6150.5 6157.3 6174.0 5640.9 5696.8 5719.2 5771.1 5781.1 5745.0 5757.3 5761.5 5762.0 5799.0 4697.1 5328.8 5617.1 5745.7 5749.0 5727.1 5737.8 5765.7 5776.7 5827.3 4605.8 5570.3 5655.6 5680.7 5702.3 4572.7 5453.2 5799.9 6016.8 6200.9 6337.2 6402.0 6420.2 6547.2 6601.9 6715.7 6722.9 6741.5 6743.4 6748.1 5736.3 5881.8 6138.2 6153.0 6232.8 6653.8 6727.4 6812.1 6838.9 6840.0 5878.0 6377.0 6454.7 6457.5 6475.0 5707.8 5979.4 6121.9 6308.5 6414.7 6211.9 6353.8 6369.2 6450.0 6487.4 6044.1 6265.7 6569.8 6597.9 6612.7 5239.2 5242.5 5254.4 5256.7 5264.3 4783.5 4793.0 4794.3 4797.4 4810.0 6130.8 6150.7 6277.5 6288.0 6303.9 6441.7 6521.6 6566.2 6647.3 6653.7 6282.2 6288.8 6296.7 6298.8 6308.6 5601.0 5846.9 5853.1 5865.4 5932.6 6514.5 6534.6 6734.8 6760.4 6803.1 6662.2 6696.2 6737.5 6744.1 6745.7 6887.5 6910.1 6917.0 6923.2 6935.7 5464.7 6286.4 6318.0 6487.7 6611.5 5414.5 6298.8 6681.8 6717.1 6781.6 6327.2 6460.3 6464.8 6475.1 6482.8 6190.0 6242.8 6335.8 6485.4 6502.2 6109.3 6272.2 6290.7 6342.9 6354.1 6433.7 6436.9 6451.5 6467.3 6471.8 6164.9 6212.0 6239.1 6258.8 6280.0 10:19:09 INFO - PROCESS | 2001 | 10:19:09 INFO - PROCESS | 2001 | [#1] ext.html Cycles:180 Average:62304.05 Median:29581.91 stddev:138729.98 (469.0%) stddev-sans-first:139042.26 10:19:09 INFO - PROCESS | 2001 | Values: 785.0 839.1 856.4 977.8 980.0 40518.8 41109.6 42840.6 42890.0 42989.0 41277.6 41657.8 41702.7 41713.8 41764.4 8338.0 8456.4 11226.9 11543.1 11709.2 11298.3 11349.9 11384.9 11392.4 11401.6 9422.7 11370.3 11372.6 11376.3 11398.9 11302.1 11319.4 11334.4 11343.0 11349.3 9514.8 11101.3 11186.0 11194.2 11206.3 35397.0 35546.6 35625.3 35629.9 35652.8 3901.8 3939.6 3950.7 3961.1 3970.0 21260.2 26272.9 26370.9 27004.6 27606.4 3236.6 3247.5 3256.3 3269.3 3287.3 313077.0 316655.0 418534.0 428599.9 431425.0 44738.8 45037.0 45055.3 45112.1 45253.9 46854.0 46857.5 46864.5 47008.3 47208.8 14275.5 14280.4 14354.9 14393.0 14415.9 10252.5 12462.7 12480.1 12517.6 12518.0 12197.3 12258.5 12259.4 12315.3 12405.9 5903.0 5930.7 5943.1 5962.4 5972.4 1137.9 1151.8 1163.3 1170.5 1209.4 23110.5 26631.7 27130.0 27172.6 27260.7 1244.8 1275.4 1276.6 1308.4 1318.4 13517.6 13905.5 14163.5 14234.7 14252.6 437978.8 628372.0 644420.0 667731.0 668522.0 51623.0 51839.7 51914.5 52081.5 52299.0 1301.6 1491.4 1513.2 1528.3 1530.6 27415.2 29037.3 29312.0 29441.1 29443.3 43212.8 43528.0 43542.0 43626.8 43734.6 522364.0 524404.0 527547.0 529118.0 529832.0 25907.9 26369.3 30951.4 31691.8 31945.4 39081.8 39521.4 40879.4 40984.4 41018.6 28068.6 28432.9 28880.9 29076.3 29301.6 22987.7 28641.0 28857.8 29419.6 29801.9 32419.5 33187.3 33519.7 33884.8 33888.0 28138.0 28860.3 28927.3 29248.6 29720.6 22459.3 23387.8 23630.4 23772.8 23963.3 10:19:09 INFO - PROCESS | 2001 | 10:19:09 INFO - PROCESS | 2001 | [#2] jquery.html Cycles:180 Average:44591.05 Median:21900.73 stddev:130337.38 (595.1%) stddev-sans-first:130660.80 10:19:09 INFO - PROCESS | 2001 | Values: 411.9 428.8 446.7 450.5 454.8 20559.9 21290.8 21910.7 22055.1 22173.2 21679.2 22288.4 22859.3 22869.7 23199.3 5413.8 6810.1 7076.0 7081.8 7082.2 6020.3 7031.9 7038.7 7039.7 7071.0 6798.7 6944.0 6944.5 7027.5 7050.8 6644.6 6894.9 6899.3 6963.9 6973.1 6789.5 6790.7 6791.1 6801.5 6803.3 26888.5 26939.9 27097.6 27249.7 27384.9 2912.8 2931.6 3018.4 3172.9 3491.5 16090.7 16621.6 16803.2 17041.3 17364.9 2079.9 2158.3 2167.3 2172.2 2175.2 154688.0 158173.4 172960.0 180518.2 181117.2 19300.6 20961.8 21023.1 21150.0 21171.7 32266.0 32772.5 32909.8 32932.3 32945.3 10125.9 10817.3 10880.6 10973.9 11050.0 9229.8 9316.0 9339.1 9448.4 9482.4 8063.7 9202.5 9441.3 9540.8 9558.1 3350.7 3964.7 4232.6 4269.0 4270.9 928.9 1039.9 1074.6 1076.9 1092.5 21672.8 21710.9 21890.8 21937.6 21946.8 1077.6 1079.9 1104.1 1114.8 1117.2 10743.8 11128.3 11362.7 11386.5 11415.3 751712.0 777816.0 779565.0 791662.0 795643.0 122812.0 168048.2 169383.5 169883.0 171656.0 1057.9 1231.0 1231.6 1261.9 1264.8 13611.5 18172.0 19289.6 19301.9 19434.6 40090.8 63220.7 63304.4 63444.0 64621.7 87061.1 88400.0 88691.0 89066.0 89969.6 9946.0 11407.7 11425.4 11525.3 11707.6 12839.3 12852.2 12929.4 13217.9 13320.9 8728.2 9790.1 10980.8 11457.4 11739.0 9799.7 10066.3 10489.6 10611.9 10628.5 11355.1 11422.7 11663.0 11757.4 11770.5 10359.9 11515.5 11674.4 11729.6 11801.8 11131.9 11311.1 11473.4 11521.4 11558.7 10:19:09 INFO - PROCESS | 2001 | 10:19:09 INFO - PROCESS | 2001 | [#3] mootools.html Cycles:180 Average:6780.44 Median:3115.94 stddev:4196.49 (134.7%) stddev-sans-first:4189.62 10:19:09 INFO - PROCESS | 2001 | Values: 1517.6 1600.1 1630.3 1636.9 1638.4 8444.7 9860.6 11388.9 11534.9 11557.5 11436.8 11596.6 11606.0 11606.7 11627.8 2130.6 2450.0 2465.4 2471.9 2474.7 2432.9 2489.9 2506.7 2516.6 2518.0 2465.2 2474.3 2498.2 2508.3 2509.6 2433.0 2443.2 2469.1 2470.5 2475.6 2444.5 2473.4 2473.8 2476.5 2481.1 11270.7 11436.7 11466.5 11502.9 11521.4 5945.1 6079.3 6120.9 6123.4 6125.7 3713.9 3795.7 3839.4 3854.9 3889.7 1297.3 1922.7 1936.7 1986.2 2013.2 14248.0 14528.5 15756.8 16068.3 16103.8 4270.7 4672.2 4703.0 4908.3 4914.9 11980.6 12012.5 12082.8 12115.6 12123.2 5967.8 5980.0 5981.8 5983.0 5985.2 5388.0 5430.3 5475.8 5483.2 5556.6 3828.6 3881.2 3905.5 3929.3 3950.9 1073.8 1076.9 1081.5 1085.8 1091.1 932.5 944.9 948.5 962.3 1013.8 5600.0 6507.3 6568.2 6606.9 6738.6 885.0 1007.8 1024.7 1043.3 1082.3 4087.2 4151.8 4160.2 4215.2 4369.5 11765.8 13467.7 13883.3 14092.6 14102.4 13428.0 13926.4 13948.0 13965.9 13971.3 9057.9 9937.2 10170.1 10179.4 10193.2 5913.4 5995.6 6511.9 6778.8 6910.9 11858.7 12030.6 12133.9 12147.6 12341.1 12567.8 12828.3 12829.7 12845.9 12851.5 8874.5 8888.0 8976.8 9079.6 9083.5 9822.9 9841.0 9881.4 9884.0 9914.3 7020.5 8294.2 8522.7 8569.6 8587.7 8301.5 8370.3 8387.5 8392.6 8530.0 7388.3 7840.4 8111.1 8148.7 8244.3 5131.8 5869.1 5956.8 6082.2 6088.7 5917.3 6014.6 6048.3 6056.7 6170.0 10:19:09 INFO - PROCESS | 2001 | 10:19:09 INFO - PROCESS | 2001 | [#4] prototype.html Cycles:180 Average:4824.16 Median:5185.96 stddev:1325.87 (25.6%) stddev-sans-first:1286.96 10:19:09 INFO - PROCESS | 2001 | Values: 380.6 399.0 399.8 403.7 408.8 5251.2 5424.6 5484.7 5499.9 5545.5 4817.5 5263.7 5275.8 5337.5 5460.6 4046.2 4370.7 4766.7 4771.1 4825.0 4063.8 4675.9 4682.5 4811.5 4835.0 4014.6 4480.7 4489.6 4681.6 4764.7 3860.0 4748.9 4759.3 4780.6 4810.5 4212.9 4295.4 4518.7 4544.9 4607.4 4937.4 5285.2 5285.3 5329.6 5356.4 4843.0 5283.9 5382.2 5418.5 5566.9 5125.7 5671.5 5699.4 5923.6 5931.0 4309.0 4607.1 4632.3 4644.8 4650.1 5153.9 5222.7 5642.4 5873.8 6008.6 4594.4 4638.6 4642.4 4644.9 4647.7 4129.3 4164.2 4529.5 4672.4 4746.8 4774.9 4871.9 5067.2 5109.2 5142.8 4690.8 5041.6 5089.8 5730.9 5794.4 1383.3 1614.1 1616.0 1621.8 1631.4 1037.8 1114.6 1123.7 1195.4 1248.2 4678.5 4978.4 5354.6 5358.5 5395.8 5215.5 5548.0 6048.9 6076.5 6078.4 4869.8 4997.9 5039.7 5152.9 5439.3 2714.8 3083.4 3107.6 3126.4 3133.9 5656.4 5900.6 6150.0 6168.8 6280.6 5140.0 5764.5 6052.6 6057.6 6144.4 4943.9 5416.0 5660.5 6211.8 6216.8 5612.6 5801.0 5809.6 5875.9 6015.4 5158.5 5233.8 5465.2 5633.3 5665.3 5227.3 5456.0 5843.4 5875.1 6185.2 4141.4 4184.0 4223.6 4320.2 4670.9 5037.5 5213.4 5304.4 5793.4 6074.5 5504.1 5505.6 5711.8 5721.9 5869.4 4576.0 5547.7 5762.5 5834.7 5949.4 5149.4 5623.7 5651.3 5720.7 5749.9 5724.9 5739.0 5780.4 5811.4 5836.7 5060.9 5516.7 5668.3 5730.7 5752.0 10:19:09 INFO - PROCESS | 2001 | 10:19:09 INFO - PROCESS | 2001 | [#5] yui.html Cycles:180 Average:21457.99 Median:16632.00 stddev:31119.51 (187.1%) stddev-sans-first:31167.74 10:19:09 INFO - PROCESS | 2001 | Values: 688.9 749.7 751.7 789.8 899.9 15353.4 15953.4 16533.0 16578.0 16686.0 15301.0 15544.3 15649.9 15899.8 15921.0 998.6 1033.7 1037.9 1038.4 1039.4 999.2 1027.6 1045.6 1054.1 1055.3 1078.2 1088.2 1090.5 1090.9 1092.3 1081.4 1082.4 1083.5 1084.3 1084.5 804.8 808.0 808.1 810.2 813.6 14723.5 17304.5 17305.2 17821.1 18064.7 14632.3 14746.6 14826.7 15132.3 15142.8 9727.6 11435.2 12134.0 12174.7 12408.3 4006.9 4582.6 4630.3 4739.0 4886.4 88423.6 105641.4 116549.5 121189.4 123917.0 9659.8 9915.5 10545.8 11075.2 11386.4 22088.3 22281.6 22383.9 22394.0 22426.7 7857.8 7978.8 8032.4 8115.4 8224.1 4556.6 4598.8 4615.9 4627.9 4646.3 3458.1 3550.1 3552.7 3555.6 3571.6 635.1 640.1 646.1 646.2 662.9 515.6 544.7 552.6 581.9 604.6 7880.9 9758.3 9827.3 9957.4 10351.4 537.8 546.2 566.0 602.3 603.7 4044.5 4717.4 5027.7 5071.3 5084.6 109404.0 110362.0 112384.0 112471.0 114464.0 92724.0 98756.0 99288.0 101040.0 102568.0 59944.4 74693.0 82431.8 83865.6 85710.6 10914.6 11155.6 11482.7 11486.7 11541.9 51113.7 51395.0 51400.5 52484.0 52995.4 60991.7 64838.0 65965.3 66258.0 67645.0 13152.7 13299.9 13347.6 13617.0 13884.0 15203.2 15348.8 15465.1 15630.1 16276.5 13870.0 14089.5 14359.0 14503.6 14659.0 14310.1 14499.5 14709.9 15008.5 15034.5 13487.5 13620.2 13668.6 14070.4 14963.0 9225.0 10215.1 10426.4 10427.3 10526.5 11009.8 11943.2 11946.8 12084.0 12399.6 10:19:09 INFO - PROCESS | 2001 | -------- Summary: end -------- 10:19:09 INFO - PROCESS | 2001 | 10:19:10 INFO - TEST-INFO | 2001: exit 0 10:19:10 INFO - TEST-OK | dromaeo_css | took 1115062ms 10:19:10 INFO - TEST-START | kraken 10:19:10 INFO - operating with platform_type : mac_ 10:19:10 INFO - Initialising browser for kraken test... 10:19:10 INFO - TEST-INFO | started process 2010 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmpUcRNrC/profile http://localhost:49264/getInfo.html) 10:19:12 INFO - PROCESS | 2010 | 2017-05-24 10:19:12.837 plugin-container[2012:22137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 10:19:12 INFO - PROCESS | 2010 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:19:12 INFO - PROCESS | 2010 | 2017-05-24 10:19:12.837 plugin-container[2012:22137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:19:12 INFO - PROCESS | 2010 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:19:12 INFO - PROCESS | 2010 | 2017-05-24 10:19:12.837 plugin-container[2012:22137] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:19:12 INFO - PROCESS | 2010 | __metrics Screen width/height:1600/1200 10:19:12 INFO - PROCESS | 2010 | colorDepth:24 10:19:12 INFO - PROCESS | 2010 | Browser inner width/height: 1024/689 10:19:12 INFO - PROCESS | 2010 | __metrics 10:19:12 ERROR - PROCESS | 2010 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:19:13 INFO - TEST-INFO | 2010: exit 0 10:19:13 INFO - Browser initialized. 10:19:13 INFO - Running cycle 1/1 for kraken test... 10:19:13 INFO - TEST-INFO | started process 2013 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmpUcRNrC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:16 INFO - PROCESS | 2013 | 2017-05-24 10:19:16.174 plugin-container[2014:22256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 10:19:16 INFO - PROCESS | 2013 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:19:16 INFO - PROCESS | 2013 | 2017-05-24 10:19:16.175 plugin-container[2014:22256] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 10:19:16 INFO - PROCESS | 2013 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:19:16 INFO - PROCESS | 2013 | 2017-05-24 10:19:16.175 plugin-container[2014:22256] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:19:26 INFO - PROCESS | 2013 | RSS: Main: 174391296 10:19:26 INFO - PROCESS | 2013 | 10:19:29 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection) 10:19:30 INFO - PROCESS | 2013 | RSS: Main: 187756544 10:19:30 INFO - PROCESS | 2013 | 10:19:32 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-dft) 10:19:33 INFO - PROCESS | 2013 | RSS: Main: 195821568 10:19:33 INFO - PROCESS | 2013 | 10:19:36 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-fft) 10:19:37 INFO - PROCESS | 2013 | RSS: Main: 199573504 10:19:37 INFO - PROCESS | 2013 | 10:19:39 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator) 10:19:39 INFO - PROCESS | 2013 | RSS: Main: 199901184 10:19:39 INFO - PROCESS | 2013 | 10:19:41 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:19:42 INFO - PROCESS | 2013 | RSS: Main: 201379840 10:19:42 INFO - PROCESS | 2013 | 10:19:45 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom) 10:19:45 INFO - PROCESS | 2013 | RSS: Main: 205410304 10:19:45 INFO - PROCESS | 2013 | 10:19:49 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate) 10:19:49 INFO - PROCESS | 2013 | RSS: Main: 204705792 10:19:49 INFO - PROCESS | 2013 | 10:19:52 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial) 10:19:53 INFO - PROCESS | 2013 | RSS: Main: 204963840 10:19:53 INFO - PROCESS | 2013 | 10:19:55 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:19:55 INFO - PROCESS | 2013 | RSS: Main: 204550144 10:19:55 INFO - PROCESS | 2013 | 10:19:57 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:19:58 INFO - PROCESS | 2013 | RSS: Main: 204595200 10:19:58 INFO - PROCESS | 2013 | 10:20:00 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:20:00 INFO - PROCESS | 2013 | RSS: Main: 204582912 10:20:00 INFO - PROCESS | 2013 | 10:20:03 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:20:04 INFO - PROCESS | 2013 | RSS: Main: 204980224 10:20:04 INFO - PROCESS | 2013 | 10:20:07 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:20:08 INFO - PROCESS | 2013 | RSS: Main: 205037568 10:20:08 INFO - PROCESS | 2013 | 10:20:09 INFO - PROCESS | 2013 | Cycle 1(1): loaded http://localhost:49264/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49264/tests/kraken/driver.html?testName=ai-astar) 10:20:10 INFO - PROCESS | 2013 | RSS: Main: 206946304 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | __start_tp_report 10:20:10 INFO - PROCESS | 2013 | _x_x_mozilla_page_load 10:20:10 INFO - PROCESS | 2013 | _x_x_mozilla_page_load_details 10:20:10 INFO - PROCESS | 2013 | |i|pagename|runs| 10:20:10 INFO - PROCESS | 2013 | |0;ai-astar;107;123;122;159;172;171;119;118;124;175 10:20:10 INFO - PROCESS | 2013 | |1;audio-beat-detection;141;114;135;172;137;109;112;112;127;113 10:20:10 INFO - PROCESS | 2013 | |2;audio-dft;194;200;195;196;188;197;194;197;201;192 10:20:10 INFO - PROCESS | 2013 | |3;audio-fft;84;58;59;88;61;61;51;64;94;82 10:20:10 INFO - PROCESS | 2013 | |4;audio-oscillator;81;71;85;73;71;69;82;70;73;102 10:20:10 INFO - PROCESS | 2013 | |5;imaging-gaussian-blur;112;89;89;88;89;103;89;88;134;89 10:20:10 INFO - PROCESS | 2013 | |6;imaging-darkroom;96;111;151;108;149;133;152;137;95;95 10:20:10 INFO - PROCESS | 2013 | |7;imaging-desaturate;94;91;80;140;141;91;93;117;105;91 10:20:10 INFO - PROCESS | 2013 | |8;json-parse-financial;66;63;65;66;68;68;67;68;68;69 10:20:10 INFO - PROCESS | 2013 | |9;json-stringify-tinderbox;56;62;58;63;62;55;57;59;58;57 10:20:10 INFO - PROCESS | 2013 | |10;stanford-crypto-aes;84;82;81;86;86;84;83;85;84;87 10:20:10 INFO - PROCESS | 2013 | |11;stanford-crypto-ccm;157;150;121;138;164;143;144;144;195;151 10:20:10 INFO - PROCESS | 2013 | |12;stanford-crypto-pbkdf2;166;161;152;143;197;213;165;166;216;161 10:20:10 INFO - PROCESS | 2013 | |13;stanford-crypto-sha256-iterative;67;66;67;66;66;64;75;64;63;64 10:20:10 INFO - PROCESS | 2013 | __end_tp_report 10:20:10 INFO - PROCESS | 2013 | __start_cc_report 10:20:10 INFO - PROCESS | 2013 | _x_x_mozilla_cycle_collect,994 10:20:10 INFO - PROCESS | 2013 | __end_cc_report 10:20:10 INFO - PROCESS | 2013 | __startTimestamp1495646410069__endTimestamp 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | ------- Summary: start ------- 10:20:10 INFO - PROCESS | 2013 | Number of tests: 14 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#0] ai-astar Cycles:10 Average:139.00 Median:141.50 stddev:26.76 (18.9%) stddev-sans-first:25.75 10:20:10 INFO - PROCESS | 2013 | Values: 107.0 123.0 122.0 159.0 172.0 171.0 119.0 118.0 124.0 175.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:131.00 stddev:19.79 (15.1%) stddev-sans-first:20.35 10:20:10 INFO - PROCESS | 2013 | Values: 141.0 114.0 135.0 172.0 137.0 109.0 112.0 112.0 127.0 113.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#2] audio-dft Cycles:10 Average:195.40 Median:196.50 stddev:3.78 (1.9%) stddev-sans-first:3.97 10:20:10 INFO - PROCESS | 2013 | Values: 194.0 200.0 195.0 196.0 188.0 197.0 194.0 197.0 201.0 192.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#3] audio-fft Cycles:10 Average:70.20 Median:73.00 stddev:15.14 (20.7%) stddev-sans-first:15.22 10:20:10 INFO - PROCESS | 2013 | Values: 84.0 58.0 59.0 88.0 61.0 61.0 51.0 64.0 94.0 82.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:73.00 stddev:10.23 (14.0%) stddev-sans-first:10.78 10:20:10 INFO - PROCESS | 2013 | Values: 81.0 71.0 85.0 73.0 71.0 69.0 82.0 70.0 73.0 102.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:89.00 stddev:15.32 (17.2%) stddev-sans-first:15.26 10:20:10 INFO - PROCESS | 2013 | Values: 112.0 89.0 89.0 88.0 89.0 103.0 89.0 88.0 134.0 89.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#6] imaging-darkroom Cycles:10 Average:122.70 Median:151.50 stddev:24.18 (16.0%) stddev-sans-first:23.64 10:20:10 INFO - PROCESS | 2013 | Values: 96.0 111.0 151.0 108.0 149.0 133.0 152.0 137.0 95.0 95.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#7] imaging-desaturate Cycles:10 Average:104.30 Median:91.00 stddev:21.44 (23.6%) stddev-sans-first:22.42 10:20:10 INFO - PROCESS | 2013 | Values: 94.0 91.0 80.0 140.0 141.0 91.0 93.0 117.0 105.0 91.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:68.00 stddev:1.81 (2.7%) stddev-sans-first:1.90 10:20:10 INFO - PROCESS | 2013 | Values: 66.0 63.0 65.0 66.0 68.0 68.0 67.0 68.0 68.0 69.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:58.50 stddev:2.75 (4.7%) stddev-sans-first:2.74 10:20:10 INFO - PROCESS | 2013 | Values: 56.0 62.0 58.0 63.0 62.0 55.0 57.0 59.0 58.0 57.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.50 stddev:1.87 (2.2%) stddev-sans-first:1.99 10:20:10 INFO - PROCESS | 2013 | Values: 84.0 82.0 81.0 86.0 86.0 84.0 83.0 85.0 84.0 87.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:150.50 stddev:19.36 (12.9%) stddev-sans-first:20.40 10:20:10 INFO - PROCESS | 2013 | Values: 157.0 150.0 121.0 138.0 164.0 143.0 144.0 144.0 195.0 151.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:166.00 stddev:25.40 (15.3%) stddev-sans-first:26.77 10:20:10 INFO - PROCESS | 2013 | Values: 166.0 161.0 152.0 143.0 197.0 213.0 165.0 166.0 216.0 161.0 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - PROCESS | 2013 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:3.39 (5.1%) stddev-sans-first:3.59 10:20:10 INFO - PROCESS | 2013 | Values: 67.0 66.0 67.0 66.0 66.0 64.0 75.0 64.0 63.0 64.0 10:20:10 INFO - PROCESS | 2013 | -------- Summary: end -------- 10:20:10 INFO - PROCESS | 2013 | 10:20:10 INFO - TEST-INFO | 2013: exit 0 10:20:11 INFO - TEST-OK | kraken | took 60801ms 10:20:11 INFO - SUITE-END | took 1175s 10:20:11 INFO - Completed test suite (00:19:36) 10:20:11 INFO - javascript benchmark 10:20:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7928.402816951303, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2959.437706835701, 2972.509535926571, 3639.1811842335496, 3645.5807582128064, 3645.981770091149, 6149.415805498476, 6152.692365381729, 6169.722362493687, 6185.628862268256, 6215.440610345079, 5103.6937783733, 6129.81610551683, 6150.507959363252, 6157.322694503601, 6174.012158054712, 5640.887182256362, 5696.77212911483, 5719.199312096289, 5771.076627739562, 5781.103928891029, 5745.0, 5757.337906140785, 5761.4814666679995, 5762.031952815479, 5798.971005144968, 4697.107549565589, 5328.786848526054, 5617.101263797793, 5745.712714364265, 5749.022666146765, 5727.11229759388, 5737.770489180428, 5765.682887441189, 5776.682282474462, 5827.300723913115, 4605.769711514411, 5570.275864137677, 5655.604107712462, 5680.6591604503865, 5702.344230413472, 4572.702774319659, 5453.2092846537425, 5799.855003624901, 6016.789412370546, 6200.906986395207, 6337.176167098248, 6401.967990160066, 6420.165378500812, 6547.247066587356, 6601.93398066018, 6715.697793599251, 6722.899156512656, 6741.460683145337, 6743.393094621459, 6748.055272261888, 5736.3403208631125, 5881.823635272963, 6138.232720909887, 6152.969235153885, 6232.813015609494, 6653.833654158588, 6727.394534491919, 6812.114425124702, 6838.897416538756, 6840.042394064835, 5878.030125029322, 6377.0, 6454.677266136674, 6457.5479716419395, 6475.028745688184, 5707.828765137053, 5979.431953964367, 6121.908171377433, 6308.495320374359, 6414.652922886153, 6211.875762484725, 6353.777617783356, 6369.172007638977, 6450.0, 6487.3836985486305, 6044.123602077674, 6265.749370025239, 6569.770058047945, 6597.901031484532, 6612.735490580323, 5239.214117882272, 5242.501962313574, 5254.369475662868, 5256.737163141904, 5264.263003179558, 4783.545563171493, 4792.95207047925, 4794.304825800239, 4797.352357431771, 4809.975950120227, 6130.816075517741, 6150.692465376714, 6277.52918531112, 6287.968560157261, 6303.936960630335, 6441.677916104176, 6521.576097553644, 6566.179227596551, 6647.268800431949, 6653.667316634149, 6282.2147231596055, 6288.811335659938, 6296.65368404733, 6298.7795427160745, 6308.558400911892, 5601.0, 5846.94153058464, 5853.092770620476, 5865.35481097079, 5932.644041357532, 6514.478841692653, 6534.607923524509, 6734.831629209309, 6760.357766012221, 6803.081583986296, 6662.200535935719, 6696.1629796275465, 6737.461003119641, 6744.0895479109695, 6745.730170793309, 6887.483438742014, 6910.101686780687, 6917.0, 6923.2038315594355, 6935.687894044881, 5464.672119672832, 6286.402791734778, 6317.96841015792, 6487.740490380521, 6611.471082313307, 5414.53132336351, 6298.803227386782, 6681.76613818514, 6717.1267735194115, 6781.6270105144695, 6327.240731112296, 6460.25707043696, 6464.773732919325, 6475.061116138039, 6482.805515834532, 6189.978653522118, 6242.781502647385, 6335.809925702236, 6485.3514648534765, 6502.154719886385, 6109.252844142398, 6272.18461599989, 6290.716917738804, 6342.90485642725, 6354.142190804368, 6433.710483028369, 6436.871262574816, 6451.516136289703, 6467.320931302145, 6471.838204044937, 6164.8802487409985, 6212.006079027334, 6239.0953311768635, 6258.812235632939, 6279.96860015697], "value": 6017.554096791901, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.9686012559498, 839.0979696825913, 856.3748463621554, 977.8337682593958, 980.0052946259548, 40518.79740601296, 41109.588904110984, 42840.57159428405, 42890.0, 42989.0, 41277.58722412775, 41657.791711041406, 41702.748917532525, 41713.791431042875, 41764.37353439693, 8338.0, 8456.365772567051, 11226.887731122706, 11543.076553875711, 11709.180357374988, 11298.265612735146, 11349.886501135006, 11384.886151138508, 11392.373419461926, 11401.600943966963, 9422.717318480456, 11370.317780933128, 11372.601958931438, 11376.317420954769, 11398.943005285004, 11302.095832333434, 11319.434028298552, 11334.376609286486, 11343.035841953444, 11349.262297950607, 9514.762130946712, 11101.278416902875, 11186.0, 11194.160437967184, 11206.327620342805, 35397.0, 35546.64453355485, 35625.28749425023, 35629.93110206698, 35652.82173589141, 3901.7854018028997, 3939.6454319111417, 3950.6839452843706, 3961.1483531040676, 3969.960300396988, 21260.152670233245, 26272.86863565689, 26370.86814565934, 27004.594931076048, 27606.447871042466, 3236.6116066072223, 3247.512873069035, 3256.3161736035463, 3269.346130773855, 3287.2603664175367, 313077.0, 316655.0, 418534.0, 428599.85700071615, 431425.0, 44738.77630611891, 45037.0, 45055.32417013747, 45112.097758044656, 45253.868653283935, 46854.0, 46857.531424686, 46864.5313546867, 47008.294875576896, 47208.76395618069, 14275.500357487646, 14280.42878284857, 14354.928225359015, 14393.0, 14415.855841441451, 10252.538635761408, 12462.688432789253, 12480.063995200215, 12517.624471265877, 12518.0, 12197.268163910194, 12258.509659613694, 12259.387030648431, 12315.261084334968, 12405.937970310271, 5902.970485147633, 5930.733117009659, 5943.10853371989, 5962.360350903449, 5972.432618901197, 1137.8634563852224, 1151.78691941991, 1163.3078318400537, 1170.4674373160192, 1209.3832145605804, 23110.537789244456, 26631.733682662925, 27130.0, 27172.59241111385, 27260.727392726214, 1244.8319476870506, 1275.43880692495, 1276.5914907229783, 1308.3981368570373, 1318.4001279417841, 13517.59447216585, 13905.513307034205, 14163.504277350243, 14234.715305694035, 14252.572422827332, 437978.81010595383, 628372.0, 644420.0, 667731.0, 668522.0, 51623.0, 51839.74080129575, 51914.48085519097, 52081.47918520766, 52299.0, 1301.576987479073, 1491.4183468447313, 1513.2055670772713, 1528.2664321125922, 1530.5867415797672, 27415.17754467369, 29037.264755935637, 29311.974080907064, 29441.116766497038, 29443.263918402212, 43212.783936080115, 43528.0, 43542.0, 43626.78186609047, 43734.56265437432, 522364.0, 524404.0, 527547.0, 529118.0, 529832.0, 25907.870460647577, 26369.340766480993, 30951.380972380877, 31691.84154079215, 31945.36109277848, 39081.80459097686, 39521.407178892914, 40879.38680919729, 40984.38523422211, 41018.58981410267, 28068.578971315037, 28432.85783571069, 28880.85559572273, 29076.27309317284, 29301.560476592444, 22987.65518517257, 28641.0, 28857.845686172317, 29419.55870661899, 29801.850990744908, 32419.513707294882, 33187.33625327528, 33519.664803351654, 33884.81403150878, 33888.0, 28138.01516946979, 28860.27849303784, 28927.276818079717, 29248.56127158137, 29720.554191686708, 22459.32622021342, 23387.76612233924, 23630.409239768454, 23772.762272377746, 23963.281101566285], "value": 18998.161462418455, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.88055463915464, 428.8036378503974, 446.74758761299864, 450.53819834669457, 454.82593204482566, 20559.897200514013, 21290.787092129056, 21910.671339929893, 22055.11779528817, 22173.2239371622, 21679.241226557075, 22288.442788930246, 22859.314220573367, 22869.656955145685, 23199.304020879357, 5413.83602525457, 6810.148731408586, 7076.0, 7081.822954426155, 7082.220955694869, 6020.277566692003, 7031.859362812741, 7038.718451261943, 7039.683214255345, 7070.964645176793, 6798.728050877959, 6944.0, 6944.47916406269, 7027.47293952953, 7050.823729406755, 6644.568103073333, 6894.896576551356, 6899.275576064516, 6963.895541566855, 6973.058637083979, 6789.490788190856, 6790.728370865159, 6791.117154769898, 6801.4898882584, 6803.319668033157, 26888.46223075547, 26939.865300673566, 27097.593536096974, 27249.727502725116, 27384.863075684694, 2912.8397938113394, 2931.618889544367, 3018.381231847488, 3172.9206769830707, 3491.5286436331025, 16090.712742980533, 16621.584460388465, 16803.15984200785, 17041.318347266093, 17364.91317543404, 2079.8856062916534, 2158.298552970291, 2167.2847960173103, 2172.1528603844513, 2175.1516908405574, 154688.0, 158173.41826581702, 172960.0, 180518.19481805144, 181117.18882811136, 19300.613987720448, 20961.790382095984, 21023.107920287246, 21150.0, 21171.68242476364, 32266.0, 32772.508412373834, 32909.84815531445, 32932.3413531728, 32945.34109317848, 10125.949370253102, 10817.296875703054, 10880.619178328721, 10973.945130274456, 11050.0, 9229.833422247868, 9316.02181770918, 9339.066093390607, 9448.433094014381, 9482.431054136774, 8063.677452901936, 9202.539873006323, 9441.339106262632, 9540.809183816285, 9558.091981261769, 3350.6649335066786, 3964.671834935329, 4232.555581663943, 4269.039466120098, 4270.871873843789, 928.9024652411534, 1039.9064084232455, 1074.5755426606447, 1076.860008198929, 1092.5036292072575, 21672.78327216739, 21710.89144554267, 21890.781092189194, 21937.561248775255, 21946.780532194793, 1077.5743581285349, 1079.875814281352, 1104.100158370925, 1114.7658991611852, 1117.1788735279563, 10743.78512429747, 11128.276662017104, 11362.659120226406, 11386.544538218379, 11415.258008229439, 751712.0, 777816.0, 779565.0, 791662.0, 795643.0217848874, 122812.0, 168048.1597592004, 169383.45924811138, 169883.0, 171656.0, 1057.9259451838298, 1231.0459393969745, 1231.6489800406841, 1261.9305938173304, 1264.7533730922567, 13611.52359667407, 18172.0, 19289.614207716048, 19301.90349048246, 19434.61130777405, 40090.79954600208, 63220.73558528896, 63304.36695633169, 63444.0, 64621.67689161524, 87061.12938870784, 88400.0, 88691.0, 89066.0, 89969.55015224882, 9946.0, 11407.714807129889, 11425.371604561496, 11525.250858694159, 11707.590234341757, 12839.293838838954, 12852.228866268053, 12929.418176182282, 13217.867821321664, 13320.93339533296, 8728.214460698568, 9790.118889299712, 10980.835287470534, 11457.417033186792, 11739.002184814386, 9799.65701200483, 10066.295359324777, 10489.632862849763, 10611.946940265248, 10628.468576570986, 11355.091592672732, 11422.714432139262, 11662.950334469939, 11757.353345566076, 11770.470328835052, 10359.896401035892, 11515.481803319039, 11674.357910315015, 11729.648110556696, 11801.822972655245, 11131.944340278247, 11311.095112390823, 11473.4263286837, 11521.366324851879, 11558.653240402802], "value": 11806.730435878193, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1517.5826647671888, 1600.1119378744806, 1630.2500849609187, 1636.885418020739, 1638.3692278472784, 8444.66221351146, 9860.556274967626, 11388.943055284733, 11534.942325288372, 11557.479913403897, 11436.771264574703, 11596.594119205829, 11606.0, 11606.709832254199, 11627.767444651103, 2130.648443006906, 2450.0444826517582, 2465.4206261528525, 2471.9011239550396, 2474.6782918220606, 2432.8540287582714, 2489.8879550420183, 2506.711728151268, 2516.5973444248925, 2518.0, 2465.1988103866224, 2474.2948259745976, 2498.150628786212, 2508.3227528567277, 2509.5608268553024, 2433.0267892842826, 2443.2059580636364, 2469.086438017927, 2470.493548822487, 2475.591527397977, 2444.511097780451, 2473.418746594549, 2473.777360037587, 2476.5418397596322, 2481.10680155144, 11270.661880143609, 11436.714082148013, 11466.48400821965, 11502.942485287604, 11521.423928803526, 5945.108233764927, 6079.331273559905, 6120.938790612081, 6123.387661233885, 6125.724342404603, 3713.8700145494777, 3795.6773674237725, 3839.4240863870364, 3854.9229015419814, 3889.708271879592, 1297.2800095946745, 1922.6827573450364, 1936.6707659697886, 1986.1658103596524, 2013.1846602126163, 14248.002639815217, 14528.491502797457, 15756.763648545166, 16068.276927538172, 16103.838961610469, 4270.701050926467, 4672.182368085571, 4702.976485117552, 4908.2637604358915, 4914.90170196594, 11980.58067967617, 12012.459439325252, 12082.818757718642, 12115.575954841539, 12123.211991220542, 5967.76128954837, 5980.0, 5981.760729570856, 5982.970085149634, 5985.191999080069, 5387.973060134753, 5430.294061772023, 5475.753591088411, 5483.177523371526, 5556.583256255797, 3828.578856325802, 3881.1849511602313, 3905.453236546886, 3929.332013557702, 3950.906019809506, 1073.8174510333263, 1076.9192310576739, 1081.5079138991898, 1085.7991271614776, 1091.0943916549245, 932.529072818236, 944.9149576538007, 948.5115165689675, 962.2638681408728, 1013.7668336282549, 5600.0, 6507.251666058369, 6568.211814582281, 6606.933930660728, 6738.629375384303, 884.9601767920459, 1007.7531004903846, 1024.7395703285001, 1043.2540733375672, 1082.2532452607675, 4087.202995415865, 4151.771652559078, 4160.167966406731, 4215.220184265921, 4369.47566292047, 11765.823512647317, 13467.730645387037, 13883.305834708224, 14092.647683807987, 14102.435902563988, 13428.0, 13926.442942282196, 13948.023638345421, 13965.860341396456, 13971.301434928213, 9057.864132038025, 9937.205023598095, 10170.08469237772, 10179.389236645826, 10193.184545236363, 5913.438223368773, 5995.550333725076, 6511.902321465276, 6778.796636100925, 6910.896336555056, 11858.703532411759, 12030.63908082759, 12133.939330303292, 12147.574834880737, 12341.13612047184, 12567.811482827949, 12828.294443805307, 12829.743405131658, 12845.908097811416, 12851.485940562647, 8874.467531947845, 8887.955560222157, 8976.820463590822, 9079.636814527345, 9083.500407477388, 9822.901770982198, 9841.015898410387, 9881.357711748713, 9884.011598840058, 9914.256430767866, 7020.473464490082, 8294.170582941657, 8522.659093636434, 8569.571521424028, 8587.699430519902, 8301.543415112226, 8370.330373569977, 8387.454815436977, 8392.580370981304, 8529.9764028317, 7388.335049845539, 7840.411969102226, 8111.107778144517, 8148.674053037812, 8244.340452763885, 5131.820386286462, 5869.148973398748, 5956.821295361146, 6082.178905847654, 6088.695565221633, 5917.260342457193, 6014.609050411709, 6048.266661356901, 6056.66688332146, 6170.012797952306], "value": 5250.284909724095, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.6193806193806, 398.9840406383745, 399.7801209334866, 403.7012610668107, 408.7874305361211, 5251.186066159751, 5424.64028147756, 5484.6983415912155, 5499.890002199953, 5545.500904918556, 4817.494163112875, 5263.710495922723, 5275.815346462875, 5337.4662533746605, 5460.590455715827, 4046.231216068952, 4370.6503479721705, 4766.713997160165, 4771.141194584973, 4824.975875120619, 4063.756174629532, 4675.919215948904, 4682.484926658065, 4811.473976162508, 4835.0, 4014.558398576154, 4480.686351955364, 4489.595936365727, 4681.565585895332, 4764.714117152982, 3860.0, 4748.928766068511, 4759.262314341283, 4780.569748722614, 4810.494898035691, 4212.9157416851795, 4295.367760251116, 4518.706284091523, 4544.8863778405475, 4607.35497030416, 4937.358143441365, 5285.180796976437, 5285.339332583427, 5329.573634109262, 5356.35723713153, 4842.975785121087, 5283.86790330241, 5382.219578161184, 5418.539424148952, 5566.916496252559, 5125.743712814345, 5671.461211184931, 5699.430056994309, 5923.5853490255695, 5930.970345148246, 4308.9784551077355, 4607.055553611502, 4632.3051542268595, 4644.8374306899095, 4650.139724151009, 5153.871153221162, 5222.712750798704, 5642.4170651636505, 5873.765049397976, 6008.609440386361, 4594.448666160082, 4638.5593368629925, 4642.3500709900645, 4644.930326045112, 4647.697899636512, 4129.256733787887, 4164.18917230874, 4529.456465224194, 4672.415948006499, 4746.795912758874, 4774.856754297377, 4871.906339474459, 5067.214581739836, 5109.241009854637, 5142.845714628567, 4690.835820746258, 5041.621878359065, 5089.770960306795, 5730.882477916766, 5794.362620111784, 1383.3221721356463, 1614.144503413193, 1615.967680646404, 1621.7810595569683, 1631.404537343861, 1037.818381783185, 1114.5844777132995, 1123.702218911989, 1195.396324855942, 1248.2073883084306, 4678.461976872635, 4978.4025916890205, 5354.598405119554, 5358.526405238591, 5395.78416863321, 5215.461438875541, 5547.972260138754, 6048.939510604926, 6076.453119219292, 6078.419610901109, 4869.756512174448, 4997.90004199914, 5039.748012599355, 5152.945289285075, 5439.3200849893765, 2714.8099633025895, 3083.383323335342, 3107.564940908275, 3126.3747250549527, 3133.85897634607, 5656.406077361818, 5900.64596124234, 6150.0, 6168.784092556739, 6280.623162610258, 5139.974300128475, 5764.452377024176, 6052.636841789506, 6057.57596968208, 6144.446999770043, 4943.901121977612, 5415.972920135374, 5660.518855897295, 6211.81364559064, 6216.844578885383, 5612.588765054708, 5800.954498888117, 5809.618056596944, 5875.94124058771, 6015.383308260921, 5158.509941555546, 5233.790648374023, 5465.2238022642005, 5633.295838020247, 5665.348484924121, 5227.320448341843, 5455.972720136374, 5843.444872737083, 5875.122419203305, 6185.195924529782, 4141.426258021932, 4183.974926143114, 4223.640990515841, 4320.179165958458, 4670.929936050894, 5037.524108365216, 5213.418419288448, 5304.363476382704, 5793.449622285876, 6074.479091177112, 5504.146857237135, 5505.6421332614855, 5711.8000869969355, 5721.8569535761935, 5869.354371019099, 4575.99328147807, 5547.694876781654, 5762.493315407811, 5834.7082645866685, 5949.434803693643, 5149.433562308068, 5623.718814059363, 5651.2935883014625, 5720.656760594377, 5749.885002299847, 5724.942750572441, 5738.971305143447, 5780.393058728773, 5811.418858114154, 5836.678982655994, 5060.898782024413, 5516.724163791875, 5668.291463567054, 5730.656160630376, 5752.0], "value": 4431.798333903713, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.869114868175, 749.6514120933765, 751.7481643649373, 789.8499285135824, 899.9460032398055, 15353.385864565418, 15953.44162954294, 16533.008019518824, 16578.00531968081, 16685.998840069573, 15301.0, 15544.300506477208, 15649.904506684537, 15899.761503577427, 15921.04473731577, 998.5805961496189, 1033.7415646088477, 1037.9481025948671, 1038.3873514626368, 1039.433508737736, 999.2355847776421, 1027.6351895077253, 1045.6497073480393, 1054.1092776603757, 1055.2665897201448, 1078.2075174746556, 1088.2382332367335, 1090.4602221900132, 1090.907272881806, 1092.2790957967743, 1081.4268437727978, 1082.3884505254507, 1083.5015892689373, 1084.3060441317564, 1084.528230219853, 804.798800299925, 808.0465051239535, 808.0626473290954, 810.222186700768, 813.5932033983008, 14723.484678036217, 17304.480865574053, 17305.221265043103, 17821.10894455285, 18064.729029064576, 14632.268386580736, 14746.557603271918, 14826.703465930728, 15132.319045642973, 15142.772858407132, 9727.610895564168, 11435.196058345271, 12134.0, 12174.695632609168, 12408.317542535156, 4006.8797936061965, 4582.633389328879, 4630.259158534652, 4738.976305118486, 4886.413630364379, 88423.55788221082, 105641.41537876938, 116549.4789731085, 121189.39405303005, 123917.0, 9659.758506037264, 9915.454649994319, 10545.789084218426, 11075.170662009721, 11386.430678466044, 22088.33734987953, 22281.55436891253, 22383.888080559493, 22393.992270347873, 22426.663600046013, 7857.803554911173, 7978.760637180894, 8032.437729359006, 8115.3507719382305, 8224.054233763194, 4556.612687921497, 4598.793054312564, 4615.861524154281, 4627.9074418511445, 4646.312151129333, 3458.1354661334667, 3550.0592343029116, 3552.715782737375, 3555.6444355564236, 3571.5892672342493, 635.1362147479424, 640.1166390381337, 646.1374065622352, 646.2051676438008, 662.9038789375513, 515.569499467941, 544.7370252189436, 552.6352607279176, 581.8719881626035, 604.6372176693949, 7880.921190788019, 9758.268129890286, 9827.26295527824, 9957.402555846673, 10351.378917264989, 537.8329026013482, 546.2352706211336, 565.9886802264014, 602.3284038297267, 603.7464265008707, 4044.4539987101953, 4717.410323709536, 5027.673201241981, 5071.290019397287, 5084.6440749147205, 109404.0, 110362.0, 112384.0, 112471.0, 114464.0, 92724.0, 98756.0, 99288.0, 101040.0, 102567.97432025585, 59944.40055599388, 74693.0, 82431.7635235472, 83865.58067209747, 85710.57144714237, 10914.563417463212, 11155.553777848796, 11482.655520334403, 11486.712832179262, 11541.884581154081, 51113.744431277606, 51395.0, 51400.48599514107, 52484.0, 52995.41013769593, 60991.69504152628, 64838.0, 65965.34034659591, 66258.0, 67644.98532521918, 13152.671183220496, 13299.867001329863, 13347.563286720046, 13617.04680672383, 13884.028118031641, 15203.239838008276, 15348.76976845326, 15465.072095674295, 15630.06219626826, 16276.51170464888, 13870.029097963046, 14089.506867259597, 14359.0, 14503.637409064857, 14659.0, 14310.141391516543, 14499.492517761828, 14709.92645036768, 15008.47470338533, 15034.548963531111, 13487.460501579826, 13620.18278903269, 13668.589942301747, 14070.437182512585, 14963.0, 9225.031371705874, 10215.080642742189, 10426.374417534973, 10427.322224055411, 10526.473676316307, 11009.755855989762, 11943.223690460092, 11946.76106477883, 12083.972862306802, 12399.566015189424], "value": 7907.721765041738, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 123.0, 122.0, 159.0, 172.0, 171.0, 119.0, 118.0, 124.0, 175.0], "value": 139.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 114.0, 135.0, 172.0, 137.0, 109.0, 112.0, 112.0, 127.0, 113.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 200.0, 195.0, 196.0, 188.0, 197.0, 194.0, 197.0, 201.0, 192.0], "value": 195.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 59.0, 88.0, 61.0, 61.0, 51.0, 64.0, 94.0, 82.0], "value": 70.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 71.0, 85.0, 73.0, 71.0, 69.0, 82.0, 70.0, 73.0, 102.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 89.0, 89.0, 88.0, 89.0, 103.0, 89.0, 88.0, 134.0, 89.0], "value": 97.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 111.0, 151.0, 108.0, 149.0, 133.0, 152.0, 137.0, 95.0, 95.0], "value": 122.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 91.0, 80.0, 140.0, 141.0, 91.0, 93.0, 117.0, 105.0, 91.0], "value": 104.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 65.0, 66.0, 68.0, 68.0, 67.0, 68.0, 68.0, 69.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 62.0, 58.0, 63.0, 62.0, 55.0, 57.0, 59.0, 58.0, 57.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 81.0, 86.0, 86.0, 84.0, 83.0, 85.0, 84.0, 87.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 150.0, 121.0, 138.0, 164.0, 143.0, 144.0, 144.0, 195.0, 151.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 161.0, 152.0, 143.0, 197.0, 213.0, 165.0, 166.0, 216.0, 161.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 66.0, 64.0, 75.0, 64.0, 63.0, 64.0], "value": 66.2, "unit": "score"}]}]} 10:20:11 INFO - Return code: 0 10:20:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:20:11 ERROR - # TBPL SUCCESS # 10:20:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:20:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:20:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:20:11 INFO - 2017-05-24 10:20:11.406 screenresolution[2018:22634] starting screenresolution argv=screenresolution get 10:20:11 INFO - 2017-05-24 10:20:11.409 screenresolution[2018:22634] Display 0: 1600x1200x32@60 10:20:11 INFO - 2017-05-24 10:20:11.415 screenresolution[2019:22637] starting screenresolution argv=screenresolution list 10:20:11 INFO - Available Modes on Display 0 10:20:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:20:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:20:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:20:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:20:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:20:11 INFO - Intel Iris: 10:20:11 INFO - Chipset Model: Intel Iris 10:20:11 INFO - Type: GPU 10:20:11 INFO - Bus: Built-In 10:20:11 INFO - VRAM (Dynamic, Max): 1536 MB 10:20:11 INFO - Vendor: Intel (0x8086) 10:20:11 INFO - Device ID: 0x0a2e 10:20:11 INFO - Revision ID: 0x0009 10:20:11 INFO - Displays: 10:20:11 INFO - Display: 10:20:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:20:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:20:11 INFO - Display Serial Number: 1600x1200 60 10:20:11 INFO - Main Display: Yes 10:20:11 INFO - Mirror: Off 10:20:11 INFO - Online: Yes 10:20:11 INFO - Rotation: Supported 10:20:11 INFO - Return code: 0 10:20:11 INFO - Running post-action listener: _resource_record_post_action 10:20:11 INFO - [mozharness: 2017-05-24 17:20:11.655894Z] Finished run-tests step (success) 10:20:11 INFO - Running post-run listener: _resource_record_post_run 10:20:11 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 32165888; Write bytes: 425601536; Read time: 193; Write time: 1417 10:20:11 INFO - TinderboxPrint: CPU usage
54.6% 10:20:11 INFO - TinderboxPrint: I/O read bytes / time
32,165,888 / 193 10:20:11 INFO - TinderboxPrint: I/O write bytes / time
425,601,536 / 1,417 10:20:11 INFO - TinderboxPrint: CPU idle
2,163.4 (45.4%) 10:20:11 INFO - TinderboxPrint: CPU system
957.3 (20.1%) 10:20:11 INFO - TinderboxPrint: CPU user
1,649.0 (34.6%) 10:20:11 INFO - TinderboxPrint: Swap in / out
148,930,560 / 0 10:20:11 INFO - install - Wall time: 16s; CPU: 55.0%; Read bytes: 117441024; Write bytes: 113127424; Read time: 10522; Write time: 244 10:20:11 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 32055296; Write bytes: 300491776; Read time: 191; Write time: 1131 10:20:12 INFO - Running post-run listener: _upload_blobber_files 10:20:12 INFO - Blob upload gear active. 10:20:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:20:12 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 10:20:12 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'] 10:20:12 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 10:20:12 INFO - (blobuploader) - INFO - Open directory for files ... 10:20:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:20:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:20:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:13 INFO - (blobuploader) - INFO - Done attempting. 10:20:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:20:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:20:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:14 INFO - (blobuploader) - INFO - Done attempting. 10:20:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:20:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:20:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:16 INFO - (blobuploader) - INFO - Done attempting. 10:20:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:20:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:20:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:18 INFO - (blobuploader) - INFO - Done attempting. 10:20:18 INFO - (blobuploader) - INFO - Iteration through files over. 10:20:18 INFO - Return code: 0 10:20:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:20:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:20:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b4e999b32612e800f9691ecdd35781c19afac4cddca6c486908b1a804b62a09eea70d1694126015be73bb475ad62b835da98b7758b970cc71fd961ea708b4d2b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1278c3fb2db7e5a8cd75af875aa87a842fd3650c4d051b0190b8f717df7374ea71acf537e2f12ac1c1a3ff4029ce6e4bbe080096537af09f23c18ffa47751554", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a3bc6343862fec9746d1133fe20babeeb8e0204e4c381a9a10e4607a87fdfa7d6a269b16a58f58a7ab63a7b1dcbf68741308120bcc9d4657005218357322e7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d97d46c4f8eea7c52598f044a904862e5ffd37f56b46dd128c218e7fbe2488527b930f36a8cc67b759096f482817bc2742515eee4de4cfbf30b3355233be65b0"} 10:20:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:20:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:20:18 INFO - Contents: 10:20:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b4e999b32612e800f9691ecdd35781c19afac4cddca6c486908b1a804b62a09eea70d1694126015be73bb475ad62b835da98b7758b970cc71fd961ea708b4d2b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1278c3fb2db7e5a8cd75af875aa87a842fd3650c4d051b0190b8f717df7374ea71acf537e2f12ac1c1a3ff4029ce6e4bbe080096537af09f23c18ffa47751554", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a3bc6343862fec9746d1133fe20babeeb8e0204e4c381a9a10e4607a87fdfa7d6a269b16a58f58a7ab63a7b1dcbf68741308120bcc9d4657005218357322e7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d97d46c4f8eea7c52598f044a904862e5ffd37f56b46dd128c218e7fbe2488527b930f36a8cc67b759096f482817bc2742515eee4de4cfbf30b3355233be65b0"} 10:20:18 INFO - Running post-run listener: copy_logs_to_upload_dir 10:20:18 INFO - Copying logs to upload dir... 10:20:18 INFO - mkdir: /builds/slave/test/build/upload/logs 10:20:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.097989 ========= master_lag: -0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-05-24 10:20:18.975947) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 10:20:18.980397) ========= 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.9Kgz32bsnX/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.6wuCm8EERm/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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/b4e999b32612e800f9691ecdd35781c19afac4cddca6c486908b1a804b62a09eea70d1694126015be73bb475ad62b835da98b7758b970cc71fd961ea708b4d2b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1278c3fb2db7e5a8cd75af875aa87a842fd3650c4d051b0190b8f717df7374ea71acf537e2f12ac1c1a3ff4029ce6e4bbe080096537af09f23c18ffa47751554", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a3bc6343862fec9746d1133fe20babeeb8e0204e4c381a9a10e4607a87fdfa7d6a269b16a58f58a7ab63a7b1dcbf68741308120bcc9d4657005218357322e7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d97d46c4f8eea7c52598f044a904862e5ffd37f56b46dd128c218e7fbe2488527b930f36a8cc67b759096f482817bc2742515eee4de4cfbf30b3355233be65b0"} build_url:https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/VzG-MyAjRwumyazjxyyHcg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b4e999b32612e800f9691ecdd35781c19afac4cddca6c486908b1a804b62a09eea70d1694126015be73bb475ad62b835da98b7758b970cc71fd961ea708b4d2b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1278c3fb2db7e5a8cd75af875aa87a842fd3650c4d051b0190b8f717df7374ea71acf537e2f12ac1c1a3ff4029ce6e4bbe080096537af09f23c18ffa47751554", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a3bc6343862fec9746d1133fe20babeeb8e0204e4c381a9a10e4607a87fdfa7d6a269b16a58f58a7ab63a7b1dcbf68741308120bcc9d4657005218357322e7c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d97d46c4f8eea7c52598f044a904862e5ffd37f56b46dd128c218e7fbe2488527b930f36a8cc67b759096f482817bc2742515eee4de4cfbf30b3355233be65b0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 10:20:19.020861) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 10:20:19.021382) ========= 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.9Kgz32bsnX/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.6wuCm8EERm/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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.005245 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 10:20:19.071052) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 10:20:19.071407) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 10:20:19.071807) ========= ========= Total master_lag: 0.02 =========