builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0293 starttime: 1496796967.18 results: success (0) revision: fd04166b7114949ce63783e10a069b98d76df573 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:07.175623) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:07.176228) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:07.176528) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:07.264157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:07.264520) ========= 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.qXfrNqoglL/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.EfZiyTVXCu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-06 17:56:07-- 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% 40.9M=0s 2017-06-06 17:56:07 (40.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155980 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:07.455898) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:07.456666) ========= 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.qXfrNqoglL/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.EfZiyTVXCu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.027438 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:07.507663) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-06 17:56:07.507984) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fd04166b7114949ce63783e10a069b98d76df573 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev fd04166b7114949ce63783e10a069b98d76df573 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qXfrNqoglL/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.EfZiyTVXCu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-06 17:56:07,606 truncating revision to first 12 chars 2017-06-06 17:56:07,606 Setting DEBUG logging. 2017-06-06 17:56:07,606 attempt 1/10 2017-06-06 17:56:07,606 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fd04166b7114?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-06 17:56:09,491 unpacking tar archive at: mozilla-central-fd04166b7114/testing/mozharness/ program finished with exit code 0 elapsedTime=2.156870 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-06 17:56:09.686735) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:09.687140) ========= script_repo_revision: fd04166b7114949ce63783e10a069b98d76df573 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:09.687553) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:09.687939) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 17:56:09.706733) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-06 17:56:09.707122) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qXfrNqoglL/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.EfZiyTVXCu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:56:09 INFO - MultiFileLogger online at 20170606 17:56:09 in /builds/slave/test 17:56:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:56:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:56:09 INFO - {'append_to_log': False, 17:56:09 INFO - 'base_work_dir': '/builds/slave/test', 17:56:09 INFO - 'blob_upload_branch': 'Firefox', 17:56:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:56:09 INFO - 'branch': 'Firefox', 17:56:09 INFO - 'buildbot_json_path': 'buildprops.json', 17:56:09 INFO - 'config_files': ('talos/mac_config.py',), 17:56:09 INFO - 'default_actions': ('clobber', 17:56:09 INFO - 'read-buildbot-config', 17:56:09 INFO - 'download-and-extract', 17:56:09 INFO - 'populate-webroot', 17:56:09 INFO - 'create-virtualenv', 17:56:09 INFO - 'install', 17:56:09 INFO - 'setup-mitmproxy', 17:56:09 INFO - 'run-tests'), 17:56:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:56:09 INFO - 'download_minidump_stackwalk': True, 17:56:09 INFO - 'download_symbols': 'ondemand', 17:56:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:56:09 INFO - 'tooltool.py': '/tools/tooltool.py', 17:56:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:56:09 INFO - '/tools/misc-python/virtualenv.py')}, 17:56:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:56:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:56:09 INFO - 'gecko_profile': False, 17:56:09 INFO - 'gecko_profile_interval': 0, 17:56:09 INFO - 'installer_path': 'installer.exe', 17:56:09 INFO - 'log_level': 'info', 17:56:09 INFO - 'log_name': 'talos', 17:56:09 INFO - 'log_to_console': True, 17:56:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:56:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:56:09 INFO - 'opt_config_files': (), 17:56:09 INFO - 'pip_index': False, 17:56:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:56:09 INFO - 'cmd': ('bash', 17:56:09 INFO - '-c', 17:56:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:56:09 INFO - 'enabled': True, 17:56:09 INFO - 'halt_on_failure': False, 17:56:09 INFO - 'name': 'check_screen_resolution'},), 17:56:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:56:09 INFO - 'cmd': ('bash', 17:56:09 INFO - '-c', 17:56:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:56:09 INFO - 'enabled': True, 17:56:09 INFO - 'halt_on_failure': False, 17:56:09 INFO - 'name': 'check_screen_resolution'},), 17:56:09 INFO - 'run_cmd_checks_enabled': True, 17:56:09 INFO - 'suite': 'dromaeojs-e10s', 17:56:09 INFO - 'system_bits': '32', 17:56:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:56:09 INFO - 'title': 't-yosemite-r7-0293', 17:56:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:56:09 INFO - 'use_talos_json': True, 17:56:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:56:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:56:09 INFO - 'work_dir': 'build'} 17:56:09 INFO - [mozharness: 2017-06-07 00:56:09.915111Z] Running clobber step. 17:56:09 INFO - Running pre-action listener: _resource_record_pre_action 17:56:09 INFO - Running main action method: clobber 17:56:09 INFO - rmtree: /builds/slave/test/build 17:56:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:56:12 INFO - Running post-action listener: _resource_record_post_action 17:56:12 INFO - [mozharness: 2017-06-07 00:56:12.333987Z] Finished clobber step (success) 17:56:12 INFO - [mozharness: 2017-06-07 00:56:12.334119Z] Running read-buildbot-config step. 17:56:12 INFO - Running pre-action listener: _resource_record_pre_action 17:56:12 INFO - Running main action method: read_buildbot_config 17:56:12 INFO - Using buildbot properties: 17:56:12 INFO - { 17:56:12 INFO - "project": "", 17:56:12 INFO - "product": "firefox", 17:56:12 INFO - "who": "kwierso@gmail.com", 17:56:12 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 17:56:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:56:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:56:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:56:12 INFO - "stage_platform": "macosx64", 17:56:12 INFO - "basedir": "/builds/slave/test", 17:56:12 INFO - "buildnumber": 87, 17:56:12 INFO - "platform": "yosemite_r7", 17:56:12 INFO - "parent_task_id": "FL468XEsSSOafbxknmA7bg", 17:56:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:56:12 INFO - "slavebuilddir": "test", 17:56:12 INFO - "taskId": "Kcd-VBpmRriXWc7HTxnULg", 17:56:12 INFO - "branch": "mozilla-central", 17:56:12 INFO - "script_repo_revision": "production", 17:56:12 INFO - "revision": "fd04166b7114949ce63783e10a069b98d76df573", 17:56:12 INFO - "slavename": "t-yosemite-r7-0293", 17:56:12 INFO - "repo_path": "mozilla-central" 17:56:12 INFO - } 17:56:12 INFO - Finding installer, test and symbols from parent task. 17:56:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kcd-VBpmRriXWc7HTxnULg'}, attempt #1 17:56:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kcd-VBpmRriXWc7HTxnULg'}, attempt #1 17:56:13 INFO - Task dependencies: JhPreKUcTQiCIzbRxJ-8TA FL468XEsSSOafbxknmA7bg 17:56:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg'}, attempt #1 17:56:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:56:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/test_packages.json 17:56:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/target.crashreporter-symbols.zip 17:56:13 INFO - Running post-action listener: _resource_record_post_action 17:56:13 INFO - [mozharness: 2017-06-07 00:56:13.704230Z] Finished read-buildbot-config step (success) 17:56:13 INFO - [mozharness: 2017-06-07 00:56:13.704379Z] Running download-and-extract step. 17:56:13 INFO - Running pre-action listener: _resource_record_pre_action 17:56:13 INFO - Running main action method: download_and_extract 17:56:13 INFO - mkdir: /builds/slave/test/build/tests 17:56: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')]} 17:56:13 INFO - trying https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:56:13 INFO - Downloading https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:56:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:56:14 INFO - Downloaded 1328 bytes. 17:56:14 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:56:14 INFO - Using the following test package requirements: 17:56:14 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:14 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:56:14 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:56:14 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:14 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:56:14 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:14 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:56:14 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:14 INFO - u'jsshell-mac.zip'], 17:56:14 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:14 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:56:14 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:56:14 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:14 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:56:14 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:14 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:56:14 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:14 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 17:56:14 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:56:14 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:56:14 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:56:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:56:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:56:14 INFO - Fetch https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:56:15 INFO - Content-Length response header: 57633794 17:56:15 INFO - Bytes received: 57633794 17:56:34 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:56:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:56:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:56:34 INFO - Fetch https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:56:35 INFO - Content-Length response header: 57633794 17:56:35 INFO - Bytes received: 57633794 17:56:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:56:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:56:53 INFO - Fetch https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:56:54 INFO - Content-Length response header: 14447523 17:56:54 INFO - Bytes received: 14447523 17:56:55 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')]} 17:56:55 INFO - trying https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:56:55 INFO - Downloading https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:56:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:56:57 INFO - Downloaded 67991599 bytes. 17:56:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:56:57 INFO - mkdir: /builds/slave/test/properties 17:56:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:56:57 INFO - Writing to file /builds/slave/test/properties/build_url 17:56:57 INFO - Contents: 17:56:57 INFO - build_url:https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:56:57 INFO - Running post-action listener: _resource_record_post_action 17:56:57 INFO - Running post-action listener: set_extra_try_arguments 17:56:57 INFO - [mozharness: 2017-06-07 00:56:57.233686Z] Finished download-and-extract step (success) 17:56:57 INFO - [mozharness: 2017-06-07 00:56:57.233854Z] Running populate-webroot step. 17:56:57 INFO - Running pre-action listener: _resource_record_pre_action 17:56:57 INFO - Running main action method: populate_webroot 17:56:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:56:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:56:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:56:57 INFO - '--tppagecycles', 17:56:57 INFO - '7'], 17:56:57 INFO - 'tests': ['tsvgm']}}, 17:56:57 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:56:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:56:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:56:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:56:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:56:57 INFO - 'talos_options': ['--disable-e10s'], 17:56:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:56:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:56:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:56:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:56:57 INFO - 'talos_options': ['--disable-e10s'], 17:56:57 INFO - 'tests': ['damp', 'tps']}, 17:56:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:56:57 INFO - 'tests': ['damp', 'tps']}, 17:56:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:56:57 INFO - 'tests': ['dromaeo_dom']}, 17:56:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:56:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:56:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:56:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:56:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:56:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:56:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:56:57 INFO - 'tests': ['a11yr', 17:56:57 INFO - 'ts_paint', 17:56:57 INFO - 'tpaint', 17:56:57 INFO - 'sessionrestore', 17:56:57 INFO - 'sessionrestore_no_auto_restore', 17:56:57 INFO - 'tabpaint']}, 17:56:57 INFO - 'other-e10s': {'tests': ['a11yr', 17:56:57 INFO - 'ts_paint', 17:56:57 INFO - 'tpaint', 17:56:57 INFO - 'sessionrestore', 17:56:57 INFO - 'sessionrestore_no_auto_restore', 17:56:57 INFO - 'tabpaint']}, 17:56:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:56:57 INFO - 'ts_paint', 17:56:57 INFO - 'tpaint', 17:56:57 INFO - 'sessionrestore', 17:56:57 INFO - 'sessionrestore_no_auto_restore', 17:56:57 INFO - 'tabpaint']}, 17:56:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:56:57 INFO - 'ts_paint', 17:56:57 INFO - 'tpaint', 17:56:57 INFO - 'sessionrestore', 17:56:57 INFO - 'sessionrestore_no_auto_restore', 17:56:57 INFO - 'tabpaint']}, 17:56:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:56:57 INFO - 'tests': ['a11yr', 17:56:57 INFO - 'ts_paint', 17:56:57 INFO - 'tpaint', 17:56:57 INFO - 'sessionrestore', 17:56:57 INFO - 'sessionrestore_no_auto_restore', 17:56:57 INFO - 'tabpaint']}, 17:56:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:56:57 INFO - 'tests': ['a11yr', 17:56:57 INFO - 'ts_paint', 17:56:57 INFO - 'tpaint', 17:56:57 INFO - 'sessionrestore', 17:56:57 INFO - 'sessionrestore_no_auto_restore', 17:56:57 INFO - 'tabpaint']}, 17:56:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:56:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:56:57 INFO - 'bloom_basic_ref']}, 17:56:57 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 17:56:57 INFO - 'talos_options': ['--mitmproxy', 17:56:57 INFO - 'mitmproxy-recording-1.mp', 17:56:57 INFO - '--firstNonBlankPaint'], 17:56:57 INFO - 'tests': ['Quantum_1']}, 17:56:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:56:57 INFO - 'tests': ['tsvgx', 17:56:57 INFO - 'tsvgr_opacity', 17:56:57 INFO - 'tart', 17:56:57 INFO - 'tscrollx', 17:56:57 INFO - 'cart', 17:56:57 INFO - 'tsvg_static']}, 17:56:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:56:57 INFO - 'tsvgr_opacity', 17:56:57 INFO - 'tart', 17:56:57 INFO - 'tscrollx', 17:56:57 INFO - 'cart', 17:56:57 INFO - 'tsvg_static']}, 17:56:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:56:57 INFO - 'talos_options': ['--disable-e10s'], 17:56:57 INFO - 'tests': ['tp5o']}, 17:56:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:56:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:56:57 INFO - 'talos_options': ['--disable-e10s', 17:56:57 INFO - '--xperf_path', 17:56:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:56:57 INFO - 'tests': ['tp5n']}, 17:56:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:56:57 INFO - 'talos_options': ['--xperf_path', 17:56:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:56:57 INFO - 'tests': ['tp5n']}}, 17:56:57 INFO - 'talos.zip': {'path': '', 17:56:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:56:57 INFO - Running post-action listener: _resource_record_post_action 17:56:57 INFO - [mozharness: 2017-06-07 00:56:57.256356Z] Finished populate-webroot step (success) 17:56:57 INFO - [mozharness: 2017-06-07 00:56:57.256485Z] Running create-virtualenv step. 17:56:57 INFO - Running pre-action listener: _resource_record_pre_action 17:56:57 INFO - Running main action method: create_virtualenv 17:56:57 INFO - Creating virtualenv /builds/slave/test/build/venv 17:56:57 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 17:56:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:56:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:56:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:56:57 INFO - Using real prefix '/tools/python27' 17:56:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:56:58 INFO - Installing distribute.............................................................................................................................................................................................done. 17:57:00 INFO - Installing pip.................done. 17:57:00 INFO - Return code: 0 17:57:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:57:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:57:01 INFO - Reading from file tmpfile_stdout 17:57:01 INFO - Output received: 17:57:01 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) 17:57:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:57: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')]} 17:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57: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')]} 17:57:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104bf3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9802596680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, '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.qXfrNqoglL/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.EfZiyTVXCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:57: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', 'pip>=1.5'] in /builds/slave/test/build 17:57: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 pip>=1.5 17:57:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:01 INFO - 'HOME': '/Users/cltbld', 17:57:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:01 INFO - 'LOGNAME': 'cltbld', 17:57:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:01 INFO - 'MOZ_NO_REMOTE': '1', 17:57:01 INFO - 'NO_EM_RESTART': '1', 17:57:01 INFO - 'PAGER': '/bin/cat', 17:57:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:01 INFO - 'PWD': '/builds/slave/test', 17:57:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:01 INFO - 'SHELL': '/bin/bash', 17:57:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:01 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:01 INFO - 'USER': 'cltbld', 17:57:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:01 INFO - 'XPC_FLAGS': '0x0', 17:57:01 INFO - 'XPC_SERVICE_NAME': '0', 17:57:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:01 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 17:57:01 INFO - Cleaning up... 17:57:01 INFO - Return code: 0 17:57:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:57: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')]} 17:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57: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')]} 17:57:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57: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', '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 0x104bf3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9802596680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, '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.qXfrNqoglL/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.EfZiyTVXCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:57: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', 'psutil>=3.1.1'] in /builds/slave/test/build 17:57: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 psutil>=3.1.1 17:57:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:01 INFO - 'HOME': '/Users/cltbld', 17:57:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:01 INFO - 'LOGNAME': 'cltbld', 17:57:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:01 INFO - 'MOZ_NO_REMOTE': '1', 17:57:01 INFO - 'NO_EM_RESTART': '1', 17:57:01 INFO - 'PAGER': '/bin/cat', 17:57:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:01 INFO - 'PWD': '/builds/slave/test', 17:57:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:01 INFO - 'SHELL': '/bin/bash', 17:57:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:01 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:01 INFO - 'USER': 'cltbld', 17:57:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:01 INFO - 'XPC_FLAGS': '0x0', 17:57:01 INFO - 'XPC_SERVICE_NAME': '0', 17:57:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:01 INFO - Downloading/unpacking psutil>=3.1.1 17:57: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 17:57: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 17:57: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 17:57: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 17:57:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:57:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:57:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:57:04 INFO - Installing collected packages: psutil 17:57:04 INFO - Running setup.py install for psutil 17:57:04 INFO - building 'psutil._psutil_osx' extension 17:57:04 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 17:57:04 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 17:57:04 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 17:57:04 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 17:57:04 INFO - building 'psutil._psutil_posix' extension 17:57:04 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 17:57:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:57:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:57:05 INFO - ^ 17:57:05 INFO - 1 warning generated. 17:57:05 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 17:57:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:57:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:57:05 INFO - Successfully installed psutil 17:57:05 INFO - Cleaning up... 17:57:05 INFO - Return code: 0 17:57:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:57:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:05 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 0x104bf3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9802596680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, '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.qXfrNqoglL/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.EfZiyTVXCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:57:05 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 17:57:05 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 17:57:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:05 INFO - 'HOME': '/Users/cltbld', 17:57:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:05 INFO - 'LOGNAME': 'cltbld', 17:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:05 INFO - 'MOZ_NO_REMOTE': '1', 17:57:05 INFO - 'NO_EM_RESTART': '1', 17:57:05 INFO - 'PAGER': '/bin/cat', 17:57:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:05 INFO - 'PWD': '/builds/slave/test', 17:57:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:05 INFO - 'SHELL': '/bin/bash', 17:57:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:05 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:05 INFO - 'USER': 'cltbld', 17:57:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:05 INFO - 'XPC_FLAGS': '0x0', 17:57:05 INFO - 'XPC_SERVICE_NAME': '0', 17:57:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:57: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 17:57: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 17:57: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 17:57: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 17:57:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:57:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:57:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:57:07 INFO - Installing collected packages: mozsystemmonitor 17:57:07 INFO - Running setup.py install for mozsystemmonitor 17:57:07 INFO - Successfully installed mozsystemmonitor 17:57:07 INFO - Cleaning up... 17:57:07 INFO - Return code: 0 17:57:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:57: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')]} 17:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57: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')]} 17:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:07 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 0x104bf3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9802596680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, '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.qXfrNqoglL/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.EfZiyTVXCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:57:07 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 17:57:07 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 17:57:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:07 INFO - 'HOME': '/Users/cltbld', 17:57:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:07 INFO - 'LOGNAME': 'cltbld', 17:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:07 INFO - 'MOZ_NO_REMOTE': '1', 17:57:07 INFO - 'NO_EM_RESTART': '1', 17:57:07 INFO - 'PAGER': '/bin/cat', 17:57:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:07 INFO - 'PWD': '/builds/slave/test', 17:57:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:07 INFO - 'SHELL': '/bin/bash', 17:57:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:07 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:07 INFO - 'USER': 'cltbld', 17:57:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:07 INFO - 'XPC_FLAGS': '0x0', 17:57:07 INFO - 'XPC_SERVICE_NAME': '0', 17:57:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:07 INFO - Downloading/unpacking jsonschema==2.5.1 17:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:57:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:09 INFO - Downloading functools32-3.2.3-2.tar.gz 17:57:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:57:09 INFO - warning: no files found matching '*.txt' 17:57:09 INFO - no previously-included directories found matching 'build' 17:57:09 INFO - no previously-included directories found matching 'dist' 17:57:09 INFO - no previously-included directories found matching '.git*' 17:57:09 INFO - Installing collected packages: jsonschema, functools32 17:57:09 INFO - Running setup.py install for functools32 17:57:09 INFO - warning: no files found matching '*.txt' 17:57:09 INFO - no previously-included directories found matching 'build' 17:57:09 INFO - no previously-included directories found matching 'dist' 17:57:09 INFO - no previously-included directories found matching '.git*' 17:57:09 INFO - Successfully installed jsonschema functools32 17:57:09 INFO - Cleaning up... 17:57:09 INFO - Return code: 0 17:57:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:57:09 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')]} 17:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:09 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')]} 17:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:09 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 0x104bf3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9802596680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, '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.qXfrNqoglL/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.EfZiyTVXCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:57:09 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 17:57:09 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 17:57:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:09 INFO - 'HOME': '/Users/cltbld', 17:57:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:09 INFO - 'LOGNAME': 'cltbld', 17:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:09 INFO - 'MOZ_NO_REMOTE': '1', 17:57:09 INFO - 'NO_EM_RESTART': '1', 17:57:09 INFO - 'PAGER': '/bin/cat', 17:57:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:09 INFO - 'PWD': '/builds/slave/test', 17:57:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:09 INFO - 'SHELL': '/bin/bash', 17:57:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:09 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:09 INFO - 'USER': 'cltbld', 17:57:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:09 INFO - 'XPC_FLAGS': '0x0', 17:57:09 INFO - 'XPC_SERVICE_NAME': '0', 17:57:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:57:09 INFO - Cleaning up... 17:57:09 INFO - Return code: 0 17:57:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:57:09 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')]} 17:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:09 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')]} 17:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:09 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 0x104bf3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9802596680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, '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.qXfrNqoglL/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.EfZiyTVXCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:57:09 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 17:57:09 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 17:57:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:09 INFO - 'HOME': '/Users/cltbld', 17:57:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:09 INFO - 'LOGNAME': 'cltbld', 17:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:09 INFO - 'MOZ_NO_REMOTE': '1', 17:57:09 INFO - 'NO_EM_RESTART': '1', 17:57:09 INFO - 'PAGER': '/bin/cat', 17:57:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:09 INFO - 'PWD': '/builds/slave/test', 17:57:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:09 INFO - 'SHELL': '/bin/bash', 17:57:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:09 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:09 INFO - 'USER': 'cltbld', 17:57:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:09 INFO - 'XPC_FLAGS': '0x0', 17:57:09 INFO - 'XPC_SERVICE_NAME': '0', 17:57:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:10 INFO - Downloading/unpacking blobuploader==1.2.4 17:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:11 INFO - Downloading blobuploader-1.2.4.tar.gz 17:57:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:57:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:57:11 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 17:57:11 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 17:57:11 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 17:57:11 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 17:57:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:57:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:12 INFO - Downloading docopt-0.6.1.tar.gz 17:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:57:12 INFO - Installing collected packages: blobuploader, requests, docopt 17:57:12 INFO - Running setup.py install for blobuploader 17:57:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:57:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:57:12 INFO - Running setup.py install for requests 17:57:13 INFO - Running setup.py install for docopt 17:57:13 INFO - Successfully installed blobuploader requests docopt 17:57:13 INFO - Cleaning up... 17:57:13 INFO - Return code: 0 17:57:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57: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')]} 17:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57: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')]} 17:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:13 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 0x104bf3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9802596680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, '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.qXfrNqoglL/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.EfZiyTVXCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:57:13 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 17:57:13 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 17:57:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:13 INFO - 'HOME': '/Users/cltbld', 17:57:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:13 INFO - 'LOGNAME': 'cltbld', 17:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:13 INFO - 'MOZ_NO_REMOTE': '1', 17:57:13 INFO - 'NO_EM_RESTART': '1', 17:57:13 INFO - 'PAGER': '/bin/cat', 17:57:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:13 INFO - 'PWD': '/builds/slave/test', 17:57:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:13 INFO - 'SHELL': '/bin/bash', 17:57:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:13 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:13 INFO - 'USER': 'cltbld', 17:57:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:13 INFO - 'XPC_FLAGS': '0x0', 17:57:13 INFO - 'XPC_SERVICE_NAME': '0', 17:57:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:57:13 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ZMh_Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:57:13 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-OHQzNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:57:13 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-Iyeo_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:57:13 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ZDFWto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:57:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:57:13 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-DAHNTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:57:14 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-UP5FWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:57:14 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-v_wZzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:57:14 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ejGgTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:57:14 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-PMvFMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:57:14 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-0nvFtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:57:14 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-gds_io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:57:14 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-bJC2FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:57:14 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-mXZz7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:57:14 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-IDexsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:57:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:14 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-O8_90k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:57:15 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-BBGpCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:57:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:57:15 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-EvNkRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:57:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:57:15 INFO - Running setup.py install for manifestparser 17:57:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:57:15 INFO - Running setup.py install for mozcrash 17:57:15 INFO - Running setup.py install for mozdebug 17:57:15 INFO - Running setup.py install for mozdevice 17:57:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:57:15 INFO - Running setup.py install for mozfile 17:57:15 INFO - Running setup.py install for mozhttpd 17:57:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:57:16 INFO - Running setup.py install for mozinfo 17:57:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:57:16 INFO - Running setup.py install for mozInstall 17:57:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:57:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:57:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:57:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:57:16 INFO - Running setup.py install for mozleak 17:57:16 INFO - Running setup.py install for mozlog 17:57:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:57:16 INFO - Running setup.py install for moznetwork 17:57:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:57:16 INFO - Running setup.py install for mozprocess 17:57:17 INFO - Running setup.py install for mozprofile 17:57:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:57:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:57:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:57:17 INFO - Running setup.py install for mozrunner 17:57:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:57:17 INFO - Running setup.py install for mozscreenshot 17:57:17 INFO - Running setup.py install for moztest 17:57:17 INFO - Running setup.py install for mozversion 17:57:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:57:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:57:17 INFO - Cleaning up... 17:57:17 INFO - Return code: 0 17:57:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57:17 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')]} 17:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:17 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')]} 17:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:17 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 0x104bf3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9802596680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, '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.qXfrNqoglL/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.EfZiyTVXCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:57:17 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 17:57:17 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 17:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:17 INFO - 'HOME': '/Users/cltbld', 17:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:17 INFO - 'LOGNAME': 'cltbld', 17:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:17 INFO - 'MOZ_NO_REMOTE': '1', 17:57:17 INFO - 'NO_EM_RESTART': '1', 17:57:17 INFO - 'PAGER': '/bin/cat', 17:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:17 INFO - 'PWD': '/builds/slave/test', 17:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:17 INFO - 'SHELL': '/bin/bash', 17:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:17 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:17 INFO - 'USER': 'cltbld', 17:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:17 INFO - 'XPC_FLAGS': '0x0', 17:57:17 INFO - 'XPC_SERVICE_NAME': '0', 17:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:57:18 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-m62ExI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:57:18 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)) 17:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:57:18 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-ip1st0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:57:18 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)) 17:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:57:18 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-xuGPvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:57:18 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)) 17:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:57:18 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-gU7uxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:57:18 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-STOQUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:57:18 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)) 17:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:57:18 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-aX3Qxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:57:18 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)) 17:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:57:18 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-8pTUjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:57:18 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-lZGjhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:57:18 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)) 17:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:57:18 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-iliL3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:57:18 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)) 17:57:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:57:18 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-VlDXuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:57:19 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)) 17:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:57:19 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-eHChpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:57:19 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)) 17:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:57:19 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-54v6ML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:57:19 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)) 17:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:57:19 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-rJO_ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:57:19 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)) 17:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:57:19 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-5yPreS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:57:19 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)) 17:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:19 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-2dOuXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:19 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)) 17:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:57:19 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-iAz_Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:57:19 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)) 17:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:57:19 INFO - Running setup.py (path:/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/pip-DboWnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:57:19 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)) 17:57:19 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)) 17:57:19 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)) 17:57:19 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)) 17:57:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:57:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:57:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:57:19 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 17:57:19 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 17:57:19 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 17:57:19 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 17:57:21 INFO - Downloading blessings-1.6.tar.gz 17:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:57:21 INFO - Installing collected packages: blessings 17:57:21 INFO - Running setup.py install for blessings 17:57:21 INFO - Successfully installed blessings 17:57:21 INFO - Cleaning up... 17:57:21 INFO - Return code: 0 17:57:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:57:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:57:21 INFO - Reading from file tmpfile_stdout 17:57:21 INFO - Current package versions: 17:57:21 INFO - blessings == 1.6 17:57:21 INFO - blobuploader == 1.2.4 17:57:21 INFO - docopt == 0.6.1 17:57:21 INFO - functools32 == 3.2.3-2 17:57:21 INFO - jsonschema == 2.5.1 17:57:21 INFO - manifestparser == 1.1 17:57:21 INFO - mozInstall == 1.12 17:57:21 INFO - mozcrash == 1.0 17:57:21 INFO - mozdebug == 0.1 17:57:21 INFO - mozdevice == 0.51 17:57:21 INFO - mozfile == 1.2 17:57:21 INFO - mozhttpd == 0.7 17:57:21 INFO - mozinfo == 0.10 17:57:21 INFO - mozleak == 0.1 17:57:21 INFO - mozlog == 3.4 17:57:21 INFO - moznetwork == 0.27 17:57:21 INFO - mozprocess == 0.25 17:57:21 INFO - mozprofile == 0.28 17:57:21 INFO - mozrunner == 6.13 17:57:21 INFO - mozscreenshot == 0.1 17:57:21 INFO - mozsystemmonitor == 0.3 17:57:21 INFO - moztest == 0.8 17:57:21 INFO - mozversion == 1.4 17:57:21 INFO - psutil == 3.1.1 17:57:21 INFO - requests == 1.2.3 17:57:21 INFO - wsgiref == 0.1.2 17:57:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57:21 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')]} 17:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:21 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')]} 17:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:21 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 0x104bf3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9802596680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, '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.qXfrNqoglL/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.EfZiyTVXCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:57:21 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 17:57:21 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 17:57:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:21 INFO - 'HOME': '/Users/cltbld', 17:57:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:21 INFO - 'LOGNAME': 'cltbld', 17:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:21 INFO - 'MOZ_NO_REMOTE': '1', 17:57:21 INFO - 'NO_EM_RESTART': '1', 17:57:21 INFO - 'PAGER': '/bin/cat', 17:57:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:21 INFO - 'PWD': '/builds/slave/test', 17:57:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:21 INFO - 'SHELL': '/bin/bash', 17:57:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:21 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:21 INFO - 'USER': 'cltbld', 17:57:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:21 INFO - 'XPC_FLAGS': '0x0', 17:57:21 INFO - 'XPC_SERVICE_NAME': '0', 17:57:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:22 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)) 17:57:22 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)) 17:57:22 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)) 17:57:22 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)) 17:57:22 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)) 17:57:22 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)) 17:57:22 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)) 17:57:22 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)) 17:57:22 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)) 17:57:22 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:57:22 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 17:57:22 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 17:57:22 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 17:57:22 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 17:57:23 INFO - Downloading marionette_driver-1.4.0.tar.gz 17:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 17:57:23 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)) 17:57:23 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)) 17:57:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:57:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:57:23 INFO - Installing collected packages: marionette-driver 17:57:23 INFO - Running setup.py install for marionette-driver 17:57:23 INFO - Successfully installed marionette-driver 17:57:23 INFO - Cleaning up... 17:57:23 INFO - Return code: 0 17:57:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:57:23 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')]} 17:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:23 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')]} 17:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x104bf3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104bf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9802596680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, '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.qXfrNqoglL/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.EfZiyTVXCu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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 17:57:23 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 17:57:23 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 17:57:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:23 INFO - 'HOME': '/Users/cltbld', 17:57:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:23 INFO - 'LOGNAME': 'cltbld', 17:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:23 INFO - 'MOZ_NO_REMOTE': '1', 17:57:23 INFO - 'NO_EM_RESTART': '1', 17:57:23 INFO - 'PAGER': '/bin/cat', 17:57:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:23 INFO - 'PWD': '/builds/slave/test', 17:57:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:23 INFO - 'SHELL': '/bin/bash', 17:57:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:23 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:23 INFO - 'USER': 'cltbld', 17:57:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:23 INFO - 'XPC_FLAGS': '0x0', 17:57:23 INFO - 'XPC_SERVICE_NAME': '0', 17:57:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:57:24 INFO - Cleaning up... 17:57:24 INFO - Return code: 0 17:57:24 INFO - Running post-action listener: _resource_record_post_action 17:57:24 INFO - Running post-action listener: _start_resource_monitoring 17:57:24 INFO - Starting resource monitoring. 17:57:24 INFO - [mozharness: 2017-06-07 00:57:24.168586Z] Finished create-virtualenv step (success) 17:57:24 INFO - [mozharness: 2017-06-07 00:57:24.169577Z] Running install step. 17:57:24 INFO - Running pre-action listener: _resource_record_pre_action 17:57:24 INFO - Running main action method: install 17:57:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:57:24 INFO - Reading from file tmpfile_stdout 17:57:24 INFO - Detecting whether we're running mozinstall >=1.0... 17:57:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:57:24 INFO - Reading from file tmpfile_stdout 17:57:24 INFO - Output received: 17:57:24 INFO - Usage: mozinstall [options] installer 17:57:24 INFO - Options: 17:57:24 INFO - -h, --help show this help message and exit 17:57:24 INFO - -d DEST, --destination=DEST 17:57:24 INFO - Directory to install application into. [default: 17:57:24 INFO - "/builds/slave/test"] 17:57:24 INFO - --app=APP Application being installed. [default: firefox] 17:57:24 INFO - mkdir: /builds/slave/test/build/application 17:57:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:57:45 INFO - Reading from file tmpfile_stdout 17:57:45 INFO - Output received: 17:57:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:57:45 INFO - Running post-action listener: _resource_record_post_action 17:57:45 INFO - [mozharness: 2017-06-07 00:57:45.972446Z] Finished install step (success) 17:57:45 INFO - [mozharness: 2017-06-07 00:57:45.972548Z] Running setup-mitmproxy step. 17:57:45 INFO - Running pre-action listener: _resource_record_pre_action 17:57:45 INFO - Running main action method: setup_mitmproxy 17:57:45 INFO - Skipping: mitmproxy is not required 17:57:45 INFO - Running post-action listener: _resource_record_post_action 17:57:45 INFO - [mozharness: 2017-06-07 00:57:45.973289Z] Finished setup-mitmproxy step (success) 17:57:45 INFO - [mozharness: 2017-06-07 00:57:45.973472Z] Running run-tests step. 17:57:45 INFO - Running pre-action listener: _resource_record_pre_action 17:57:45 INFO - Running main action method: run_tests 17:57:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:57:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:57:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:57:45 INFO - 2017-06-06 17:57:45.988 screenresolution[1633:8169] starting screenresolution argv=screenresolution get 17:57:45 INFO - 2017-06-06 17:57:45.990 screenresolution[1633:8169] Display 0: 1600x1200x32@60 17:57:45 INFO - 2017-06-06 17:57:45.996 screenresolution[1634:8172] starting screenresolution argv=screenresolution list 17:57:45 INFO - Available Modes on Display 0 17:57:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:57:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:57:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:57:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:57:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:57:46 INFO - Intel Iris: 17:57:46 INFO - Chipset Model: Intel Iris 17:57:46 INFO - Type: GPU 17:57:46 INFO - Bus: Built-In 17:57:46 INFO - VRAM (Dynamic, Max): 1536 MB 17:57:46 INFO - Vendor: Intel (0x8086) 17:57:46 INFO - Device ID: 0x0a2e 17:57:46 INFO - Revision ID: 0x0009 17:57:46 INFO - Displays: 17:57:46 INFO - Display: 17:57:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:57:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:57:46 INFO - Display Serial Number: 1600x1200 60 17:57:46 INFO - Main Display: Yes 17:57:46 INFO - Mirror: Off 17:57:46 INFO - Online: Yes 17:57:46 INFO - Rotation: Supported 17:57:46 INFO - Return code: 0 17:57:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:57:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:57:46 INFO - Python 2.7.3 17:57:46 INFO - Return code: 0 17:57:46 INFO - grabbing minidump binary from tooltool 17:57:46 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')]} 17:57:46 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 0x104949d30>, '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 0x104913cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10493abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:57:46 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 17:57:46 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 17:57:46 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 17:57:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:57:46 INFO - Return code: 0 17:57:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:57:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:57:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:57:46 INFO - ENV: RUST_BACKTRACE is now 1 17:57:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:57:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:57:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--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 17:57:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0293 --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 17:57:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qXfrNqoglL/Render', 17:57:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:57:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:57:46 INFO - 'HOME': '/Users/cltbld', 17:57:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:57:46 INFO - 'LOGNAME': 'cltbld', 17:57:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:57:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:46 INFO - 'MOZ_NO_REMOTE': '1', 17:57:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:57:46 INFO - 'NO_EM_RESTART': '1', 17:57:46 INFO - 'PAGER': '/bin/cat', 17:57:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:46 INFO - 'PWD': '/builds/slave/test', 17:57:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:57:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:57:46 INFO - 'RUST_BACKTRACE': '1', 17:57:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:57:46 INFO - 'SHELL': '/bin/bash', 17:57:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EfZiyTVXCu/Listeners', 17:57:46 INFO - 'TMPDIR': '/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/', 17:57:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:57:46 INFO - 'USER': 'cltbld', 17:57:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:57:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:46 INFO - 'XPC_FLAGS': '0x0', 17:57:46 INFO - 'XPC_SERVICE_NAME': '0', 17:57:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:57:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0293', '--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 17:57:46 INFO - mozversion application_buildid: 20170606162154 17:57:46 INFO - mozversion application_changeset: fd04166b7114949ce63783e10a069b98d76df573 17:57:46 INFO - mozversion application_display_name: Nightly 17:57:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:57:46 INFO - mozversion application_name: Firefox 17:57:46 INFO - mozversion application_remotingname: firefox 17:57:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:57:46 INFO - mozversion application_vendor: Mozilla 17:57:46 INFO - mozversion application_version: 55.0a1 17:57:46 INFO - mozversion platform_buildid: 20170606162154 17:57:46 INFO - mozversion platform_changeset: fd04166b7114949ce63783e10a069b98d76df573 17:57:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:57:46 INFO - mozversion platform_version: 55.0a1 17:57:46 INFO - using testdate: 1496797066 17:57:46 INFO - actual date: 1496797066 17:57:46 INFO - starting webserver on 'localhost:49229' 17:57:46 INFO - SUITE-START | Running 2 tests 17:57:46 INFO - TEST-START | dromaeo_css 17:57:46 INFO - operating with platform_type : mac_ 17:57:46 INFO - Initialising browser for dromaeo_css test... 17:57:46 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpDxd61R/profile 17:57:46 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 17:58:20 INFO - TEST-INFO | 1644: killed by SIGTERM 17:58:20 INFO - Browser initialized. 17:58:20 INFO - Running cycle 1/1 for dromaeo_css test... 17:58:20 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmpDxd61R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:58:22 INFO - PID 1652 | 2017-06-06 17:58:22.177 plugin-container[1653:8664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 17:58:22 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:58:22 INFO - PID 1652 | 2017-06-06 17:58:22.178 plugin-container[1653:8664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa003, name = 'com.apple.CFPasteboardClient' 17:58:22 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:58:22 INFO - PID 1652 | 2017-06-06 17:58:22.178 plugin-container[1653:8664] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:58:22 INFO - PID 1652 | 2017-06-06 17:58:22.872 plugin-container[1654:8712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 17:58:22 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:58:22 INFO - PID 1652 | 2017-06-06 17:58:22.872 plugin-container[1654:8712] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.CFPasteboardClient' 17:58:22 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:58:22 INFO - PID 1652 | 2017-06-06 17:58:22.872 plugin-container[1654:8712] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:58:32 INFO - PID 1652 | RSS: Main: 260034560 17:58:32 INFO - PID 1652 | 18:01:34 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 18:01:35 INFO - PID 1652 | RSS: Main: 267669504 18:01:35 INFO - PID 1652 | 18:04:37 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 18:04:38 INFO - PID 1652 | RSS: Main: 282533888 18:04:38 INFO - PID 1652 | 18:07:40 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 18:07:40 INFO - PID 1652 | RSS: Main: 282648576 18:07:40 INFO - PID 1652 | 18:10:42 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 18:10:43 INFO - PID 1652 | RSS: Main: 286388224 18:10:43 INFO - PID 1652 | 18:13:45 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 18:13:46 INFO - PID 1652 | RSS: Main: 286478336 18:13:46 INFO - PID 1652 | 18:16:48 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 18:16:48 INFO - PID 1652 | RSS: Main: 288010240 18:16:48 INFO - PID 1652 | 18:16:48 INFO - PID 1652 | __start_tp_report 18:16:48 INFO - PID 1652 | _x_x_mozilla_page_load 18:16:48 INFO - PID 1652 | _x_x_mozilla_page_load_details 18:16:48 INFO - PID 1652 | |i|pagename|runs| 18:16:48 INFO - PID 1652 | |0;dojo.html;3480.91297717557;3584.1577229351096;3584.3727347714143;3614.7831130132195;3618.6924111450526;5736.282964629427;6100.542459315547;6124.601900876445;6163.722632481538;6219.253689557259;6139.15484235078;6150.538709596787;6153.569250152491;6182.598131121474;6192.907106393408;4965.808206030545;5371.3822910365225;5438.293021907147;5480.945190548104;5524.226608274845;5031.2453132030305;5297.920531192035;5477.726113694319;5525.778968841261;5555.111182210847;4440.755758433284;5328.760205790749;5495.780168793243;5568.582356323272;5596.888062238773;5354.170103633943;5418.214358917974;5437.891242175135;5485.753141108659;5513.365962914236;5417.187421886749;5418.241446197536;5419.458054194549;5441.5102640762125;5472.452754724496;6064.272287325505;6162.137300777895;6180.5982611130585;6187.597806142587;6213.192285002966;5545.556355491551;6411.070394792776;6500.252470965852;6597;6654.600723956578;5657.519110875581;6755.088063111466;6764.323567643246;6764.560303580301;6771.4582833373215;6043.667598282049;6275.24697036354;6279.466245369149;6288.496920246369;6296.087067375296;5623.578231632644;5708.771649134072;6650.068990341356;6758.42553382968;6883.862322753517;6333;6560.475161987029;6572.244191917991;6603;6623.20521537409;6101.932161871655;6469.547131700736;6473.126127972663;6651.434628056572;6757.358050985148;5572.777088916479;6670.533062685662;6677.165354330708;6680.298568650222;6686.83282917931;6420.390062944013;6516.706748196342;6533.901991470132;6641.269460359356;6717.093192419059;5286.3392075990505;5362.168863826113;5369.167778994262;5383.488568585978;5392.137258038773;4727.527247275245;4742.099001189763;4745.501722319176;4747.596454301354;4791.377120974251;6175.475084617768;6178.69106544671;6231.626102433868;6333.588316759396;6338.873222535523;6676.365745254193;6726.091977583062;6748.4938629602975;6787.898181527281;6799.626020568916;6287.622742635456;6305.747770089145;6311.621302721851;6324.557280990379;6329.968350158219;5763.538916886639;5897;5939.82180534576;5965.105234214902;5992.041273396236;6763.154605674291;6794.388505034472;6822.147231596051;6913.239543650094;6918.06606108189;6801.761938332134;6811.216710078206;6814.795556133324;6824.7952561423235;6825.931740682529;5239.711815850165;6076.72654730547;6839.863202736017;6914.5159838812815;6930.826729331807;6365.490760739037;6453.677316134268;6537.901931471127;6559.917613593703;6659.933400666125;6728;6794.388505034571;6806.387425131762;6825.795226143224;6837.62393068386;6327;6400.39196276353;6555.2133743951035;6585;6616.272210056986;6433.5496515243485;6501.772437964649;6508.381703738138;6519.869602607826;6530.118434011348;5816.621919575214;6429.935700642934;6440.130582371507;6447.6453795041725;6448.258450278278;6333.36666333363;6582.703778329891;6583.078369028436;6583.111279977142;6610.471162306908;6291.528135389773;6333;6426.971684530453;6462.806115816533;6470.06184103293 18:16:48 INFO - PID 1652 | |1;ext.html;739.6634531288263;791.9168487308832;850.1625898489988;1248.5567623493664;1322.861099584543;38441;39669.00827479307;39709.40435893464;39726.60273397265;39766.80116599416;39374.8031259844;39740.80129599348;39780;39797;39895.800520997356;7847.764567062997;8008.399370047241;8284.337253019745;8476.279516241097;8512.1487851215;8409.621567029499;8532.87200691987;8538.743837684879;8540.060593334729;8561.871571926426;8492.872606910902;8497.575121243914;8499.787505312355;8562.14378562145;8575.614097365633;8477.74566762998;8487.618057187441;8536.359773017048;8552.828943421127;8557.529335886524;8313.33493320533;8380.958095209546;8382.413231073828;8389.748307550783;8398.244158025745;37856.86429407122;37967.24065518674;37999;38030.80984595087;38117;3606.026372879308;3613.295407395559;3639.5268615080136;3654.0682126057686;3654.4152935530187;27883.302917427227;28052.859735701393;30763;31041.534376984364;31100.22249443782;2817.887284508617;2869.3113652723405;2877.2375320540073;2885.6970018148004;2892.8119672221237;200553;228841;288572.9999999979;309344.99999999773;309944;38004.809975949946;48013;48079.75960120247;48091.5190848087;48186.27720584194;51554.48445515572;51581.71045723887;51672.741636291576;51745.48254517407;51750.74124629428;13751.106178098391;13877.583672489842;13954.790678139836;13983.650408739659;14027.579172624837;11234;11297.548098075986;11346.773064538827;11353.388720594145;11359.432028398713;14018.369173387222;14039.719205615831;14070.2964851757;14083;14184.503542375967;5307.52232299095;6233.438990490878;6272.905906411407;6315.810525684236;6333.429991300805;914.2000749344323;960.3709570231509;972.6498460554195;1013.5540362240738;1057.9894201058046;22936.655950160763;24943.126990555607;25011;25270;25313.62029569558;1069.967481380456;1102.7739313440725;1127.8533790607332;1149.230015889356;1152.9711757205969;11868.82196767032;12964;12970.351482425842;12980.480780768852;12981.091323607447;326711;327171;328760;329015.1943162563;329678;45663.54336456659;56015;56021.43978560244;56276.31171064875;56298.15552766712;1308.738252349534;1365.2491129878526;1475.8007668964767;1531.257340190007;1539.3380846236225;20575;22753.658695119917;23108.653370199125;24517.38706532351;24712.011519539017;27536.724632753416;36088.27823443464;36533.817330913174;38570.6142938567;41151.38272925848;206624;253586;256074;256655;257668.71165644802;34755.95732127939;35736.642633573334;35954;36207.913762587166;36360.818195908854;37787;44476.55523444724;46836.59490215299;46871;47031.88356523896;26444.074457393897;31393.372132557677;31441.685583143877;31900.361992760478;31949.840250798596;30136.547951780303;30241.546376803926;31549.526757098203;31648.89228876978;32318;38574.61425385823;38762;38825.805870970464;38932.41601375925;39058.80470597629;30293;30749.846250769497;30839.537406938463;31815.363692726478;32459;24292.505886410363;24593.6984223749;24853.627195592442;25090.49819003573;25458 18:16:48 INFO - PID 1652 | |2;jquery.html;437.7832972678523;439.11080062872696;446.90838378132474;453.81393628612255;454.1122106282219;22236;23154.884225578848;23199.768002319994;23232.41918952027;24153;23650.76349236507;24126;24133.879330603366;24223.39441513963;24251.39371515713;6102.0541816018585;6258.687065646723;6268.874622507547;6282.748690052393;6291;5555.333359996806;5952.7499225162765;6066.211392518966;6206.627602343874;6266.342034086424;6027.819165425044;6095.725692343833;6154.907676384858;6162.691865406712;6179.443850053494;6118.63288202707;6141.785037523688;6150.354212807635;6153.969230153865;6158.507319414436;4648.790804413809;5475.09660905954;5479.945200547983;5486.259354987065;5491.093969495028;29436;29767.404651907058;29814.403711925857;29865.850670746506;30218.244543886358;2216.1024784962115;2311.595470792621;2312.7455979842202;2321.9651705224437;2324.639680849471;20358.28745993883;20455.97720113988;20654.07056682438;20708.275210367567;20729.481762955897;2313.1556981701724;2320.3734991552187;2363.1374548289923;2366.8461549999365;2403.831731778776;174004.12997935055;180308;199322;203659;204763.97618011816;25923.351916202246;26038;26560.468790624465;27902.581461277692;28191;45387;45444.772776135906;45445.318320225226;45475;45525.54474455213;11190.832137517944;11323.15076369259;11366.943165284287;11382.487788049557;11399.373034483184;8942.061083586259;8956.656501524692;8980.281577473786;9025;9044.457332560067;11008.724781880357;12038;12509.49962001528;12549.623511294676;12648.494060237672;4086.9386959195635;5028.220625803006;5040.84877453677;5047.671901326402;5054.9241761373605;860.5052095045374;922.7220299884574;947.8767760191269;971.9028097190225;1001.5192707500295;19644.90177549103;21962.780372196074;22126.336209913727;22752.431189220402;22841.543169136523;858.5406807358033;869.2915274051628;966.4925913895121;1000.1598657128047;1013.8124446977333;9166.724998250063;10395.024945260377;11139.888601114048;11174.88251174876;11281.548738050551;551799;554394;555546;575939;583010;153965;157961.2101939483;161362;161703;162271;1005.2460654509118;1049.8267785815408;1065.9946700266448;1173.266708307308;1233.0382301804607;22398.888005559867;23879.044838206974;23909;23923.33494968534;23927.401814954766;69771.65114174396;69888.95166572607;71544.64227678828;71872.64063679648;72016;92890.53554732184;93714;94455;94816.05183948348;95574.04425955651;10265.435401053013;10510.684679459628;10511.211659125749;10532.57869685204;10567.682969510926;17424.651506969534;18617.348392806187;18824.341148059753;18852.49180065619;18855.434336969913;15517.379304828131;15549.84450155484;15598.986065906134;15718.842811571738;15749.527514174593;13702.314884255948;13988.37052332668;14042.719145616826;14056.578302650936;14160.858391416366;15506.147161906203;15643.608909777347;15647.608809779847;15771.211439428213;15777.68444631124;10359.585616575252;10362.067413932782;10449.164066874784;10452;10479.633212837514;14983.101013939198;15084.396624134912;15268.770968435705;15293;15314.004589701633 18:16:48 INFO - PID 1652 | |3;mootools.html;1332.2273081612666;1435.1460880775935;1454.7236025155225;1461.5615315405382;1470.610288273607;11726;12121.454534545945;12221.388930553481;12267.895889369966;12466.252024878513;12026.639200823967;12057.095717821177;12070.094742894296;12183.57357492488;12188.51245950161;2529.557327467695;2537.13737329308;2579.85810780407;2582.6513420688157;2583.0959164292535;2147.44166516706;2559.6800399950007;2565.576679847823;2565.9743402565923;2577.162422212779;2508.097085049381;2526.6589010483535;2539.3016920346868;2543.389586499236;2544.160427059075;2470.234227389506;2483.1184929350093;2494.5759220932487;2497.1884137655243;2500.8124390670773;1791.6506281275156;2041.6427125253022;2469.5060987802512;2479.194261864883;2520.180941194101;11867.940660296643;11869.643910682693;11927.821082683766;11970.162088653798;11971.820422693667;6439.162908821823;6680.7327706891665;6698.765543205965;6705.899411508832;6709.4296984755865;3630.49173115764;4010.3382941814566;4197.685173611992;4266.679999000087;4281.079567892918;1231.648980040693;1232.1375037473715;1235.1292338901053;1253.4422182128956;1255.9937200314032;15317.387304507807;15718.13550254724;16698;16789.160541972975;16833;5547.861303467364;5571.4150014248735;5779.913301300484;5785.88428231433;5800.332961709373;12462.626121216377;12472.251664899954;12485.750284994248;12492.12555121151;12510.437030333656;6478.838029049311;6533.934660653427;6558.737650493927;6560.212774467094;6595.274519802818;6027.758889644365;6123.601965872204;6140.938590614126;6175.073738939195;6177.505799536027;4387.890302742457;4417.006173610912;4437.689361744711;4460.910781784346;4525.592696657317;1271.4405661508906;1289.9032572557096;1294.8640392758625;1304.9412776425083;1309.463120120746;791.6991543213658;819.3240576524391;825.591332290508;833.913129907559;836.615157027774;6370.649614271167;6796.150481189851;7291.906214067826;7428.33145016951;7595.012648355789;954.2413781044087;959.7666997907655;976.9741771140416;995.5520015992832;996.940183588987;4672.392588963413;4691.272852707836;4857.781399837016;4890.657653964259;4984.102861484895;14530.709385812224;14994.775078373834;15088.54734357971;15276.847231527765;15436.228188590525;12293.692657683629;14919.179445130405;15093.320800564;15307.387704491919;15494.767578486331;8549.27331176857;8588.570571471402;8696.78258043554;8713.651453941771;8740.562971851383;6921.342472465108;7160.248173941661;7218;7569.394448444001;7689.3464055555905;11467;13358.732825343634;13616.455341786614;13675.452981880613;13823.792643110562;13678.521251756141;14036.368363423466;14098.929505352407;14152.009359345158;14177;9161.86257206129;9457.763555911157;9461.952690236505;9463.952680236554;9468.337216394786;8885.689000885197;10530.210234232616;10765.838512422477;10874.728131796768;10890.727731806768;9070.954645226731;9258.120478554527;9342.813143737223;9344.766380840261;9362.625494980124;9205.711200431811;9206.861897071683;9207.76980575491;9216.387132251963;9261.583228754587;8246.422750407413;8281.875771863306;8334.333253339597;8405.621747021522;8437.325013998989;6061.424164704346;6142.293636231891;6416.230052393743;6417.518686098655;6589.835254118685;5523.447655234444;6164.599301045418;6404.775832845828;6482.805515834532;6596.80209593713 18:16:48 INFO - PID 1652 | |4;prototype.html;342.5632318793538;374.54305746988683;401.87541862022755;402.29999800347406;406.09238352282654;5955;6318.241810982689;6542.83642908927;6554.639494827788;6599;6160.106784516242;6198.876022479548;6269.623822570649;6280.905786413196;6373.38691136634;4618.745968971705;4631.189541830183;4641.721496710191;4641.976790116061;4657.254839225725;4022.5116706818517;4488.192125417424;4655.371524844137;4662.440507139131;4664.183767840631;3983.6813054955533;4463.196624607569;4551.317302404633;4616.653750968674;4621.8844528886875;4128.380742888561;4608.1705293047235;4612.215923293032;4618.214903466419;4632.86101416958;3735.4210097434943;3975.264576053439;3976.224636195943;3983.7609743415487;3992.461017762594;5414.10667239905;5712.657525481509;6163.291221509494;6214.65819379934;6273.686315684243;4613.907721845578;5108.872278193037;5251;5475.616706830523;5528.391876893538;6390.6485143317095;6640.402363787234;6731.225909020476;6825.849857294937;6849.212340580797;4135.97932010341;4520.480144783326;4521.638268938477;4531.84138555149;4533.523979982087;6924.753544362013;7178.38983686382;7190;7280.6723697433745;7307.3058059484265;3806.7525610835205;3900.1614652849207;4257.892947833586;4361.825526978885;4612.723236605814;3932.0956180078742;4647.233206520954;4667.696599721007;4682.297655351656;4691.507391723888;4560.293154561048;5384.353877534642;5470.72646367688;5496.587755918242;5505.926344362891;5706.029974904276;5904.350521442638;6703.865922681519;6861.210960739479;6881.41507971828;1602.6634406774474;1612.11333766428;1613.846684564988;1620.4490473239155;1623.2208539900914;939.793245485992;1033.571068006784;1062.3466568060587;1069.8288273876142;1077.466654006272;6498.5126115541525;6536.411722944863;6537;6542.214934207926;6607.306232845578;5924.585279030426;7154.7495837646475;7182.60495672743;7189.352958233784;7210.351068403764;6539.934600654028;6542.906269993651;6551.344865513411;6552.803415897531;6562.442192413699;3665.7983810890655;3696.0205545530457;3712.424574191005;3721.330160571123;3723.9627603724157;6488.610683359014;6590.934090659032;7244;7340.37606803417;7375.4099672026105;7342.48602597813;7348.485606007528;7366.889496657554;7383.2247614000835;7398.371138453184;6213.440790328892;6464.022702519034;7105.893411598727;7304.634768261566;7369.336759691439;6320.841978950563;6809.693563789542;7147.3210045045635;7158.749443769443;7162.7493037743425;6465.353464653497;6520.380563846427;7042.929570704432;7062.011318415323;7067.293270672892;6819.215790183994;7293.416526677959;7360.96319518399;7380.597686439559;7409.333160015624;4072.307707689759;4579.908401832011;4615.423072115986;4632.536746325475;4649.209634362099;6277.2781130170615;6833.624150671761;6874.346937040973;7151.749688760869;7156.320149585781;6264.5614806964895;6865.313468653095;6866.862662746817;6883.621400823003;6892.001319617255;6813.931860681528;6823.191854158751;6856.828579285558;6871.896921546081;6881.208661004049;5770.163326317745;6007.219061521974;6572.474202063728;6636.83407914806;6802.931970680428;5748.655080695172;6853.965730171317;6880.179126558087;6887.311268873073;6902.792916212321;6692.59844409337;6702.463802895659;6709.4632429404555;6720.193576770819;6747.797566073025 18:16:48 INFO - PID 1652 | |5;yui.html;717.9174394944577;832.9458585191962;847.682119205298;874.0472884555833;880.5069161269687;14713.852861471381;15249.771253431207;16857.578560535992;17175.65648687027;17303.39438119666;16273.349066037374;17784.377546785832;18111.184996675147;18183.4544963651;18216.817831821678;984.0749695286444;990.8216521026249;993.0218734546465;994.8358520844071;1002.5288114586133;1004.6383302011307;1011.0849681038671;1011.1506334678868;1013.3970287678819;1016.7508960304699;952.7094236257939;1052.4369462337652;1061.3790932304612;1063.8297872340427;1068.3643232276822;1043.4574021508772;1052.9262951593391;1057.133150816331;1057.582255009271;1064.7497838008062;764.533634482965;766.6933226709305;770.8265640230959;774.8605251054807;786.9213078692085;17706.557336066573;17912;17977.55056123594;18035.368762093265;18229.908850455664;17881.28474861004;17940;17998.820011799846;18089.276428942827;18415.631687366178;13022.283774392405;14853.2573371331;15053.774193387108;15141.621459463491;15142;3524.594671612772;3556.1643013891717;3557.7331700122336;3601.0817241603486;3722.6091260417807;152207;155260.07351584322;155591.76062362498;157191;157631.42368576283;13852.238126902916;13886.375113119933;14064.789028164585;14142.43430262778;14159.433622654979;22408.66387004196;26791.598126028126;26805.061822836105;27286.31784205371;27348.726512735015;7794.493357931717;7836.960815195887;7911.604419778988;8089.797755056171;8192.83614327722;3669.5779985302033;3707.536557930259;3709.94266634008;3712.3688972874675;3726.459663348799;4276.08064266178;4348.239058164809;4360.345948107746;4368.7815609219415;4383.07955329378;937.4281688170211;950.6910254167424;952.9332946693664;953.823542644613;955.1022039283051;646.4175618404504;650.463367721622;665.544102289933;683.2177157155129;689.4725534965755;10787.541149658191;11424.657260282205;11437.771244575062;11766.235194712317;11873.643790686294;503.46381104124725;587.309910854744;628.613402757301;643.8036398898288;647.9416852483205;4954.232094025432;5585.022621041383;5592.245046918614;5771.653700777967;5901.911471327933;120832;128816.35591821981;131624;132429.67570324367;137927;99501.50249248852;105196;117222;117396.41301793436;117455.2381714275;98009;103693;103900;104079.47960260302;104880.47559762152;10964.56141754321;10968.725781855517;11213.887861121611;11656.650300491;11712.590059347882;42695.786521067195;43556.69329920108;43565.910852228946;43777.56222437862;44834.327485088405;54670;57812.84374312574;60458.39541604704;60509.39490605037;61941.38058619356;13964.30178491051;15145;15209.6197595061;15263.465778697693;16497;16610.75083873767;17203.655926881143;18439.815601844348;19108.33120840764;19514.8048519513;17394.391196308068;17657.64684706273;17895.19471623754;18240.726389103907;18256.543586410447;17510.387136450165;17534.210960506553;17999.370022049166;18234.544136396165;18375.989320587498;17199.484015479557;17814.732779008584;17908.55228619295;17926.820731792515;18056;10330.797452789659;11613.825792612948;11677.240979336317;12172.634820955385;12491.875081249435;11657.41712914334;12840.871591284342;13699.931500342434;13848.30758462093;13986.650333741738 18:16:48 INFO - PID 1652 | __end_tp_report 18:16:48 INFO - PID 1652 | __start_cc_report 18:16:48 INFO - PID 1652 | _x_x_mozilla_cycle_collect,451 18:16:48 INFO - PID 1652 | __end_cc_report 18:16:48 INFO - PID 1652 | __startTimestamp1496798208822__endTimestamp 18:16:48 INFO - PID 1652 | 18:16:48 INFO - PID 1652 | ------- Summary: start ------- 18:16:48 INFO - PID 1652 | Number of tests: 6 18:16:48 INFO - PID 1652 | 18:16:48 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6100.49 Median:6327.00 stddev:723.17 (11.4%) stddev-sans-first:695.58 18:16:48 INFO - PID 1652 | Values: 3480.9 3584.2 3584.4 3614.8 3618.7 5736.3 6100.5 6124.6 6163.7 6219.3 6139.2 6150.5 6153.6 6182.6 6192.9 4965.8 5371.4 5438.3 5480.9 5524.2 5031.2 5297.9 5477.7 5525.8 5555.1 4440.8 5328.8 5495.8 5568.6 5596.9 5354.2 5418.2 5437.9 5485.8 5513.4 5417.2 5418.2 5419.5 5441.5 5472.5 6064.3 6162.1 6180.6 6187.6 6213.2 5545.6 6411.1 6500.3 6597.0 6654.6 5657.5 6755.1 6764.3 6764.6 6771.5 6043.7 6275.2 6279.5 6288.5 6296.1 5623.6 5708.8 6650.1 6758.4 6883.9 6333.0 6560.5 6572.2 6603.0 6623.2 6101.9 6469.5 6473.1 6651.4 6757.4 5572.8 6670.5 6677.2 6680.3 6686.8 6420.4 6516.7 6533.9 6641.3 6717.1 5286.3 5362.2 5369.2 5383.5 5392.1 4727.5 4742.1 4745.5 4747.6 4791.4 6175.5 6178.7 6231.6 6333.6 6338.9 6676.4 6726.1 6748.5 6787.9 6799.6 6287.6 6305.7 6311.6 6324.6 6330.0 5763.5 5897.0 5939.8 5965.1 5992.0 6763.2 6794.4 6822.1 6913.2 6918.1 6801.8 6811.2 6814.8 6824.8 6825.9 5239.7 6076.7 6839.9 6914.5 6930.8 6365.5 6453.7 6537.9 6559.9 6659.9 6728.0 6794.4 6806.4 6825.8 6837.6 6327.0 6400.4 6555.2 6585.0 6616.3 6433.5 6501.8 6508.4 6519.9 6530.1 5816.6 6429.9 6440.1 6447.6 6448.3 6333.4 6582.7 6583.1 6583.1 6610.5 6291.5 6333.0 6427.0 6462.8 6470.1 18:16:48 INFO - PID 1652 | 18:16:48 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:43690.90 Median:35246.30 stddev:74484.13 (211.3%) stddev-sans-first:74623.26 18:16:48 INFO - PID 1652 | Values: 739.7 791.9 850.2 1248.6 1322.9 38441.0 39669.0 39709.4 39726.6 39766.8 39374.8 39740.8 39780.0 39797.0 39895.8 7847.8 8008.4 8284.3 8476.3 8512.1 8409.6 8532.9 8538.7 8540.1 8561.9 8492.9 8497.6 8499.8 8562.1 8575.6 8477.7 8487.6 8536.4 8552.8 8557.5 8313.3 8381.0 8382.4 8389.7 8398.2 37856.9 37967.2 37999.0 38030.8 38117.0 3606.0 3613.3 3639.5 3654.1 3654.4 27883.3 28052.9 30763.0 31041.5 31100.2 2817.9 2869.3 2877.2 2885.7 2892.8 200553.0 228841.0 288573.0 309345.0 309944.0 38004.8 48013.0 48079.8 48091.5 48186.3 51554.5 51581.7 51672.7 51745.5 51750.7 13751.1 13877.6 13954.8 13983.7 14027.6 11234.0 11297.5 11346.8 11353.4 11359.4 14018.4 14039.7 14070.3 14083.0 14184.5 5307.5 6233.4 6272.9 6315.8 6333.4 914.2 960.4 972.6 1013.6 1058.0 22936.7 24943.1 25011.0 25270.0 25313.6 1070.0 1102.8 1127.9 1149.2 1153.0 11868.8 12964.0 12970.4 12980.5 12981.1 326711.0 327171.0 328760.0 329015.2 329678.0 45663.5 56015.0 56021.4 56276.3 56298.2 1308.7 1365.2 1475.8 1531.3 1539.3 20575.0 22753.7 23108.7 24517.4 24712.0 27536.7 36088.3 36533.8 38570.6 41151.4 206624.0 253586.0 256074.0 256655.0 257668.7 34756.0 35736.6 35954.0 36207.9 36360.8 37787.0 44476.6 46836.6 46871.0 47031.9 26444.1 31393.4 31441.7 31900.4 31949.8 30136.5 30241.5 31549.5 31648.9 32318.0 38574.6 38762.0 38825.8 38932.4 39058.8 30293.0 30749.8 30839.5 31815.4 32459.0 24292.5 24593.7 24853.6 25090.5 25458.0 18:16:48 INFO - PID 1652 | 18:16:48 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:41183.35 Median:13008.80 stddev:97787.94 (751.7%) stddev-sans-first:98014.41 18:16:48 INFO - PID 1652 | Values: 437.8 439.1 446.9 453.8 454.1 22236.0 23154.9 23199.8 23232.4 24153.0 23650.8 24126.0 24133.9 24223.4 24251.4 6102.1 6258.7 6268.9 6282.7 6291.0 5555.3 5952.7 6066.2 6206.6 6266.3 6027.8 6095.7 6154.9 6162.7 6179.4 6118.6 6141.8 6150.4 6154.0 6158.5 4648.8 5475.1 5479.9 5486.3 5491.1 29436.0 29767.4 29814.4 29865.9 30218.2 2216.1 2311.6 2312.7 2322.0 2324.6 20358.3 20456.0 20654.1 20708.3 20729.5 2313.2 2320.4 2363.1 2366.8 2403.8 174004.1 180308.0 199322.0 203659.0 204764.0 25923.4 26038.0 26560.5 27902.6 28191.0 45387.0 45444.8 45445.3 45475.0 45525.5 11190.8 11323.2 11366.9 11382.5 11399.4 8942.1 8956.7 8980.3 9025.0 9044.5 11008.7 12038.0 12509.5 12549.6 12648.5 4086.9 5028.2 5040.8 5047.7 5054.9 860.5 922.7 947.9 971.9 1001.5 19644.9 21962.8 22126.3 22752.4 22841.5 858.5 869.3 966.5 1000.2 1013.8 9166.7 10395.0 11139.9 11174.9 11281.5 551799.0 554394.0 555546.0 575939.0 583010.0 153965.0 157961.2 161362.0 161703.0 162271.0 1005.2 1049.8 1066.0 1173.3 1233.0 22398.9 23879.0 23909.0 23923.3 23927.4 69771.7 69889.0 71544.6 71872.6 72016.0 92890.5 93714.0 94455.0 94816.1 95574.0 10265.4 10510.7 10511.2 10532.6 10567.7 17424.7 18617.3 18824.3 18852.5 18855.4 15517.4 15549.8 15599.0 15718.8 15749.5 13702.3 13988.4 14042.7 14056.6 14160.9 15506.1 15643.6 15647.6 15771.2 15777.7 10359.6 10362.1 10449.2 10452.0 10479.6 14983.1 15084.4 15268.8 15293.0 15314.0 18:16:48 INFO - PID 1652 | 18:16:48 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:7237.63 Median:4104.01 stddev:4532.72 (110.4%) stddev-sans-first:4523.71 18:16:48 INFO - PID 1652 | Values: 1332.2 1435.1 1454.7 1461.6 1470.6 11726.0 12121.5 12221.4 12267.9 12466.3 12026.6 12057.1 12070.1 12183.6 12188.5 2529.6 2537.1 2579.9 2582.7 2583.1 2147.4 2559.7 2565.6 2566.0 2577.2 2508.1 2526.7 2539.3 2543.4 2544.2 2470.2 2483.1 2494.6 2497.2 2500.8 1791.7 2041.6 2469.5 2479.2 2520.2 11867.9 11869.6 11927.8 11970.2 11971.8 6439.2 6680.7 6698.8 6705.9 6709.4 3630.5 4010.3 4197.7 4266.7 4281.1 1231.6 1232.1 1235.1 1253.4 1256.0 15317.4 15718.1 16698.0 16789.2 16833.0 5547.9 5571.4 5779.9 5785.9 5800.3 12462.6 12472.3 12485.8 12492.1 12510.4 6478.8 6533.9 6558.7 6560.2 6595.3 6027.8 6123.6 6140.9 6175.1 6177.5 4387.9 4417.0 4437.7 4460.9 4525.6 1271.4 1289.9 1294.9 1304.9 1309.5 791.7 819.3 825.6 833.9 836.6 6370.6 6796.2 7291.9 7428.3 7595.0 954.2 959.8 977.0 995.6 996.9 4672.4 4691.3 4857.8 4890.7 4984.1 14530.7 14994.8 15088.5 15276.8 15436.2 12293.7 14919.2 15093.3 15307.4 15494.8 8549.3 8588.6 8696.8 8713.7 8740.6 6921.3 7160.2 7218.0 7569.4 7689.3 11467.0 13358.7 13616.5 13675.5 13823.8 13678.5 14036.4 14098.9 14152.0 14177.0 9161.9 9457.8 9462.0 9464.0 9468.3 8885.7 10530.2 10765.8 10874.7 10890.7 9071.0 9258.1 9342.8 9344.8 9362.6 9205.7 9206.9 9207.8 9216.4 9261.6 8246.4 8281.9 8334.3 8405.6 8437.3 6061.4 6142.3 6416.2 6417.5 6589.8 5523.4 6164.6 6404.8 6482.8 6596.8 18:16:48 INFO - PID 1652 | 18:16:48 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5508.21 Median:6275.48 stddev:1771.18 (28.2%) stddev-sans-first:1733.19 18:16:48 INFO - PID 1652 | Values: 342.6 374.5 401.9 402.3 406.1 5955.0 6318.2 6542.8 6554.6 6599.0 6160.1 6198.9 6269.6 6280.9 6373.4 4618.7 4631.2 4641.7 4642.0 4657.3 4022.5 4488.2 4655.4 4662.4 4664.2 3983.7 4463.2 4551.3 4616.7 4621.9 4128.4 4608.2 4612.2 4618.2 4632.9 3735.4 3975.3 3976.2 3983.8 3992.5 5414.1 5712.7 6163.3 6214.7 6273.7 4613.9 5108.9 5251.0 5475.6 5528.4 6390.6 6640.4 6731.2 6825.8 6849.2 4136.0 4520.5 4521.6 4531.8 4533.5 6924.8 7178.4 7190.0 7280.7 7307.3 3806.8 3900.2 4257.9 4361.8 4612.7 3932.1 4647.2 4667.7 4682.3 4691.5 4560.3 5384.4 5470.7 5496.6 5505.9 5706.0 5904.4 6703.9 6861.2 6881.4 1602.7 1612.1 1613.8 1620.4 1623.2 939.8 1033.6 1062.3 1069.8 1077.5 6498.5 6536.4 6537.0 6542.2 6607.3 5924.6 7154.7 7182.6 7189.4 7210.4 6539.9 6542.9 6551.3 6552.8 6562.4 3665.8 3696.0 3712.4 3721.3 3724.0 6488.6 6590.9 7244.0 7340.4 7375.4 7342.5 7348.5 7366.9 7383.2 7398.4 6213.4 6464.0 7105.9 7304.6 7369.3 6320.8 6809.7 7147.3 7158.7 7162.7 6465.4 6520.4 7042.9 7062.0 7067.3 6819.2 7293.4 7361.0 7380.6 7409.3 4072.3 4579.9 4615.4 4632.5 4649.2 6277.3 6833.6 6874.3 7151.7 7156.3 6264.6 6865.3 6866.9 6883.6 6892.0 6813.9 6823.2 6856.8 6871.9 6881.2 5770.2 6007.2 6572.5 6636.8 6802.9 5748.7 6854.0 6880.2 6887.3 6902.8 6692.6 6702.5 6709.5 6720.2 6747.8 18:16:48 INFO - PID 1652 | 18:16:48 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:24873.51 Median:17988.19 stddev:38103.76 (211.8%) stddev-sans-first:38167.49 18:16:48 INFO - PID 1652 | Values: 717.9 832.9 847.7 874.0 880.5 14713.9 15249.8 16857.6 17175.7 17303.4 16273.3 17784.4 18111.2 18183.5 18216.8 984.1 990.8 993.0 994.8 1002.5 1004.6 1011.1 1011.2 1013.4 1016.8 952.7 1052.4 1061.4 1063.8 1068.4 1043.5 1052.9 1057.1 1057.6 1064.7 764.5 766.7 770.8 774.9 786.9 17706.6 17912.0 17977.6 18035.4 18229.9 17881.3 17940.0 17998.8 18089.3 18415.6 13022.3 14853.3 15053.8 15141.6 15142.0 3524.6 3556.2 3557.7 3601.1 3722.6 152207.0 155260.1 155591.8 157191.0 157631.4 13852.2 13886.4 14064.8 14142.4 14159.4 22408.7 26791.6 26805.1 27286.3 27348.7 7794.5 7837.0 7911.6 8089.8 8192.8 3669.6 3707.5 3709.9 3712.4 3726.5 4276.1 4348.2 4360.3 4368.8 4383.1 937.4 950.7 952.9 953.8 955.1 646.4 650.5 665.5 683.2 689.5 10787.5 11424.7 11437.8 11766.2 11873.6 503.5 587.3 628.6 643.8 647.9 4954.2 5585.0 5592.2 5771.7 5901.9 120832.0 128816.4 131624.0 132429.7 137927.0 99501.5 105196.0 117222.0 117396.4 117455.2 98009.0 103693.0 103900.0 104079.5 104880.5 10964.6 10968.7 11213.9 11656.7 11712.6 42695.8 43556.7 43565.9 43777.6 44834.3 54670.0 57812.8 60458.4 60509.4 61941.4 13964.3 15145.0 15209.6 15263.5 16497.0 16610.8 17203.7 18439.8 19108.3 19514.8 17394.4 17657.6 17895.2 18240.7 18256.5 17510.4 17534.2 17999.4 18234.5 18376.0 17199.5 17814.7 17908.6 17926.8 18056.0 10330.8 11613.8 11677.2 12172.6 12491.9 11657.4 12840.9 13699.9 13848.3 13986.7 18:16:48 INFO - PID 1652 | -------- Summary: end -------- 18:16:48 INFO - PID 1652 | 18:16:49 INFO - TEST-INFO | 1652: exit 0 18:16:49 INFO - TEST-OK | dromaeo_css | took 1142841ms 18:16:49 INFO - TEST-START | kraken 18:16:49 INFO - operating with platform_type : mac_ 18:16:49 INFO - Initialising browser for kraken test... 18:16:49 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmppuxV1L/profile 18:16:49 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 18:17:23 INFO - TEST-INFO | 1663: killed by SIGTERM 18:17:23 INFO - Browser initialized. 18:17:23 INFO - Running cycle 1/1 for kraken test... 18:17:23 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/T/tmppuxV1L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:17:25 INFO - PID 1667 | 2017-06-06 18:17:25.200 plugin-container[1668:11293] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9943, name = 'com.apple.tsm.portname' 18:17:25 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:17:25 INFO - PID 1667 | 2017-06-06 18:17:25.200 plugin-container[1668:11293] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9947, name = 'com.apple.CFPasteboardClient' 18:17:25 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:17:25 INFO - PID 1667 | 2017-06-06 18:17:25.200 plugin-container[1668:11293] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:17:25 INFO - PID 1667 | 2017-06-06 18:17:25.721 plugin-container[1669:11339] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 18:17:25 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:17:25 INFO - PID 1667 | 2017-06-06 18:17:25.722 plugin-container[1669:11339] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 18:17:25 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:17:25 INFO - PID 1667 | 2017-06-06 18:17:25.722 plugin-container[1669:11339] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:17:35 INFO - PID 1667 | RSS: Main: 268525568 18:17:35 INFO - PID 1667 | 18:17:37 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 18:17:38 INFO - PID 1667 | RSS: Main: 275701760 18:17:38 INFO - PID 1667 | 18:17:41 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 18:17:42 INFO - PID 1667 | RSS: Main: 283693056 18:17:42 INFO - PID 1667 | 18:17:45 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 18:17:46 INFO - PID 1667 | RSS: Main: 281759744 18:17:46 INFO - PID 1667 | 18:17:48 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 18:17:49 INFO - PID 1667 | RSS: Main: 283148288 18:17:49 INFO - PID 1667 | 18:17:51 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:17:51 INFO - PID 1667 | RSS: Main: 283566080 18:17:51 INFO - PID 1667 | 18:17:54 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 18:17:55 INFO - PID 1667 | RSS: Main: 290668544 18:17:55 INFO - PID 1667 | 18:17:58 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 18:17:59 INFO - PID 1667 | RSS: Main: 292548608 18:17:59 INFO - PID 1667 | 18:18:02 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 18:18:02 INFO - PID 1667 | RSS: Main: 291655680 18:18:02 INFO - PID 1667 | 18:18:04 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:18:04 INFO - PID 1667 | RSS: Main: 291700736 18:18:04 INFO - PID 1667 | 18:18:06 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:18:07 INFO - PID 1667 | RSS: Main: 291659776 18:18:07 INFO - PID 1667 | 18:18:09 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:18:10 INFO - PID 1667 | RSS: Main: 291360768 18:18:10 INFO - PID 1667 | 18:18:13 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:18:14 INFO - PID 1667 | RSS: Main: 291254272 18:18:14 INFO - PID 1667 | 18:18:17 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:18:17 INFO - PID 1667 | RSS: Main: 291500032 18:18:17 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 18:18:19 INFO - PID 1667 | RSS: Main: 293670912 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | __start_tp_report 18:18:19 INFO - PID 1667 | _x_x_mozilla_page_load 18:18:19 INFO - PID 1667 | _x_x_mozilla_page_load_details 18:18:19 INFO - PID 1667 | |i|pagename|runs| 18:18:19 INFO - PID 1667 | |0;ai-astar;105;117;119;168;172;114;113;114;115;115 18:18:19 INFO - PID 1667 | |1;audio-beat-detection;146;129;108;150;146;95;146;147;157;127 18:18:19 INFO - PID 1667 | |2;audio-dft;200;201;225;259;204;259;264;274;276;200 18:18:19 INFO - PID 1667 | |3;audio-fft;86;81;82;81;62;60;59;61;92;81 18:18:19 INFO - PID 1667 | |4;audio-oscillator;84;69;87;82;103;102;110;73;68;88 18:18:19 INFO - PID 1667 | |5;imaging-gaussian-blur;89;90;97;88;108;136;88;136;137;93 18:18:19 INFO - PID 1667 | |6;imaging-darkroom;105;94;152;97;99;95;96;96;137;153 18:18:19 INFO - PID 1667 | |7;imaging-desaturate;93;95;91;91;93;92;92;95;93;110 18:18:19 INFO - PID 1667 | |8;json-parse-financial;50;50;50;53;51;49;49;50;51;50 18:18:19 INFO - PID 1667 | |9;json-stringify-tinderbox;56;55;66;68;66;69;50;57;80;80 18:18:19 INFO - PID 1667 | |10;stanford-crypto-aes;87;85;87;87;87;79;110;110;127;120 18:18:19 INFO - PID 1667 | |11;stanford-crypto-ccm;138;140;134;146;134;151;153;158;152;160 18:18:19 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;227;189;188;188;226;188;184;192;190;190 18:18:19 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;62;97;100;102;65;65;69;80;67;93 18:18:19 INFO - PID 1667 | __end_tp_report 18:18:19 INFO - PID 1667 | __start_cc_report 18:18:19 INFO - PID 1667 | _x_x_mozilla_cycle_collect,1204 18:18:19 INFO - PID 1667 | __end_cc_report 18:18:19 INFO - PID 1667 | __startTimestamp1496798299973__endTimestamp 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | ------- Summary: start ------- 18:18:19 INFO - PID 1667 | Number of tests: 14 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:125.20 Median:116.00 stddev:23.91 (20.6%) stddev-sans-first:24.21 18:18:19 INFO - PID 1667 | Values: 105.0 117.0 119.0 168.0 172.0 114.0 113.0 114.0 115.0 115.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:146.50 stddev:20.12 (13.7%) stddev-sans-first:20.95 18:18:19 INFO - PID 1667 | Values: 146.0 129.0 108.0 150.0 146.0 95.0 146.0 147.0 157.0 127.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:236.20 Median:259.00 stddev:33.08 (12.8%) stddev-sans-first:32.39 18:18:19 INFO - PID 1667 | Values: 200.0 201.0 225.0 259.0 204.0 259.0 264.0 274.0 276.0 200.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:74.50 Median:81.00 stddev:12.52 (15.5%) stddev-sans-first:12.57 18:18:19 INFO - PID 1667 | Values: 86.0 81.0 82.0 81.0 62.0 60.0 59.0 61.0 92.0 81.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:86.60 Median:77.50 stddev:14.62 (18.9%) stddev-sans-first:15.48 18:18:19 INFO - PID 1667 | Values: 84.0 69.0 87.0 82.0 103.0 102.0 110.0 73.0 68.0 88.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:106.20 Median:88.50 stddev:21.62 (24.4%) stddev-sans-first:22.02 18:18:19 INFO - PID 1667 | Values: 89.0 90.0 97.0 88.0 108.0 136.0 88.0 136.0 137.0 93.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:112.40 Median:95.50 stddev:24.66 (25.8%) stddev-sans-first:26.01 18:18:19 INFO - PID 1667 | Values: 105.0 94.0 152.0 97.0 99.0 95.0 96.0 96.0 137.0 153.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:93.00 stddev:5.62 (6.0%) stddev-sans-first:5.94 18:18:19 INFO - PID 1667 | Values: 93.0 95.0 91.0 91.0 93.0 92.0 92.0 95.0 93.0 110.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.00 stddev:1.16 (2.3%) stddev-sans-first:1.22 18:18:19 INFO - PID 1667 | Values: 50.0 50.0 50.0 53.0 51.0 49.0 49.0 50.0 51.0 50.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:67.00 stddev:10.25 (15.3%) stddev-sans-first:10.38 18:18:19 INFO - PID 1667 | Values: 56.0 55.0 66.0 68.0 66.0 69.0 50.0 57.0 80.0 80.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:97.90 Median:86.00 stddev:17.08 (19.9%) stddev-sans-first:17.66 18:18:19 INFO - PID 1667 | Values: 87.0 85.0 87.0 87.0 87.0 79.0 110.0 110.0 127.0 120.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:151.50 stddev:9.63 (6.4%) stddev-sans-first:9.70 18:18:19 INFO - PID 1667 | Values: 138.0 140.0 134.0 146.0 134.0 151.0 153.0 158.0 152.0 160.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.20 Median:190.00 stddev:16.10 (8.5%) stddev-sans-first:12.65 18:18:19 INFO - PID 1667 | Values: 227.0 189.0 188.0 188.0 226.0 188.0 184.0 192.0 190.0 190.0 18:18:19 INFO - PID 1667 | 18:18:19 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.00 Median:68.00 stddev:16.35 (24.0%) stddev-sans-first:15.99 18:18:19 INFO - PID 1667 | Values: 62.0 97.0 100.0 102.0 65.0 65.0 69.0 80.0 67.0 93.0 18:18:19 INFO - PID 1667 | -------- Summary: end -------- 18:18:19 INFO - PID 1667 | 18:18:20 INFO - TEST-INFO | 1667: exit 0 18:18:20 INFO - TEST-OK | kraken | took 91414ms 18:18:20 INFO - SUITE-END | took 1234s 18:18:21 INFO - Completed test suite (00:20:34) 18:18:21 INFO - javascript benchmark 18:18:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8236.7110653523, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3480.91297717557, 3584.1577229351096, 3584.3727347714143, 3614.7831130132195, 3618.6924111450526, 5736.282964629427, 6100.542459315547, 6124.601900876445, 6163.722632481538, 6219.253689557259, 6139.15484235078, 6150.538709596787, 6153.569250152491, 6182.598131121474, 6192.907106393408, 4965.808206030545, 5371.3822910365225, 5438.293021907147, 5480.945190548104, 5524.226608274845, 5031.2453132030305, 5297.920531192035, 5477.726113694319, 5525.778968841261, 5555.111182210847, 4440.755758433284, 5328.760205790749, 5495.780168793243, 5568.582356323272, 5596.888062238773, 5354.170103633943, 5418.214358917974, 5437.891242175135, 5485.753141108659, 5513.365962914236, 5417.187421886749, 5418.241446197536, 5419.458054194549, 5441.5102640762125, 5472.452754724496, 6064.272287325505, 6162.137300777895, 6180.5982611130585, 6187.597806142587, 6213.192285002966, 5545.556355491551, 6411.070394792776, 6500.252470965852, 6597.0, 6654.600723956578, 5657.519110875581, 6755.088063111466, 6764.323567643246, 6764.560303580301, 6771.4582833373215, 6043.667598282049, 6275.24697036354, 6279.466245369149, 6288.496920246369, 6296.087067375296, 5623.578231632644, 5708.771649134072, 6650.068990341356, 6758.42553382968, 6883.862322753517, 6333.0, 6560.475161987029, 6572.244191917991, 6603.0, 6623.20521537409, 6101.932161871655, 6469.547131700736, 6473.126127972663, 6651.434628056572, 6757.358050985148, 5572.777088916479, 6670.533062685662, 6677.165354330708, 6680.298568650222, 6686.83282917931, 6420.390062944013, 6516.706748196342, 6533.901991470132, 6641.269460359356, 6717.093192419059, 5286.3392075990505, 5362.168863826113, 5369.167778994262, 5383.488568585978, 5392.137258038773, 4727.527247275245, 4742.099001189763, 4745.501722319176, 4747.596454301354, 4791.377120974251, 6175.475084617768, 6178.69106544671, 6231.626102433868, 6333.588316759396, 6338.873222535523, 6676.365745254193, 6726.091977583062, 6748.4938629602975, 6787.898181527281, 6799.626020568916, 6287.622742635456, 6305.747770089145, 6311.621302721851, 6324.557280990379, 6329.968350158219, 5763.538916886639, 5897.0, 5939.82180534576, 5965.105234214902, 5992.041273396236, 6763.154605674291, 6794.388505034472, 6822.147231596051, 6913.239543650094, 6918.06606108189, 6801.761938332134, 6811.216710078206, 6814.795556133324, 6824.7952561423235, 6825.931740682529, 5239.711815850165, 6076.72654730547, 6839.863202736017, 6914.5159838812815, 6930.826729331807, 6365.490760739037, 6453.677316134268, 6537.901931471127, 6559.917613593703, 6659.933400666125, 6728.0, 6794.388505034571, 6806.387425131762, 6825.795226143224, 6837.62393068386, 6327.0, 6400.39196276353, 6555.2133743951035, 6585.0, 6616.272210056986, 6433.5496515243485, 6501.772437964649, 6508.381703738138, 6519.869602607826, 6530.118434011348, 5816.621919575214, 6429.935700642934, 6440.130582371507, 6447.6453795041725, 6448.258450278278, 6333.36666333363, 6582.703778329891, 6583.078369028436, 6583.111279977142, 6610.471162306908, 6291.528135389773, 6333.0, 6426.971684530453, 6462.806115816533, 6470.06184103293], "value": 6055.175832235433, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.6634531288263, 791.9168487308832, 850.1625898489988, 1248.5567623493664, 1322.861099584543, 38441.0, 39669.00827479307, 39709.40435893464, 39726.60273397265, 39766.80116599416, 39374.8031259844, 39740.80129599348, 39780.0, 39797.0, 39895.800520997356, 7847.764567062997, 8008.399370047241, 8284.337253019745, 8476.279516241097, 8512.1487851215, 8409.621567029499, 8532.87200691987, 8538.743837684879, 8540.060593334729, 8561.871571926426, 8492.872606910902, 8497.575121243914, 8499.787505312355, 8562.14378562145, 8575.614097365633, 8477.74566762998, 8487.618057187441, 8536.359773017048, 8552.828943421127, 8557.529335886524, 8313.33493320533, 8380.958095209546, 8382.413231073828, 8389.748307550783, 8398.244158025745, 37856.86429407122, 37967.24065518674, 37999.0, 38030.80984595087, 38117.0, 3606.026372879308, 3613.295407395559, 3639.5268615080136, 3654.0682126057686, 3654.4152935530187, 27883.302917427227, 28052.859735701393, 30763.0, 31041.534376984364, 31100.22249443782, 2817.887284508617, 2869.3113652723405, 2877.2375320540073, 2885.6970018148004, 2892.8119672221237, 200553.0, 228841.0, 288572.9999999979, 309344.99999999773, 309944.0, 38004.809975949946, 48013.0, 48079.75960120247, 48091.5190848087, 48186.27720584194, 51554.48445515572, 51581.71045723887, 51672.741636291576, 51745.48254517407, 51750.74124629428, 13751.106178098391, 13877.583672489842, 13954.790678139836, 13983.650408739659, 14027.579172624837, 11234.0, 11297.548098075986, 11346.773064538827, 11353.388720594145, 11359.432028398713, 14018.369173387222, 14039.719205615831, 14070.2964851757, 14083.0, 14184.503542375967, 5307.52232299095, 6233.438990490878, 6272.905906411407, 6315.810525684236, 6333.429991300805, 914.2000749344323, 960.3709570231509, 972.6498460554195, 1013.5540362240738, 1057.9894201058046, 22936.655950160763, 24943.126990555607, 25011.0, 25270.0, 25313.62029569558, 1069.967481380456, 1102.7739313440725, 1127.8533790607332, 1149.230015889356, 1152.9711757205969, 11868.82196767032, 12964.0, 12970.351482425842, 12980.480780768852, 12981.091323607447, 326711.0, 327171.0, 328760.0, 329015.1943162563, 329678.0, 45663.54336456659, 56015.0, 56021.43978560244, 56276.31171064875, 56298.15552766712, 1308.738252349534, 1365.2491129878526, 1475.8007668964767, 1531.257340190007, 1539.3380846236225, 20575.0, 22753.658695119917, 23108.653370199125, 24517.38706532351, 24712.011519539017, 27536.724632753416, 36088.27823443464, 36533.817330913174, 38570.6142938567, 41151.38272925848, 206624.0, 253586.0, 256074.0, 256655.0, 257668.71165644802, 34755.95732127939, 35736.642633573334, 35954.0, 36207.913762587166, 36360.818195908854, 37787.0, 44476.55523444724, 46836.59490215299, 46871.0, 47031.88356523896, 26444.074457393897, 31393.372132557677, 31441.685583143877, 31900.361992760478, 31949.840250798596, 30136.547951780303, 30241.546376803926, 31549.526757098203, 31648.89228876978, 32318.0, 38574.61425385823, 38762.0, 38825.805870970464, 38932.41601375925, 39058.80470597629, 30293.0, 30749.846250769497, 30839.537406938463, 31815.363692726478, 32459.0, 24292.505886410363, 24593.6984223749, 24853.627195592442, 25090.49819003573, 25458.0], "value": 17502.328385369085, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.7832972678523, 439.11080062872696, 446.90838378132474, 453.81393628612255, 454.1122106282219, 22236.0, 23154.884225578848, 23199.768002319994, 23232.41918952027, 24153.0, 23650.76349236507, 24126.0, 24133.879330603366, 24223.39441513963, 24251.39371515713, 6102.0541816018585, 6258.687065646723, 6268.874622507547, 6282.748690052393, 6291.0, 5555.333359996806, 5952.7499225162765, 6066.211392518966, 6206.627602343874, 6266.342034086424, 6027.819165425044, 6095.725692343833, 6154.907676384858, 6162.691865406712, 6179.443850053494, 6118.63288202707, 6141.785037523688, 6150.354212807635, 6153.969230153865, 6158.507319414436, 4648.790804413809, 5475.09660905954, 5479.945200547983, 5486.259354987065, 5491.093969495028, 29436.0, 29767.404651907058, 29814.403711925857, 29865.850670746506, 30218.244543886358, 2216.1024784962115, 2311.595470792621, 2312.7455979842202, 2321.9651705224437, 2324.639680849471, 20358.28745993883, 20455.97720113988, 20654.07056682438, 20708.275210367567, 20729.481762955897, 2313.1556981701724, 2320.3734991552187, 2363.1374548289923, 2366.8461549999365, 2403.831731778776, 174004.12997935055, 180308.0, 199322.0, 203659.0, 204763.97618011816, 25923.351916202246, 26038.0, 26560.468790624465, 27902.581461277692, 28191.0, 45387.0, 45444.772776135906, 45445.318320225226, 45475.0, 45525.54474455213, 11190.832137517944, 11323.15076369259, 11366.943165284287, 11382.487788049557, 11399.373034483184, 8942.061083586259, 8956.656501524692, 8980.281577473786, 9025.0, 9044.457332560067, 11008.724781880357, 12038.0, 12509.49962001528, 12549.623511294676, 12648.494060237672, 4086.9386959195635, 5028.220625803006, 5040.84877453677, 5047.671901326402, 5054.9241761373605, 860.5052095045374, 922.7220299884574, 947.8767760191269, 971.9028097190225, 1001.5192707500295, 19644.90177549103, 21962.780372196074, 22126.336209913727, 22752.431189220402, 22841.543169136523, 858.5406807358033, 869.2915274051628, 966.4925913895121, 1000.1598657128047, 1013.8124446977333, 9166.724998250063, 10395.024945260377, 11139.888601114048, 11174.88251174876, 11281.548738050551, 551799.0, 554394.0, 555546.0, 575939.0, 583010.0, 153965.0, 157961.2101939483, 161362.0, 161703.0, 162271.0, 1005.2460654509118, 1049.8267785815408, 1065.9946700266448, 1173.266708307308, 1233.0382301804607, 22398.888005559867, 23879.044838206974, 23909.0, 23923.33494968534, 23927.401814954766, 69771.65114174396, 69888.95166572607, 71544.64227678828, 71872.64063679648, 72016.0, 92890.53554732184, 93714.0, 94455.0, 94816.05183948348, 95574.04425955651, 10265.435401053013, 10510.684679459628, 10511.211659125749, 10532.57869685204, 10567.682969510926, 17424.651506969534, 18617.348392806187, 18824.341148059753, 18852.49180065619, 18855.434336969913, 15517.379304828131, 15549.84450155484, 15598.986065906134, 15718.842811571738, 15749.527514174593, 13702.314884255948, 13988.37052332668, 14042.719145616826, 14056.578302650936, 14160.858391416366, 15506.147161906203, 15643.608909777347, 15647.608809779847, 15771.211439428213, 15777.68444631124, 10359.585616575252, 10362.067413932782, 10449.164066874784, 10452.0, 10479.633212837514, 14983.101013939198, 15084.396624134912, 15268.770968435705, 15293.0, 15314.004589701633], "value": 12491.336390684051, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1332.2273081612666, 1435.1460880775935, 1454.7236025155225, 1461.5615315405382, 1470.610288273607, 11726.0, 12121.454534545945, 12221.388930553481, 12267.895889369966, 12466.252024878513, 12026.639200823967, 12057.095717821177, 12070.094742894296, 12183.57357492488, 12188.51245950161, 2529.557327467695, 2537.13737329308, 2579.85810780407, 2582.6513420688157, 2583.0959164292535, 2147.44166516706, 2559.6800399950007, 2565.576679847823, 2565.9743402565923, 2577.162422212779, 2508.097085049381, 2526.6589010483535, 2539.3016920346868, 2543.389586499236, 2544.160427059075, 2470.234227389506, 2483.1184929350093, 2494.5759220932487, 2497.1884137655243, 2500.8124390670773, 1791.6506281275156, 2041.6427125253022, 2469.5060987802512, 2479.194261864883, 2520.180941194101, 11867.940660296643, 11869.643910682693, 11927.821082683766, 11970.162088653798, 11971.820422693667, 6439.162908821823, 6680.7327706891665, 6698.765543205965, 6705.899411508832, 6709.4296984755865, 3630.49173115764, 4010.3382941814566, 4197.685173611992, 4266.679999000087, 4281.079567892918, 1231.648980040693, 1232.1375037473715, 1235.1292338901053, 1253.4422182128956, 1255.9937200314032, 15317.387304507807, 15718.13550254724, 16698.0, 16789.160541972975, 16833.0, 5547.861303467364, 5571.4150014248735, 5779.913301300484, 5785.88428231433, 5800.332961709373, 12462.626121216377, 12472.251664899954, 12485.750284994248, 12492.12555121151, 12510.437030333656, 6478.838029049311, 6533.934660653427, 6558.737650493927, 6560.212774467094, 6595.274519802818, 6027.758889644365, 6123.601965872204, 6140.938590614126, 6175.073738939195, 6177.505799536027, 4387.890302742457, 4417.006173610912, 4437.689361744711, 4460.910781784346, 4525.592696657317, 1271.4405661508906, 1289.9032572557096, 1294.8640392758625, 1304.9412776425083, 1309.463120120746, 791.6991543213658, 819.3240576524391, 825.591332290508, 833.913129907559, 836.615157027774, 6370.649614271167, 6796.150481189851, 7291.906214067826, 7428.33145016951, 7595.012648355789, 954.2413781044087, 959.7666997907655, 976.9741771140416, 995.5520015992832, 996.940183588987, 4672.392588963413, 4691.272852707836, 4857.781399837016, 4890.657653964259, 4984.102861484895, 14530.709385812224, 14994.775078373834, 15088.54734357971, 15276.847231527765, 15436.228188590525, 12293.692657683629, 14919.179445130405, 15093.320800564, 15307.387704491919, 15494.767578486331, 8549.27331176857, 8588.570571471402, 8696.78258043554, 8713.651453941771, 8740.562971851383, 6921.342472465108, 7160.248173941661, 7218.0, 7569.394448444001, 7689.3464055555905, 11467.0, 13358.732825343634, 13616.455341786614, 13675.452981880613, 13823.792643110562, 13678.521251756141, 14036.368363423466, 14098.929505352407, 14152.009359345158, 14177.0, 9161.86257206129, 9457.763555911157, 9461.952690236505, 9463.952680236554, 9468.337216394786, 8885.689000885197, 10530.210234232616, 10765.838512422477, 10874.728131796768, 10890.727731806768, 9070.954645226731, 9258.120478554527, 9342.813143737223, 9344.766380840261, 9362.625494980124, 9205.711200431811, 9206.861897071683, 9207.76980575491, 9216.387132251963, 9261.583228754587, 8246.422750407413, 8281.875771863306, 8334.333253339597, 8405.621747021522, 8437.325013998989, 6061.424164704346, 6142.293636231891, 6416.230052393743, 6417.518686098655, 6589.835254118685, 5523.447655234444, 6164.599301045418, 6404.775832845828, 6482.805515834532, 6596.80209593713], "value": 5481.84008882163, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.5632318793538, 374.54305746988683, 401.87541862022755, 402.29999800347406, 406.09238352282654, 5955.0, 6318.241810982689, 6542.83642908927, 6554.639494827788, 6599.0, 6160.106784516242, 6198.876022479548, 6269.623822570649, 6280.905786413196, 6373.38691136634, 4618.745968971705, 4631.189541830183, 4641.721496710191, 4641.976790116061, 4657.254839225725, 4022.5116706818517, 4488.192125417424, 4655.371524844137, 4662.440507139131, 4664.183767840631, 3983.6813054955533, 4463.196624607569, 4551.317302404633, 4616.653750968674, 4621.8844528886875, 4128.380742888561, 4608.1705293047235, 4612.215923293032, 4618.214903466419, 4632.86101416958, 3735.4210097434943, 3975.264576053439, 3976.224636195943, 3983.7609743415487, 3992.461017762594, 5414.10667239905, 5712.657525481509, 6163.291221509494, 6214.65819379934, 6273.686315684243, 4613.907721845578, 5108.872278193037, 5251.0, 5475.616706830523, 5528.391876893538, 6390.6485143317095, 6640.402363787234, 6731.225909020476, 6825.849857294937, 6849.212340580797, 4135.97932010341, 4520.480144783326, 4521.638268938477, 4531.84138555149, 4533.523979982087, 6924.753544362013, 7178.38983686382, 7190.0, 7280.6723697433745, 7307.3058059484265, 3806.7525610835205, 3900.1614652849207, 4257.892947833586, 4361.825526978885, 4612.723236605814, 3932.0956180078742, 4647.233206520954, 4667.696599721007, 4682.297655351656, 4691.507391723888, 4560.293154561048, 5384.353877534642, 5470.72646367688, 5496.587755918242, 5505.926344362891, 5706.029974904276, 5904.350521442638, 6703.865922681519, 6861.210960739479, 6881.41507971828, 1602.6634406774474, 1612.11333766428, 1613.846684564988, 1620.4490473239155, 1623.2208539900914, 939.793245485992, 1033.571068006784, 1062.3466568060587, 1069.8288273876142, 1077.466654006272, 6498.5126115541525, 6536.411722944863, 6537.0, 6542.214934207926, 6607.306232845578, 5924.585279030426, 7154.7495837646475, 7182.60495672743, 7189.352958233784, 7210.351068403764, 6539.934600654028, 6542.906269993651, 6551.344865513411, 6552.803415897531, 6562.442192413699, 3665.7983810890655, 3696.0205545530457, 3712.424574191005, 3721.330160571123, 3723.9627603724157, 6488.610683359014, 6590.934090659032, 7244.0, 7340.37606803417, 7375.4099672026105, 7342.48602597813, 7348.485606007528, 7366.889496657554, 7383.2247614000835, 7398.371138453184, 6213.440790328892, 6464.022702519034, 7105.893411598727, 7304.634768261566, 7369.336759691439, 6320.841978950563, 6809.693563789542, 7147.3210045045635, 7158.749443769443, 7162.7493037743425, 6465.353464653497, 6520.380563846427, 7042.929570704432, 7062.011318415323, 7067.293270672892, 6819.215790183994, 7293.416526677959, 7360.96319518399, 7380.597686439559, 7409.333160015624, 4072.307707689759, 4579.908401832011, 4615.423072115986, 4632.536746325475, 4649.209634362099, 6277.2781130170615, 6833.624150671761, 6874.346937040973, 7151.749688760869, 7156.320149585781, 6264.5614806964895, 6865.313468653095, 6866.862662746817, 6883.621400823003, 6892.001319617255, 6813.931860681528, 6823.191854158751, 6856.828579285558, 6871.896921546081, 6881.208661004049, 5770.163326317745, 6007.219061521974, 6572.474202063728, 6636.83407914806, 6802.931970680428, 5748.655080695172, 6853.965730171317, 6880.179126558087, 6887.311268873073, 6902.792916212321, 6692.59844409337, 6702.463802895659, 6709.4632429404555, 6720.193576770819, 6747.797566073025], "value": 4946.497220261262, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.9174394944577, 832.9458585191962, 847.682119205298, 874.0472884555833, 880.5069161269687, 14713.852861471381, 15249.771253431207, 16857.578560535992, 17175.65648687027, 17303.39438119666, 16273.349066037374, 17784.377546785832, 18111.184996675147, 18183.4544963651, 18216.817831821678, 984.0749695286444, 990.8216521026249, 993.0218734546465, 994.8358520844071, 1002.5288114586133, 1004.6383302011307, 1011.0849681038671, 1011.1506334678868, 1013.3970287678819, 1016.7508960304699, 952.7094236257939, 1052.4369462337652, 1061.3790932304612, 1063.8297872340427, 1068.3643232276822, 1043.4574021508772, 1052.9262951593391, 1057.133150816331, 1057.582255009271, 1064.7497838008062, 764.533634482965, 766.6933226709305, 770.8265640230959, 774.8605251054807, 786.9213078692085, 17706.557336066573, 17912.0, 17977.55056123594, 18035.368762093265, 18229.908850455664, 17881.28474861004, 17940.0, 17998.820011799846, 18089.276428942827, 18415.631687366178, 13022.283774392405, 14853.2573371331, 15053.774193387108, 15141.621459463491, 15142.0, 3524.594671612772, 3556.1643013891717, 3557.7331700122336, 3601.0817241603486, 3722.6091260417807, 152207.0, 155260.07351584322, 155591.76062362498, 157191.0, 157631.42368576283, 13852.238126902916, 13886.375113119933, 14064.789028164585, 14142.43430262778, 14159.433622654979, 22408.66387004196, 26791.598126028126, 26805.061822836105, 27286.31784205371, 27348.726512735015, 7794.493357931717, 7836.960815195887, 7911.604419778988, 8089.797755056171, 8192.83614327722, 3669.5779985302033, 3707.536557930259, 3709.94266634008, 3712.3688972874675, 3726.459663348799, 4276.08064266178, 4348.239058164809, 4360.345948107746, 4368.7815609219415, 4383.07955329378, 937.4281688170211, 950.6910254167424, 952.9332946693664, 953.823542644613, 955.1022039283051, 646.4175618404504, 650.463367721622, 665.544102289933, 683.2177157155129, 689.4725534965755, 10787.541149658191, 11424.657260282205, 11437.771244575062, 11766.235194712317, 11873.643790686294, 503.46381104124725, 587.309910854744, 628.613402757301, 643.8036398898288, 647.9416852483205, 4954.232094025432, 5585.022621041383, 5592.245046918614, 5771.653700777967, 5901.911471327933, 120832.0, 128816.35591821981, 131624.0, 132429.67570324367, 137927.0, 99501.50249248852, 105196.0, 117222.0, 117396.41301793436, 117455.2381714275, 98009.0, 103693.0, 103900.0, 104079.47960260302, 104880.47559762152, 10964.56141754321, 10968.725781855517, 11213.887861121611, 11656.650300491, 11712.590059347882, 42695.786521067195, 43556.69329920108, 43565.910852228946, 43777.56222437862, 44834.327485088405, 54670.0, 57812.84374312574, 60458.39541604704, 60509.39490605037, 61941.38058619356, 13964.30178491051, 15145.0, 15209.6197595061, 15263.465778697693, 16497.0, 16610.75083873767, 17203.655926881143, 18439.815601844348, 19108.33120840764, 19514.8048519513, 17394.391196308068, 17657.64684706273, 17895.19471623754, 18240.726389103907, 18256.543586410447, 17510.387136450165, 17534.210960506553, 17999.370022049166, 18234.544136396165, 18375.989320587498, 17199.484015479557, 17814.732779008584, 17908.55228619295, 17926.820731792515, 18056.0, 10330.797452789659, 11613.825792612948, 11677.240979336317, 12172.634820955385, 12491.875081249435, 11657.41712914334, 12840.871591284342, 13699.931500342434, 13848.30758462093, 13986.650333741738], "value": 8698.321989043938, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1606.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 119.0, 168.0, 172.0, 114.0, 113.0, 114.0, 115.0, 115.0], "value": 125.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 129.0, 108.0, 150.0, 146.0, 95.0, 146.0, 147.0, 157.0, 127.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 201.0, 225.0, 259.0, 204.0, 259.0, 264.0, 274.0, 276.0, 200.0], "value": 236.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 81.0, 82.0, 81.0, 62.0, 60.0, 59.0, 61.0, 92.0, 81.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 87.0, 82.0, 103.0, 102.0, 110.0, 73.0, 68.0, 88.0], "value": 86.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 97.0, 88.0, 108.0, 136.0, 88.0, 136.0, 137.0, 93.0], "value": 106.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 94.0, 152.0, 97.0, 99.0, 95.0, 96.0, 96.0, 137.0, 153.0], "value": 112.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 91.0, 91.0, 93.0, 92.0, 92.0, 95.0, 93.0, 110.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 53.0, 51.0, 49.0, 49.0, 50.0, 51.0, 50.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 66.0, 68.0, 66.0, 69.0, 50.0, 57.0, 80.0, 80.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 87.0, 87.0, 79.0, 110.0, 110.0, 127.0, 120.0], "value": 97.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 134.0, 146.0, 134.0, 151.0, 153.0, 158.0, 152.0, 160.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 189.0, 188.0, 188.0, 226.0, 188.0, 184.0, 192.0, 190.0, 190.0], "value": 196.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 97.0, 100.0, 102.0, 65.0, 65.0, 69.0, 80.0, 67.0, 93.0], "value": 80.0, "unit": "score"}]}]} 18:18:21 INFO - Return code: 0 18:18:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:18:21 INFO - # TBPL SUCCESS # 18:18:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:18:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:18:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:18:21 INFO - 2017-06-06 18:18:21.394 screenresolution[1675:11728] starting screenresolution argv=screenresolution get 18:18:21 INFO - 2017-06-06 18:18:21.397 screenresolution[1675:11728] Display 0: 1600x1200x32@60 18:18:21 INFO - 2017-06-06 18:18:21.403 screenresolution[1676:11731] starting screenresolution argv=screenresolution list 18:18:21 INFO - Available Modes on Display 0 18:18:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:18:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:18:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:18:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:18:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:18:21 INFO - Intel Iris: 18:18:21 INFO - Chipset Model: Intel Iris 18:18:21 INFO - Type: GPU 18:18:21 INFO - Bus: Built-In 18:18:21 INFO - VRAM (Dynamic, Max): 1536 MB 18:18:21 INFO - Vendor: Intel (0x8086) 18:18:21 INFO - Device ID: 0x0a2e 18:18:21 INFO - Revision ID: 0x0009 18:18:21 INFO - Displays: 18:18:21 INFO - Display: 18:18:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:18:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:18:21 INFO - Display Serial Number: 1600x1200 60 18:18:21 INFO - Main Display: Yes 18:18:21 INFO - Mirror: Off 18:18:21 INFO - Online: Yes 18:18:21 INFO - Rotation: Supported 18:18:21 INFO - Return code: 0 18:18:21 INFO - Running post-action listener: _resource_record_post_action 18:18:21 INFO - [mozharness: 2017-06-07 01:18:21.638432Z] Finished run-tests step (success) 18:18:21 INFO - Running post-run listener: _resource_record_post_run 18:18:21 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 34866176; Write bytes: 570395648; Read time: 222; Write time: 1754 18:18:21 INFO - TinderboxPrint: CPU usage
53.1% 18:18:21 INFO - TinderboxPrint: I/O read bytes / time
34,866,176 / 222 18:18:21 INFO - TinderboxPrint: I/O write bytes / time
570,395,648 / 1,754 18:18:21 INFO - TinderboxPrint: CPU idle
2,357.0 (46.9%) 18:18:21 INFO - TinderboxPrint: CPU system
1,014.5 (20.2%) 18:18:21 INFO - TinderboxPrint: CPU user
1,654.2 (32.9%) 18:18:21 INFO - TinderboxPrint: Swap in / out
278,630,400 / 0 18:18:21 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230664704; Write bytes: 229257216; Read time: 16669; Write time: 491 18:18:21 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:18:21 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 33924096; Write bytes: 320771072; Read time: 209; Write time: 1205 18:18:22 INFO - Running post-run listener: _upload_blobber_files 18:18:22 INFO - Blob upload gear active. 18:18:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:18:22 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 18:18:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:18:22 INFO - (blobuploader) - INFO - Open directory for files ... 18:18:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:18:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:18:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:18:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:18:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:18:23 INFO - (blobuploader) - INFO - Done attempting. 18:18:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:18:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:18:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:18:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:18:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:18:24 INFO - (blobuploader) - INFO - Done attempting. 18:18:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:18:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:18:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:18:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:18:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:18:25 INFO - (blobuploader) - INFO - Done attempting. 18:18:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:18:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:18:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:18:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:18:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:18:27 INFO - (blobuploader) - INFO - Done attempting. 18:18:27 INFO - (blobuploader) - INFO - Iteration through files over. 18:18:27 INFO - Return code: 0 18:18:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:18:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:18:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21167a7d7ebb191458ab985b7e668b44eecb77ef37dacdb706e354a06f78fb464356502d54d13cc42dda9469bc1342e121e2a2db61de2ac80a8dfdb4789ebc55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c42d489127612cc144cd131ae82786972bcfba4363e91d972df8802267f08cc6e73ccf5e5a9fe953b021e1950c75a91ff4eb93eed24be55ddfabf929c03fb0cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dbd235bac5f68cce831efcc54b14e15affaef368911ea6bb72417266d7864e8414d51414cdb9d60cee653e3a1a4f49b7e58e22de3539fae213db88540dcd18c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4624101411ec825d90f9e60e0f45193a0b60c2fbfb153c38f901f9a22dcff9ffdb3d3341d520643d211c68dc4789786728d290dbb9914124182b37750f6468ae"} 18:18:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:18:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:18:27 INFO - Contents: 18:18:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21167a7d7ebb191458ab985b7e668b44eecb77ef37dacdb706e354a06f78fb464356502d54d13cc42dda9469bc1342e121e2a2db61de2ac80a8dfdb4789ebc55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c42d489127612cc144cd131ae82786972bcfba4363e91d972df8802267f08cc6e73ccf5e5a9fe953b021e1950c75a91ff4eb93eed24be55ddfabf929c03fb0cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dbd235bac5f68cce831efcc54b14e15affaef368911ea6bb72417266d7864e8414d51414cdb9d60cee653e3a1a4f49b7e58e22de3539fae213db88540dcd18c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4624101411ec825d90f9e60e0f45193a0b60c2fbfb153c38f901f9a22dcff9ffdb3d3341d520643d211c68dc4789786728d290dbb9914124182b37750f6468ae"} 18:18:27 INFO - Running post-run listener: copy_logs_to_upload_dir 18:18:27 INFO - Copying logs to upload dir... 18:18:27 INFO - mkdir: /builds/slave/test/build/upload/logs 18:18:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.449622 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-06 18:18:27.197391) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 18:18:27.201771) ========= 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.qXfrNqoglL/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.EfZiyTVXCu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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/Firefox/sha512/21167a7d7ebb191458ab985b7e668b44eecb77ef37dacdb706e354a06f78fb464356502d54d13cc42dda9469bc1342e121e2a2db61de2ac80a8dfdb4789ebc55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c42d489127612cc144cd131ae82786972bcfba4363e91d972df8802267f08cc6e73ccf5e5a9fe953b021e1950c75a91ff4eb93eed24be55ddfabf929c03fb0cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dbd235bac5f68cce831efcc54b14e15affaef368911ea6bb72417266d7864e8414d51414cdb9d60cee653e3a1a4f49b7e58e22de3539fae213db88540dcd18c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4624101411ec825d90f9e60e0f45193a0b60c2fbfb153c38f901f9a22dcff9ffdb3d3341d520643d211c68dc4789786728d290dbb9914124182b37750f6468ae"} build_url:https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011134 build_url: 'https://queue.taskcluster.net/v1/task/FL468XEsSSOafbxknmA7bg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21167a7d7ebb191458ab985b7e668b44eecb77ef37dacdb706e354a06f78fb464356502d54d13cc42dda9469bc1342e121e2a2db61de2ac80a8dfdb4789ebc55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c42d489127612cc144cd131ae82786972bcfba4363e91d972df8802267f08cc6e73ccf5e5a9fe953b021e1950c75a91ff4eb93eed24be55ddfabf929c03fb0cc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dbd235bac5f68cce831efcc54b14e15affaef368911ea6bb72417266d7864e8414d51414cdb9d60cee653e3a1a4f49b7e58e22de3539fae213db88540dcd18c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4624101411ec825d90f9e60e0f45193a0b60c2fbfb153c38f901f9a22dcff9ffdb3d3341d520643d211c68dc4789786728d290dbb9914124182b37750f6468ae"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 18:18:27.240817) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 18:18:27.241191) ========= 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.qXfrNqoglL/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.EfZiyTVXCu/Listeners TMPDIR=/var/folders/_l/g7gsx8tj15q7mjfg0y9vcz5c00000w/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.005207 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 18:18:27.290553) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 18:18:27.290895) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 18:18:27.291297) ========= ========= Total master_lag: 0.19 =========