builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1497987459.05 results: success (0) buildid: 20170620102523 builduid: 11b48ada2a7b41859deb1c0e00e5046c revision: f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:39.049430) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:39.050104) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:39.050446) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:39.100279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:39.100699) ========= 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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-20 12:37:39-- 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% 8.13M=0.001s 2017-06-20 12:37:39 (8.13 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117012 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:39.242342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:39.242720) ========= 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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.023463 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:39.292164) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-20 12:37:39.292575) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b9bf5qRcYI/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.kcXtpDTRzw/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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-20 12:37:39,518 truncating revision to first 12 chars 2017-06-20 12:37:39,518 Setting DEBUG logging. 2017-06-20 12:37:39,518 attempt 1/10 2017-06-20 12:37:39,518 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f82ee19ca78d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-20 12:37:40,758 unpacking tar archive at: mozilla-beta-f82ee19ca78d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.494861 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-20 12:37:40.811022) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:40.811460) ========= script_repo_revision: f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:40.811989) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:40.812320) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 12:37:40.830608) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-20 12:37:40.830975) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b9bf5qRcYI/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.kcXtpDTRzw/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:37:41 INFO - MultiFileLogger online at 20170620 12:37:41 in /builds/slave/test 12:37:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:37:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:37:41 INFO - {'append_to_log': False, 12:37:41 INFO - 'base_work_dir': '/builds/slave/test', 12:37:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:37:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:37:41 INFO - 'branch': 'Mozilla-Beta', 12:37:41 INFO - 'buildbot_json_path': 'buildprops.json', 12:37:41 INFO - 'config_files': ('talos/mac_config.py',), 12:37:41 INFO - 'default_actions': ('clobber', 12:37:41 INFO - 'read-buildbot-config', 12:37:41 INFO - 'download-and-extract', 12:37:41 INFO - 'populate-webroot', 12:37:41 INFO - 'create-virtualenv', 12:37:41 INFO - 'install', 12:37:41 INFO - 'setup-mitmproxy', 12:37:41 INFO - 'run-tests'), 12:37:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:37:41 INFO - 'download_minidump_stackwalk': True, 12:37:41 INFO - 'download_symbols': 'ondemand', 12:37:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:37:41 INFO - 'tooltool.py': '/tools/tooltool.py', 12:37:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:37:41 INFO - '/tools/misc-python/virtualenv.py')}, 12:37:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:37:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:37:41 INFO - 'gecko_profile': False, 12:37:41 INFO - 'gecko_profile_interval': 0, 12:37:41 INFO - 'installer_path': 'installer.exe', 12:37:41 INFO - 'log_level': 'info', 12:37:41 INFO - 'log_name': 'talos', 12:37:41 INFO - 'log_to_console': True, 12:37:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:37:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:37:41 INFO - 'opt_config_files': (), 12:37:41 INFO - 'pip_index': False, 12:37:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:37:41 INFO - 'cmd': ('bash', 12:37:41 INFO - '-c', 12:37:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:37:41 INFO - 'enabled': True, 12:37:41 INFO - 'halt_on_failure': False, 12:37:41 INFO - 'name': 'check_screen_resolution'},), 12:37:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:37:41 INFO - 'cmd': ('bash', 12:37:41 INFO - '-c', 12:37:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:37:41 INFO - 'enabled': True, 12:37:41 INFO - 'halt_on_failure': False, 12:37:41 INFO - 'name': 'check_screen_resolution'},), 12:37:41 INFO - 'run_cmd_checks_enabled': True, 12:37:41 INFO - 'suite': 'dromaeojs-e10s', 12:37:41 INFO - 'system_bits': '32', 12:37:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:37:41 INFO - 'title': 't-yosemite-r7-0029', 12:37:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:37:41 INFO - 'use_talos_json': True, 12:37:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:37:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:37:41 INFO - 'work_dir': 'build'} 12:37:41 INFO - [mozharness: 2017-06-20 19:37:41.188293Z] Running clobber step. 12:37:41 INFO - Running pre-action listener: _resource_record_pre_action 12:37:41 INFO - Running main action method: clobber 12:37:41 INFO - rmtree: /builds/slave/test/build 12:37:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:37:43 INFO - Running post-action listener: _resource_record_post_action 12:37:43 INFO - [mozharness: 2017-06-20 19:37:43.228781Z] Finished clobber step (success) 12:37:43 INFO - [mozharness: 2017-06-20 19:37:43.228982Z] Running read-buildbot-config step. 12:37:43 INFO - Running pre-action listener: _resource_record_pre_action 12:37:43 INFO - Running main action method: read_buildbot_config 12:37:43 INFO - Using buildbot properties: 12:37:43 INFO - { 12:37:43 INFO - "project": "", 12:37:43 INFO - "product": "firefox", 12:37:43 INFO - "script_repo_revision": "production", 12:37:43 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 12:37:43 INFO - "repository": "", 12:37:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 12:37:43 INFO - "buildid": "20170620102523", 12:37:43 INFO - "pgo_build": "False", 12:37:43 INFO - "basedir": "/builds/slave/test", 12:37:43 INFO - "buildnumber": 4, 12:37:43 INFO - "slavename": "t-yosemite-r7-0029", 12:37:43 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:37:43 INFO - "platform": "yosemite_r7_devedition", 12:37:43 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 12:37:43 INFO - "branch": "mozilla-beta", 12:37:43 INFO - "repo_path": "releases/mozilla-beta", 12:37:43 INFO - "revision": "f82ee19ca78db55bf66ae0de4cf9cbac987b67f8", 12:37:43 INFO - "stage_platform": "macosx64-devedition", 12:37:43 INFO - "builduid": "11b48ada2a7b41859deb1c0e00e5046c", 12:37:43 INFO - "slavebuilddir": "test" 12:37:43 INFO - } 12:37:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 12:37:43 INFO - Running post-action listener: _resource_record_post_action 12:37:43 INFO - [mozharness: 2017-06-20 19:37:43.232338Z] Finished read-buildbot-config step (success) 12:37:43 INFO - [mozharness: 2017-06-20 19:37:43.232462Z] Running download-and-extract step. 12:37:43 INFO - Running pre-action listener: _resource_record_pre_action 12:37:43 INFO - Running main action method: download_and_extract 12:37:43 INFO - mkdir: /builds/slave/test/build/tests 12:37:43 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')]} 12:37:43 INFO - trying https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 12:37:43 INFO - Downloading https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 12:37:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 12:37:44 INFO - Downloaded 1290 bytes. 12:37:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 12:37:44 INFO - Using the following test package requirements: 12:37:44 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:37:44 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 12:37:44 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 12:37:44 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:37:44 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 12:37:44 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:37:44 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 12:37:44 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:37:44 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:37:44 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 12:37:44 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 12:37:44 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:37:44 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 12:37:44 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:37:44 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 12:37:44 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:37:44 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 12:37:44 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 12:37:44 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 12:37:44 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 12:37:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 12:37:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 12:37:44 INFO - Fetch https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 12:37:46 INFO - Content-Length response header: 59545458 12:37:46 INFO - Bytes received: 59545458 12:38:04 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:38:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 12:38:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 12:38:04 INFO - Fetch https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 12:38:06 INFO - Content-Length response header: 59545458 12:38:06 INFO - Bytes received: 59545458 12:38:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 12:38:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 12:38:23 INFO - Fetch https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 12:38:25 INFO - Content-Length response header: 14454470 12:38:25 INFO - Bytes received: 14454470 12:38:25 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')]} 12:38:25 INFO - trying https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 12:38:25 INFO - Downloading https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 12:38:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 12:38:27 INFO - Downloaded 66852414 bytes. 12:38:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 12:38:27 INFO - mkdir: /builds/slave/test/properties 12:38:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:38:27 INFO - Writing to file /builds/slave/test/properties/build_url 12:38:27 INFO - Contents: 12:38:27 INFO - build_url:https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 12:38:28 INFO - Running post-action listener: _resource_record_post_action 12:38:28 INFO - Running post-action listener: set_extra_try_arguments 12:38:28 INFO - [mozharness: 2017-06-20 19:38:28.288021Z] Finished download-and-extract step (success) 12:38:28 INFO - [mozharness: 2017-06-20 19:38:28.288151Z] Running populate-webroot step. 12:38:28 INFO - Running pre-action listener: _resource_record_pre_action 12:38:28 INFO - Running main action method: populate_webroot 12:38:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:38:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:38:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:38:28 INFO - '--tppagecycles', 12:38:28 INFO - '7'], 12:38:28 INFO - 'tests': ['tsvgm']}}, 12:38:28 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:38:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:38:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:38:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:38:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:38:28 INFO - 'talos_options': ['--disable-e10s'], 12:38:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:38:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:38:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:38:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:38:28 INFO - 'talos_options': ['--disable-e10s'], 12:38:28 INFO - 'tests': ['damp', 'tps']}, 12:38:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:38:28 INFO - 'tests': ['damp', 'tps']}, 12:38:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:38:28 INFO - 'tests': ['dromaeo_dom']}, 12:38:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:38:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:38:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:38:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:38:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:38:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:38:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:38:28 INFO - 'tests': ['a11yr', 12:38:28 INFO - 'ts_paint', 12:38:28 INFO - 'tpaint', 12:38:28 INFO - 'sessionrestore', 12:38:28 INFO - 'sessionrestore_no_auto_restore', 12:38:28 INFO - 'tabpaint']}, 12:38:28 INFO - 'other-e10s': {'tests': ['a11yr', 12:38:28 INFO - 'ts_paint', 12:38:28 INFO - 'tpaint', 12:38:28 INFO - 'sessionrestore', 12:38:28 INFO - 'sessionrestore_no_auto_restore', 12:38:28 INFO - 'tabpaint']}, 12:38:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:38:28 INFO - 'ts_paint', 12:38:28 INFO - 'tpaint', 12:38:28 INFO - 'sessionrestore', 12:38:28 INFO - 'sessionrestore_no_auto_restore', 12:38:28 INFO - 'tabpaint']}, 12:38:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:38:28 INFO - 'ts_paint', 12:38:28 INFO - 'tpaint', 12:38:28 INFO - 'sessionrestore', 12:38:28 INFO - 'sessionrestore_no_auto_restore', 12:38:28 INFO - 'tabpaint']}, 12:38:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:38:28 INFO - 'tests': ['a11yr', 12:38:28 INFO - 'ts_paint', 12:38:28 INFO - 'tpaint', 12:38:28 INFO - 'sessionrestore', 12:38:28 INFO - 'sessionrestore_no_auto_restore', 12:38:28 INFO - 'tabpaint']}, 12:38:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:38:28 INFO - 'tests': ['a11yr', 12:38:28 INFO - 'ts_paint', 12:38:28 INFO - 'tpaint', 12:38:28 INFO - 'sessionrestore', 12:38:28 INFO - 'sessionrestore_no_auto_restore', 12:38:28 INFO - 'tabpaint']}, 12:38:28 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:38:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:38:28 INFO - 'bloom_basic_ref']}, 12:38:28 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:38:28 INFO - 'talos_options': ['--mitmproxy', 12:38:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:38:28 INFO - '--firstNonBlankPaint'], 12:38:28 INFO - 'tests': ['quantum_pageload_google', 12:38:28 INFO - 'quantum_pageload_youtube', 12:38:28 INFO - 'quantum_pageload_amazon', 12:38:28 INFO - 'quantum_pageload_facebook']}, 12:38:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:38:28 INFO - 'tests': ['tsvgx', 12:38:28 INFO - 'tsvgr_opacity', 12:38:28 INFO - 'tart', 12:38:28 INFO - 'tscrollx', 12:38:28 INFO - 'cart', 12:38:28 INFO - 'tsvg_static']}, 12:38:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:38:28 INFO - 'tsvgr_opacity', 12:38:28 INFO - 'tart', 12:38:28 INFO - 'tscrollx', 12:38:28 INFO - 'cart', 12:38:28 INFO - 'tsvg_static']}, 12:38:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:38:28 INFO - 'talos_options': ['--disable-e10s'], 12:38:28 INFO - 'tests': ['tp5o']}, 12:38:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:38:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:38:28 INFO - 'talos_options': ['--disable-e10s', 12:38:28 INFO - '--xperf_path', 12:38:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:38:28 INFO - 'tests': ['tp5n']}, 12:38:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:38:28 INFO - 'talos_options': ['--xperf_path', 12:38:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:38:28 INFO - 'tests': ['tp5n']}}, 12:38:28 INFO - 'talos.zip': {'path': '', 12:38:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:38:28 INFO - Running post-action listener: _resource_record_post_action 12:38:28 INFO - [mozharness: 2017-06-20 19:38:28.308977Z] Finished populate-webroot step (success) 12:38:28 INFO - [mozharness: 2017-06-20 19:38:28.309093Z] Running create-virtualenv step. 12:38:28 INFO - Running pre-action listener: _resource_record_pre_action 12:38:28 INFO - Running main action method: create_virtualenv 12:38:28 INFO - Creating virtualenv /builds/slave/test/build/venv 12:38:28 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 12:38:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:38:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:38:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:38:28 INFO - Using real prefix '/tools/python27' 12:38:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:38:29 INFO - Installing distribute.............................................................................................................................................................................................done. 12:38:32 INFO - Installing pip.................done. 12:38:32 INFO - Return code: 0 12:38:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:38:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:38:32 INFO - Reading from file tmpfile_stdout 12:38:32 INFO - Output received: 12:38:32 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) 12:38:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:38:32 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')]} 12:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:32 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')]} 12:38:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:32 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 0x101649c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdfbd8c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, '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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:38:32 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 12:38:32 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 12:38:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:38:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:32 INFO - 'HOME': '/Users/cltbld', 12:38:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:32 INFO - 'LOGNAME': 'cltbld', 12:38:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:32 INFO - 'MOZ_NO_REMOTE': '1', 12:38:32 INFO - 'NO_EM_RESTART': '1', 12:38:32 INFO - 'PAGER': '/bin/cat', 12:38:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:32 INFO - 'PWD': '/builds/slave/test', 12:38:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:32 INFO - 'SHELL': '/bin/bash', 12:38:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:38:32 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:38:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:32 INFO - 'USER': 'cltbld', 12:38:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:32 INFO - 'XPC_FLAGS': '0x0', 12:38:32 INFO - 'XPC_SERVICE_NAME': '0', 12:38:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:32 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 12:38:32 INFO - Cleaning up... 12:38:32 INFO - Return code: 0 12:38:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:38:32 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')]} 12:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:32 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')]} 12:38:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:32 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 0x101649c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdfbd8c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, '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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:38:32 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 12:38:32 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 12:38:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:38:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:32 INFO - 'HOME': '/Users/cltbld', 12:38:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:32 INFO - 'LOGNAME': 'cltbld', 12:38:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:32 INFO - 'MOZ_NO_REMOTE': '1', 12:38:32 INFO - 'NO_EM_RESTART': '1', 12:38:32 INFO - 'PAGER': '/bin/cat', 12:38:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:32 INFO - 'PWD': '/builds/slave/test', 12:38:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:32 INFO - 'SHELL': '/bin/bash', 12:38:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:38:32 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:38:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:32 INFO - 'USER': 'cltbld', 12:38:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:32 INFO - 'XPC_FLAGS': '0x0', 12:38:32 INFO - 'XPC_SERVICE_NAME': '0', 12:38:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:32 INFO - Downloading/unpacking psutil>=3.1.1 12:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:38:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:38:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:38:35 INFO - Installing collected packages: psutil 12:38:35 INFO - Running setup.py install for psutil 12:38:35 INFO - building 'psutil._psutil_osx' extension 12:38:35 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 12:38:35 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 12:38:35 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 12:38:35 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 12:38:35 INFO - building 'psutil._psutil_posix' extension 12:38:35 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 12:38:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:38:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:38:35 INFO - ^ 12:38:36 INFO - 1 warning generated. 12:38:36 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 12:38:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:38:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:38:36 INFO - Successfully installed psutil 12:38:36 INFO - Cleaning up... 12:38:36 INFO - Return code: 0 12:38:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:38:36 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')]} 12:38:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:36 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')]} 12:38:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:36 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 0x101649c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdfbd8c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, '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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:38:36 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 12:38:36 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 12:38:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:38:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:36 INFO - 'HOME': '/Users/cltbld', 12:38:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:36 INFO - 'LOGNAME': 'cltbld', 12:38:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:36 INFO - 'MOZ_NO_REMOTE': '1', 12:38:36 INFO - 'NO_EM_RESTART': '1', 12:38:36 INFO - 'PAGER': '/bin/cat', 12:38:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:36 INFO - 'PWD': '/builds/slave/test', 12:38:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:36 INFO - 'SHELL': '/bin/bash', 12:38:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:38:36 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:38:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:36 INFO - 'USER': 'cltbld', 12:38:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:36 INFO - 'XPC_FLAGS': '0x0', 12:38:36 INFO - 'XPC_SERVICE_NAME': '0', 12:38:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:38:36 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 12:38:36 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 12:38:36 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 12:38:36 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 12:38:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:38:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:38:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:38:37 INFO - Installing collected packages: mozsystemmonitor 12:38:37 INFO - Running setup.py install for mozsystemmonitor 12:38:38 INFO - Successfully installed mozsystemmonitor 12:38:38 INFO - Cleaning up... 12:38:38 INFO - Return code: 0 12:38:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:38:38 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')]} 12:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:38 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')]} 12:38:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x101649c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdfbd8c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, '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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:38:38 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 12:38:38 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 12:38:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:38:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:38 INFO - 'HOME': '/Users/cltbld', 12:38:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:38 INFO - 'LOGNAME': 'cltbld', 12:38:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:38 INFO - 'MOZ_NO_REMOTE': '1', 12:38:38 INFO - 'NO_EM_RESTART': '1', 12:38:38 INFO - 'PAGER': '/bin/cat', 12:38:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:38 INFO - 'PWD': '/builds/slave/test', 12:38:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:38 INFO - 'SHELL': '/bin/bash', 12:38:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:38:38 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:38:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:38 INFO - 'USER': 'cltbld', 12:38:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:38 INFO - 'XPC_FLAGS': '0x0', 12:38:38 INFO - 'XPC_SERVICE_NAME': '0', 12:38:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:38 INFO - Downloading/unpacking jsonschema==2.5.1 12:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:38:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:38:39 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 12:38:39 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 12:38:39 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 12:38:39 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 12:38:40 INFO - Downloading functools32-3.2.3-2.tar.gz 12:38:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:38:40 INFO - warning: no files found matching '*.txt' 12:38:40 INFO - no previously-included directories found matching 'build' 12:38:40 INFO - no previously-included directories found matching 'dist' 12:38:40 INFO - no previously-included directories found matching '.git*' 12:38:40 INFO - Installing collected packages: jsonschema, functools32 12:38:40 INFO - Running setup.py install for functools32 12:38:40 INFO - warning: no files found matching '*.txt' 12:38:40 INFO - no previously-included directories found matching 'build' 12:38:40 INFO - no previously-included directories found matching 'dist' 12:38:40 INFO - no previously-included directories found matching '.git*' 12:38:40 INFO - Successfully installed jsonschema functools32 12:38:40 INFO - Cleaning up... 12:38:40 INFO - Return code: 0 12:38:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:38:40 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')]} 12:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:40 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')]} 12:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:40 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 0x101649c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdfbd8c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, '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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:38:40 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 12:38:40 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 12:38:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:38:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:40 INFO - 'HOME': '/Users/cltbld', 12:38:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:40 INFO - 'LOGNAME': 'cltbld', 12:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:40 INFO - 'MOZ_NO_REMOTE': '1', 12:38:40 INFO - 'NO_EM_RESTART': '1', 12:38:40 INFO - 'PAGER': '/bin/cat', 12:38:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:40 INFO - 'PWD': '/builds/slave/test', 12:38:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:40 INFO - 'SHELL': '/bin/bash', 12:38:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:38:40 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:38:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:40 INFO - 'USER': 'cltbld', 12:38:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:40 INFO - 'XPC_FLAGS': '0x0', 12:38:40 INFO - 'XPC_SERVICE_NAME': '0', 12:38:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:38:40 INFO - Cleaning up... 12:38:40 INFO - Return code: 0 12:38:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:38:40 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')]} 12:38:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:40 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')]} 12:38:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:40 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 0x101649c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdfbd8c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, '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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:38:40 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 12:38:40 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 12:38:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:38:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:40 INFO - 'HOME': '/Users/cltbld', 12:38:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:40 INFO - 'LOGNAME': 'cltbld', 12:38:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:40 INFO - 'MOZ_NO_REMOTE': '1', 12:38:40 INFO - 'NO_EM_RESTART': '1', 12:38:40 INFO - 'PAGER': '/bin/cat', 12:38:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:40 INFO - 'PWD': '/builds/slave/test', 12:38:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:40 INFO - 'SHELL': '/bin/bash', 12:38:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:38:40 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:38:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:40 INFO - 'USER': 'cltbld', 12:38:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:40 INFO - 'XPC_FLAGS': '0x0', 12:38:40 INFO - 'XPC_SERVICE_NAME': '0', 12:38:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:41 INFO - Downloading/unpacking blobuploader==1.2.4 12:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:42 INFO - Downloading blobuploader-1.2.4.tar.gz 12:38:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:38:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:38:42 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 12:38:42 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 12:38:42 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 12:38:42 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 12:38:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:38:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:38:43 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 12:38:43 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 12:38:43 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 12:38:43 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 12:38:43 INFO - Downloading docopt-0.6.1.tar.gz 12:38:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:38:43 INFO - Installing collected packages: blobuploader, requests, docopt 12:38:43 INFO - Running setup.py install for blobuploader 12:38:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:38:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:38:43 INFO - Running setup.py install for requests 12:38:44 INFO - Running setup.py install for docopt 12:38:44 INFO - Successfully installed blobuploader requests docopt 12:38:44 INFO - Cleaning up... 12:38:44 INFO - Return code: 0 12:38:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:44 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')]} 12:38:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:44 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')]} 12:38:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:44 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 0x101649c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdfbd8c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, '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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:38:44 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 12:38:44 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 12:38:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:38:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:44 INFO - 'HOME': '/Users/cltbld', 12:38:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:44 INFO - 'LOGNAME': 'cltbld', 12:38:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:44 INFO - 'MOZ_NO_REMOTE': '1', 12:38:44 INFO - 'NO_EM_RESTART': '1', 12:38:44 INFO - 'PAGER': '/bin/cat', 12:38:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:44 INFO - 'PWD': '/builds/slave/test', 12:38:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:44 INFO - 'SHELL': '/bin/bash', 12:38:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:38:44 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:38:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:44 INFO - 'USER': 'cltbld', 12:38:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:44 INFO - 'XPC_FLAGS': '0x0', 12:38:44 INFO - 'XPC_SERVICE_NAME': '0', 12:38:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:38:44 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-mXIzmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:38:44 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ikNIW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:38:44 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-KRtEG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:38:44 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-uhD7_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:38:44 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-eE4r4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:38:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-MPoWew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:38:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-5yUUeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:38:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-n4Tfwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:38:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-WJqsWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:38:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1uM7of-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:38:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-n4LpNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:38:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-9vCvwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:38:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-VxpOa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:38:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-SsiU2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:38:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:45 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-e0L4Fw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:38:46 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-8QxzIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:38:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:38:46 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-A_DUWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:38:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:38:46 INFO - Running setup.py install for manifestparser 12:38:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:38:46 INFO - Running setup.py install for mozcrash 12:38:46 INFO - Running setup.py install for mozdebug 12:38:46 INFO - Running setup.py install for mozdevice 12:38:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:38:46 INFO - Running setup.py install for mozfile 12:38:47 INFO - Running setup.py install for mozhttpd 12:38:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:38:47 INFO - Running setup.py install for mozinfo 12:38:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:38:47 INFO - Running setup.py install for mozInstall 12:38:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:38:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:38:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:38:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:38:47 INFO - Running setup.py install for mozleak 12:38:47 INFO - Running setup.py install for mozlog 12:38:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:38:47 INFO - Running setup.py install for moznetwork 12:38:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:38:47 INFO - Running setup.py install for mozprocess 12:38:48 INFO - Running setup.py install for mozprofile 12:38:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:38:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:38:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:38:48 INFO - Running setup.py install for mozrunner 12:38:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:38:48 INFO - Running setup.py install for mozscreenshot 12:38:48 INFO - Running setup.py install for moztest 12:38:48 INFO - Running setup.py install for mozversion 12:38:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:38:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:38:48 INFO - Cleaning up... 12:38:48 INFO - Return code: 0 12:38:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:48 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')]} 12:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:48 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')]} 12:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:48 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 0x101649c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdfbd8c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, '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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:38:48 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 12:38:48 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 12:38:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:38:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:48 INFO - 'HOME': '/Users/cltbld', 12:38:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:48 INFO - 'LOGNAME': 'cltbld', 12:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:48 INFO - 'MOZ_NO_REMOTE': '1', 12:38:48 INFO - 'NO_EM_RESTART': '1', 12:38:48 INFO - 'PAGER': '/bin/cat', 12:38:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:48 INFO - 'PWD': '/builds/slave/test', 12:38:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:48 INFO - 'SHELL': '/bin/bash', 12:38:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:38:48 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:38:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:48 INFO - 'USER': 'cltbld', 12:38:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:48 INFO - 'XPC_FLAGS': '0x0', 12:38:48 INFO - 'XPC_SERVICE_NAME': '0', 12:38:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:38:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-57DgxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:38:49 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)) 12:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:38:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-4LsXTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:38:49 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)) 12:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:38:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wqESN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:38:49 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)) 12:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:38:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-C_klwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:38:49 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)) 12:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:38:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zp5qrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:38:49 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)) 12:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:38:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-TIhfQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:38:49 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)) 12:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:38:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_WMjr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:38:49 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)) 12:38:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:38:49 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-oeSVsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:38:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:38:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-QXWd3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:38:50 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)) 12:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:38:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-D5ZJLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:38:50 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)) 12:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:38:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-jIiFpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:38:50 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)) 12:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:38:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-AgUsl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:38:50 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)) 12:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:38:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-C5A6wC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:38:50 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)) 12:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:38:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-3qDDhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:38:50 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)) 12:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_QLe8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:50 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)) 12:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:38:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-NIocb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:38:50 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)) 12:38:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:38:50 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-mh4kS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:38:50 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)) 12:38:50 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)) 12:38:50 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)) 12:38:50 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)) 12:38:50 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)) 12:38:50 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)) 12:38:50 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:38:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:38:50 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 12:38:50 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 12:38:50 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 12:38:50 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 12:38:52 INFO - Downloading blessings-1.6.tar.gz 12:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:38:52 INFO - Installing collected packages: blessings 12:38:52 INFO - Running setup.py install for blessings 12:38:52 INFO - Successfully installed blessings 12:38:52 INFO - Cleaning up... 12:38:52 INFO - Return code: 0 12:38:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:38:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:38:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:38:52 INFO - Reading from file tmpfile_stdout 12:38:52 INFO - Current package versions: 12:38:52 INFO - blessings == 1.6 12:38:52 INFO - blobuploader == 1.2.4 12:38:52 INFO - docopt == 0.6.1 12:38:52 INFO - functools32 == 3.2.3-2 12:38:52 INFO - jsonschema == 2.5.1 12:38:52 INFO - manifestparser == 1.1 12:38:52 INFO - mozInstall == 1.13 12:38:52 INFO - mozcrash == 1.0 12:38:52 INFO - mozdebug == 0.1 12:38:52 INFO - mozdevice == 0.51 12:38:52 INFO - mozfile == 1.2 12:38:52 INFO - mozhttpd == 0.7 12:38:52 INFO - mozinfo == 0.10 12:38:52 INFO - mozleak == 0.1 12:38:52 INFO - mozlog == 3.4 12:38:52 INFO - moznetwork == 0.27 12:38:52 INFO - mozprocess == 0.25 12:38:52 INFO - mozprofile == 0.28 12:38:52 INFO - mozrunner == 6.13 12:38:52 INFO - mozscreenshot == 0.1 12:38:52 INFO - mozsystemmonitor == 0.3 12:38:52 INFO - moztest == 0.8 12:38:52 INFO - mozversion == 1.4 12:38:52 INFO - psutil == 3.1.1 12:38:52 INFO - requests == 1.2.3 12:38:52 INFO - wsgiref == 0.1.2 12:38:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:52 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')]} 12:38:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:52 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')]} 12:38:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x101649c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdfbd8c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, '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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:38:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:38:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:38:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:38:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:52 INFO - 'HOME': '/Users/cltbld', 12:38:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:52 INFO - 'LOGNAME': 'cltbld', 12:38:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:52 INFO - 'MOZ_NO_REMOTE': '1', 12:38:52 INFO - 'NO_EM_RESTART': '1', 12:38:52 INFO - 'PAGER': '/bin/cat', 12:38:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:52 INFO - 'PWD': '/builds/slave/test', 12:38:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:52 INFO - 'SHELL': '/bin/bash', 12:38:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:38:52 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:38:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:52 INFO - 'USER': 'cltbld', 12:38:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:52 INFO - 'XPC_FLAGS': '0x0', 12:38:52 INFO - 'XPC_SERVICE_NAME': '0', 12:38:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:38:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:38:53 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:38:53 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 12:38:53 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 12:38:53 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 12:38:53 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 12:38:54 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:38:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:38:54 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)) 12:38:54 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)) 12:38:54 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)) 12:38:54 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)) 12:38:54 INFO - Installing collected packages: marionette-driver 12:38:54 INFO - Running setup.py install for marionette-driver 12:38:54 INFO - Successfully installed marionette-driver 12:38:54 INFO - Cleaning up... 12:38:54 INFO - Return code: 0 12:38:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:38:54 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')]} 12:38:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:54 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')]} 12:38:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:54 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 0x101649c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10164d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdfbd8c4d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, '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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 12:38:54 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 12:38:54 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 12:38:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:38:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:54 INFO - 'HOME': '/Users/cltbld', 12:38:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:54 INFO - 'LOGNAME': 'cltbld', 12:38:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:54 INFO - 'MOZ_NO_REMOTE': '1', 12:38:54 INFO - 'NO_EM_RESTART': '1', 12:38:54 INFO - 'PAGER': '/bin/cat', 12:38:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:54 INFO - 'PWD': '/builds/slave/test', 12:38:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:54 INFO - 'SHELL': '/bin/bash', 12:38:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:38:54 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:38:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:54 INFO - 'USER': 'cltbld', 12:38:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:54 INFO - 'XPC_FLAGS': '0x0', 12:38:54 INFO - 'XPC_SERVICE_NAME': '0', 12:38:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:38:55 INFO - Cleaning up... 12:38:55 INFO - Return code: 0 12:38:55 INFO - Running post-action listener: _resource_record_post_action 12:38:55 INFO - Running post-action listener: _start_resource_monitoring 12:38:55 INFO - Starting resource monitoring. 12:38:55 INFO - [mozharness: 2017-06-20 19:38:55.273472Z] Finished create-virtualenv step (success) 12:38:55 INFO - [mozharness: 2017-06-20 19:38:55.274601Z] Running install step. 12:38:55 INFO - Running pre-action listener: _resource_record_pre_action 12:38:55 INFO - Running main action method: install 12:38:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:38:55 INFO - Reading from file tmpfile_stdout 12:38:55 INFO - Detecting whether we're running mozinstall >=1.0... 12:38:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:38:55 INFO - Reading from file tmpfile_stdout 12:38:55 INFO - Output received: 12:38:55 INFO - Usage: mozinstall [options] installer 12:38:55 INFO - Options: 12:38:55 INFO - -h, --help show this help message and exit 12:38:55 INFO - -d DEST, --destination=DEST 12:38:55 INFO - Directory to install application into. [default: 12:38:55 INFO - "/builds/slave/test"] 12:38:55 INFO - --app=APP Application being installed. [default: firefox] 12:38:55 INFO - mkdir: /builds/slave/test/build/application 12:38:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:39:17 INFO - Reading from file tmpfile_stdout 12:39:17 INFO - Output received: 12:39:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:39:17 INFO - Running post-action listener: _resource_record_post_action 12:39:17 INFO - [mozharness: 2017-06-20 19:39:17.051577Z] Finished install step (success) 12:39:17 INFO - [mozharness: 2017-06-20 19:39:17.051738Z] Running setup-mitmproxy step. 12:39:17 INFO - Running pre-action listener: _resource_record_pre_action 12:39:17 INFO - Running main action method: setup_mitmproxy 12:39:17 INFO - Skipping: mitmproxy is not required 12:39:17 INFO - Running post-action listener: _resource_record_post_action 12:39:17 INFO - [mozharness: 2017-06-20 19:39:17.052412Z] Finished setup-mitmproxy step (success) 12:39:17 INFO - [mozharness: 2017-06-20 19:39:17.052542Z] Running run-tests step. 12:39:17 INFO - Running pre-action listener: _resource_record_pre_action 12:39:17 INFO - Running main action method: run_tests 12:39:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:39:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:39:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:39:17 INFO - 2017-06-20 12:39:17.067 screenresolution[7509:143709] starting screenresolution argv=screenresolution get 12:39:17 INFO - 2017-06-20 12:39:17.070 screenresolution[7509:143709] Display 0: 1600x1200x32@60 12:39:17 INFO - 2017-06-20 12:39:17.076 screenresolution[7510:143711] starting screenresolution argv=screenresolution list 12:39:17 INFO - Available Modes on Display 0 12:39:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:39:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:39:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:39:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:39:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:39:17 INFO - Intel Iris: 12:39:17 INFO - Chipset Model: Intel Iris 12:39:17 INFO - Type: GPU 12:39:17 INFO - Bus: Built-In 12:39:17 INFO - VRAM (Dynamic, Max): 1536 MB 12:39:17 INFO - Vendor: Intel (0x8086) 12:39:17 INFO - Device ID: 0x0a2e 12:39:17 INFO - Revision ID: 0x0009 12:39:17 INFO - Displays: 12:39:17 INFO - Display: 12:39:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:39:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:39:17 INFO - Display Serial Number: 1600x1200 60 12:39:17 INFO - Main Display: Yes 12:39:17 INFO - Mirror: Off 12:39:17 INFO - Online: Yes 12:39:17 INFO - Rotation: Supported 12:39:17 INFO - Return code: 0 12:39:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:39:17 INFO - Python 2.7.3 12:39:17 INFO - Return code: 0 12:39:17 INFO - grabbing minidump binary from tooltool 12:39: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')]} 12:39:17 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 0x10139fd30>, '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 0x101369cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:39:17 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 12:39:17 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 12:39:17 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 12:39:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:39:17 INFO - Return code: 0 12:39:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:39:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:39:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:39:17 INFO - ENV: RUST_BACKTRACE is now 1 12:39:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:39:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:39:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 12:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 12:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b9bf5qRcYI/Render', 12:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:17 INFO - 'HOME': '/Users/cltbld', 12:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:17 INFO - 'LOGNAME': 'cltbld', 12:39:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:39:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:17 INFO - 'MOZ_NO_REMOTE': '1', 12:39:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:39:17 INFO - 'NO_EM_RESTART': '1', 12:39:17 INFO - 'PAGER': '/bin/cat', 12:39:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:17 INFO - 'PWD': '/builds/slave/test', 12:39:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:17 INFO - 'RUST_BACKTRACE': '1', 12:39:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:39:17 INFO - 'SHELL': '/bin/bash', 12:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kcXtpDTRzw/Listeners', 12:39:17 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 12:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:17 INFO - 'USER': 'cltbld', 12:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:17 INFO - 'XPC_FLAGS': '0x0', 12:39:17 INFO - 'XPC_SERVICE_NAME': '0', 12:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 12:39:17 INFO - mozversion application_buildid: 20170620102523 12:39:17 INFO - mozversion application_changeset: f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 12:39:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:39:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:39:17 INFO - mozversion application_name: Firefox 12:39:17 INFO - mozversion application_remotingname: firefox-dev 12:39:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:39:17 INFO - mozversion application_vendor: Mozilla 12:39:17 INFO - mozversion application_version: 55.0 12:39:17 INFO - mozversion platform_buildid: 20170620102523 12:39:17 INFO - mozversion platform_changeset: f82ee19ca78db55bf66ae0de4cf9cbac987b67f8 12:39:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:39:17 INFO - mozversion platform_version: 55.0 12:39:17 INFO - using testdate: 1497987557 12:39:17 INFO - actual date: 1497987557 12:39:17 INFO - starting webserver on 'localhost:49613' 12:39:17 INFO - SUITE-START | Running 2 tests 12:39:17 INFO - TEST-START | dromaeo_css 12:39:17 INFO - operating with platform_type : mac_ 12:39:17 INFO - Initialising browser for dromaeo_css test... 12:39:17 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49613/getInfo.html -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp84J9mj/profile 12:39:17 INFO - TEST-INFO | started process 7520 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49613/getInfo.html -foreground) 12:39:50 INFO - TEST-INFO | 7520: killed by SIGTERM 12:39:50 INFO - Browser initialized. 12:39:50 INFO - Running cycle 1/1 for dromaeo_css test... 12:39:50 INFO - TEST-INFO | started process 7526 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmp84J9mj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:39:52 INFO - PID 7526 | 2017-06-20 12:39:52.688 plugin-container[7527:144322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 12:39:52 INFO - PID 7526 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:52 INFO - PID 7526 | 2017-06-20 12:39:52.689 plugin-container[7527:144322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 12:39:52 INFO - PID 7526 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:52 INFO - PID 7526 | 2017-06-20 12:39:52.689 plugin-container[7527:144322] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:39:53 INFO - PID 7526 | 2017-06-20 12:39:53.436 plugin-container[7528:144367] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 12:39:53 INFO - PID 7526 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:53 INFO - PID 7526 | 2017-06-20 12:39:53.436 plugin-container[7528:144367] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 12:39:53 INFO - PID 7526 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:53 INFO - PID 7526 | 2017-06-20 12:39:53.437 plugin-container[7528:144367] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:40:02 INFO - PID 7526 | RSS: Main: 215007232 12:40:02 INFO - PID 7526 | 12:43:08 INFO - PID 7526 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49613/tests/dromaeo/cssquery-ext.html) 12:43:10 INFO - PID 7526 | RSS: Main: 232615936 12:43:10 INFO - PID 7526 | 12:46:12 INFO - PID 7526 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-ext.html (next: http://localhost:49613/tests/dromaeo/cssquery-jquery.html) 12:46:13 INFO - PID 7526 | RSS: Main: 244027392 12:46:13 INFO - PID 7526 | 12:49:14 INFO - PID 7526 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49613/tests/dromaeo/cssquery-mootools.html) 12:49:15 INFO - PID 7526 | RSS: Main: 246382592 12:49:15 INFO - PID 7526 | 12:52:17 INFO - PID 7526 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49613/tests/dromaeo/cssquery-prototype.html) 12:52:17 INFO - PID 7526 | RSS: Main: 248000512 12:52:17 INFO - PID 7526 | 12:55:20 INFO - PID 7526 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49613/tests/dromaeo/cssquery-yui.html) 12:55:21 INFO - PID 7526 | RSS: Main: 248549376 12:55:21 INFO - PID 7526 | 12:58:22 INFO - PID 7526 | Cycle 1(1): loaded http://localhost:49613/tests/dromaeo/cssquery-yui.html (next: http://localhost:49613/tests/dromaeo/cssquery-dojo.html) 12:58:23 INFO - PID 7526 | RSS: Main: 248713216 12:58:23 INFO - PID 7526 | 12:58:23 INFO - PID 7526 | __start_tp_report 12:58:23 INFO - PID 7526 | _x_x_mozilla_page_load 12:58:23 INFO - PID 7526 | _x_x_mozilla_page_load_details 12:58:23 INFO - PID 7526 | |i|pagename|runs| 12:58:23 INFO - PID 7526 | |0;dojo.html;3478.5825700915893;3552.698020668243;3574.9463758043626;3576.0880975351297;3624.528811254538;6198.907016394752;6219.315875253724;6232.096346029823;6240.032794916784;6243.968780156093;6250.40621140992;6352.523560732952;6356.777512787054;6374.394432528902;6416.133821934038;5539.224508568814;5541.085720856054;5557.805476808313;5607.439256074401;5650.830475085733;5526.778928842861;5567.4432556744405;5630.436956304377;5647.802326918559;5658.405867383927;5408.540274076709;5415.079436495766;5500.147477141049;5564.137558678412;5576.302962129734;5468.835934921959;5490.368607610136;5527.170924361338;5557.166425036236;5574;5441.891162176774;5448;5496.835094947118;5546.722663866831;5546.973809845151;6280.026595877601;6302.46429053531;6304.7793327233785;6308.558400911938;6310.0850376695125;6797.354251346114;6810.9318906810795;6817.0796942413135;6846.760363387258;6864.176298844171;5890.234269544974;6670.132882725215;6842.589444633338;6928.826779330557;6952.165740111219;6344.206974128234;6373.936260637427;6442.9355706443275;6448.484121270287;6469.353064693493;6833;7078.504504684725;7096.112985876765;7105.538140020882;7113.110861142357;6562.278149403562;6569.343065693488;6594;6614.8346291343105;6615.735370585123;6910.136232970879;6943.27095654949;6954.165500139914;6956.860862782817;6963.338482844122;6852.862942741117;6855.07456493377;6855.5886646801355;6862.862742745217;6900.3444672756;6550.475961923034;6692.765753198614;6820.624865632339;6846.418054465426;6865.759698410531;4855.713235992464;5439.129739241782;5451.536619387396;5456.590755693339;5484.424135465799;3993.2412841560013;4762.095201911626;4820.325154478376;4911.600193944709;4919.311296418504;6400.135981642418;6462;6468.579542329733;6469;6473.838154046092;6978.47661425385;6989.685464154125;7021.05215795861;7022.754203602849;7031.472639551952;6121.724522396503;6182.783602573888;6213.471854892385;6409.9679501602195;6436.195475565554;6071.848203794852;6089.147519347206;6120.693965301717;6120.938790612036;6138.817583427401;6996.440284777307;7103.751368702071;7119.466040047121;7138.750143744944;7145.857082858418;6138.570300078952;6950.930490695028;6960.825979350557;6981.79054628362;6985.406240469617;7123.501354905314;7151.570905745672;7152.570845749272;7158.06945097134;7183.964080179566;5828.009238429562;6441.903371449525;6605.636689982097;6654.767083152066;6839.2818754032105;6794.524383293122;6873.243943166148;6946.305369463013;6951.304869513008;6981.232064473005;6657.434118100015;6735.831604209738;6744.460443164633;6755.3582409671;6762.763303284361;6604.801855944329;6700.296468870698;6715.395614394728;6733.562318449481;6750.3924646782025;6369.394907483782;6404.1674582304;6419.903701444388;6434.099226108255;6450.48396128318;6553.967230163818;6593.868122637617;6600.933990660032;6678.766243181465;6738.73045078211;5379.811706590251;6306.243250809932;6535.019747037983;6547.639879806466;6557.442617377576 12:58:23 INFO - PID 7526 | |1;ext.html;812.719611733952;834.3450391442718;842.1326034184789;858.3047731337617;872.075599864144;37975.05062373439;38998.61001389985;39079.0230244244;39153.21693566131;39172;38120;39024.02439939003;39389.803050984774;39431;39525.011874703145;8367.874481882776;8387;8505.447145935525;8518.616662250182;8531.018932822712;6859.862802743942;8223.136570660085;8269.966254218223;8359.791005224857;8368.832623347529;8299.626516806758;8354.791130221733;8387.412881098326;8414.747557573282;8415.831683366328;8318.958405207995;8327.333813294921;8340.249377556018;8344.707935222237;8380.203880631361;6825.317468253184;8129.390295727844;8183.877241841377;8201.671933122669;8216.301614362786;30186;37585.62414375848;37732.622673773185;37844.43233351474;37849.243015139815;3537.1157210697324;3558.2705545363137;3567.4113771227717;3567.571891373026;3579.6599323064265;30642;30719;30886;30996.225094372596;30999.915002975013;3036.149878034154;3070.355225402668;3134.435801555719;3137.8587963541695;3138.168385377854;256893.4310656888;259853;261568.3843161563;333581;333992;39933.40099898504;49298.753506232955;49376.25935610969;50004;50090.49909500931;51463;52130.739346303024;52179.21731174035;52189.73905130526;52339.476605234224;15460.5361839145;15532.92233538825;15611.921940390226;15623.296951637203;15793.447229347148;12715.427805748763;12859.485620575259;12875.163114397534;12899.097063205485;12903;12714.237145771283;12748;12749;12789.168704034208;12888.420021099073;5502.642328248651;5549.38956714761;5573.804916827891;5592.720363981784;5599.132134519156;934.6261495401893;980.9362391444533;1007.9798404031878;1014.9495272393037;1021.9795604087876;21659.783402166093;23161.795504831647;23359.7664023361;23693.81530923447;24648.630270545957;993.4089216915924;1035.2287545778292;1039.3192458939407;1040.323789536792;1099.4118146791427;11989.040876729841;12682.80975785364;12830.486780528674;12909.935450322877;12968.157069790434;336261;336639;337705;338032;339179;55104.62238444071;55483.445165548634;55554.16668749972;55670.72164639232;55779.721101394236;1522.6726253855475;1550.8914375993797;1560.9843901561067;1563.2808907902265;1565.5694683962001;22925.42686432786;23426.297211083696;23500.412489687893;23505.942232599915;24482.63276050825;38572.22855542817;38955.805220973714;38968.6103138965;39001.60998390093;39101.60898391093;258347;273831;274917;274966;276069;35278.29443411169;35496;35615;35765.64234357623;37100.62899370972;43345.56654433415;45639.85900352518;45977.77011114923;46029.30956035595;46356.91393887216;23606.763932361144;27999.0200342987;29965.850170749007;30350.241243968194;31734.206644834063;29670.258243544085;30361;31127.688723113384;31255.687443125276;31781.841090794398;37506;37508;37512.87461376163;37822.243555128196;37938.43092353562;25532.361690957874;28715.8564207186;29292.414151717272;29813.552796707634;31873.840630797626;20017.899910500353;23029;23055;23490.708011059556;24749.876250618632 12:58:23 INFO - PID 7526 | |2;jquery.html;514.2286570144782;515.5282916131738;518.2999760784626;527.0514239124103;530.6206062665195;21942.122315107415;22332.553348933012;23124.843757812105;23167.420814479647;23212.071517139335;23654;25361.61957570638;25373.492530149386;25397.87301063497;25460.236192914243;6479.74081036758;6642.93357066428;6663.433608143314;6677.899831502531;6685.331466853324;6578.572392794477;6589.20929488462;6619.569727967691;6675.999999999975;6677;3750.5311836020496;4936.432310284309;6487.935120648804;6521.445677117451;6573.375529324707;6517.902231466532;6555.115059466959;6559.47524198063;6560.770373036945;6585.835354116185;6033.276006879202;6075;6075.33171351151;6079.544034197453;6083.17877086592;29944.70055299441;30821.075367739002;31360.745570177165;31647.841760791278;31887;2225.4436390902274;2234.9776502235095;2242.338510139513;2255.4474153832416;2259.2205689037255;19631.920244386696;19958.70061949072;20155.0930208141;20166.596668066555;20305.898470507702;2146.495573540217;2173.815225705817;2203.5262418580082;2210.292706333974;2218.589560931233;219928;220174;224941;224959;225242;15450.768238476432;15604.609884752972;15659.843401566066;15724.29240684172;15775.447859324868;20899.79100209009;21216.78783212179;21242.681359779617;21277.78722212789;21290.78709212919;10495.790084198274;10503;10702.785944281071;10760.139188864872;10779.407132607781;9877.012298770209;9912;9945.30382873192;9947.154491868272;10047.24645651579;9291.535423228946;9399.483028433502;9434.716958491255;9457.621695132118;9484;2625.658664373619;2629.4741051789338;2636.4858852523957;2640.3003204150914;2670.692870319899;921.6037104045325;934.2199263614822;947.696737044153;999.8050380175941;1006.104566935428;21612.675809862863;21667.891660541598;21918.684878907316;22450.97941185301;22546.985385657685;987.4075554667143;993.1806259835664;1004.1264100232844;1038.9168866490663;1047.1361127070045;11253.549858005588;11311.868813118623;11318.603848865416;11570.652880413601;11757.823632645517;511358.20813658135;535351;559102;578504;590101;147186;147878;151971.2401438008;157602;158361;1024.9282550221562;1189.120051162134;1202.7895118354254;1203.8555373355077;1207.444575495282;19017.809821901603;19220.71168932495;20005.799942000976;20251;20415.387538373874;47669.76165119152;72492;72628.6368568175;73454.63272683602;73862.63068684837;61503;73912.26087739054;96295;99829;100262.99737002536;12078.154529182873;12193.817092743438;12442.626721198378;12454.501819927102;12454.750904981669;18808.15363308627;18897.055147242856;18978.861268323944;19276.32532861343;19310.71033934464;14889.776653350425;15312.540623780858;15413.152276624445;15477.380904763684;15516.922415387851;11329.98030177288;13395.12931659439;13547.051706380458;13816;14280.28598570088;15037;15748.606284842512;15860.52418427449;15925.601859953595;15980.200989950688;11966.22219555726;12098.818517722417;12394;12416.81374779397;12424.192427492184;15127.470538531099;15219.391224351344;15248.008879422801;15272.541823745305;15345.693086137991 12:58:23 INFO - PID 7526 | |3;mootools.html;1201.9699507512312;1382.5345134157794;1399.4682020832083;1485.4206859324859;1503.4136686692189;13340.066195366317;14014.579562613126;14149.29253537324;14158.221297828615;14161.999999999987;13340.132891362055;13681.179129252252;13693.109947853383;13746.587602371945;13824.308784560782;2818.9718102819015;2844.601755754186;2858.9285267868368;2867.996201329539;2875;2789.079603730774;2839.687634360219;2841.7300356466103;2845.3029007893097;2847.7009913959046;2672.265127090056;2779.0967935420967;2797.8181418207855;2841.0908509276937;2846.7437930586348;2824.2233385818963;2852.258412812673;2855.9428811423863;2864.613277207571;2873.195505258531;2330.5222429401933;2808.4242730240253;2827.4910516107093;2834.6031555582235;2847.743703066734;13357.53248636293;13549.187048777105;13652.658683532893;13662.11196272239;13680.247586382746;7504.512206706547;7755.961220193919;7781.922180778176;7784.727534536264;7801;4750.691205071659;4801.087793319293;4829.975850120762;4904.877378065541;4924.827631032933;1316.210273835691;1319.8680131986725;1325.0856908732944;1356.1930651262483;1357.6198664373992;20098.29655962049;20187.88966606836;20204.292849750334;20224.089915953817;20268.79731202684;6601.471882249409;6668.399844013965;6762.256151823295;6797.72809087631;6824.532725464049;14267.928660356632;14334.139951602729;14490.203038832966;14549;14553.490627828187;6816.386525212655;7038.3665470107935;7431.405487560982;7540.509866858637;7561.962190189014;5667.773289068391;6400.55196136266;6916.55042422241;6931.1682598088555;6978.616176110261;5101.566366858784;5169.172932330809;5188.507091826271;5246.239295302236;5308.946910530923;1513.3038802151134;1519.8708109810568;1522.5280163149237;1524.839891811366;1538.476917847937;678.3149019490351;808.3169721585251;828.5525816059381;849.1381248033127;851.9616617252115;7940.761777146695;8002.719904803421;8165.836683266421;8304.626291816883;8388.28699560532;860.6686425726016;954.627695198873;964.5707660091332;1000.559753708366;1029.8300780371305;4894.681845680019;4963.801447942042;4966.751662416865;5005.649604527648;5282.418933917266;18295.817041829676;18726.25094996185;18834.623307534046;18985;19011;18359.541011474554;18373.887002994827;18604.44186674402;18721.344752933583;18956.146973386232;9487.952560237154;9587.137157655845;9619.086186812241;9665.71002869915;9667.129958303787;7154.284571542804;7694.384449244159;7787.8831817523915;7963.924870142457;8181.550014749246;16231.837681623505;16842.1578921051;16859.24133414024;16866;17100.743488847926;15999;16245.43140990107;17276.913615432346;17321.653566928842;17380.565485862953;10907.400092994962;11139.220254582102;11152.55016847837;11159.330440173291;11229.887701122883;12274.815877761663;12321.322327272086;12700.491980320683;12773;12788.616341509769;10119.797604048024;10147.391156530632;10288.485575721334;10337.9483102584;10624.203184761329;9961.950190249003;10099.091081802379;10203.489825508843;10455.16358691318;10480.371177729361;8815.250703690002;9328.253739700944;9349.39228950116;9420.387674801115;9453.527323633652;6569.436134841301;6796.524243302922;6940.826479338057;7192.31672991065;7195.568265904062;5917.201177840936;6073.271207455134;6129.754809807736;6904.4131248842405;7324.315407456422 12:58:23 INFO - PID 7526 | |4;prototype.html;327.51527738946356;384.4771111288647;431.7318000194911;446.241389637616;451.76056689954316;5704.800331988381;6580.322017885444;6583.37457941496;6621.31156555079;6676.399124078832;6190.3190649028575;6490.253620833594;6511.28375878653;6530.70611822468;6698.6985585648645;4265.936010959838;4641.025384669224;4787.784549695255;4792.616590672737;4809.831655892053;4352.216601011816;4453.864264612519;4812.975935120337;4829.251466022754;4859.125357435661;4100.446439730643;4216.515100763406;4552.68131230814;4778.952210477885;4802.735849528274;4802.423709154907;4803.279508073782;4813.49458306878;4873.903371741365;4879.609631229493;3202.903912882594;4189.648281374888;4190.931312515319;4207.684423668237;4222.535521092708;6242.469390101847;6289.7169627366875;6293.213348331458;6293.811185664437;6347.428731414148;4898.730569818658;5045.974770126126;5409.972950135263;5768.278965129359;5783.334916484616;5766.971165144148;6932.930670693279;6999.475039372017;7019.29807019294;7025.192102908129;4044.69664775143;4567.634589232854;4587.105514424688;4852.199387101159;4860.295257187723;6175.87648247037;6345.333739957284;7256.984022236891;7332.413406927433;7388.556686598821;4301.204277208726;4799.712017278975;4926.236433352836;4952.62855285855;4954.950450495449;3985.461962635065;4432.512423633398;5019.447860735316;5027.949720502821;5031.698098114125;5063.797448102035;5708.543316534668;5721.713914304268;5761;5764.942350576524;6936.722531098801;6963.199232088274;6964.040282879756;6989.860202795916;6997.300269972962;1577.2350410051115;1690.8647308215313;1693.3311342019833;1694.8898321609056;1698.5668654493272;1055.5883205549842;1062.6015244283394;1081.6971248050559;1089.4389389464432;1143.388287266325;6047.183630209865;6163.660998645118;6363.236411630634;6477.578957367756;6538.640374779337;5973.910391344133;7190.712371505081;7214.603196824225;7220.855582888313;7241.312075352833;6420.5184611154355;6431.324710905381;6437.517186211053;6467.126937863328;6489.935100649028;3684.6499582539614;3709.3323201823932;3722.7952462614817;3725.590185079639;3730.552333719971;6022.969885150634;6818.420434263044;7325.396072004921;7470.813229669302;7604.429667774939;5464.754086066137;6154.261488621304;6285.5600107992;7345.9715639810465;7360.411167106618;7149.9285007149265;7321.926780732125;7330.266973302733;7349.083987390202;7352.19125896151;5824.475797178275;6328.588641738273;6945.853934100816;7098.929010709827;7192.280771922975;5792.391798861059;6638.701258443285;7012.929870701228;7048.365647091787;7051.541649792747;6275.772100904296;7294.708211671474;7308.451866109956;7354.014416107728;7395.630218488946;4042.717009809285;4851;4886.169351210374;4916.532929371704;4964.578010869117;5910.408959104056;6187.690615469299;7170.211276759448;7185.820354491179;7189.676464559003;5623.915641265467;6881.277465866212;6887.896681549681;6903.827404314731;6924.723011079501;6878.27778083294;6901.482388820758;6912.827179320558;6918.792436226921;7003.3346832050875;6760.08738820253;6773.324536808032;6793.422559082534;6808.251092379735;6843.828904277433;6828.317168283132;6858.41703455192;6893.965530172317;6906.102206713095;6908.48186386013;6640.800775976729;6672.299408562031;6690.832729181615;6706.579644381303;6786.660666966731 12:58:23 INFO - PID 7526 | |5;yui.html;725.4776560876169;806.959652017399;819.4468733604818;843.1652663862774;848.1645579104581;16527.83472165278;17068.402605908796;17074.06092664903;18641;19282;18070.096495175254;18704.25182992682;18728.906355468236;18959.33642322519;18963.715544266848;804.6901942752024;942.7595963029435;955.8996305387939;960.8702825118589;961.7211008807437;993.7664648807524;993.8260804359244;995.1789773436943;1000.0049950299463;1000.1998401278947;1040.380973320873;1042.6038105519892;1044.5143008501045;1046.0219694585548;1046.0899017854476;976.5361453309656;1029.6396261308557;1035;1036.1503567075001;1039.080413833755;750.8047907544023;756.3193126186421;763.0728664672395;763.5266134996338;764.0831002796666;17180.570485737833;17231.82768172315;17331.306747730076;17485.825141748675;17494.562635934075;14736.852631473763;17545.56136096595;17705.29178832845;17901.7314740279;18010.459686209433;14811;15285.082895026222;15320.923395383063;15389.461368852148;15400.922995385063;3634.0551456621206;3662.34077865984;3668.52309199805;3680.0983758978955;3683.7421380503374;138163;143694;146731.53268467286;147129.26435367862;150938;13780.448782048607;13817.930910345383;13847.446102155802;14018.948578856627;14082;27189.320266993484;27255.182344529694;27349;27437.451250974867;27542.862285688843;6844.315568443116;7059.71761129549;8141.18588141193;8164.918350816416;8231.42380033392;3542.539469868952;3913.1195481016543;4078.530968938551;4084.7753373564738;4089.7750623715983;4566.246569316092;4624.622830340861;4627.699199552018;4647.209974304376;4683.297505374221;995.9701208963743;998.1316254858175;1003.5283416794096;1011.7824667696333;1015.2639336480992;639.7536948274948;646.8221239159175;648.5460177875506;667.9465642748569;669.8392385827366;10944.4527773611;11222.775544489064;12346.691332716575;12386.938065309796;12432.191907525981;614.8124821929355;640.6924676155378;643.5045015338163;674.7334802752983;674.8582797612459;4920.458749537548;5008.899822003539;5376.6505177164145;5707.689540066752;5782.5663075268685;150276;151334;151374;154150;157097;129020;131371.34314328557;133291.33354333165;134131;136830;115281.4235928815;117484;117533.41233293779;117619;119238.21142682867;10615;10816.67549973502;10833.837492437462;11104.44477776124;11455.713607159887;44140.55859441493;44436;45523;46363;46447.53552464432;63197.68401157965;63279.683601581695;66291;66642.33357666362;66847;13817.585472435843;14243.857561424253;14599.270036497921;14769.335379907716;14820.7776883349;15484.92257538705;15729.292181851615;16231.756523651919;16372.181390930644;18532.536686582942;15464.5360639181;17823.197956091746;17848.46454606364;17874.731879022085;18222.36221732286;16838.494845154666;17197.65604687924;17346.566335841704;17530.822327734295;18147.18337674834;14316.928415357857;16382.262798173871;16429.096399697653;17737.847039942364;17746.822531774516;11495.425228738362;11754.941225293818;11912.642620721046;12343;12526.123171377916;12121.333326667118;12757.48970041225;13269.137506062076;13558.271785082803;13943.860561394256 12:58:23 INFO - PID 7526 | __end_tp_report 12:58:23 INFO - PID 7526 | __start_cc_report 12:58:23 INFO - PID 7526 | _x_x_mozilla_cycle_collect,402 12:58:23 INFO - PID 7526 | __end_cc_report 12:58:23 INFO - PID 7526 | __startTimestamp1497988703014__endTimestamp 12:58:23 INFO - PID 7526 | 12:58:23 INFO - PID 7526 | ------- Summary: start ------- 12:58:23 INFO - PID 7526 | Number of tests: 6 12:58:23 INFO - PID 7526 | 12:58:23 INFO - PID 7526 | [#0] dojo.html Cycles:165 Average:6281.54 Median:6468.58 stddev:778.22 (12.0%) stddev-sans-first:748.90 12:58:23 INFO - PID 7526 | Values: 3478.6 3552.7 3574.9 3576.1 3624.5 6198.9 6219.3 6232.1 6240.0 6244.0 6250.4 6352.5 6356.8 6374.4 6416.1 5539.2 5541.1 5557.8 5607.4 5650.8 5526.8 5567.4 5630.4 5647.8 5658.4 5408.5 5415.1 5500.1 5564.1 5576.3 5468.8 5490.4 5527.2 5557.2 5574.0 5441.9 5448.0 5496.8 5546.7 5547.0 6280.0 6302.5 6304.8 6308.6 6310.1 6797.4 6810.9 6817.1 6846.8 6864.2 5890.2 6670.1 6842.6 6928.8 6952.2 6344.2 6373.9 6442.9 6448.5 6469.4 6833.0 7078.5 7096.1 7105.5 7113.1 6562.3 6569.3 6594.0 6614.8 6615.7 6910.1 6943.3 6954.2 6956.9 6963.3 6852.9 6855.1 6855.6 6862.9 6900.3 6550.5 6692.8 6820.6 6846.4 6865.8 4855.7 5439.1 5451.5 5456.6 5484.4 3993.2 4762.1 4820.3 4911.6 4919.3 6400.1 6462.0 6468.6 6469.0 6473.8 6978.5 6989.7 7021.1 7022.8 7031.5 6121.7 6182.8 6213.5 6410.0 6436.2 6071.8 6089.1 6120.7 6120.9 6138.8 6996.4 7103.8 7119.5 7138.8 7145.9 6138.6 6950.9 6960.8 6981.8 6985.4 7123.5 7151.6 7152.6 7158.1 7184.0 5828.0 6441.9 6605.6 6654.8 6839.3 6794.5 6873.2 6946.3 6951.3 6981.2 6657.4 6735.8 6744.5 6755.4 6762.8 6604.8 6700.3 6715.4 6733.6 6750.4 6369.4 6404.2 6419.9 6434.1 6450.5 6554.0 6593.9 6600.9 6678.8 6738.7 5379.8 6306.2 6535.0 6547.6 6557.4 12:58:23 INFO - PID 7526 | 12:58:23 INFO - PID 7526 | [#1] ext.html Cycles:180 Average:45185.51 Median:19527.14 stddev:79154.54 (405.4%) stddev-sans-first:79306.48 12:58:23 INFO - PID 7526 | Values: 812.7 834.3 842.1 858.3 872.1 37975.1 38998.6 39079.0 39153.2 39172.0 38120.0 39024.0 39389.8 39431.0 39525.0 8367.9 8387.0 8505.4 8518.6 8531.0 6859.9 8223.1 8270.0 8359.8 8368.8 8299.6 8354.8 8387.4 8414.7 8415.8 8319.0 8327.3 8340.2 8344.7 8380.2 6825.3 8129.4 8183.9 8201.7 8216.3 30186.0 37585.6 37732.6 37844.4 37849.2 3537.1 3558.3 3567.4 3567.6 3579.7 30642.0 30719.0 30886.0 30996.2 30999.9 3036.1 3070.4 3134.4 3137.9 3138.2 256893.4 259853.0 261568.4 333581.0 333992.0 39933.4 49298.8 49376.3 50004.0 50090.5 51463.0 52130.7 52179.2 52189.7 52339.5 15460.5 15532.9 15611.9 15623.3 15793.4 12715.4 12859.5 12875.2 12899.1 12903.0 12714.2 12748.0 12749.0 12789.2 12888.4 5502.6 5549.4 5573.8 5592.7 5599.1 934.6 980.9 1008.0 1014.9 1022.0 21659.8 23161.8 23359.8 23693.8 24648.6 993.4 1035.2 1039.3 1040.3 1099.4 11989.0 12682.8 12830.5 12909.9 12968.2 336261.0 336639.0 337705.0 338032.0 339179.0 55104.6 55483.4 55554.2 55670.7 55779.7 1522.7 1550.9 1561.0 1563.3 1565.6 22925.4 23426.3 23500.4 23505.9 24482.6 38572.2 38955.8 38968.6 39001.6 39101.6 258347.0 273831.0 274917.0 274966.0 276069.0 35278.3 35496.0 35615.0 35765.6 37100.6 43345.6 45639.9 45977.8 46029.3 46356.9 23606.8 27999.0 29965.9 30350.2 31734.2 29670.3 30361.0 31127.7 31255.7 31781.8 37506.0 37508.0 37512.9 37822.2 37938.4 25532.4 28715.9 29292.4 29813.6 31873.8 20017.9 23029.0 23055.0 23490.7 24749.9 12:58:23 INFO - PID 7526 | 12:58:23 INFO - PID 7526 | [#2] jquery.html Cycles:180 Average:40262.69 Median:2214.44 stddev:97762.25 (4414.8%) stddev-sans-first:97990.94 12:58:23 INFO - PID 7526 | Values: 514.2 515.5 518.3 527.1 530.6 21942.1 22332.6 23124.8 23167.4 23212.1 23654.0 25361.6 25373.5 25397.9 25460.2 6479.7 6642.9 6663.4 6677.9 6685.3 6578.6 6589.2 6619.6 6676.0 6677.0 3750.5 4936.4 6487.9 6521.4 6573.4 6517.9 6555.1 6559.5 6560.8 6585.8 6033.3 6075.0 6075.3 6079.5 6083.2 29944.7 30821.1 31360.7 31647.8 31887.0 2225.4 2235.0 2242.3 2255.4 2259.2 19631.9 19958.7 20155.1 20166.6 20305.9 2146.5 2173.8 2203.5 2210.3 2218.6 219928.0 220174.0 224941.0 224959.0 225242.0 15450.8 15604.6 15659.8 15724.3 15775.4 20899.8 21216.8 21242.7 21277.8 21290.8 10495.8 10503.0 10702.8 10760.1 10779.4 9877.0 9912.0 9945.3 9947.2 10047.2 9291.5 9399.5 9434.7 9457.6 9484.0 2625.7 2629.5 2636.5 2640.3 2670.7 921.6 934.2 947.7 999.8 1006.1 21612.7 21667.9 21918.7 22451.0 22547.0 987.4 993.2 1004.1 1038.9 1047.1 11253.5 11311.9 11318.6 11570.7 11757.8 511358.2 535351.0 559102.0 578504.0 590101.0 147186.0 147878.0 151971.2 157602.0 158361.0 1024.9 1189.1 1202.8 1203.9 1207.4 19017.8 19220.7 20005.8 20251.0 20415.4 47669.8 72492.0 72628.6 73454.6 73862.6 61503.0 73912.3 96295.0 99829.0 100263.0 12078.2 12193.8 12442.6 12454.5 12454.8 18808.2 18897.1 18978.9 19276.3 19310.7 14889.8 15312.5 15413.2 15477.4 15516.9 11330.0 13395.1 13547.1 13816.0 14280.3 15037.0 15748.6 15860.5 15925.6 15980.2 11966.2 12098.8 12394.0 12416.8 12424.2 15127.5 15219.4 15248.0 15272.5 15345.7 12:58:23 INFO - PID 7526 | 12:58:23 INFO - PID 7526 | [#3] mootools.html Cycles:180 Average:8409.17 Median:2837.15 stddev:5578.41 (196.6%) stddev-sans-first:5567.77 12:58:23 INFO - PID 7526 | Values: 1202.0 1382.5 1399.5 1485.4 1503.4 13340.1 14014.6 14149.3 14158.2 14162.0 13340.1 13681.2 13693.1 13746.6 13824.3 2819.0 2844.6 2858.9 2868.0 2875.0 2789.1 2839.7 2841.7 2845.3 2847.7 2672.3 2779.1 2797.8 2841.1 2846.7 2824.2 2852.3 2855.9 2864.6 2873.2 2330.5 2808.4 2827.5 2834.6 2847.7 13357.5 13549.2 13652.7 13662.1 13680.2 7504.5 7756.0 7781.9 7784.7 7801.0 4750.7 4801.1 4830.0 4904.9 4924.8 1316.2 1319.9 1325.1 1356.2 1357.6 20098.3 20187.9 20204.3 20224.1 20268.8 6601.5 6668.4 6762.3 6797.7 6824.5 14267.9 14334.1 14490.2 14549.0 14553.5 6816.4 7038.4 7431.4 7540.5 7562.0 5667.8 6400.6 6916.6 6931.2 6978.6 5101.6 5169.2 5188.5 5246.2 5308.9 1513.3 1519.9 1522.5 1524.8 1538.5 678.3 808.3 828.6 849.1 852.0 7940.8 8002.7 8165.8 8304.6 8388.3 860.7 954.6 964.6 1000.6 1029.8 4894.7 4963.8 4966.8 5005.6 5282.4 18295.8 18726.3 18834.6 18985.0 19011.0 18359.5 18373.9 18604.4 18721.3 18956.1 9488.0 9587.1 9619.1 9665.7 9667.1 7154.3 7694.4 7787.9 7963.9 8181.6 16231.8 16842.2 16859.2 16866.0 17100.7 15999.0 16245.4 17276.9 17321.7 17380.6 10907.4 11139.2 11152.6 11159.3 11229.9 12274.8 12321.3 12700.5 12773.0 12788.6 10119.8 10147.4 10288.5 10337.9 10624.2 9962.0 10099.1 10203.5 10455.2 10480.4 8815.3 9328.3 9349.4 9420.4 9453.5 6569.4 6796.5 6940.8 7192.3 7195.6 5917.2 6073.3 6129.8 6904.4 7324.3 12:58:23 INFO - PID 7526 | 12:58:23 INFO - PID 7526 | [#4] prototype.html Cycles:180 Average:5581.51 Median:6259.12 stddev:1742.95 (27.8%) stddev-sans-first:1702.64 12:58:23 INFO - PID 7526 | Values: 327.5 384.5 431.7 446.2 451.8 5704.8 6580.3 6583.4 6621.3 6676.4 6190.3 6490.3 6511.3 6530.7 6698.7 4265.9 4641.0 4787.8 4792.6 4809.8 4352.2 4453.9 4813.0 4829.3 4859.1 4100.4 4216.5 4552.7 4779.0 4802.7 4802.4 4803.3 4813.5 4873.9 4879.6 3202.9 4189.6 4190.9 4207.7 4222.5 6242.5 6289.7 6293.2 6293.8 6347.4 4898.7 5046.0 5410.0 5768.3 5783.3 5767.0 6932.9 6999.5 7019.3 7025.2 4044.7 4567.6 4587.1 4852.2 4860.3 6175.9 6345.3 7257.0 7332.4 7388.6 4301.2 4799.7 4926.2 4952.6 4955.0 3985.5 4432.5 5019.4 5027.9 5031.7 5063.8 5708.5 5721.7 5761.0 5764.9 6936.7 6963.2 6964.0 6989.9 6997.3 1577.2 1690.9 1693.3 1694.9 1698.6 1055.6 1062.6 1081.7 1089.4 1143.4 6047.2 6163.7 6363.2 6477.6 6538.6 5973.9 7190.7 7214.6 7220.9 7241.3 6420.5 6431.3 6437.5 6467.1 6489.9 3684.6 3709.3 3722.8 3725.6 3730.6 6023.0 6818.4 7325.4 7470.8 7604.4 5464.8 6154.3 6285.6 7346.0 7360.4 7149.9 7321.9 7330.3 7349.1 7352.2 5824.5 6328.6 6945.9 7098.9 7192.3 5792.4 6638.7 7012.9 7048.4 7051.5 6275.8 7294.7 7308.5 7354.0 7395.6 4042.7 4851.0 4886.2 4916.5 4964.6 5910.4 6187.7 7170.2 7185.8 7189.7 5623.9 6881.3 6887.9 6903.8 6924.7 6878.3 6901.5 6912.8 6918.8 7003.3 6760.1 6773.3 6793.4 6808.3 6843.8 6828.3 6858.4 6894.0 6906.1 6908.5 6640.8 6672.3 6690.8 6706.6 6786.7 12:58:23 INFO - PID 7526 | 12:58:23 INFO - PID 7526 | [#5] yui.html Cycles:180 Average:26389.62 Median:17625.43 stddev:41531.88 (235.6%) stddev-sans-first:41603.69 12:58:23 INFO - PID 7526 | Values: 725.5 807.0 819.4 843.2 848.2 16527.8 17068.4 17074.1 18641.0 19282.0 18070.1 18704.3 18728.9 18959.3 18963.7 804.7 942.8 955.9 960.9 961.7 993.8 993.8 995.2 1000.0 1000.2 1040.4 1042.6 1044.5 1046.0 1046.1 976.5 1029.6 1035.0 1036.2 1039.1 750.8 756.3 763.1 763.5 764.1 17180.6 17231.8 17331.3 17485.8 17494.6 14736.9 17545.6 17705.3 17901.7 18010.5 14811.0 15285.1 15320.9 15389.5 15400.9 3634.1 3662.3 3668.5 3680.1 3683.7 138163.0 143694.0 146731.5 147129.3 150938.0 13780.4 13817.9 13847.4 14018.9 14082.0 27189.3 27255.2 27349.0 27437.5 27542.9 6844.3 7059.7 8141.2 8164.9 8231.4 3542.5 3913.1 4078.5 4084.8 4089.8 4566.2 4624.6 4627.7 4647.2 4683.3 996.0 998.1 1003.5 1011.8 1015.3 639.8 646.8 648.5 667.9 669.8 10944.5 11222.8 12346.7 12386.9 12432.2 614.8 640.7 643.5 674.7 674.9 4920.5 5008.9 5376.7 5707.7 5782.6 150276.0 151334.0 151374.0 154150.0 157097.0 129020.0 131371.3 133291.3 134131.0 136830.0 115281.4 117484.0 117533.4 117619.0 119238.2 10615.0 10816.7 10833.8 11104.4 11455.7 44140.6 44436.0 45523.0 46363.0 46447.5 63197.7 63279.7 66291.0 66642.3 66847.0 13817.6 14243.9 14599.3 14769.3 14820.8 15484.9 15729.3 16231.8 16372.2 18532.5 15464.5 17823.2 17848.5 17874.7 18222.4 16838.5 17197.7 17346.6 17530.8 18147.2 14316.9 16382.3 16429.1 17737.8 17746.8 11495.4 11754.9 11912.6 12343.0 12526.1 12121.3 12757.5 13269.1 13558.3 13943.9 12:58:23 INFO - PID 7526 | -------- Summary: end -------- 12:58:23 INFO - PID 7526 | 12:58:23 INFO - TEST-INFO | 7526: exit 0 12:58:23 INFO - TEST-OK | dromaeo_css | took 1145861ms 12:58:23 INFO - TEST-START | kraken 12:58:23 INFO - operating with platform_type : mac_ 12:58:23 INFO - Initialising browser for kraken test... 12:58:23 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49613/getInfo.html -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpvaXL9m/profile 12:58:23 INFO - TEST-INFO | started process 7533 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49613/getInfo.html -foreground) 12:58:56 INFO - TEST-INFO | 7533: killed by SIGTERM 12:58:56 INFO - Browser initialized. 12:58:56 INFO - Running cycle 1/1 for kraken test... 12:58:56 INFO - TEST-INFO | started process 7537 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpvaXL9m/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:58:58 INFO - PID 7537 | 2017-06-20 12:58:58.617 plugin-container[7538:146647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 12:58:58 INFO - PID 7537 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:58:58 INFO - PID 7537 | 2017-06-20 12:58:58.618 plugin-container[7538:146647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x981b, name = 'com.apple.CFPasteboardClient' 12:58:58 INFO - PID 7537 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:58:58 INFO - PID 7537 | 2017-06-20 12:58:58.618 plugin-container[7538:146647] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:58:59 INFO - PID 7537 | 2017-06-20 12:58:59.394 plugin-container[7539:146692] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 12:58:59 INFO - PID 7537 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:58:59 INFO - PID 7537 | 2017-06-20 12:58:59.394 plugin-container[7539:146692] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.CFPasteboardClient' 12:58:59 INFO - PID 7537 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:58:59 INFO - PID 7537 | 2017-06-20 12:58:59.394 plugin-container[7539:146692] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:59:08 INFO - PID 7537 | RSS: Main: 215875584 12:59:08 INFO - PID 7537 | 12:59:11 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49613/tests/kraken/driver.html?testName=audio-beat-detection) 12:59:12 INFO - PID 7537 | RSS: Main: 228597760 12:59:12 INFO - PID 7537 | 12:59:14 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49613/tests/kraken/driver.html?testName=audio-dft) 12:59:15 INFO - PID 7537 | RSS: Main: 241147904 12:59:15 INFO - PID 7537 | 12:59:18 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49613/tests/kraken/driver.html?testName=audio-fft) 12:59:19 INFO - PID 7537 | RSS: Main: 245813248 12:59:19 INFO - PID 7537 | 12:59:21 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49613/tests/kraken/driver.html?testName=audio-oscillator) 12:59:21 INFO - PID 7537 | RSS: Main: 246788096 12:59:21 INFO - PID 7537 | 12:59:24 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49613/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:59:24 INFO - PID 7537 | RSS: Main: 247762944 12:59:24 INFO - PID 7537 | 12:59:27 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49613/tests/kraken/driver.html?testName=imaging-darkroom) 12:59:28 INFO - PID 7537 | RSS: Main: 252764160 12:59:28 INFO - PID 7537 | 12:59:31 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49613/tests/kraken/driver.html?testName=imaging-desaturate) 12:59:31 INFO - PID 7537 | RSS: Main: 252350464 12:59:31 INFO - PID 7537 | 12:59:35 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49613/tests/kraken/driver.html?testName=json-parse-financial) 12:59:35 INFO - PID 7537 | RSS: Main: 252534784 12:59:35 INFO - PID 7537 | 12:59:37 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49613/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:59:37 INFO - PID 7537 | RSS: Main: 251736064 12:59:37 INFO - PID 7537 | 12:59:39 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49613/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:59:40 INFO - PID 7537 | RSS: Main: 251756544 12:59:40 INFO - PID 7537 | 12:59:42 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49613/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:59:43 INFO - PID 7537 | RSS: Main: 251695104 12:59:43 INFO - PID 7537 | 12:59:45 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49613/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:59:46 INFO - PID 7537 | RSS: Main: 252198912 12:59:46 INFO - PID 7537 | 12:59:49 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49613/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:59:50 INFO - PID 7537 | RSS: Main: 252739584 12:59:50 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | Cycle 1(1): loaded http://localhost:49613/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49613/tests/kraken/driver.html?testName=ai-astar) 12:59:52 INFO - PID 7537 | RSS: Main: 254103552 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | __start_tp_report 12:59:52 INFO - PID 7537 | _x_x_mozilla_page_load 12:59:52 INFO - PID 7537 | _x_x_mozilla_page_load_details 12:59:52 INFO - PID 7537 | |i|pagename|runs| 12:59:52 INFO - PID 7537 | |0;ai-astar;104;117;106;115;116;115;115;103;111;138 12:59:52 INFO - PID 7537 | |1;audio-beat-detection;144;114;148;142;108;130;129;108;158;111 12:59:52 INFO - PID 7537 | |2;audio-dft;201;197;198;199;198;199;199;199;198;197 12:59:52 INFO - PID 7537 | |3;audio-fft;69;79;81;162;60;87;91;86;121;56 12:59:52 INFO - PID 7537 | |4;audio-oscillator;69;91;112;59;99;118;138;102;71;103 12:59:52 INFO - PID 7537 | |5;imaging-gaussian-blur;91;91;92;92;92;90;91;90;92;91 12:59:52 INFO - PID 7537 | |6;imaging-darkroom;98;101;98;98;100;114;98;122;101;155 12:59:52 INFO - PID 7537 | |7;imaging-desaturate;91;89;100;144;144;92;91;88;92;92 12:59:52 INFO - PID 7537 | |8;json-parse-financial;43;43;69;76;82;76;54;54;52;50 12:59:52 INFO - PID 7537 | |9;json-stringify-tinderbox;56;55;58;58;58;65;55;56;58;58 12:59:52 INFO - PID 7537 | |10;stanford-crypto-aes;81;79;82;79;81;80;80;83;82;95 12:59:52 INFO - PID 7537 | |11;stanford-crypto-ccm;136;149;127;149;135;159;129;134;157;166 12:59:52 INFO - PID 7537 | |12;stanford-crypto-pbkdf2;216;191;183;176;188;182;177;208;157;238 12:59:52 INFO - PID 7537 | |13;stanford-crypto-sha256-iterative;68;67;64;67;65;69;70;64;90;92 12:59:52 INFO - PID 7537 | __end_tp_report 12:59:52 INFO - PID 7537 | __start_cc_report 12:59:52 INFO - PID 7537 | _x_x_mozilla_cycle_collect,1024 12:59:52 INFO - PID 7537 | __end_cc_report 12:59:52 INFO - PID 7537 | __startTimestamp1497988792332__endTimestamp 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | ------- Summary: start ------- 12:59:52 INFO - PID 7537 | Number of tests: 14 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.00 stddev:9.92 (8.6%) stddev-sans-first:9.84 12:59:52 INFO - PID 7537 | Values: 104.0 117.0 106.0 115.0 116.0 115.0 115.0 103.0 111.0 138.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:136.00 stddev:18.34 (13.5%) stddev-sans-first:18.66 12:59:52 INFO - PID 7537 | Values: 144.0 114.0 148.0 142.0 108.0 130.0 129.0 108.0 158.0 111.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#2] audio-dft Cycles:10 Average:198.50 Median:199.00 stddev:1.18 (0.6%) stddev-sans-first:0.83 12:59:52 INFO - PID 7537 | Values: 201.0 197.0 198.0 199.0 198.0 199.0 199.0 199.0 198.0 197.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#3] audio-fft Cycles:10 Average:89.20 Median:80.00 stddev:31.38 (39.2%) stddev-sans-first:32.42 12:59:52 INFO - PID 7537 | Values: 69.0 79.0 81.0 162.0 60.0 87.0 91.0 86.0 121.0 56.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:64.00 stddev:24.37 (38.1%) stddev-sans-first:23.78 12:59:52 INFO - PID 7537 | Values: 69.0 91.0 112.0 59.0 99.0 118.0 138.0 102.0 71.0 103.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:91.50 stddev:0.79 (0.9%) stddev-sans-first:0.83 12:59:52 INFO - PID 7537 | Values: 91.0 91.0 92.0 92.0 92.0 90.0 91.0 90.0 92.0 91.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#6] imaging-darkroom Cycles:10 Average:108.50 Median:126.50 stddev:18.26 (14.4%) stddev-sans-first:18.97 12:59:52 INFO - PID 7537 | Values: 98.0 101.0 98.0 98.0 100.0 114.0 98.0 122.0 101.0 155.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#7] imaging-desaturate Cycles:10 Average:102.30 Median:91.00 stddev:22.21 (24.4%) stddev-sans-first:23.17 12:59:52 INFO - PID 7537 | Values: 91.0 89.0 100.0 144.0 144.0 92.0 91.0 88.0 92.0 92.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:61.50 stddev:14.49 (23.6%) stddev-sans-first:14.02 12:59:52 INFO - PID 7537 | Values: 43.0 43.0 69.0 76.0 82.0 76.0 54.0 54.0 52.0 50.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#9] json-stringify-tinderbox Cycles:10 Average:57.70 Median:58.00 stddev:2.87 (4.9%) stddev-sans-first:2.98 12:59:52 INFO - PID 7537 | Values: 56.0 55.0 58.0 58.0 58.0 65.0 55.0 56.0 58.0 58.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:81.50 stddev:4.69 (5.7%) stddev-sans-first:4.95 12:59:52 INFO - PID 7537 | Values: 81.0 79.0 82.0 79.0 81.0 80.0 80.0 83.0 82.0 95.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:149.00 stddev:13.69 (9.2%) stddev-sans-first:14.20 12:59:52 INFO - PID 7537 | Values: 136.0 149.0 127.0 149.0 135.0 159.0 129.0 134.0 157.0 166.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.60 Median:189.50 stddev:23.21 (12.3%) stddev-sans-first:22.88 12:59:52 INFO - PID 7537 | Values: 216.0 191.0 183.0 176.0 188.0 182.0 177.0 208.0 157.0 238.0 12:59:52 INFO - PID 7537 | 12:59:52 INFO - PID 7537 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:68.50 stddev:10.43 (15.2%) stddev-sans-first:10.98 12:59:52 INFO - PID 7537 | Values: 68.0 67.0 64.0 67.0 65.0 69.0 70.0 64.0 90.0 92.0 12:59:52 INFO - PID 7537 | -------- Summary: end -------- 12:59:52 INFO - PID 7537 | 12:59:53 INFO - TEST-INFO | 7537: exit 0 12:59:53 INFO - TEST-OK | kraken | took 89538ms 12:59:53 INFO - SUITE-END | took 1235s 12:59:53 INFO - Completed test suite (00:20:35) 12:59:53 INFO - javascript benchmark 12:59:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8427.963482247233, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3478.5825700915893, 3552.698020668243, 3574.9463758043626, 3576.0880975351297, 3624.528811254538, 6198.907016394752, 6219.315875253724, 6232.096346029823, 6240.032794916784, 6243.968780156093, 6250.40621140992, 6352.523560732952, 6356.777512787054, 6374.394432528902, 6416.133821934038, 5539.224508568814, 5541.085720856054, 5557.805476808313, 5607.439256074401, 5650.830475085733, 5526.778928842861, 5567.4432556744405, 5630.436956304377, 5647.802326918559, 5658.405867383927, 5408.540274076709, 5415.079436495766, 5500.147477141049, 5564.137558678412, 5576.302962129734, 5468.835934921959, 5490.368607610136, 5527.170924361338, 5557.166425036236, 5574.0, 5441.891162176774, 5448.0, 5496.835094947118, 5546.722663866831, 5546.973809845151, 6280.026595877601, 6302.46429053531, 6304.7793327233785, 6308.558400911938, 6310.0850376695125, 6797.354251346114, 6810.9318906810795, 6817.0796942413135, 6846.760363387258, 6864.176298844171, 5890.234269544974, 6670.132882725215, 6842.589444633338, 6928.826779330557, 6952.165740111219, 6344.206974128234, 6373.936260637427, 6442.9355706443275, 6448.484121270287, 6469.353064693493, 6833.0, 7078.504504684725, 7096.112985876765, 7105.538140020882, 7113.110861142357, 6562.278149403562, 6569.343065693488, 6594.0, 6614.8346291343105, 6615.735370585123, 6910.136232970879, 6943.27095654949, 6954.165500139914, 6956.860862782817, 6963.338482844122, 6852.862942741117, 6855.07456493377, 6855.5886646801355, 6862.862742745217, 6900.3444672756, 6550.475961923034, 6692.765753198614, 6820.624865632339, 6846.418054465426, 6865.759698410531, 4855.713235992464, 5439.129739241782, 5451.536619387396, 5456.590755693339, 5484.424135465799, 3993.2412841560013, 4762.095201911626, 4820.325154478376, 4911.600193944709, 4919.311296418504, 6400.135981642418, 6462.0, 6468.579542329733, 6469.0, 6473.838154046092, 6978.47661425385, 6989.685464154125, 7021.05215795861, 7022.754203602849, 7031.472639551952, 6121.724522396503, 6182.783602573888, 6213.471854892385, 6409.9679501602195, 6436.195475565554, 6071.848203794852, 6089.147519347206, 6120.693965301717, 6120.938790612036, 6138.817583427401, 6996.440284777307, 7103.751368702071, 7119.466040047121, 7138.750143744944, 7145.857082858418, 6138.570300078952, 6950.930490695028, 6960.825979350557, 6981.79054628362, 6985.406240469617, 7123.501354905314, 7151.570905745672, 7152.570845749272, 7158.06945097134, 7183.964080179566, 5828.009238429562, 6441.903371449525, 6605.636689982097, 6654.767083152066, 6839.2818754032105, 6794.524383293122, 6873.243943166148, 6946.305369463013, 6951.304869513008, 6981.232064473005, 6657.434118100015, 6735.831604209738, 6744.460443164633, 6755.3582409671, 6762.763303284361, 6604.801855944329, 6700.296468870698, 6715.395614394728, 6733.562318449481, 6750.3924646782025, 6369.394907483782, 6404.1674582304, 6419.903701444388, 6434.099226108255, 6450.48396128318, 6553.967230163818, 6593.868122637617, 6600.933990660032, 6678.766243181465, 6738.73045078211, 5379.811706590251, 6306.243250809932, 6535.019747037983, 6547.639879806466, 6557.442617377576], "value": 6226.898529029111, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [812.719611733952, 834.3450391442718, 842.1326034184789, 858.3047731337617, 872.075599864144, 37975.05062373439, 38998.61001389985, 39079.0230244244, 39153.21693566131, 39172.0, 38120.0, 39024.02439939003, 39389.803050984774, 39431.0, 39525.011874703145, 8367.874481882776, 8387.0, 8505.447145935525, 8518.616662250182, 8531.018932822712, 6859.862802743942, 8223.136570660085, 8269.966254218223, 8359.791005224857, 8368.832623347529, 8299.626516806758, 8354.791130221733, 8387.412881098326, 8414.747557573282, 8415.831683366328, 8318.958405207995, 8327.333813294921, 8340.249377556018, 8344.707935222237, 8380.203880631361, 6825.317468253184, 8129.390295727844, 8183.877241841377, 8201.671933122669, 8216.301614362786, 30186.0, 37585.62414375848, 37732.622673773185, 37844.43233351474, 37849.243015139815, 3537.1157210697324, 3558.2705545363137, 3567.4113771227717, 3567.571891373026, 3579.6599323064265, 30642.0, 30719.0, 30886.0, 30996.225094372596, 30999.915002975013, 3036.149878034154, 3070.355225402668, 3134.435801555719, 3137.8587963541695, 3138.168385377854, 256893.4310656888, 259853.0, 261568.3843161563, 333581.0, 333992.0, 39933.40099898504, 49298.753506232955, 49376.25935610969, 50004.0, 50090.49909500931, 51463.0, 52130.739346303024, 52179.21731174035, 52189.73905130526, 52339.476605234224, 15460.5361839145, 15532.92233538825, 15611.921940390226, 15623.296951637203, 15793.447229347148, 12715.427805748763, 12859.485620575259, 12875.163114397534, 12899.097063205485, 12903.0, 12714.237145771283, 12748.0, 12749.0, 12789.168704034208, 12888.420021099073, 5502.642328248651, 5549.38956714761, 5573.804916827891, 5592.720363981784, 5599.132134519156, 934.6261495401893, 980.9362391444533, 1007.9798404031878, 1014.9495272393037, 1021.9795604087876, 21659.783402166093, 23161.795504831647, 23359.7664023361, 23693.81530923447, 24648.630270545957, 993.4089216915924, 1035.2287545778292, 1039.3192458939407, 1040.323789536792, 1099.4118146791427, 11989.040876729841, 12682.80975785364, 12830.486780528674, 12909.935450322877, 12968.157069790434, 336261.0, 336639.0, 337705.0, 338032.0, 339179.0, 55104.62238444071, 55483.445165548634, 55554.16668749972, 55670.72164639232, 55779.721101394236, 1522.6726253855475, 1550.8914375993797, 1560.9843901561067, 1563.2808907902265, 1565.5694683962001, 22925.42686432786, 23426.297211083696, 23500.412489687893, 23505.942232599915, 24482.63276050825, 38572.22855542817, 38955.805220973714, 38968.6103138965, 39001.60998390093, 39101.60898391093, 258347.0, 273831.0, 274917.0, 274966.0, 276069.0, 35278.29443411169, 35496.0, 35615.0, 35765.64234357623, 37100.62899370972, 43345.56654433415, 45639.85900352518, 45977.77011114923, 46029.30956035595, 46356.91393887216, 23606.763932361144, 27999.0200342987, 29965.850170749007, 30350.241243968194, 31734.206644834063, 29670.258243544085, 30361.0, 31127.688723113384, 31255.687443125276, 31781.841090794398, 37506.0, 37508.0, 37512.87461376163, 37822.243555128196, 37938.43092353562, 25532.361690957874, 28715.8564207186, 29292.414151717272, 29813.552796707634, 31873.840630797626, 20017.899910500353, 23029.0, 23055.0, 23490.708011059556, 24749.876250618632], "value": 17432.687435456875, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.2286570144782, 515.5282916131738, 518.2999760784626, 527.0514239124103, 530.6206062665195, 21942.122315107415, 22332.553348933012, 23124.843757812105, 23167.420814479647, 23212.071517139335, 23654.0, 25361.61957570638, 25373.492530149386, 25397.87301063497, 25460.236192914243, 6479.74081036758, 6642.93357066428, 6663.433608143314, 6677.899831502531, 6685.331466853324, 6578.572392794477, 6589.20929488462, 6619.569727967691, 6675.999999999975, 6677.0, 3750.5311836020496, 4936.432310284309, 6487.935120648804, 6521.445677117451, 6573.375529324707, 6517.902231466532, 6555.115059466959, 6559.47524198063, 6560.770373036945, 6585.835354116185, 6033.276006879202, 6075.0, 6075.33171351151, 6079.544034197453, 6083.17877086592, 29944.70055299441, 30821.075367739002, 31360.745570177165, 31647.841760791278, 31887.0, 2225.4436390902274, 2234.9776502235095, 2242.338510139513, 2255.4474153832416, 2259.2205689037255, 19631.920244386696, 19958.70061949072, 20155.0930208141, 20166.596668066555, 20305.898470507702, 2146.495573540217, 2173.815225705817, 2203.5262418580082, 2210.292706333974, 2218.589560931233, 219928.0, 220174.0, 224941.0, 224959.0, 225242.0, 15450.768238476432, 15604.609884752972, 15659.843401566066, 15724.29240684172, 15775.447859324868, 20899.79100209009, 21216.78783212179, 21242.681359779617, 21277.78722212789, 21290.78709212919, 10495.790084198274, 10503.0, 10702.785944281071, 10760.139188864872, 10779.407132607781, 9877.012298770209, 9912.0, 9945.30382873192, 9947.154491868272, 10047.24645651579, 9291.535423228946, 9399.483028433502, 9434.716958491255, 9457.621695132118, 9484.0, 2625.658664373619, 2629.4741051789338, 2636.4858852523957, 2640.3003204150914, 2670.692870319899, 921.6037104045325, 934.2199263614822, 947.696737044153, 999.8050380175941, 1006.104566935428, 21612.675809862863, 21667.891660541598, 21918.684878907316, 22450.97941185301, 22546.985385657685, 987.4075554667143, 993.1806259835664, 1004.1264100232844, 1038.9168866490663, 1047.1361127070045, 11253.549858005588, 11311.868813118623, 11318.603848865416, 11570.652880413601, 11757.823632645517, 511358.20813658135, 535351.0, 559102.0, 578504.0, 590101.0, 147186.0, 147878.0, 151971.2401438008, 157602.0, 158361.0, 1024.9282550221562, 1189.120051162134, 1202.7895118354254, 1203.8555373355077, 1207.444575495282, 19017.809821901603, 19220.71168932495, 20005.799942000976, 20251.0, 20415.387538373874, 47669.76165119152, 72492.0, 72628.6368568175, 73454.63272683602, 73862.63068684837, 61503.0, 73912.26087739054, 96295.0, 99829.0, 100262.99737002536, 12078.154529182873, 12193.817092743438, 12442.626721198378, 12454.501819927102, 12454.750904981669, 18808.15363308627, 18897.055147242856, 18978.861268323944, 19276.32532861343, 19310.71033934464, 14889.776653350425, 15312.540623780858, 15413.152276624445, 15477.380904763684, 15516.922415387851, 11329.98030177288, 13395.12931659439, 13547.051706380458, 13816.0, 14280.28598570088, 15037.0, 15748.606284842512, 15860.52418427449, 15925.601859953595, 15980.200989950688, 11966.22219555726, 12098.818517722417, 12394.0, 12416.81374779397, 12424.192427492184, 15127.470538531099, 15219.391224351344, 15248.008879422801, 15272.541823745305, 15345.693086137991], "value": 11994.367862337745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1201.9699507512312, 1382.5345134157794, 1399.4682020832083, 1485.4206859324859, 1503.4136686692189, 13340.066195366317, 14014.579562613126, 14149.29253537324, 14158.221297828615, 14161.999999999987, 13340.132891362055, 13681.179129252252, 13693.109947853383, 13746.587602371945, 13824.308784560782, 2818.9718102819015, 2844.601755754186, 2858.9285267868368, 2867.996201329539, 2875.0, 2789.079603730774, 2839.687634360219, 2841.7300356466103, 2845.3029007893097, 2847.7009913959046, 2672.265127090056, 2779.0967935420967, 2797.8181418207855, 2841.0908509276937, 2846.7437930586348, 2824.2233385818963, 2852.258412812673, 2855.9428811423863, 2864.613277207571, 2873.195505258531, 2330.5222429401933, 2808.4242730240253, 2827.4910516107093, 2834.6031555582235, 2847.743703066734, 13357.53248636293, 13549.187048777105, 13652.658683532893, 13662.11196272239, 13680.247586382746, 7504.512206706547, 7755.961220193919, 7781.922180778176, 7784.727534536264, 7801.0, 4750.691205071659, 4801.087793319293, 4829.975850120762, 4904.877378065541, 4924.827631032933, 1316.210273835691, 1319.8680131986725, 1325.0856908732944, 1356.1930651262483, 1357.6198664373992, 20098.29655962049, 20187.88966606836, 20204.292849750334, 20224.089915953817, 20268.79731202684, 6601.471882249409, 6668.399844013965, 6762.256151823295, 6797.72809087631, 6824.532725464049, 14267.928660356632, 14334.139951602729, 14490.203038832966, 14549.0, 14553.490627828187, 6816.386525212655, 7038.3665470107935, 7431.405487560982, 7540.509866858637, 7561.962190189014, 5667.773289068391, 6400.55196136266, 6916.55042422241, 6931.1682598088555, 6978.616176110261, 5101.566366858784, 5169.172932330809, 5188.507091826271, 5246.239295302236, 5308.946910530923, 1513.3038802151134, 1519.8708109810568, 1522.5280163149237, 1524.839891811366, 1538.476917847937, 678.3149019490351, 808.3169721585251, 828.5525816059381, 849.1381248033127, 851.9616617252115, 7940.761777146695, 8002.719904803421, 8165.836683266421, 8304.626291816883, 8388.28699560532, 860.6686425726016, 954.627695198873, 964.5707660091332, 1000.559753708366, 1029.8300780371305, 4894.681845680019, 4963.801447942042, 4966.751662416865, 5005.649604527648, 5282.418933917266, 18295.817041829676, 18726.25094996185, 18834.623307534046, 18985.0, 19011.0, 18359.541011474554, 18373.887002994827, 18604.44186674402, 18721.344752933583, 18956.146973386232, 9487.952560237154, 9587.137157655845, 9619.086186812241, 9665.71002869915, 9667.129958303787, 7154.284571542804, 7694.384449244159, 7787.8831817523915, 7963.924870142457, 8181.550014749246, 16231.837681623505, 16842.1578921051, 16859.24133414024, 16866.0, 17100.743488847926, 15999.0, 16245.43140990107, 17276.913615432346, 17321.653566928842, 17380.565485862953, 10907.400092994962, 11139.220254582102, 11152.55016847837, 11159.330440173291, 11229.887701122883, 12274.815877761663, 12321.322327272086, 12700.491980320683, 12773.0, 12788.616341509769, 10119.797604048024, 10147.391156530632, 10288.485575721334, 10337.9483102584, 10624.203184761329, 9961.950190249003, 10099.091081802379, 10203.489825508843, 10455.16358691318, 10480.371177729361, 8815.250703690002, 9328.253739700944, 9349.39228950116, 9420.387674801115, 9453.527323633652, 6569.436134841301, 6796.524243302922, 6940.826479338057, 7192.31672991065, 7195.568265904062, 5917.201177840936, 6073.271207455134, 6129.754809807736, 6904.4131248842405, 7324.315407456422], "value": 6201.321246451566, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.51527738946356, 384.4771111288647, 431.7318000194911, 446.241389637616, 451.76056689954316, 5704.800331988381, 6580.322017885444, 6583.37457941496, 6621.31156555079, 6676.399124078832, 6190.3190649028575, 6490.253620833594, 6511.28375878653, 6530.70611822468, 6698.6985585648645, 4265.936010959838, 4641.025384669224, 4787.784549695255, 4792.616590672737, 4809.831655892053, 4352.216601011816, 4453.864264612519, 4812.975935120337, 4829.251466022754, 4859.125357435661, 4100.446439730643, 4216.515100763406, 4552.68131230814, 4778.952210477885, 4802.735849528274, 4802.423709154907, 4803.279508073782, 4813.49458306878, 4873.903371741365, 4879.609631229493, 3202.903912882594, 4189.648281374888, 4190.931312515319, 4207.684423668237, 4222.535521092708, 6242.469390101847, 6289.7169627366875, 6293.213348331458, 6293.811185664437, 6347.428731414148, 4898.730569818658, 5045.974770126126, 5409.972950135263, 5768.278965129359, 5783.334916484616, 5766.971165144148, 6932.930670693279, 6999.475039372017, 7019.29807019294, 7025.192102908129, 4044.69664775143, 4567.634589232854, 4587.105514424688, 4852.199387101159, 4860.295257187723, 6175.87648247037, 6345.333739957284, 7256.984022236891, 7332.413406927433, 7388.556686598821, 4301.204277208726, 4799.712017278975, 4926.236433352836, 4952.62855285855, 4954.950450495449, 3985.461962635065, 4432.512423633398, 5019.447860735316, 5027.949720502821, 5031.698098114125, 5063.797448102035, 5708.543316534668, 5721.713914304268, 5761.0, 5764.942350576524, 6936.722531098801, 6963.199232088274, 6964.040282879756, 6989.860202795916, 6997.300269972962, 1577.2350410051115, 1690.8647308215313, 1693.3311342019833, 1694.8898321609056, 1698.5668654493272, 1055.5883205549842, 1062.6015244283394, 1081.6971248050559, 1089.4389389464432, 1143.388287266325, 6047.183630209865, 6163.660998645118, 6363.236411630634, 6477.578957367756, 6538.640374779337, 5973.910391344133, 7190.712371505081, 7214.603196824225, 7220.855582888313, 7241.312075352833, 6420.5184611154355, 6431.324710905381, 6437.517186211053, 6467.126937863328, 6489.935100649028, 3684.6499582539614, 3709.3323201823932, 3722.7952462614817, 3725.590185079639, 3730.552333719971, 6022.969885150634, 6818.420434263044, 7325.396072004921, 7470.813229669302, 7604.429667774939, 5464.754086066137, 6154.261488621304, 6285.5600107992, 7345.9715639810465, 7360.411167106618, 7149.9285007149265, 7321.926780732125, 7330.266973302733, 7349.083987390202, 7352.19125896151, 5824.475797178275, 6328.588641738273, 6945.853934100816, 7098.929010709827, 7192.280771922975, 5792.391798861059, 6638.701258443285, 7012.929870701228, 7048.365647091787, 7051.541649792747, 6275.772100904296, 7294.708211671474, 7308.451866109956, 7354.014416107728, 7395.630218488946, 4042.717009809285, 4851.0, 4886.169351210374, 4916.532929371704, 4964.578010869117, 5910.408959104056, 6187.690615469299, 7170.211276759448, 7185.820354491179, 7189.676464559003, 5623.915641265467, 6881.277465866212, 6887.896681549681, 6903.827404314731, 6924.723011079501, 6878.27778083294, 6901.482388820758, 6912.827179320558, 6918.792436226921, 7003.3346832050875, 6760.08738820253, 6773.324536808032, 6793.422559082534, 6808.251092379735, 6843.828904277433, 6828.317168283132, 6858.41703455192, 6893.965530172317, 6906.102206713095, 6908.48186386013, 6640.800775976729, 6672.299408562031, 6690.832729181615, 6706.579644381303, 6786.660666966731], "value": 5039.09431101404, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.4776560876169, 806.959652017399, 819.4468733604818, 843.1652663862774, 848.1645579104581, 16527.83472165278, 17068.402605908796, 17074.06092664903, 18641.0, 19282.0, 18070.096495175254, 18704.25182992682, 18728.906355468236, 18959.33642322519, 18963.715544266848, 804.6901942752024, 942.7595963029435, 955.8996305387939, 960.8702825118589, 961.7211008807437, 993.7664648807524, 993.8260804359244, 995.1789773436943, 1000.0049950299463, 1000.1998401278947, 1040.380973320873, 1042.6038105519892, 1044.5143008501045, 1046.0219694585548, 1046.0899017854476, 976.5361453309656, 1029.6396261308557, 1035.0, 1036.1503567075001, 1039.080413833755, 750.8047907544023, 756.3193126186421, 763.0728664672395, 763.5266134996338, 764.0831002796666, 17180.570485737833, 17231.82768172315, 17331.306747730076, 17485.825141748675, 17494.562635934075, 14736.852631473763, 17545.56136096595, 17705.29178832845, 17901.7314740279, 18010.459686209433, 14811.0, 15285.082895026222, 15320.923395383063, 15389.461368852148, 15400.922995385063, 3634.0551456621206, 3662.34077865984, 3668.52309199805, 3680.0983758978955, 3683.7421380503374, 138163.0, 143694.0, 146731.53268467286, 147129.26435367862, 150938.0, 13780.448782048607, 13817.930910345383, 13847.446102155802, 14018.948578856627, 14082.0, 27189.320266993484, 27255.182344529694, 27349.0, 27437.451250974867, 27542.862285688843, 6844.315568443116, 7059.71761129549, 8141.18588141193, 8164.918350816416, 8231.42380033392, 3542.539469868952, 3913.1195481016543, 4078.530968938551, 4084.7753373564738, 4089.7750623715983, 4566.246569316092, 4624.622830340861, 4627.699199552018, 4647.209974304376, 4683.297505374221, 995.9701208963743, 998.1316254858175, 1003.5283416794096, 1011.7824667696333, 1015.2639336480992, 639.7536948274948, 646.8221239159175, 648.5460177875506, 667.9465642748569, 669.8392385827366, 10944.4527773611, 11222.775544489064, 12346.691332716575, 12386.938065309796, 12432.191907525981, 614.8124821929355, 640.6924676155378, 643.5045015338163, 674.7334802752983, 674.8582797612459, 4920.458749537548, 5008.899822003539, 5376.6505177164145, 5707.689540066752, 5782.5663075268685, 150276.0, 151334.0, 151374.0, 154150.0, 157097.0, 129020.0, 131371.34314328557, 133291.33354333165, 134131.0, 136830.0, 115281.4235928815, 117484.0, 117533.41233293779, 117619.0, 119238.21142682867, 10615.0, 10816.67549973502, 10833.837492437462, 11104.44477776124, 11455.713607159887, 44140.55859441493, 44436.0, 45523.0, 46363.0, 46447.53552464432, 63197.68401157965, 63279.683601581695, 66291.0, 66642.33357666362, 66847.0, 13817.585472435843, 14243.857561424253, 14599.270036497921, 14769.335379907716, 14820.7776883349, 15484.92257538705, 15729.292181851615, 16231.756523651919, 16372.181390930644, 18532.536686582942, 15464.5360639181, 17823.197956091746, 17848.46454606364, 17874.731879022085, 18222.36221732286, 16838.494845154666, 17197.65604687924, 17346.566335841704, 17530.822327734295, 18147.18337674834, 14316.928415357857, 16382.262798173871, 16429.096399697653, 17737.847039942364, 17746.822531774516, 11495.425228738362, 11754.941225293818, 11912.642620721046, 12343.0, 12526.123171377916, 12121.333326667118, 12757.48970041225, 13269.137506062076, 13558.271785082803, 13943.860561394256], "value": 8807.617659369145, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1536.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 106.0, 115.0, 116.0, 115.0, 115.0, 103.0, 111.0, 138.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 148.0, 142.0, 108.0, 130.0, 129.0, 108.0, 158.0, 111.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 197.0, 198.0, 199.0, 198.0, 199.0, 199.0, 199.0, 198.0, 197.0], "value": 198.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 81.0, 162.0, 60.0, 87.0, 91.0, 86.0, 121.0, 56.0], "value": 89.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 91.0, 112.0, 59.0, 99.0, 118.0, 138.0, 102.0, 71.0, 103.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 92.0, 92.0, 92.0, 90.0, 91.0, 90.0, 92.0, 91.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 101.0, 98.0, 98.0, 100.0, 114.0, 98.0, 122.0, 101.0, 155.0], "value": 108.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 100.0, 144.0, 144.0, 92.0, 91.0, 88.0, 92.0, 92.0], "value": 102.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 69.0, 76.0, 82.0, 76.0, 54.0, 54.0, 52.0, 50.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 58.0, 58.0, 58.0, 65.0, 55.0, 56.0, 58.0, 58.0], "value": 57.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 82.0, 79.0, 81.0, 80.0, 80.0, 83.0, 82.0, 95.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 149.0, 127.0, 149.0, 135.0, 159.0, 129.0, 134.0, 157.0, 166.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 191.0, 183.0, 176.0, 188.0, 182.0, 177.0, 208.0, 157.0, 238.0], "value": 191.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 64.0, 67.0, 65.0, 69.0, 70.0, 64.0, 90.0, 92.0], "value": 71.6, "unit": "score"}]}]} 12:59:53 INFO - Return code: 0 12:59:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:59:53 INFO - # TBPL SUCCESS # 12:59:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:59:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:59:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:59:53 INFO - 2017-06-20 12:59:53.744 screenresolution[7545:147087] starting screenresolution argv=screenresolution get 12:59:53 INFO - 2017-06-20 12:59:53.747 screenresolution[7545:147087] Display 0: 1600x1200x32@60 12:59:53 INFO - 2017-06-20 12:59:53.753 screenresolution[7546:147090] starting screenresolution argv=screenresolution list 12:59:53 INFO - Available Modes on Display 0 12:59:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:59:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:59:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:59:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:59:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:59:53 INFO - Intel Iris: 12:59:53 INFO - Chipset Model: Intel Iris 12:59:53 INFO - Type: GPU 12:59:53 INFO - Bus: Built-In 12:59:53 INFO - VRAM (Dynamic, Max): 1536 MB 12:59:53 INFO - Vendor: Intel (0x8086) 12:59:53 INFO - Device ID: 0x0a2e 12:59:53 INFO - Revision ID: 0x0009 12:59:53 INFO - Displays: 12:59:53 INFO - Display: 12:59:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:59:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:59:53 INFO - Display Serial Number: 1600x1200 60 12:59:53 INFO - Main Display: Yes 12:59:53 INFO - Mirror: Off 12:59:53 INFO - Online: Yes 12:59:53 INFO - Rotation: Supported 12:59:53 INFO - Return code: 0 12:59:53 INFO - Running post-action listener: _resource_record_post_action 12:59:53 INFO - [mozharness: 2017-06-20 19:59:53.990897Z] Finished run-tests step (success) 12:59:53 INFO - Running post-run listener: _resource_record_post_run 12:59:54 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 1022976; Write bytes: 561943552; Read time: 30; Write time: 1740 12:59:54 INFO - TinderboxPrint: CPU usage
53.0% 12:59:54 INFO - TinderboxPrint: I/O read bytes / time
1,022,976 / 30 12:59:54 INFO - TinderboxPrint: I/O write bytes / time
561,943,552 / 1,740 12:59:54 INFO - TinderboxPrint: CPU idle
2,365.2 (47.0%) 12:59:54 INFO - TinderboxPrint: CPU system
1,025.4 (20.4%) 12:59:54 INFO - TinderboxPrint: CPU user
1,643.0 (32.6%) 12:59:54 INFO - TinderboxPrint: Swap in / out
243,499,008 / 0 12:59:54 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 227566080; Write bytes: 228063232; Read time: 16934; Write time: 458 12:59:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:59:54 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 1022464; Write bytes: 315431936; Read time: 30; Write time: 1240 12:59:54 INFO - Running post-run listener: _upload_blobber_files 12:59:54 INFO - Blob upload gear active. 12:59:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:59:54 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 12:59:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:59:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:59:54 INFO - (blobuploader) - INFO - Open directory for files ... 12:59:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:59:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:59:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:56 INFO - (blobuploader) - INFO - Done attempting. 12:59:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:59:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:59:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:57 INFO - (blobuploader) - INFO - Done attempting. 12:59:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:59:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:59:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:58 INFO - (blobuploader) - INFO - Done attempting. 12:59:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:59:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:00:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:00:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:00:00 INFO - (blobuploader) - INFO - Done attempting. 13:00:00 INFO - (blobuploader) - INFO - Iteration through files over. 13:00:00 INFO - Return code: 0 13:00:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:00:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:00:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/846181bb417ad1acedfca564e256f08c1671e7b63bf59958be0bdba999f88f60c2659c829ce30449a73350d724bf9e506ac8f037728b85efaa31b4db4b37f4a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d34a3d71a53a28c9750c0adfb9f27f779a4336e0ef4fa7a08ae064ae01b8845872460eab4ca070720b33296eb0a1a484e984fb360212f64d16e866dc833e10d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/822e0d9f9ebdc659bf20bf30e1bb3522d55a91f714b117aefbd66cfacb0e5d190048e62e32889a20a52f6f814c81df2fa6f9e7fd92f99dc1d8aab591fb53396b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/822b9c6991b8a97eca843022aaad57fe2aef9600499166afb14848d18e08fb6e4d2856a1982700a52409aedd0d3311d7a79e2298ad301be8b40e473418462be1"} 13:00:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:00:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:00:00 INFO - Contents: 13:00:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/846181bb417ad1acedfca564e256f08c1671e7b63bf59958be0bdba999f88f60c2659c829ce30449a73350d724bf9e506ac8f037728b85efaa31b4db4b37f4a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d34a3d71a53a28c9750c0adfb9f27f779a4336e0ef4fa7a08ae064ae01b8845872460eab4ca070720b33296eb0a1a484e984fb360212f64d16e866dc833e10d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/822e0d9f9ebdc659bf20bf30e1bb3522d55a91f714b117aefbd66cfacb0e5d190048e62e32889a20a52f6f814c81df2fa6f9e7fd92f99dc1d8aab591fb53396b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/822b9c6991b8a97eca843022aaad57fe2aef9600499166afb14848d18e08fb6e4d2856a1982700a52409aedd0d3311d7a79e2298ad301be8b40e473418462be1"} 13:00:00 INFO - Running post-run listener: copy_logs_to_upload_dir 13:00:00 INFO - Copying logs to upload dir... 13:00:00 INFO - mkdir: /builds/slave/test/build/upload/logs 13:00:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.271293 ========= master_lag: 0.18 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-20 13:00:00.285385) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 13:00:00.289229) ========= 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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/846181bb417ad1acedfca564e256f08c1671e7b63bf59958be0bdba999f88f60c2659c829ce30449a73350d724bf9e506ac8f037728b85efaa31b4db4b37f4a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d34a3d71a53a28c9750c0adfb9f27f779a4336e0ef4fa7a08ae064ae01b8845872460eab4ca070720b33296eb0a1a484e984fb360212f64d16e866dc833e10d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/822e0d9f9ebdc659bf20bf30e1bb3522d55a91f714b117aefbd66cfacb0e5d190048e62e32889a20a52f6f814c81df2fa6f9e7fd92f99dc1d8aab591fb53396b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/822b9c6991b8a97eca843022aaad57fe2aef9600499166afb14848d18e08fb6e4d2856a1982700a52409aedd0d3311d7a79e2298ad301be8b40e473418462be1"} build_url:https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011416 build_url: 'https://queue.taskcluster.net/v1/task/IVr2xFHwT_af0rRhJXpdGg/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/846181bb417ad1acedfca564e256f08c1671e7b63bf59958be0bdba999f88f60c2659c829ce30449a73350d724bf9e506ac8f037728b85efaa31b4db4b37f4a3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d34a3d71a53a28c9750c0adfb9f27f779a4336e0ef4fa7a08ae064ae01b8845872460eab4ca070720b33296eb0a1a484e984fb360212f64d16e866dc833e10d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/822e0d9f9ebdc659bf20bf30e1bb3522d55a91f714b117aefbd66cfacb0e5d190048e62e32889a20a52f6f814c81df2fa6f9e7fd92f99dc1d8aab591fb53396b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/822b9c6991b8a97eca843022aaad57fe2aef9600499166afb14848d18e08fb6e4d2856a1982700a52409aedd0d3311d7a79e2298ad301be8b40e473418462be1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 13:00:00.322157) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 13:00:00.322497) ========= 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.b9bf5qRcYI/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.kcXtpDTRzw/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005501 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 13:00:00.373111) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 13:00:00.373637) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 13:00:00.374139) ========= ========= Total master_lag: 0.32 =========