builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0008 starttime: 1497539140.94 results: success (0) revision: 9e20e9175de1bbcea33b9b67be64d3734156bae4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:40.942942) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:40.943387) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:40.943659) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:40.968568) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:40.968919) ========= 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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-15 08:05:40-- 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% 47.4M=0s 2017-06-15 08:05:41 (47.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086046 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:41.077035) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:41.077366) ========= 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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.036401 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:41.133436) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-15 08:05:41.133769) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9e20e9175de1bbcea33b9b67be64d3734156bae4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9e20e9175de1bbcea33b9b67be64d3734156bae4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5InYrVQ8C3/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.kUIRF8bb7P/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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-15 08:05:41,221 truncating revision to first 12 chars 2017-06-15 08:05:41,222 Setting DEBUG logging. 2017-06-15 08:05:41,222 attempt 1/10 2017-06-15 08:05:41,222 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9e20e9175de1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-15 08:05:43,357 unpacking tar archive at: mozilla-beta-9e20e9175de1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.413773 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-15 08:05:43.568448) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:43.568785) ========= script_repo_revision: 9e20e9175de1bbcea33b9b67be64d3734156bae4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:43.569166) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:43.569448) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-15 08:05:43.587002) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2017-06-15 08:05:43.587337) ========= /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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:05:43 INFO - MultiFileLogger online at 20170615 08:05:43 in /builds/slave/test 08:05:43 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 08:05:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:05:43 INFO - {'append_to_log': False, 08:05:43 INFO - 'base_work_dir': '/builds/slave/test', 08:05:43 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:05:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:05:43 INFO - 'branch': 'Mozilla-Beta', 08:05:43 INFO - 'buildbot_json_path': 'buildprops.json', 08:05:43 INFO - 'config_files': ('talos/mac_config.py',), 08:05:43 INFO - 'default_actions': ('clobber', 08:05:43 INFO - 'read-buildbot-config', 08:05:43 INFO - 'download-and-extract', 08:05:43 INFO - 'populate-webroot', 08:05:43 INFO - 'create-virtualenv', 08:05:43 INFO - 'install', 08:05:43 INFO - 'setup-mitmproxy', 08:05:43 INFO - 'run-tests'), 08:05:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:05:43 INFO - 'download_minidump_stackwalk': True, 08:05:43 INFO - 'download_symbols': 'ondemand', 08:05:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:05:43 INFO - 'tooltool.py': '/tools/tooltool.py', 08:05:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:05:43 INFO - '/tools/misc-python/virtualenv.py')}, 08:05:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:05:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:05:43 INFO - 'gecko_profile': False, 08:05:43 INFO - 'gecko_profile_interval': 0, 08:05:43 INFO - 'installer_path': 'installer.exe', 08:05:43 INFO - 'log_level': 'info', 08:05:43 INFO - 'log_name': 'talos', 08:05:43 INFO - 'log_to_console': True, 08:05:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:05:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:05:43 INFO - 'opt_config_files': (), 08:05:43 INFO - 'pip_index': False, 08:05:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:05:43 INFO - 'cmd': ('bash', 08:05:43 INFO - '-c', 08:05:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:05:43 INFO - 'enabled': True, 08:05:43 INFO - 'halt_on_failure': False, 08:05:43 INFO - 'name': 'check_screen_resolution'},), 08:05:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:05:43 INFO - 'cmd': ('bash', 08:05:43 INFO - '-c', 08:05:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:05:43 INFO - 'enabled': True, 08:05:43 INFO - 'halt_on_failure': False, 08:05:43 INFO - 'name': 'check_screen_resolution'},), 08:05:43 INFO - 'run_cmd_checks_enabled': True, 08:05:43 INFO - 'suite': 'dromaeojs-e10s', 08:05:43 INFO - 'system_bits': '32', 08:05:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:05:43 INFO - 'title': 't-yosemite-r7-0008', 08:05:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:05:43 INFO - 'use_talos_json': True, 08:05:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:05:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:05:43 INFO - 'work_dir': 'build'} 08:05:43 INFO - [mozharness: 2017-06-15 15:05:43.785234Z] Running clobber step. 08:05:43 INFO - Running pre-action listener: _resource_record_pre_action 08:05:43 INFO - Running main action method: clobber 08:05:43 INFO - rmtree: /builds/slave/test/build 08:05:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:05:46 INFO - Running post-action listener: _resource_record_post_action 08:05:46 INFO - [mozharness: 2017-06-15 15:05:46.400884Z] Finished clobber step (success) 08:05:46 INFO - [mozharness: 2017-06-15 15:05:46.401039Z] Running read-buildbot-config step. 08:05:46 INFO - Running pre-action listener: _resource_record_pre_action 08:05:46 INFO - Running main action method: read_buildbot_config 08:05:46 INFO - Using buildbot properties: 08:05:46 INFO - { 08:05:46 INFO - "project": "", 08:05:46 INFO - "product": "firefox", 08:05:46 INFO - "who": "ffxbld@noreply.mozilla.org", 08:05:46 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 08:05:46 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:05:46 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 08:05:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:05:46 INFO - "stage_platform": "macosx64", 08:05:46 INFO - "basedir": "/builds/slave/test", 08:05:46 INFO - "buildnumber": 2, 08:05:46 INFO - "platform": "yosemite_r7", 08:05:46 INFO - "parent_task_id": "e1EiLJb0SXuJLhcDZBMFNQ", 08:05:46 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:05:46 INFO - "slavebuilddir": "test", 08:05:46 INFO - "taskId": "fybvtkr6TEO9VY6i9HyIZw", 08:05:46 INFO - "branch": "mozilla-beta", 08:05:46 INFO - "script_repo_revision": "production", 08:05:46 INFO - "revision": "9e20e9175de1bbcea33b9b67be64d3734156bae4", 08:05:46 INFO - "slavename": "t-yosemite-r7-0008", 08:05:46 INFO - "repo_path": "releases/mozilla-beta" 08:05:46 INFO - } 08:05:46 INFO - Finding installer, test and symbols from parent task. 08:05:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fybvtkr6TEO9VY6i9HyIZw'}, attempt #1 08:05:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fybvtkr6TEO9VY6i9HyIZw'}, attempt #1 08:05:47 INFO - Task dependencies: cK_Kdd4kRMSHC9vshkXvbg e1EiLJb0SXuJLhcDZBMFNQ 08:05:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ'}, attempt #1 08:05:48 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:05:48 INFO - Set test_url: https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/test_packages.json 08:05:48 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/target.crashreporter-symbols.zip 08:05:48 INFO - Running post-action listener: _resource_record_post_action 08:05:48 INFO - [mozharness: 2017-06-15 15:05:48.160081Z] Finished read-buildbot-config step (success) 08:05:48 INFO - [mozharness: 2017-06-15 15:05:48.160227Z] Running download-and-extract step. 08:05:48 INFO - Running pre-action listener: _resource_record_pre_action 08:05:48 INFO - Running main action method: download_and_extract 08:05:48 INFO - mkdir: /builds/slave/test/build/tests 08:05: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')]} 08:05:48 INFO - trying https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 08:05:48 INFO - Downloading https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/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 08:05:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/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 08:05:49 INFO - Downloaded 1290 bytes. 08:05:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 08:05:49 INFO - Using the following test package requirements: 08:05:49 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:05:49 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 08:05:49 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 08:05:49 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:05:49 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 08:05:49 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:05:49 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 08:05:49 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:05:49 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:05:49 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 08:05:49 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 08:05:49 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:05:49 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 08:05:49 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:05:49 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 08:05:49 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:05:49 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 08:05:49 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 08:05:49 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 08:05:49 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 08:05:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 08:05:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 08:05:49 INFO - Fetch https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 08:05:50 INFO - Content-Length response header: 58981247 08:05:50 INFO - Bytes received: 58981247 08:06:09 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 08:06:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 08:06:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 08:06:09 INFO - Fetch https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 08:06:11 INFO - Content-Length response header: 58981247 08:06:11 INFO - Bytes received: 58981247 08:06:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 08:06:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 08:06:29 INFO - Fetch https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 08:06:29 INFO - Content-Length response header: 14454473 08:06:29 INFO - Bytes received: 14454473 08:06:30 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')]} 08:06:30 INFO - trying https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:06:30 INFO - Downloading https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 08:06:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/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 08:06:31 INFO - Downloaded 51091676 bytes. 08:06:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:06:31 INFO - mkdir: /builds/slave/test/properties 08:06:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:06:31 INFO - Writing to file /builds/slave/test/properties/build_url 08:06:31 INFO - Contents: 08:06:31 INFO - build_url:https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 08:06:31 INFO - Running post-action listener: _resource_record_post_action 08:06:31 INFO - Running post-action listener: set_extra_try_arguments 08:06:31 INFO - [mozharness: 2017-06-15 15:06:31.941582Z] Finished download-and-extract step (success) 08:06:31 INFO - [mozharness: 2017-06-15 15:06:31.941716Z] Running populate-webroot step. 08:06:31 INFO - Running pre-action listener: _resource_record_pre_action 08:06:31 INFO - Running main action method: populate_webroot 08:06:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:06:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:06:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:06:31 INFO - '--tppagecycles', 08:06:31 INFO - '7'], 08:06:31 INFO - 'tests': ['tsvgm']}}, 08:06:31 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:06:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:06:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:06:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:06:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:06:31 INFO - 'talos_options': ['--disable-e10s'], 08:06:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:06:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:06:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:06:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:06:31 INFO - 'talos_options': ['--disable-e10s'], 08:06:31 INFO - 'tests': ['damp', 'tps']}, 08:06:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:06:31 INFO - 'tests': ['damp', 'tps']}, 08:06:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:06:31 INFO - 'tests': ['dromaeo_dom']}, 08:06:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:06:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:06:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:06:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:06:31 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:06:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:06:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:06:31 INFO - 'tests': ['a11yr', 08:06:31 INFO - 'ts_paint', 08:06:31 INFO - 'tpaint', 08:06:31 INFO - 'sessionrestore', 08:06:31 INFO - 'sessionrestore_no_auto_restore', 08:06:31 INFO - 'tabpaint']}, 08:06:31 INFO - 'other-e10s': {'tests': ['a11yr', 08:06:31 INFO - 'ts_paint', 08:06:31 INFO - 'tpaint', 08:06:31 INFO - 'sessionrestore', 08:06:31 INFO - 'sessionrestore_no_auto_restore', 08:06:31 INFO - 'tabpaint']}, 08:06:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:06:31 INFO - 'ts_paint', 08:06:31 INFO - 'tpaint', 08:06:31 INFO - 'sessionrestore', 08:06:31 INFO - 'sessionrestore_no_auto_restore', 08:06:31 INFO - 'tabpaint']}, 08:06:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:06:31 INFO - 'ts_paint', 08:06:31 INFO - 'tpaint', 08:06:31 INFO - 'sessionrestore', 08:06:31 INFO - 'sessionrestore_no_auto_restore', 08:06:31 INFO - 'tabpaint']}, 08:06:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:06:31 INFO - 'tests': ['a11yr', 08:06:31 INFO - 'ts_paint', 08:06:31 INFO - 'tpaint', 08:06:31 INFO - 'sessionrestore', 08:06:31 INFO - 'sessionrestore_no_auto_restore', 08:06:31 INFO - 'tabpaint']}, 08:06:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:06:31 INFO - 'tests': ['a11yr', 08:06:31 INFO - 'ts_paint', 08:06:31 INFO - 'tpaint', 08:06:31 INFO - 'sessionrestore', 08:06:31 INFO - 'sessionrestore_no_auto_restore', 08:06:31 INFO - 'tabpaint']}, 08:06:31 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:06:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:06:31 INFO - 'bloom_basic_ref']}, 08:06:31 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:06:31 INFO - 'talos_options': ['--mitmproxy', 08:06:31 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:06:31 INFO - '--firstNonBlankPaint'], 08:06:31 INFO - 'tests': ['quantum_pageload_google', 08:06:31 INFO - 'quantum_pageload_youtube', 08:06:31 INFO - 'quantum_pageload_amazon', 08:06:31 INFO - 'quantum_pageload_facebook']}, 08:06:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:06:31 INFO - 'tests': ['tsvgx', 08:06:31 INFO - 'tsvgr_opacity', 08:06:31 INFO - 'tart', 08:06:31 INFO - 'tscrollx', 08:06:31 INFO - 'cart', 08:06:31 INFO - 'tsvg_static']}, 08:06:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:06:31 INFO - 'tsvgr_opacity', 08:06:31 INFO - 'tart', 08:06:31 INFO - 'tscrollx', 08:06:31 INFO - 'cart', 08:06:31 INFO - 'tsvg_static']}, 08:06:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:06:31 INFO - 'talos_options': ['--disable-e10s'], 08:06:31 INFO - 'tests': ['tp5o']}, 08:06:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:06:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:06:31 INFO - 'talos_options': ['--disable-e10s', 08:06:31 INFO - '--xperf_path', 08:06:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:06:31 INFO - 'tests': ['tp5n']}, 08:06:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:06:31 INFO - 'talos_options': ['--xperf_path', 08:06:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:06:31 INFO - 'tests': ['tp5n']}}, 08:06:31 INFO - 'talos.zip': {'path': '', 08:06:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:06:31 INFO - Running post-action listener: _resource_record_post_action 08:06:31 INFO - [mozharness: 2017-06-15 15:06:31.964855Z] Finished populate-webroot step (success) 08:06:31 INFO - [mozharness: 2017-06-15 15:06:31.964967Z] Running create-virtualenv step. 08:06:31 INFO - Running pre-action listener: _resource_record_pre_action 08:06:31 INFO - Running main action method: create_virtualenv 08:06:31 INFO - Creating virtualenv /builds/slave/test/build/venv 08:06:31 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 08:06:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:06:31 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:06:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:06:32 INFO - Using real prefix '/tools/python27' 08:06:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:06:32 INFO - Installing distribute.............................................................................................................................................................................................done. 08:06:35 INFO - Installing pip.................done. 08:06:35 INFO - Return code: 0 08:06:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:06:36 INFO - Reading from file tmpfile_stdout 08:06:36 INFO - Output received: 08:06:36 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) 08:06:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:06: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')]} 08:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06: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')]} 08:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104139c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10413d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab0491950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, '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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:06: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', 'pip>=1.5'] in /builds/slave/test/build 08:06: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 pip>=1.5 08:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:36 INFO - 'HOME': '/Users/cltbld', 08:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:36 INFO - 'LOGNAME': 'cltbld', 08:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:36 INFO - 'MOZ_NO_REMOTE': '1', 08:06:36 INFO - 'NO_EM_RESTART': '1', 08:06:36 INFO - 'PAGER': '/bin/cat', 08:06:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:36 INFO - 'PWD': '/builds/slave/test', 08:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:36 INFO - 'SHELL': '/bin/bash', 08:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:06:36 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:36 INFO - 'USER': 'cltbld', 08:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:36 INFO - 'XPC_FLAGS': '0x0', 08:06:36 INFO - 'XPC_SERVICE_NAME': '0', 08:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:36 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 08:06:36 INFO - Cleaning up... 08:06:36 INFO - Return code: 0 08:06:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:06: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')]} 08:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06: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')]} 08:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06: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', '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 0x104139c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10413d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab0491950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, '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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:06: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:06: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 psutil>=3.1.1 08:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:36 INFO - 'HOME': '/Users/cltbld', 08:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:36 INFO - 'LOGNAME': 'cltbld', 08:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:36 INFO - 'MOZ_NO_REMOTE': '1', 08:06:36 INFO - 'NO_EM_RESTART': '1', 08:06:36 INFO - 'PAGER': '/bin/cat', 08:06:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:36 INFO - 'PWD': '/builds/slave/test', 08:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:36 INFO - 'SHELL': '/bin/bash', 08:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:06:36 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:36 INFO - 'USER': 'cltbld', 08:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:36 INFO - 'XPC_FLAGS': '0x0', 08:06:36 INFO - 'XPC_SERVICE_NAME': '0', 08:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:36 INFO - Downloading/unpacking psutil>=3.1.1 08:06: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 08:06: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 08:06: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 08:06: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 08:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:06:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:06:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:06:38 INFO - Installing collected packages: psutil 08:06:38 INFO - Running setup.py install for psutil 08:06:38 INFO - building 'psutil._psutil_osx' extension 08:06:38 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 08:06:39 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 08:06:39 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 08:06:39 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 08:06:39 INFO - building 'psutil._psutil_posix' extension 08:06:39 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 08:06:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:06:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:06:39 INFO - ^ 08:06:39 INFO - 1 warning generated. 08:06:39 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 08:06:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:06:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:06:39 INFO - Successfully installed psutil 08:06:39 INFO - Cleaning up... 08:06:39 INFO - Return code: 0 08:06:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:06:39 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')]} 08:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06: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')]} 08:06:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104139c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10413d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab0491950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, '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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:06: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:06: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 mozsystemmonitor==0.3 08:06:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:06:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:40 INFO - 'HOME': '/Users/cltbld', 08:06:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:40 INFO - 'LOGNAME': 'cltbld', 08:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:40 INFO - 'MOZ_NO_REMOTE': '1', 08:06:40 INFO - 'NO_EM_RESTART': '1', 08:06:40 INFO - 'PAGER': '/bin/cat', 08:06:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:40 INFO - 'PWD': '/builds/slave/test', 08:06:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:40 INFO - 'SHELL': '/bin/bash', 08:06:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:06:40 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:06:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:40 INFO - 'USER': 'cltbld', 08:06:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:40 INFO - 'XPC_FLAGS': '0x0', 08:06:40 INFO - 'XPC_SERVICE_NAME': '0', 08:06:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:06:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:06:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:06:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:06:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:06:42 INFO - Installing collected packages: mozsystemmonitor 08:06:42 INFO - Running setup.py install for mozsystemmonitor 08:06:42 INFO - Successfully installed mozsystemmonitor 08:06:42 INFO - Cleaning up... 08:06:42 INFO - Return code: 0 08:06:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:06:42 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')]} 08:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:42 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')]} 08:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:42 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 0x104139c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10413d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab0491950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, '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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:06:42 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 08:06:42 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 08:06:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:06:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:42 INFO - 'HOME': '/Users/cltbld', 08:06:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:42 INFO - 'LOGNAME': 'cltbld', 08:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:42 INFO - 'MOZ_NO_REMOTE': '1', 08:06:42 INFO - 'NO_EM_RESTART': '1', 08:06:42 INFO - 'PAGER': '/bin/cat', 08:06:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:42 INFO - 'PWD': '/builds/slave/test', 08:06:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:42 INFO - 'SHELL': '/bin/bash', 08:06:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:06:42 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:06:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:42 INFO - 'USER': 'cltbld', 08:06:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:42 INFO - 'XPC_FLAGS': '0x0', 08:06:42 INFO - 'XPC_SERVICE_NAME': '0', 08:06:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:42 INFO - Downloading/unpacking jsonschema==2.5.1 08:06: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 08:06: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 08:06: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 08:06: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 08:06:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:06:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:06:44 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 08:06:44 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 08:06:44 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 08:06:44 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 08:06:44 INFO - Downloading functools32-3.2.3-2.tar.gz 08:06:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:06:44 INFO - warning: no files found matching '*.txt' 08:06:44 INFO - no previously-included directories found matching 'build' 08:06:44 INFO - no previously-included directories found matching 'dist' 08:06:44 INFO - no previously-included directories found matching '.git*' 08:06:44 INFO - Installing collected packages: jsonschema, functools32 08:06:44 INFO - Running setup.py install for functools32 08:06:44 INFO - warning: no files found matching '*.txt' 08:06:44 INFO - no previously-included directories found matching 'build' 08:06:44 INFO - no previously-included directories found matching 'dist' 08:06:44 INFO - no previously-included directories found matching '.git*' 08:06:44 INFO - Successfully installed jsonschema functools32 08:06:44 INFO - Cleaning up... 08:06:44 INFO - Return code: 0 08:06:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:06: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')]} 08:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06: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')]} 08:06:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:44 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 0x104139c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10413d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab0491950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, '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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:06:44 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 08:06:44 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 08:06:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:06:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:44 INFO - 'HOME': '/Users/cltbld', 08:06:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:44 INFO - 'LOGNAME': 'cltbld', 08:06:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:44 INFO - 'MOZ_NO_REMOTE': '1', 08:06:44 INFO - 'NO_EM_RESTART': '1', 08:06:44 INFO - 'PAGER': '/bin/cat', 08:06:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:44 INFO - 'PWD': '/builds/slave/test', 08:06:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:44 INFO - 'SHELL': '/bin/bash', 08:06:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:06:44 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:06:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:44 INFO - 'USER': 'cltbld', 08:06:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:44 INFO - 'XPC_FLAGS': '0x0', 08:06:44 INFO - 'XPC_SERVICE_NAME': '0', 08:06:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:06:45 INFO - Cleaning up... 08:06:45 INFO - Return code: 0 08:06:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:45 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 0x104139c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10413d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab0491950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, '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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:06:45 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 08:06:45 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 08:06:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:06:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:45 INFO - 'HOME': '/Users/cltbld', 08:06:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:45 INFO - 'LOGNAME': 'cltbld', 08:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:45 INFO - 'MOZ_NO_REMOTE': '1', 08:06:45 INFO - 'NO_EM_RESTART': '1', 08:06:45 INFO - 'PAGER': '/bin/cat', 08:06:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:45 INFO - 'PWD': '/builds/slave/test', 08:06:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:45 INFO - 'SHELL': '/bin/bash', 08:06:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:06:45 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:06:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:45 INFO - 'USER': 'cltbld', 08:06:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:45 INFO - 'XPC_FLAGS': '0x0', 08:06:45 INFO - 'XPC_SERVICE_NAME': '0', 08:06:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:45 INFO - Downloading/unpacking blobuploader==1.2.4 08:06:45 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 08:06:45 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 08:06:45 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 08:06:45 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 08:06:46 INFO - Downloading blobuploader-1.2.4.tar.gz 08:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:06:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:06:46 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 08:06:46 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 08:06:46 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 08:06:46 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 08:06:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:06:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:06:47 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 08:06:47 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 08:06:47 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 08:06:47 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 08:06:47 INFO - Downloading docopt-0.6.1.tar.gz 08:06:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:06:47 INFO - Installing collected packages: blobuploader, requests, docopt 08:06:47 INFO - Running setup.py install for blobuploader 08:06:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:06:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:06:47 INFO - Running setup.py install for requests 08:06:48 INFO - Running setup.py install for docopt 08:06:48 INFO - Successfully installed blobuploader requests docopt 08:06:48 INFO - Cleaning up... 08:06:48 INFO - Return code: 0 08:06:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:06: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')]} 08:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06: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')]} 08:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:48 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 0x104139c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10413d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab0491950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, '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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:06:48 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 08:06:48 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 08:06:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:06:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:48 INFO - 'HOME': '/Users/cltbld', 08:06:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:48 INFO - 'LOGNAME': 'cltbld', 08:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:48 INFO - 'MOZ_NO_REMOTE': '1', 08:06:48 INFO - 'NO_EM_RESTART': '1', 08:06:48 INFO - 'PAGER': '/bin/cat', 08:06:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:48 INFO - 'PWD': '/builds/slave/test', 08:06:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:48 INFO - 'SHELL': '/bin/bash', 08:06:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:06:48 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:06:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:48 INFO - 'USER': 'cltbld', 08:06:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:48 INFO - 'XPC_FLAGS': '0x0', 08:06:48 INFO - 'XPC_SERVICE_NAME': '0', 08:06:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:06:48 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-AzQyqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:06:48 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-4mu7y8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:06:48 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-1tdhL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:06:49 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-hgxamG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:06:49 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-5PMBo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:06:49 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ENvyAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:06:49 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-AiKQA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:06:49 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-S5Y5qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:06:49 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-L_HIfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:06:49 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-vaRaIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:06:49 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-tv22pW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:06:49 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-pE74jQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:06:49 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-apHYPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:06:50 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-73e7wW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:06:50 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-hnvcHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:06:50 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-yrC4Ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:06:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:06:50 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-oSmWd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:06:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:06:50 INFO - Running setup.py install for manifestparser 08:06:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:06:50 INFO - Running setup.py install for mozcrash 08:06:50 INFO - Running setup.py install for mozdebug 08:06:50 INFO - Running setup.py install for mozdevice 08:06:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:06:51 INFO - Running setup.py install for mozfile 08:06:51 INFO - Running setup.py install for mozhttpd 08:06:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:06:51 INFO - Running setup.py install for mozinfo 08:06:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:06:51 INFO - Running setup.py install for mozInstall 08:06:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:06:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:06:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:06:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:06:51 INFO - Running setup.py install for mozleak 08:06:51 INFO - Running setup.py install for mozlog 08:06:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:06:51 INFO - Running setup.py install for moznetwork 08:06:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:06:52 INFO - Running setup.py install for mozprocess 08:06:52 INFO - Running setup.py install for mozprofile 08:06:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:06:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:06:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:06:52 INFO - Running setup.py install for mozrunner 08:06:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:06:52 INFO - Running setup.py install for mozscreenshot 08:06:52 INFO - Running setup.py install for moztest 08:06:52 INFO - Running setup.py install for mozversion 08:06:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:06:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:06:53 INFO - Cleaning up... 08:06:53 INFO - Return code: 0 08:06:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:53 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 0x104139c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10413d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab0491950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, '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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:06:53 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 08:06:53 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 08:06:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:06:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:53 INFO - 'HOME': '/Users/cltbld', 08:06:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:53 INFO - 'LOGNAME': 'cltbld', 08:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:53 INFO - 'MOZ_NO_REMOTE': '1', 08:06:53 INFO - 'NO_EM_RESTART': '1', 08:06:53 INFO - 'PAGER': '/bin/cat', 08:06:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:53 INFO - 'PWD': '/builds/slave/test', 08:06:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:53 INFO - 'SHELL': '/bin/bash', 08:06:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:06:53 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:06:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:53 INFO - 'USER': 'cltbld', 08:06:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:53 INFO - 'XPC_FLAGS': '0x0', 08:06:53 INFO - 'XPC_SERVICE_NAME': '0', 08:06:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:06:53 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-tBkelg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:06:53 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)) 08:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:06:53 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-A9fbN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:06:53 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)) 08:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:06:53 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ytSJb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:06:53 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)) 08:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:06:53 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-ox8vnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:06:53 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)) 08:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:06:53 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-SYRjK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:06:53 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)) 08:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:06:53 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Ht4i19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:06:53 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)) 08:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:06:53 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-wCgsow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:06:54 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)) 08:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:06:54 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-FD5xjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:06:54 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)) 08:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:06:54 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-Hmxysf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:06:54 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)) 08:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:06:54 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-PLjj5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:06:54 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)) 08:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:06:54 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-SPWdiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:06:54 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)) 08:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:06:54 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-2oFXYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:06:54 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)) 08:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:06:54 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-n7ucZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:06:54 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)) 08:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:06:54 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-hrNuE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:06:54 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)) 08:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:06:54 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-dVGz7o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:06:54 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)) 08:06:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:06:54 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-k0gtCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:06:55 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)) 08:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:06:55 INFO - Running setup.py (path:/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/pip-KDtbA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:06:55 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)) 08:06:55 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)) 08:06:55 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)) 08:06:55 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)) 08:06:55 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)) 08:06:55 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)) 08:06:55 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)) 08:06:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:06:55 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 08:06:55 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 08:06:55 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 08:06:55 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 08:06:56 INFO - Downloading blessings-1.6.tar.gz 08:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:06:56 INFO - Installing collected packages: blessings 08:06:56 INFO - Running setup.py install for blessings 08:06:56 INFO - Successfully installed blessings 08:06:56 INFO - Cleaning up... 08:06:56 INFO - Return code: 0 08:06:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:06:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:06:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:06:57 INFO - Reading from file tmpfile_stdout 08:06:57 INFO - Current package versions: 08:06:57 INFO - blessings == 1.6 08:06:57 INFO - blobuploader == 1.2.4 08:06:57 INFO - docopt == 0.6.1 08:06:57 INFO - functools32 == 3.2.3-2 08:06:57 INFO - jsonschema == 2.5.1 08:06:57 INFO - manifestparser == 1.1 08:06:57 INFO - mozInstall == 1.13 08:06:57 INFO - mozcrash == 1.0 08:06:57 INFO - mozdebug == 0.1 08:06:57 INFO - mozdevice == 0.51 08:06:57 INFO - mozfile == 1.2 08:06:57 INFO - mozhttpd == 0.7 08:06:57 INFO - mozinfo == 0.10 08:06:57 INFO - mozleak == 0.1 08:06:57 INFO - mozlog == 3.4 08:06:57 INFO - moznetwork == 0.27 08:06:57 INFO - mozprocess == 0.25 08:06:57 INFO - mozprofile == 0.28 08:06:57 INFO - mozrunner == 6.13 08:06:57 INFO - mozscreenshot == 0.1 08:06:57 INFO - mozsystemmonitor == 0.3 08:06:57 INFO - moztest == 0.8 08:06:57 INFO - mozversion == 1.4 08:06:57 INFO - psutil == 3.1.1 08:06:57 INFO - requests == 1.2.3 08:06:57 INFO - wsgiref == 0.1.2 08:06:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:06:57 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')]} 08:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:57 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')]} 08:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:57 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 0x104139c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10413d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab0491950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, '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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:06:57 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 08:06:57 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 08:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:57 INFO - 'HOME': '/Users/cltbld', 08:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:57 INFO - 'LOGNAME': 'cltbld', 08:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:57 INFO - 'MOZ_NO_REMOTE': '1', 08:06:57 INFO - 'NO_EM_RESTART': '1', 08:06:57 INFO - 'PAGER': '/bin/cat', 08:06:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:57 INFO - 'PWD': '/builds/slave/test', 08:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:57 INFO - 'SHELL': '/bin/bash', 08:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:06:57 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:57 INFO - 'USER': 'cltbld', 08:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:57 INFO - 'XPC_FLAGS': '0x0', 08:06:57 INFO - 'XPC_SERVICE_NAME': '0', 08:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:57 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)) 08:06:57 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)) 08:06:57 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)) 08:06:57 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)) 08:06:57 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)) 08:06:57 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)) 08:06:57 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)) 08:06:57 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)) 08:06:57 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)) 08:06:57 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:06:57 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 08:06:57 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 08:06:57 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 08:06:57 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 08:06:58 INFO - Downloading marionette_driver-1.4.0.tar.gz 08:06:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 08:06:58 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)) 08:06:58 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)) 08:06:58 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)) 08:06:58 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)) 08:06:58 INFO - Installing collected packages: marionette-driver 08:06:58 INFO - Running setup.py install for marionette-driver 08:06:59 INFO - Successfully installed marionette-driver 08:06:59 INFO - Cleaning up... 08:06:59 INFO - Return code: 0 08:06:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:06:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104139c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10413d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ab0491950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, '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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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 08:06:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:06:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:06:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:06:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:06:59 INFO - 'HOME': '/Users/cltbld', 08:06:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:06:59 INFO - 'LOGNAME': 'cltbld', 08:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:06:59 INFO - 'MOZ_NO_REMOTE': '1', 08:06:59 INFO - 'NO_EM_RESTART': '1', 08:06:59 INFO - 'PAGER': '/bin/cat', 08:06:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:06:59 INFO - 'PWD': '/builds/slave/test', 08:06:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:06:59 INFO - 'SHELL': '/bin/bash', 08:06:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:06:59 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:06:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:06:59 INFO - 'USER': 'cltbld', 08:06:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:06:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:06:59 INFO - 'XPC_FLAGS': '0x0', 08:06:59 INFO - 'XPC_SERVICE_NAME': '0', 08:06:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:06:59 INFO - Cleaning up... 08:06:59 INFO - Return code: 0 08:06:59 INFO - Running post-action listener: _resource_record_post_action 08:06:59 INFO - Running post-action listener: _start_resource_monitoring 08:06:59 INFO - Starting resource monitoring. 08:06:59 INFO - [mozharness: 2017-06-15 15:06:59.436207Z] Finished create-virtualenv step (success) 08:06:59 INFO - [mozharness: 2017-06-15 15:06:59.437332Z] Running install step. 08:06:59 INFO - Running pre-action listener: _resource_record_pre_action 08:06:59 INFO - Running main action method: install 08:06:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:06:59 INFO - Reading from file tmpfile_stdout 08:06:59 INFO - Detecting whether we're running mozinstall >=1.0... 08:06:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:06:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:06:59 INFO - Reading from file tmpfile_stdout 08:06:59 INFO - Output received: 08:06:59 INFO - Usage: mozinstall [options] installer 08:06:59 INFO - Options: 08:06:59 INFO - -h, --help show this help message and exit 08:06:59 INFO - -d DEST, --destination=DEST 08:06:59 INFO - Directory to install application into. [default: 08:06:59 INFO - "/builds/slave/test"] 08:06:59 INFO - --app=APP Application being installed. [default: firefox] 08:06:59 INFO - mkdir: /builds/slave/test/build/application 08:06:59 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'] 08:06:59 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 08:07:15 INFO - Reading from file tmpfile_stdout 08:07:15 INFO - Output received: 08:07:15 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:07:15 INFO - Running post-action listener: _resource_record_post_action 08:07:15 INFO - [mozharness: 2017-06-15 15:07:15.320933Z] Finished install step (success) 08:07:15 INFO - [mozharness: 2017-06-15 15:07:15.321067Z] Running setup-mitmproxy step. 08:07:15 INFO - Running pre-action listener: _resource_record_pre_action 08:07:15 INFO - Running main action method: setup_mitmproxy 08:07:15 INFO - Skipping: mitmproxy is not required 08:07:15 INFO - Running post-action listener: _resource_record_post_action 08:07:15 INFO - [mozharness: 2017-06-15 15:07:15.321702Z] Finished setup-mitmproxy step (success) 08:07:15 INFO - [mozharness: 2017-06-15 15:07:15.321818Z] Running run-tests step. 08:07:15 INFO - Running pre-action listener: _resource_record_pre_action 08:07:15 INFO - Running main action method: run_tests 08:07:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:07:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:07:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:07:15 INFO - 2017-06-15 08:07:15.336 screenresolution[1630:7434] starting screenresolution argv=screenresolution get 08:07:15 INFO - 2017-06-15 08:07:15.339 screenresolution[1630:7434] Display 0: 1600x1200x32@60 08:07:15 INFO - 2017-06-15 08:07:15.345 screenresolution[1631:7436] starting screenresolution argv=screenresolution list 08:07:15 INFO - Available Modes on Display 0 08:07:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:07:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:07:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:07:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:07:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:07:15 INFO - Intel Iris: 08:07:15 INFO - Chipset Model: Intel Iris 08:07:15 INFO - Type: GPU 08:07:15 INFO - Bus: Built-In 08:07:15 INFO - VRAM (Dynamic, Max): 1536 MB 08:07:15 INFO - Vendor: Intel (0x8086) 08:07:15 INFO - Device ID: 0x0a2e 08:07:15 INFO - Revision ID: 0x0009 08:07:15 INFO - Displays: 08:07:15 INFO - Display: 08:07:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:07:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:07:15 INFO - Display Serial Number: 1600x1200 60 08:07:15 INFO - Main Display: Yes 08:07:15 INFO - Mirror: Off 08:07:15 INFO - Online: Yes 08:07:15 INFO - Rotation: Supported 08:07:15 INFO - Return code: 0 08:07:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:07:15 INFO - Python 2.7.3 08:07:15 INFO - Return code: 0 08:07:15 INFO - grabbing minidump binary from tooltool 08:07:15 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')]} 08:07:15 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 0x103e8fd30>, '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 0x103e59cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e80be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:07:15 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 08:07:15 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 08:07:15 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 08:07:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:07:15 INFO - Return code: 0 08:07:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:07:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:07:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:07:15 INFO - ENV: RUST_BACKTRACE is now 1 08:07:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:07:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:07:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--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 08:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0008 --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 08:07:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5InYrVQ8C3/Render', 08:07:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:07:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:07:15 INFO - 'HOME': '/Users/cltbld', 08:07:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:07:15 INFO - 'LOGNAME': 'cltbld', 08:07:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:07:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:07:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:15 INFO - 'MOZ_NO_REMOTE': '1', 08:07:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:07:15 INFO - 'NO_EM_RESTART': '1', 08:07:15 INFO - 'PAGER': '/bin/cat', 08:07:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:07:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:07:15 INFO - 'PWD': '/builds/slave/test', 08:07:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:07:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:07:15 INFO - 'RUST_BACKTRACE': '1', 08:07:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:07:15 INFO - 'SHELL': '/bin/bash', 08:07:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kUIRF8bb7P/Listeners', 08:07:15 INFO - 'TMPDIR': '/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/', 08:07:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:07:15 INFO - 'USER': 'cltbld', 08:07:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:07:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:07:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:07:15 INFO - 'XPC_FLAGS': '0x0', 08:07:15 INFO - 'XPC_SERVICE_NAME': '0', 08:07:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:07:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0008', '--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 08:07:15 INFO - mozversion application_buildid: 20170615070519 08:07:15 INFO - mozversion application_changeset: 9e20e9175de1bbcea33b9b67be64d3734156bae4 08:07:15 INFO - mozversion application_display_name: Firefox 08:07:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:07:15 INFO - mozversion application_name: Firefox 08:07:15 INFO - mozversion application_remotingname: firefox 08:07:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:07:15 INFO - mozversion application_vendor: Mozilla 08:07:15 INFO - mozversion application_version: 55.0 08:07:15 INFO - mozversion platform_buildid: 20170615070519 08:07:15 INFO - mozversion platform_changeset: 9e20e9175de1bbcea33b9b67be64d3734156bae4 08:07:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:07:15 INFO - mozversion platform_version: 55.0 08:07:15 INFO - using testdate: 1497539235 08:07:15 INFO - actual date: 1497539235 08:07:15 INFO - starting webserver on 'localhost:49233' 08:07:15 INFO - SUITE-START | Running 2 tests 08:07:15 INFO - TEST-START | dromaeo_css 08:07:15 INFO - operating with platform_type : mac_ 08:07:15 INFO - Initialising browser for dromaeo_css test... 08:07:15 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpVE9KXD/profile 08:07:16 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground) 08:07:49 INFO - TEST-INFO | 1641: killed by SIGTERM 08:07:49 INFO - Browser initialized. 08:07:49 INFO - Running cycle 1/1 for dromaeo_css test... 08:07:49 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpVE9KXD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:07:50 INFO - PID 1651 | 2017-06-15 08:07:50.781 plugin-container[1652:7966] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 08:07:50 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:07:50 INFO - PID 1651 | 2017-06-15 08:07:50.782 plugin-container[1652:7966] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 08:07:50 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:07:50 INFO - PID 1651 | 2017-06-15 08:07:50.782 plugin-container[1652:7966] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:08:00 INFO - PID 1651 | RSS: Main: 184422400 08:08:00 INFO - PID 1651 | 08:11:03 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49233/tests/dromaeo/cssquery-ext.html) 08:11:04 INFO - PID 1651 | RSS: Main: 204750848 08:11:04 INFO - PID 1651 | 08:14:06 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-ext.html (next: http://localhost:49233/tests/dromaeo/cssquery-jquery.html) 08:14:07 INFO - PID 1651 | RSS: Main: 216612864 08:14:07 INFO - PID 1651 | 08:17:08 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49233/tests/dromaeo/cssquery-mootools.html) 08:17:09 INFO - PID 1651 | RSS: Main: 218439680 08:17:09 INFO - PID 1651 | 08:20:11 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49233/tests/dromaeo/cssquery-prototype.html) 08:20:11 INFO - PID 1651 | RSS: Main: 220069888 08:20:11 INFO - PID 1651 | 08:23:14 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49233/tests/dromaeo/cssquery-yui.html) 08:23:15 INFO - PID 1651 | RSS: Main: 220934144 08:23:15 INFO - PID 1651 | 08:26:16 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-yui.html (next: http://localhost:49233/tests/dromaeo/cssquery-dojo.html) 08:26:16 INFO - PID 1651 | RSS: Main: 219774976 08:26:16 INFO - PID 1651 | 08:26:16 INFO - PID 1651 | __start_tp_report 08:26:16 INFO - PID 1651 | _x_x_mozilla_page_load 08:26:16 INFO - PID 1651 | _x_x_mozilla_page_load_details 08:26:16 INFO - PID 1651 | |i|pagename|runs| 08:26:17 INFO - PID 1651 | |0;dojo.html;3388.2884594235215;3658.8353524091413;3802.8098595070246;3812.390017597185;3850.345441274983;5170.405403378612;6390.904136437951;6400.1039854420305;6423.293437721853;6425.389587989145;6436.066770318301;6443.710033048513;6479.15770949777;6503.349665033495;6516.837079073014;5682.545396368291;5725.770969161229;5743.712814359265;5865.35481097079;5867.413258674141;5752.050911599581;5754.65472071675;5780.942190578083;5792.739326730307;5880.55895807814;5444.7822087116465;5601.019821531216;5617.129344951519;5643.5767317451355;5662.292213473316;5679.432056794329;5767.423257674241;5777.24895763548;5793.13103034544;5812.796552120655;5670.3479099903625;5716.142578613241;5720.1991721159075;5760.078387458028;5827.941720582782;6421.550491465599;6489.805305840832;6533.248676402227;6535.117759102555;6578.3421657834315;6767.2894346093935;6914.239433662293;7080.822979425556;7090.255523170044;7102.964485177541;7042.401395881357;7115.181754098241;7117.64411779409;7139.39315158207;7191.712331506734;5248.973755131238;5708.343540492855;6005.969970150121;6423.4861211103935;6631.933680663228;7321.78034658961;7354.933534637447;7364.3003914628025;7378.483506154517;7390.556566606021;6694.263631000586;6774.525783195228;6802.8299292517095;6807.761728339485;6980.406665433495;6969.337912898267;7045.964770176116;7114.288571142948;7159.713611455483;7170.354668079898;6950.20072691647;7026.859462810715;7027.929720702829;7036.542624729376;7062.6821793019435;7004.404625606779;7031.261717519689;7041.3310735480045;7064.7174113035935;7075.823104422328;5672.574556908248;5682.090865461506;5697.031504644137;5697.715114244271;5708.343540492897;3638.6179451157777;4368.497622773421;4412.940894185373;4850.878728031827;5015.297858299841;6473.126127972758;6483.7082331295205;6503.902441463382;6622.072909792633;6652.634105124168;7097.574145551283;7107.964460177769;7132.500724949204;7142.964285178541;7181.6409179540815;6613.173353330833;6616.371444712745;6624.701888415033;6626.436752875963;6642.966785166043;6136.6624835633575;6227.96886015567;6294.968525157345;6296.118543403927;6306.779262725782;7077.752278670222;7177.928220717726;7197.604131772803;7325.450591205681;7365.742199023009;7029.683664235224;7051.894221586577;7152.177499587406;7152.642367881481;7162.997180394853;5679.858003549779;7347.816304592214;7396.4822462429265;7412;7428.591427471325;5825.854353641193;6918;7023.262557431396;7029.226785053742;7042.894356584758;7149.249328820399;7172.175199852084;7176.605286709281;7182.856342873218;7199.928000719926;6665.766698165541;6760.459163267025;6767.221769496571;6826.692798823966;6833.965830170818;6858.794236172923;6914.377706006484;6929.757458488929;6931.064306318583;6942.930570694229;6464.903026454701;6795.592264464148;6809.795706128824;6830.624315662686;6834;6913.170419549686;6938.202106757787;6962.199347075151;6980.685869135799;6987.371136597731;6516.5112616553;6549.214094308713;6570.47436205112;6586.110875031809;6602.933970660424 08:26:17 INFO - PID 1651 | |1;ext.html;839.6431516605445;866.536403024382;877.8946526416831;894.677915950258;907.8955920069186;38358.61641383585;39014.60985390145;39155.0211244719;39184.21631567371;39212;31544.84227578859;38432.615673843255;38733;38752.80623596878;38764.80617596908;6485.578437401554;7794.571298578576;7937.404694647925;7938.047434307891;7938.682452701885;8538.87191692122;8594.828103437927;8649.610767515476;8658.740237792876;8663.39356245063;7148;8571.742847714579;8573.914260857406;8638.524881131536;8645.30837532999;8560;8563.272121869617;8614.138586141398;8614.267787238092;8618.56907154646;8482;8507.574621268912;8568.70009549669;8573.228409443118;8578.742637720878;36790;37012;37032;37069.88790336294;37106.70126545585;3645.343838109139;3684.1894783147663;3685.6682898538993;3686.7419280650365;3687.2994131115;30429.847850760827;30565.388692226254;30644.08067757971;30680.53979190314;30721.846390767903;2739.575365818301;3226.145071556016;3270.73834093272;3313.6023677158896;3351.145457908218;263816;267872.32127678866;269029;327238;328115;40176.79911600423;47163.76418117956;47187;47647.761761191665;47945;49460;49848;49901.750491248036;49907.0018599626;49954.50045499571;15977.201139942956;16102.597435063983;16117.919410402874;16119.919400402923;16179.948303360245;11590.536378544932;12783.977281817432;13070.934645326903;13164.47342106305;13167.868321316855;13330.666733331744;13350.332483375792;13532.32338383077;13630.454781808616;13678.931605342108;5095.312132862016;5913.379095195029;5944.346121926585;5982.850428739229;6110.633361998294;1014.705735336744;1014.7057353367588;1017.3488967061087;1066.935983840972;1071.8338657508098;19856.90071549662;23247.72137532452;23316.7668323318;23889.402764931016;24296.75703242945;980.1913421427352;1023.2939271902363;1081.2755453845941;1134.9602763903224;1198.149313987079;12304;12693.984481241663;12733;12740.490380384867;12741.299228542519;317229;319594.402027993;320555.9305086864;321226;321233;53785.731071344395;54458;54596.908061839335;54770.17844732332;54784;1336.565616174747;1494.103537877265;1518.3091693279532;1548.2955255358784;1594.7209238383236;22802.42993925165;23388.64917026212;23475.882620586788;23496.647550286416;24319.51360972806;37705.43441848319;37928.810355948044;38010;38055;38108.04729881775;289613;289647;289866;289901;290304;32646.347073057932;32785.50821737628;33010.504842427865;33759.4936075964;33967.83016084904;42100.94747631334;44404.33393499165;44734.55265447304;44958.77520612376;44984.32523512215;28708.282292942844;28965.85517072401;29938.25154371071;30544.541831872095;31608.841955790074;30412.39175216439;31120;31594.210144746565;31634.525482118246;31719.682803172596;36262.09344766296;38377;38495.80752096222;38501.844944651704;38503.22993540057;28911.42177156487;29475.263118422212;30106.84946575253;30403;31890.7243710249;24568.631470527598;24627.507449850546;24963.87518062398;25062.498750024533;25167 08:26:17 INFO - PID 1651 | |2;jquery.html;511.39655206855906;533.9225812257223;538.4965057671076;547.2393373211235;548.7887163442076;20204.797952020475;23543.529129417424;24278;24465;24494.87752561239;24929.75070249297;27366.179014629543;27411;27463.86268068657;27523.449531009366;6887.311268873123;7040.753573624924;7043.119610048744;7053.329933656294;7056.188538318108;6942.062821519106;6947.166340039177;6994.860102797941;7022.262662420449;7022.578645281274;5929.318128415244;6209.534284928626;6529.281779004329;6587.736490540373;6598.7030583623855;6572.474202063848;6626.37049480301;6637.203535575762;6646.833829154261;6673.4661227101715;6127.938720612781;6137.110119032715;6141.078838174265;6150.477209437203;6154.8769024619705;31201.37597248065;32827.6717232827;33192.50211246833;33234.50148247778;33238;2316.6061769499224;2386.081358676922;2389.438481956739;2389.749076346976;2393.365758074112;20477.692834607493;20838.68741968872;21020.15919363239;21056.473588160417;21125;2285.8171346292424;2319.5476882007847;2355.8586484810794;2363.3973336799177;2363.8463499872623;225432;227994;228916;229639.8518007416;233035.8348208265;14677.55967320982;16223.432179873647;16477.91761041187;16538.917305413397;16696.41562545305;22144.22495212692;22465.663015054786;22490.550188996454;22515.43711407228;22522.549549008927;9711.757206069904;11051.557937682403;11190.552377904793;11193.888061119447;11200.383978881076;10332.018458246455;10360.740981475523;10370.948145259375;10386.844197336895;10410.427426491464;9721.951390243003;9936.602535898628;9979.950100249453;9990.350627209207;10000.899991000142;2591.559434896072;2772.1822062491615;2773.320536468578;2777.652793400825;2784.610154578361;957.9952100239454;997.8652881861001;1006.5470538257667;1012.258914666772;1037.9896201037893;21217.575648486945;22382.880855957137;22392.116049541;22498.887505562696;23892.044318227076;1025.490627367595;1029.3463650581784;1056.9312994655322;1077.6928575355985;1080.983785243222;9126;10994.34033957965;11388.943055284837;12129.575464858688;12165.14843960932;537128;540521;546595;546941.2652936709;547363;130416;164889;166008.3399166017;166248;166601;1214.799558072908;1216.9756604867853;1236.2273579013117;1241.8820212080018;1246.0592252849042;19805.504862378555;20036.699449507978;20791.272305469236;20828.27101051456;21172.835494047977;69944.30055699378;70916.58166836531;71611.64194178996;73565.26434735584;73875;96209;99962.50018749859;100263;100329.4983525107;100712;10536.473176341306;12204;12429.564965226175;12453.875461245272;12456.190347627375;13324;14404.783928241295;14882.925585372004;15017;15034.548963531111;11849.229800062969;14969.251537422868;15731.056136631838;15757.369705211662;15849.68300634004;13804.861951380759;14002.159870407808;14234.86121110288;14345.426182952982;14767.852321476648;16149.354025838835;16444.342226310815;16728.247228874487;16749.162541873102;16764.077975711454;12052.09609279325;12215.022798176302;12233;12422.37888105573;12450.439730212343;12319.44562494672;15588.68822623564;15658.295376707847;15859.603509912344;16253.918730406273 08:26:17 INFO - PID 1651 | |3;mootools.html;1275.6236910111515;1541.814982202136;1575.3226112771504;1578.8316645682198;1586.5002524204872;14192.148471091741;14846.331915063834;14864.959452838306;14884.404623815035;14927.626809329773;14221.857781422183;14952.775708364383;15016.849831501682;15029.62425939352;15030.624234394145;2936.721011503904;2946.1456177708437;2976.940461190775;2979.2551862034493;2986.6266716660416;2902.3034471726737;2916.8541572921376;2918.1099764571795;2940;2940.1326608650497;2767.9169624911283;2802.929926751827;2935.7357837794593;2979.1509419815357;2982.597349357831;2925.3856690095104;2939.2211064068038;2942.6600808082767;2954.896578619738;2958.704129587046;2882.610847535577;2891.6530016397955;2902.7097290270804;2909.6944820793724;2956.9556456653168;14244.430222791076;14365.425382984773;14455.132692038405;14505.27473626325;14525.12849229039;7678.463709014061;8006.479578827358;8108.918910810876;8124.146964568694;8155.225253600898;4909.803607855682;5048.419431765357;5093.5415812576675;5139.820106296262;5156.896862062776;1465.8607432294023;1487.3381345301345;1503.6316102554836;1511.493649627376;1515.2196618741357;21759.67360489594;21900.23349182771;21908.780912190832;21909.780902190934;21912.671309930363;6830.521863469509;6872.10662613857;6872.625474904939;7017.05269788572;7113.502054856111;15050.32273547693;15209.163496007826;15226.162561059027;15239.619009524851;15253.694926101416;7297.233790451926;7473.738419155412;7518.323350898446;7523.172451030382;7532.7740167795055;6530.608163510205;6736.292689267654;6898.586084834826;6938.791836244921;6957.339052789976;5113.53978141969;5260.947390526122;5278.4721527846905;5297.735113244323;5402.51377376038;1353.9323033848268;1579.6998570271612;1620.7892973913315;1632.3307443947924;1640.2946732904966;695.9408450281783;839.7186942374373;842.7977285451448;864.7967727584013;897.8114595934927;7828.843423131506;7964.800879977931;8093.1961977870305;8561.14388561139;8629.784255393666;1004.3572113847143;1006.7885743993697;1010.2726037253166;1016.2479764974014;1025.2310766924807;3886.6356954993043;5173.405058418255;5200.479952004845;5417.322834645669;5470.863228419242;18730.625387492175;19001.239950402105;19584.412467625993;19599.706004409945;19830.008499574964;19581.412557623295;19885.204591816164;19890;19929.402117936486;19934.102965366594;9728.075832795874;10202.897971020344;10254.58981640741;10363.378197308186;10499.212559058102;6376.458001069961;8371.869797577248;8420.200080992296;8567.828643427221;8573.399862009848;17274.740878887078;17709.468715938543;17970.730439043688;18184.636307273515;18219;17283.06510661821;18070.277188912296;18514;18576.071196440393;18821.341253056078;11571.5949941755;11815.881841181477;11825.408729563316;11842.76314473723;11870.703232419259;11390.690070641982;12760.553380631633;13078.476860925457;13230.867691323348;13278.203307801563;10480.161587073168;10545.209109316986;10709.357438553712;10795.892041079489;10954.616588419685;10108.797824043624;10126.13927816144;10577.576896924345;10596.841047384141;10751.516181771683;9566.760830979281;9584.712458626253;9638.614455421704;9654.95172524161;9658.951705241428;6890.689918953559;7040;7054.858902821812;7284.672189751368;7293.38006269473;6940.271271516418;7048.436125110082;7224.566526008456;7306.123265208209;7318.96340518294 08:26:17 INFO - PID 1651 | |4;prototype.html;411.2782067471588;425.5064125614288;457.9404677391943;473.2191883392402;479.53485119434157;5298.549623282016;6545.672716364181;6604.834879128024;6627.204735431743;6633.767818126366;6239.005277466818;6482.675866206682;6484.286728459878;6510.934890651092;6517.849109793769;4957.900841983158;4959.305697202394;4964.5780108690715;4983.950160498385;5000.874978125539;4222.493300803908;4849.193383194193;4963.305137280783;5005.849824505252;5011.724355160465;3821.388577827548;4768.998510312838;4928.753562321869;4946.233333833262;4958.231474121499;3721.4975978242996;4898.387701537308;4910.288008238786;4922.187839006559;4970.826021089262;4086.366613174963;4174;4318.157959197958;4330.35044743291;4345.217860785057;5520.282363292787;6001.85964666712;6289.685515724242;6308.2745484269435;6357.696672182192;5791.797287094973;5799.855003624901;5806.970965145148;5810.215620891167;5825.563082768809;6042.546808989299;6924.619145946971;7019.543159894048;7023.192332881733;7027.613481258529;4874.415814860149;4916.680415772963;4928.743170491538;4939.8024079036795;4942.233953737176;6869.793906182823;7218.989341492195;7359.448041396863;7440.218777028443;7445.553266803955;4071.2671719090254;4342.740605224512;4876.5126636375535;5017.9247311290355;5049.01544198885;4645.419322584677;4920.507949205051;5136.972605478919;5142.5628821549835;5145.6621278467865;5095.541401273829;5216.956608678203;5653.9151912721345;5801.216835727208;5807.94192058074;5451.890962180734;7021.122359705037;7024.719011239493;7032.226455089936;7045.512471326726;1614.7566373892273;1677.521906256711;1744.0843557132607;1749.2449392207325;1757.5078977886212;1083.4528563075596;1105.9612913547987;1164.138537482257;1176.5529098942518;1215.914885957992;6607.372299631527;6634.488547787454;6635.469162466991;6650.434713049348;6667.299933506912;7121.643917804089;7234.131904171533;7253.709851605877;7269.164046134656;7269.620480844243;5775.902578510069;6554.934450655528;6568.934310656832;6592.978088396306;6627.734890604418;3255.788373755698;3288.868445262163;3380.256343604403;3740.625937406292;3758.3986562149926;7288.416926645856;7425.774747166765;7464.760571828539;7474.813129671802;7493.962530187314;6171.333739890205;6407.423331900151;6773.525853190328;7371.778846634609;7435.141214696326;5431.4840090191365;6276.6547839868335;6815.556988795713;7392.297731715478;7465.664045117983;7148.678309475982;7155.177154627077;7168.856622867618;7183.497155199086;7221.927780722336;5565;6486.481081513373;7030.789076327718;7046.032547054417;7084.36240738336;5816.505597024131;6332.30344662096;6461.418472337512;6933.618650974245;7031.1211098612675;4399.228486053766;4978.551930326288;5003.649744517848;5006.924896126634;5111.952049829851;5384.7576859040655;7019.051568644196;7225.963870180615;7243.927560724325;7244.384227340524;6755.831104222434;6884.4148247397525;6953.548019378724;6964.512484126062;6966.895496567454;5979.581429300081;6945.236024037251;6952.895706564506;6980.232174460907;6992.4406047517095;5939.4357536034;6796.431873266377;6808.82977925536;6839.213490448463;6857.862842743217;5138.97430512845;5430.565554755531;6299.338569450325;6778;6970.337817907291;6057;6658.8679924412;6747.224069231905;6804.9319506804295;6827.419669327964 08:26:17 INFO - PID 1651 | |5;yui.html;838.7399906029129;868.2272777228264;909.5452273863068;916.3036092569645;920.6363486422863;17821.01984390858;18119.72820407693;19810.900945495287;20405.387838364837;21117.472063198427;19323.613527729438;19463;19634.70547941782;19694.803051969513;20091.497712557193;994.8706668133133;1041.3543602966172;1078.811208038594;1081.2053140941402;1081.621432498623;1070.0209308482729;1076.9407682577455;1085.3108276244564;1088.9183311251647;1089.4661615808236;1116.430620383606;1149.793037253294;1150.7986102432083;1151.9078473722082;1152.4065106470175;1078.8219943709278;1124.1063354633004;1127.1152145565773;1130.8982191602754;1132.2131118872387;813.7151996801132;822.411975437565;825.335604838105;825.9964143565537;832.2177153475714;18204.362847300414;18639.81360186394;18675.066246687747;18684.25262989479;18887.716684249746;18360.63278734431;18494.2602295908;18545.62908741831;18731.812681873143;18803.717944230848;15580.376784928589;15612.687746245125;15682;15732.842671573253;15735.606609834731;3801.657850793442;3806.8286927088348;3838.9232215355537;3863.903402414934;3873.0704630888386;117883;131511.6848831509;140183.1963360737;141011;144513;14208.289585520683;14274.643133921527;14308.49920252786;14445.85554144445;14446.927765361315;22665.660015099787;27354.589681154797;27375.45249095007;27405.72594274032;27474.450510989667;7884.487508311941;8057.31512821405;8077.798055048671;8087;8176.141505141994;4008.3386241270446;4089.9795501022304;4093.7543747375253;4098.995746042238;4102.815373308149;4814.638902082358;4880.389951256093;4906.435759887659;4908.803647854046;4910.754462276872;1013.0123129948241;1032.5921261101823;1033.4625994482976;1035.2649618770606;1035.2649618770606;673.9258681545026;677.1298880938069;679.9796006119824;696.7665831946355;719.910011248594;11896.286222826659;12080.275183489019;12283.447244873823;12705.745885082431;12869.613911582668;585.5637550025258;593.2880543348;602.4126476685284;606.3936063936064;616.6115347331242;4729.739864307496;4811.254255590389;5706.9714651426475;5864.8533786654825;5912.083627037816;138907;142875.85686214507;144916;146074.26962865118;147690;77016.61491692506;117943.41028294804;123445;128783.35608321898;129102.70897291094;79444;95204;100859.99140008505;101516;103173.48413258036;9499.335046546952;10165.695029149138;11213.551457941918;11339.829902551633;11434.656960291204;47168.764156179;47204.29193562168;49623.255651165986;50185.996280073465;50304.74847625885;48341.758291208316;50246.748766255936;59129.40870591411;64369.67815160894;67537.98693019708;13718.38267277955;14074.648133796736;14285.42858285657;14957.476488322856;15347.693046139239;16406.589835254214;16774;16959.660806784043;17114.914425427793;17257.827421725622;15335.079895206323;15377.846221537642;15525.37898484048;15549.455769048029;15579.688406232039;14935.327910244283;15297.31162097686;15310.617234569225;15413.306401211748;15758.975666581635;13961.371738271599;14731;14940.925295373454;14968.101913885203;14973.700525989638;10927.398993055458;10988.835167472334;11018;11317.603883864025;11374.829377559508;11570.768584628091;11570.88429115698;11593.24643898144;11681.766364672829;11974.982126518995 08:26:17 INFO - PID 1651 | __end_tp_report 08:26:17 INFO - PID 1651 | __start_cc_report 08:26:17 INFO - PID 1651 | _x_x_mozilla_cycle_collect,395 08:26:17 INFO - PID 1651 | __end_cc_report 08:26:17 INFO - PID 1651 | __startTimestamp1497540376997__endTimestamp 08:26:17 INFO - PID 1651 | 08:26:17 INFO - PID 1651 | ------- Summary: start ------- 08:26:17 INFO - PID 1651 | Number of tests: 6 08:26:17 INFO - PID 1651 | 08:26:17 INFO - PID 1651 | [#0] dojo.html Cycles:165 Average:6453.40 Median:6652.63 stddev:829.75 (12.5%) stddev-sans-first:796.69 08:26:17 INFO - PID 1651 | Values: 3388.3 3658.8 3802.8 3812.4 3850.3 5170.4 6390.9 6400.1 6423.3 6425.4 6436.1 6443.7 6479.2 6503.3 6516.8 5682.5 5725.8 5743.7 5865.4 5867.4 5752.1 5754.7 5780.9 5792.7 5880.6 5444.8 5601.0 5617.1 5643.6 5662.3 5679.4 5767.4 5777.2 5793.1 5812.8 5670.3 5716.1 5720.2 5760.1 5827.9 6421.6 6489.8 6533.2 6535.1 6578.3 6767.3 6914.2 7080.8 7090.3 7103.0 7042.4 7115.2 7117.6 7139.4 7191.7 5249.0 5708.3 6006.0 6423.5 6631.9 7321.8 7354.9 7364.3 7378.5 7390.6 6694.3 6774.5 6802.8 6807.8 6980.4 6969.3 7046.0 7114.3 7159.7 7170.4 6950.2 7026.9 7027.9 7036.5 7062.7 7004.4 7031.3 7041.3 7064.7 7075.8 5672.6 5682.1 5697.0 5697.7 5708.3 3638.6 4368.5 4412.9 4850.9 5015.3 6473.1 6483.7 6503.9 6622.1 6652.6 7097.6 7108.0 7132.5 7143.0 7181.6 6613.2 6616.4 6624.7 6626.4 6643.0 6136.7 6228.0 6295.0 6296.1 6306.8 7077.8 7177.9 7197.6 7325.5 7365.7 7029.7 7051.9 7152.2 7152.6 7163.0 5679.9 7347.8 7396.5 7412.0 7428.6 5825.9 6918.0 7023.3 7029.2 7042.9 7149.2 7172.2 7176.6 7182.9 7199.9 6665.8 6760.5 6767.2 6826.7 6834.0 6858.8 6914.4 6929.8 6931.1 6942.9 6464.9 6795.6 6809.8 6830.6 6834.0 6913.2 6938.2 6962.2 6980.7 6987.4 6516.5 6549.2 6570.5 6586.1 6602.9 08:26:17 INFO - PID 1651 | 08:26:17 INFO - PID 1651 | [#1] ext.html Cycles:180 Average:45145.18 Median:18162.05 stddev:78949.21 (434.7%) stddev-sans-first:79100.60 08:26:17 INFO - PID 1651 | Values: 839.6 866.5 877.9 894.7 907.9 38358.6 39014.6 39155.0 39184.2 39212.0 31544.8 38432.6 38733.0 38752.8 38764.8 6485.6 7794.6 7937.4 7938.0 7938.7 8538.9 8594.8 8649.6 8658.7 8663.4 7148.0 8571.7 8573.9 8638.5 8645.3 8560.0 8563.3 8614.1 8614.3 8618.6 8482.0 8507.6 8568.7 8573.2 8578.7 36790.0 37012.0 37032.0 37069.9 37106.7 3645.3 3684.2 3685.7 3686.7 3687.3 30429.8 30565.4 30644.1 30680.5 30721.8 2739.6 3226.1 3270.7 3313.6 3351.1 263816.0 267872.3 269029.0 327238.0 328115.0 40176.8 47163.8 47187.0 47647.8 47945.0 49460.0 49848.0 49901.8 49907.0 49954.5 15977.2 16102.6 16117.9 16119.9 16179.9 11590.5 12784.0 13070.9 13164.5 13167.9 13330.7 13350.3 13532.3 13630.5 13678.9 5095.3 5913.4 5944.3 5982.9 6110.6 1014.7 1014.7 1017.3 1066.9 1071.8 19856.9 23247.7 23316.8 23889.4 24296.8 980.2 1023.3 1081.3 1135.0 1198.1 12304.0 12694.0 12733.0 12740.5 12741.3 317229.0 319594.4 320555.9 321226.0 321233.0 53785.7 54458.0 54596.9 54770.2 54784.0 1336.6 1494.1 1518.3 1548.3 1594.7 22802.4 23388.6 23475.9 23496.6 24319.5 37705.4 37928.8 38010.0 38055.0 38108.0 289613.0 289647.0 289866.0 289901.0 290304.0 32646.3 32785.5 33010.5 33759.5 33967.8 42100.9 44404.3 44734.6 44958.8 44984.3 28708.3 28965.9 29938.3 30544.5 31608.8 30412.4 31120.0 31594.2 31634.5 31719.7 36262.1 38377.0 38495.8 38501.8 38503.2 28911.4 29475.3 30106.8 30403.0 31890.7 24568.6 24627.5 24963.9 25062.5 25167.0 08:26:17 INFO - PID 1651 | 08:26:17 INFO - PID 1651 | [#2] jquery.html Cycles:180 Average:40969.34 Median:22478.11 stddev:96703.31 (430.2%) stddev-sans-first:96926.88 08:26:17 INFO - PID 1651 | Values: 511.4 533.9 538.5 547.2 548.8 20204.8 23543.5 24278.0 24465.0 24494.9 24929.8 27366.2 27411.0 27463.9 27523.4 6887.3 7040.8 7043.1 7053.3 7056.2 6942.1 6947.2 6994.9 7022.3 7022.6 5929.3 6209.5 6529.3 6587.7 6598.7 6572.5 6626.4 6637.2 6646.8 6673.5 6127.9 6137.1 6141.1 6150.5 6154.9 31201.4 32827.7 33192.5 33234.5 33238.0 2316.6 2386.1 2389.4 2389.7 2393.4 20477.7 20838.7 21020.2 21056.5 21125.0 2285.8 2319.5 2355.9 2363.4 2363.8 225432.0 227994.0 228916.0 229639.9 233035.8 14677.6 16223.4 16477.9 16538.9 16696.4 22144.2 22465.7 22490.6 22515.4 22522.5 9711.8 11051.6 11190.6 11193.9 11200.4 10332.0 10360.7 10370.9 10386.8 10410.4 9722.0 9936.6 9980.0 9990.4 10000.9 2591.6 2772.2 2773.3 2777.7 2784.6 958.0 997.9 1006.5 1012.3 1038.0 21217.6 22382.9 22392.1 22498.9 23892.0 1025.5 1029.3 1056.9 1077.7 1081.0 9126.0 10994.3 11388.9 12129.6 12165.1 537128.0 540521.0 546595.0 546941.3 547363.0 130416.0 164889.0 166008.3 166248.0 166601.0 1214.8 1217.0 1236.2 1241.9 1246.1 19805.5 20036.7 20791.3 20828.3 21172.8 69944.3 70916.6 71611.6 73565.3 73875.0 96209.0 99962.5 100263.0 100329.5 100712.0 10536.5 12204.0 12429.6 12453.9 12456.2 13324.0 14404.8 14882.9 15017.0 15034.5 11849.2 14969.3 15731.1 15757.4 15849.7 13804.9 14002.2 14234.9 14345.4 14767.9 16149.4 16444.3 16728.2 16749.2 16764.1 12052.1 12215.0 12233.0 12422.4 12450.4 12319.4 15588.7 15658.3 15859.6 16253.9 08:26:17 INFO - PID 1651 | 08:26:17 INFO - PID 1651 | [#3] mootools.html Cycles:180 Average:8836.44 Median:2842.77 stddev:5953.20 (209.4%) stddev-sans-first:5942.79 08:26:17 INFO - PID 1651 | Values: 1275.6 1541.8 1575.3 1578.8 1586.5 14192.1 14846.3 14865.0 14884.4 14927.6 14221.9 14952.8 15016.8 15029.6 15030.6 2936.7 2946.1 2976.9 2979.3 2986.6 2902.3 2916.9 2918.1 2940.0 2940.1 2767.9 2802.9 2935.7 2979.2 2982.6 2925.4 2939.2 2942.7 2954.9 2958.7 2882.6 2891.7 2902.7 2909.7 2957.0 14244.4 14365.4 14455.1 14505.3 14525.1 7678.5 8006.5 8108.9 8124.1 8155.2 4909.8 5048.4 5093.5 5139.8 5156.9 1465.9 1487.3 1503.6 1511.5 1515.2 21759.7 21900.2 21908.8 21909.8 21912.7 6830.5 6872.1 6872.6 7017.1 7113.5 15050.3 15209.2 15226.2 15239.6 15253.7 7297.2 7473.7 7518.3 7523.2 7532.8 6530.6 6736.3 6898.6 6938.8 6957.3 5113.5 5260.9 5278.5 5297.7 5402.5 1353.9 1579.7 1620.8 1632.3 1640.3 695.9 839.7 842.8 864.8 897.8 7828.8 7964.8 8093.2 8561.1 8629.8 1004.4 1006.8 1010.3 1016.2 1025.2 3886.6 5173.4 5200.5 5417.3 5470.9 18730.6 19001.2 19584.4 19599.7 19830.0 19581.4 19885.2 19890.0 19929.4 19934.1 9728.1 10202.9 10254.6 10363.4 10499.2 6376.5 8371.9 8420.2 8567.8 8573.4 17274.7 17709.5 17970.7 18184.6 18219.0 17283.1 18070.3 18514.0 18576.1 18821.3 11571.6 11815.9 11825.4 11842.8 11870.7 11390.7 12760.6 13078.5 13230.9 13278.2 10480.2 10545.2 10709.4 10795.9 10954.6 10108.8 10126.1 10577.6 10596.8 10751.5 9566.8 9584.7 9638.6 9655.0 9659.0 6890.7 7040.0 7054.9 7284.7 7293.4 6940.3 7048.4 7224.6 7306.1 7319.0 08:26:17 INFO - PID 1651 | 08:26:17 INFO - PID 1651 | [#4] prototype.html Cycles:180 Average:5614.87 Median:6205.17 stddev:1723.18 (27.8%) stddev-sans-first:1683.17 08:26:17 INFO - PID 1651 | Values: 411.3 425.5 457.9 473.2 479.5 5298.5 6545.7 6604.8 6627.2 6633.8 6239.0 6482.7 6484.3 6510.9 6517.8 4957.9 4959.3 4964.6 4984.0 5000.9 4222.5 4849.2 4963.3 5005.8 5011.7 3821.4 4769.0 4928.8 4946.2 4958.2 3721.5 4898.4 4910.3 4922.2 4970.8 4086.4 4174.0 4318.2 4330.4 4345.2 5520.3 6001.9 6289.7 6308.3 6357.7 5791.8 5799.9 5807.0 5810.2 5825.6 6042.5 6924.6 7019.5 7023.2 7027.6 4874.4 4916.7 4928.7 4939.8 4942.2 6869.8 7219.0 7359.4 7440.2 7445.6 4071.3 4342.7 4876.5 5017.9 5049.0 4645.4 4920.5 5137.0 5142.6 5145.7 5095.5 5217.0 5653.9 5801.2 5807.9 5451.9 7021.1 7024.7 7032.2 7045.5 1614.8 1677.5 1744.1 1749.2 1757.5 1083.5 1106.0 1164.1 1176.6 1215.9 6607.4 6634.5 6635.5 6650.4 6667.3 7121.6 7234.1 7253.7 7269.2 7269.6 5775.9 6554.9 6568.9 6593.0 6627.7 3255.8 3288.9 3380.3 3740.6 3758.4 7288.4 7425.8 7464.8 7474.8 7494.0 6171.3 6407.4 6773.5 7371.8 7435.1 5431.5 6276.7 6815.6 7392.3 7465.7 7148.7 7155.2 7168.9 7183.5 7221.9 5565.0 6486.5 7030.8 7046.0 7084.4 5816.5 6332.3 6461.4 6933.6 7031.1 4399.2 4978.6 5003.6 5006.9 5112.0 5384.8 7019.1 7226.0 7243.9 7244.4 6755.8 6884.4 6953.5 6964.5 6966.9 5979.6 6945.2 6952.9 6980.2 6992.4 5939.4 6796.4 6808.8 6839.2 6857.9 5139.0 5430.6 6299.3 6778.0 6970.3 6057.0 6658.9 6747.2 6804.9 6827.4 08:26:17 INFO - PID 1651 | 08:26:17 INFO - PID 1651 | [#5] yui.html Cycles:180 Average:24652.92 Median:16590.29 stddev:37548.21 (226.3%) stddev-sans-first:37610.97 08:26:17 INFO - PID 1651 | Values: 838.7 868.2 909.5 916.3 920.6 17821.0 18119.7 19810.9 20405.4 21117.5 19323.6 19463.0 19634.7 19694.8 20091.5 994.9 1041.4 1078.8 1081.2 1081.6 1070.0 1076.9 1085.3 1088.9 1089.5 1116.4 1149.8 1150.8 1151.9 1152.4 1078.8 1124.1 1127.1 1130.9 1132.2 813.7 822.4 825.3 826.0 832.2 18204.4 18639.8 18675.1 18684.3 18887.7 18360.6 18494.3 18545.6 18731.8 18803.7 15580.4 15612.7 15682.0 15732.8 15735.6 3801.7 3806.8 3838.9 3863.9 3873.1 117883.0 131511.7 140183.2 141011.0 144513.0 14208.3 14274.6 14308.5 14445.9 14446.9 22665.7 27354.6 27375.5 27405.7 27474.5 7884.5 8057.3 8077.8 8087.0 8176.1 4008.3 4090.0 4093.8 4099.0 4102.8 4814.6 4880.4 4906.4 4908.8 4910.8 1013.0 1032.6 1033.5 1035.3 1035.3 673.9 677.1 680.0 696.8 719.9 11896.3 12080.3 12283.4 12705.7 12869.6 585.6 593.3 602.4 606.4 616.6 4729.7 4811.3 5707.0 5864.9 5912.1 138907.0 142875.9 144916.0 146074.3 147690.0 77016.6 117943.4 123445.0 128783.4 129102.7 79444.0 95204.0 100860.0 101516.0 103173.5 9499.3 10165.7 11213.6 11339.8 11434.7 47168.8 47204.3 49623.3 50186.0 50304.7 48341.8 50246.7 59129.4 64369.7 67538.0 13718.4 14074.6 14285.4 14957.5 15347.7 16406.6 16774.0 16959.7 17114.9 17257.8 15335.1 15377.8 15525.4 15549.5 15579.7 14935.3 15297.3 15310.6 15413.3 15759.0 13961.4 14731.0 14940.9 14968.1 14973.7 10927.4 10988.8 11018.0 11317.6 11374.8 11570.8 11570.9 11593.2 11681.8 11975.0 08:26:17 INFO - PID 1651 | -------- Summary: end -------- 08:26:17 INFO - PID 1651 | 08:26:17 INFO - TEST-INFO | 1651: exit 0 08:26:17 INFO - TEST-OK | dromaeo_css | took 1141536ms 08:26:17 INFO - TEST-START | kraken 08:26:17 INFO - operating with platform_type : mac_ 08:26:17 INFO - Initialising browser for kraken test... 08:26:17 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpRyWvNL/profile 08:26:17 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground) 08:26:50 INFO - TEST-INFO | 1662: killed by SIGTERM 08:26:50 INFO - Browser initialized. 08:26:50 INFO - Running cycle 1/1 for kraken test... 08:26:50 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/T/tmpRyWvNL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:26:51 INFO - PID 1665 | 2017-06-15 08:26:51.936 plugin-container[1666:10563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 08:26:51 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:26:51 INFO - PID 1665 | 2017-06-15 08:26:51.936 plugin-container[1666:10563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 08:26:51 INFO - PID 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:26:51 INFO - PID 1665 | 2017-06-15 08:26:51.936 plugin-container[1666:10563] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:27:02 INFO - PID 1665 | RSS: Main: 186068992 08:27:02 INFO - PID 1665 | 08:27:04 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection) 08:27:05 INFO - PID 1665 | RSS: Main: 198508544 08:27:05 INFO - PID 1665 | 08:27:08 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-dft) 08:27:08 INFO - PID 1665 | RSS: Main: 207798272 08:27:08 INFO - PID 1665 | 08:27:12 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-fft) 08:27:12 INFO - PID 1665 | RSS: Main: 212029440 08:27:12 INFO - PID 1665 | 08:27:14 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator) 08:27:15 INFO - PID 1665 | RSS: Main: 214003712 08:27:15 INFO - PID 1665 | 08:27:17 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:27:17 INFO - PID 1665 | RSS: Main: 214495232 08:27:17 INFO - PID 1665 | 08:27:20 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom) 08:27:21 INFO - PID 1665 | RSS: Main: 218697728 08:27:21 INFO - PID 1665 | 08:27:24 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate) 08:27:25 INFO - PID 1665 | RSS: Main: 219041792 08:27:25 INFO - PID 1665 | 08:27:27 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial) 08:27:28 INFO - PID 1665 | RSS: Main: 218923008 08:27:28 INFO - PID 1665 | 08:27:30 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:27:30 INFO - PID 1665 | RSS: Main: 218587136 08:27:30 INFO - PID 1665 | 08:27:32 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:27:33 INFO - PID 1665 | RSS: Main: 218570752 08:27:33 INFO - PID 1665 | 08:27:35 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:27:35 INFO - PID 1665 | RSS: Main: 218517504 08:27:35 INFO - PID 1665 | 08:27:38 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:27:39 INFO - PID 1665 | RSS: Main: 218611712 08:27:39 INFO - PID 1665 | 08:27:42 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:27:42 INFO - PID 1665 | RSS: Main: 218869760 08:27:42 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49233/tests/kraken/driver.html?testName=ai-astar) 08:27:44 INFO - PID 1665 | RSS: Main: 220524544 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | __start_tp_report 08:27:44 INFO - PID 1665 | _x_x_mozilla_page_load 08:27:44 INFO - PID 1665 | _x_x_mozilla_page_load_details 08:27:44 INFO - PID 1665 | |i|pagename|runs| 08:27:44 INFO - PID 1665 | |0;ai-astar;103;114;105;116;122;118;115;102;116;119 08:27:44 INFO - PID 1665 | |1;audio-beat-detection;177;141;175;132;129;112;133;188;139;141 08:27:44 INFO - PID 1665 | |2;audio-dft;201;199;200;201;200;196;215;196;223;204 08:27:44 INFO - PID 1665 | |3;audio-fft;63;79;73;143;56;48;70;79;122;55 08:27:44 INFO - PID 1665 | |4;audio-oscillator;71;73;106;70;69;82;106;70;71;117 08:27:44 INFO - PID 1665 | |5;imaging-gaussian-blur;89;109;89;89;91;87;79;136;87;88 08:27:44 INFO - PID 1665 | |6;imaging-darkroom;94;96;95;105;109;153;124;153;94;130 08:27:44 INFO - PID 1665 | |7;imaging-desaturate;91;95;89;91;92;92;93;93;89;88 08:27:44 INFO - PID 1665 | |8;json-parse-financial;47;46;49;48;49;52;62;75;79;81 08:27:44 INFO - PID 1665 | |9;json-stringify-tinderbox;52;55;56;57;70;79;69;55;54;55 08:27:44 INFO - PID 1665 | |10;stanford-crypto-aes;79;78;78;78;81;78;79;78;78;76 08:27:44 INFO - PID 1665 | |11;stanford-crypto-ccm;133;139;131;127;141;132;130;136;136;156 08:27:44 INFO - PID 1665 | |12;stanford-crypto-pbkdf2;178;173;178;178;176;186;217;212;170;177 08:27:44 INFO - PID 1665 | |13;stanford-crypto-sha256-iterative;63;64;68;65;69;65;64;65;61;63 08:27:44 INFO - PID 1665 | __end_tp_report 08:27:44 INFO - PID 1665 | __start_cc_report 08:27:44 INFO - PID 1665 | _x_x_mozilla_cycle_collect,986 08:27:44 INFO - PID 1665 | __end_cc_report 08:27:44 INFO - PID 1665 | __startTimestamp1497540464667__endTimestamp 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | ------- Summary: start ------- 08:27:44 INFO - PID 1665 | Number of tests: 14 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#0] ai-astar Cycles:10 Average:113.00 Median:116.00 stddev:7.07 (6.1%) stddev-sans-first:6.51 08:27:44 INFO - PID 1665 | Values: 103.0 114.0 105.0 116.0 122.0 118.0 115.0 102.0 116.0 119.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#1] audio-beat-detection Cycles:10 Average:146.70 Median:141.00 stddev:24.65 (17.5%) stddev-sans-first:23.58 08:27:44 INFO - PID 1665 | Values: 177.0 141.0 175.0 132.0 129.0 112.0 133.0 188.0 139.0 141.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#2] audio-dft Cycles:10 Average:203.50 Median:201.00 stddev:8.71 (4.3%) stddev-sans-first:9.19 08:27:44 INFO - PID 1665 | Values: 201.0 199.0 200.0 201.0 200.0 196.0 215.0 196.0 223.0 204.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#3] audio-fft Cycles:10 Average:78.80 Median:66.50 stddev:30.52 (45.9%) stddev-sans-first:31.83 08:27:44 INFO - PID 1665 | Values: 63.0 79.0 73.0 143.0 56.0 48.0 70.0 79.0 122.0 55.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:70.50 stddev:18.66 (26.5%) stddev-sans-first:19.24 08:27:44 INFO - PID 1665 | Values: 71.0 73.0 106.0 70.0 69.0 82.0 106.0 70.0 71.0 117.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:94.40 Median:88.50 stddev:16.43 (18.6%) stddev-sans-first:17.31 08:27:44 INFO - PID 1665 | Values: 89.0 109.0 89.0 89.0 91.0 87.0 79.0 136.0 87.0 88.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#6] imaging-darkroom Cycles:10 Average:115.30 Median:123.50 stddev:23.50 (19.0%) stddev-sans-first:23.63 08:27:44 INFO - PID 1665 | Values: 94.0 96.0 95.0 105.0 109.0 153.0 124.0 153.0 94.0 130.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:92.00 stddev:2.16 (2.4%) stddev-sans-first:2.29 08:27:44 INFO - PID 1665 | Values: 91.0 95.0 89.0 91.0 92.0 92.0 93.0 93.0 89.0 88.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#8] json-parse-financial Cycles:10 Average:58.80 Median:57.00 stddev:14.27 (25.0%) stddev-sans-first:14.48 08:27:44 INFO - PID 1665 | Values: 47.0 46.0 49.0 48.0 49.0 52.0 62.0 75.0 79.0 81.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:56.50 stddev:9.08 (16.1%) stddev-sans-first:9.13 08:27:44 INFO - PID 1665 | Values: 52.0 55.0 56.0 57.0 70.0 79.0 69.0 55.0 54.0 55.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:78.00 stddev:1.25 (1.6%) stddev-sans-first:1.30 08:27:44 INFO - PID 1665 | Values: 79.0 78.0 78.0 78.0 81.0 78.0 79.0 78.0 78.0 76.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:136.10 Median:136.00 stddev:8.17 (6.0%) stddev-sans-first:8.59 08:27:44 INFO - PID 1665 | Values: 133.0 139.0 131.0 127.0 141.0 132.0 130.0 136.0 136.0 156.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.50 Median:178.00 stddev:16.37 (9.2%) stddev-sans-first:17.20 08:27:44 INFO - PID 1665 | Values: 178.0 173.0 178.0 178.0 176.0 186.0 217.0 212.0 170.0 177.0 08:27:44 INFO - PID 1665 | 08:27:44 INFO - PID 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:2.36 (3.6%) stddev-sans-first:2.42 08:27:44 INFO - PID 1665 | Values: 63.0 64.0 68.0 65.0 69.0 65.0 64.0 65.0 61.0 63.0 08:27:44 INFO - PID 1665 | -------- Summary: end -------- 08:27:44 INFO - PID 1665 | 08:27:45 INFO - TEST-INFO | 1665: exit 0 08:27:45 INFO - TEST-OK | kraken | took 87899ms 08:27:45 INFO - SUITE-END | took 1229s 08:27:45 INFO - Completed test suite (00:20:29) 08:27:45 INFO - javascript benchmark 08:27:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8599.419578900382, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3388.2884594235215, 3658.8353524091413, 3802.8098595070246, 3812.390017597185, 3850.345441274983, 5170.405403378612, 6390.904136437951, 6400.1039854420305, 6423.293437721853, 6425.389587989145, 6436.066770318301, 6443.710033048513, 6479.15770949777, 6503.349665033495, 6516.837079073014, 5682.545396368291, 5725.770969161229, 5743.712814359265, 5865.35481097079, 5867.413258674141, 5752.050911599581, 5754.65472071675, 5780.942190578083, 5792.739326730307, 5880.55895807814, 5444.7822087116465, 5601.019821531216, 5617.129344951519, 5643.5767317451355, 5662.292213473316, 5679.432056794329, 5767.423257674241, 5777.24895763548, 5793.13103034544, 5812.796552120655, 5670.3479099903625, 5716.142578613241, 5720.1991721159075, 5760.078387458028, 5827.941720582782, 6421.550491465599, 6489.805305840832, 6533.248676402227, 6535.117759102555, 6578.3421657834315, 6767.2894346093935, 6914.239433662293, 7080.822979425556, 7090.255523170044, 7102.964485177541, 7042.401395881357, 7115.181754098241, 7117.64411779409, 7139.39315158207, 7191.712331506734, 5248.973755131238, 5708.343540492855, 6005.969970150121, 6423.4861211103935, 6631.933680663228, 7321.78034658961, 7354.933534637447, 7364.3003914628025, 7378.483506154517, 7390.556566606021, 6694.263631000586, 6774.525783195228, 6802.8299292517095, 6807.761728339485, 6980.406665433495, 6969.337912898267, 7045.964770176116, 7114.288571142948, 7159.713611455483, 7170.354668079898, 6950.20072691647, 7026.859462810715, 7027.929720702829, 7036.542624729376, 7062.6821793019435, 7004.404625606779, 7031.261717519689, 7041.3310735480045, 7064.7174113035935, 7075.823104422328, 5672.574556908248, 5682.090865461506, 5697.031504644137, 5697.715114244271, 5708.343540492897, 3638.6179451157777, 4368.497622773421, 4412.940894185373, 4850.878728031827, 5015.297858299841, 6473.126127972758, 6483.7082331295205, 6503.902441463382, 6622.072909792633, 6652.634105124168, 7097.574145551283, 7107.964460177769, 7132.500724949204, 7142.964285178541, 7181.6409179540815, 6613.173353330833, 6616.371444712745, 6624.701888415033, 6626.436752875963, 6642.966785166043, 6136.6624835633575, 6227.96886015567, 6294.968525157345, 6296.118543403927, 6306.779262725782, 7077.752278670222, 7177.928220717726, 7197.604131772803, 7325.450591205681, 7365.742199023009, 7029.683664235224, 7051.894221586577, 7152.177499587406, 7152.642367881481, 7162.997180394853, 5679.858003549779, 7347.816304592214, 7396.4822462429265, 7412.0, 7428.591427471325, 5825.854353641193, 6918.0, 7023.262557431396, 7029.226785053742, 7042.894356584758, 7149.249328820399, 7172.175199852084, 7176.605286709281, 7182.856342873218, 7199.928000719926, 6665.766698165541, 6760.459163267025, 6767.221769496571, 6826.692798823966, 6833.965830170818, 6858.794236172923, 6914.377706006484, 6929.757458488929, 6931.064306318583, 6942.930570694229, 6464.903026454701, 6795.592264464148, 6809.795706128824, 6830.624315662686, 6834.0, 6913.170419549686, 6938.202106757787, 6962.199347075151, 6980.685869135799, 6987.371136597731, 6516.5112616553, 6549.214094308713, 6570.47436205112, 6586.110875031809, 6602.933970660424], "value": 6394.476383281662, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [839.6431516605445, 866.536403024382, 877.8946526416831, 894.677915950258, 907.8955920069186, 38358.61641383585, 39014.60985390145, 39155.0211244719, 39184.21631567371, 39212.0, 31544.84227578859, 38432.615673843255, 38733.0, 38752.80623596878, 38764.80617596908, 6485.578437401554, 7794.571298578576, 7937.404694647925, 7938.047434307891, 7938.682452701885, 8538.87191692122, 8594.828103437927, 8649.610767515476, 8658.740237792876, 8663.39356245063, 7148.0, 8571.742847714579, 8573.914260857406, 8638.524881131536, 8645.30837532999, 8560.0, 8563.272121869617, 8614.138586141398, 8614.267787238092, 8618.56907154646, 8482.0, 8507.574621268912, 8568.70009549669, 8573.228409443118, 8578.742637720878, 36790.0, 37012.0, 37032.0, 37069.88790336294, 37106.70126545585, 3645.343838109139, 3684.1894783147663, 3685.6682898538993, 3686.7419280650365, 3687.2994131115, 30429.847850760827, 30565.388692226254, 30644.08067757971, 30680.53979190314, 30721.846390767903, 2739.575365818301, 3226.145071556016, 3270.73834093272, 3313.6023677158896, 3351.145457908218, 263816.0, 267872.32127678866, 269029.0, 327238.0, 328115.0, 40176.79911600423, 47163.76418117956, 47187.0, 47647.761761191665, 47945.0, 49460.0, 49848.0, 49901.750491248036, 49907.0018599626, 49954.50045499571, 15977.201139942956, 16102.597435063983, 16117.919410402874, 16119.919400402923, 16179.948303360245, 11590.536378544932, 12783.977281817432, 13070.934645326903, 13164.47342106305, 13167.868321316855, 13330.666733331744, 13350.332483375792, 13532.32338383077, 13630.454781808616, 13678.931605342108, 5095.312132862016, 5913.379095195029, 5944.346121926585, 5982.850428739229, 6110.633361998294, 1014.705735336744, 1014.7057353367588, 1017.3488967061087, 1066.935983840972, 1071.8338657508098, 19856.90071549662, 23247.72137532452, 23316.7668323318, 23889.402764931016, 24296.75703242945, 980.1913421427352, 1023.2939271902363, 1081.2755453845941, 1134.9602763903224, 1198.149313987079, 12304.0, 12693.984481241663, 12733.0, 12740.490380384867, 12741.299228542519, 317229.0, 319594.402027993, 320555.9305086864, 321226.0, 321233.0, 53785.731071344395, 54458.0, 54596.908061839335, 54770.17844732332, 54784.0, 1336.565616174747, 1494.103537877265, 1518.3091693279532, 1548.2955255358784, 1594.7209238383236, 22802.42993925165, 23388.64917026212, 23475.882620586788, 23496.647550286416, 24319.51360972806, 37705.43441848319, 37928.810355948044, 38010.0, 38055.0, 38108.04729881775, 289613.0, 289647.0, 289866.0, 289901.0, 290304.0, 32646.347073057932, 32785.50821737628, 33010.504842427865, 33759.4936075964, 33967.83016084904, 42100.94747631334, 44404.33393499165, 44734.55265447304, 44958.77520612376, 44984.32523512215, 28708.282292942844, 28965.85517072401, 29938.25154371071, 30544.541831872095, 31608.841955790074, 30412.39175216439, 31120.0, 31594.210144746565, 31634.525482118246, 31719.682803172596, 36262.09344766296, 38377.0, 38495.80752096222, 38501.844944651704, 38503.22993540057, 28911.42177156487, 29475.263118422212, 30106.84946575253, 30403.0, 31890.7243710249, 24568.631470527598, 24627.507449850546, 24963.87518062398, 25062.498750024533, 25167.0], "value": 17557.383420669998, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.39655206855906, 533.9225812257223, 538.4965057671076, 547.2393373211235, 548.7887163442076, 20204.797952020475, 23543.529129417424, 24278.0, 24465.0, 24494.87752561239, 24929.75070249297, 27366.179014629543, 27411.0, 27463.86268068657, 27523.449531009366, 6887.311268873123, 7040.753573624924, 7043.119610048744, 7053.329933656294, 7056.188538318108, 6942.062821519106, 6947.166340039177, 6994.860102797941, 7022.262662420449, 7022.578645281274, 5929.318128415244, 6209.534284928626, 6529.281779004329, 6587.736490540373, 6598.7030583623855, 6572.474202063848, 6626.37049480301, 6637.203535575762, 6646.833829154261, 6673.4661227101715, 6127.938720612781, 6137.110119032715, 6141.078838174265, 6150.477209437203, 6154.8769024619705, 31201.37597248065, 32827.6717232827, 33192.50211246833, 33234.50148247778, 33238.0, 2316.6061769499224, 2386.081358676922, 2389.438481956739, 2389.749076346976, 2393.365758074112, 20477.692834607493, 20838.68741968872, 21020.15919363239, 21056.473588160417, 21125.0, 2285.8171346292424, 2319.5476882007847, 2355.8586484810794, 2363.3973336799177, 2363.8463499872623, 225432.0, 227994.0, 228916.0, 229639.8518007416, 233035.8348208265, 14677.55967320982, 16223.432179873647, 16477.91761041187, 16538.917305413397, 16696.41562545305, 22144.22495212692, 22465.663015054786, 22490.550188996454, 22515.43711407228, 22522.549549008927, 9711.757206069904, 11051.557937682403, 11190.552377904793, 11193.888061119447, 11200.383978881076, 10332.018458246455, 10360.740981475523, 10370.948145259375, 10386.844197336895, 10410.427426491464, 9721.951390243003, 9936.602535898628, 9979.950100249453, 9990.350627209207, 10000.899991000142, 2591.559434896072, 2772.1822062491615, 2773.320536468578, 2777.652793400825, 2784.610154578361, 957.9952100239454, 997.8652881861001, 1006.5470538257667, 1012.258914666772, 1037.9896201037893, 21217.575648486945, 22382.880855957137, 22392.116049541, 22498.887505562696, 23892.044318227076, 1025.490627367595, 1029.3463650581784, 1056.9312994655322, 1077.6928575355985, 1080.983785243222, 9126.0, 10994.34033957965, 11388.943055284837, 12129.575464858688, 12165.14843960932, 537128.0, 540521.0, 546595.0, 546941.2652936709, 547363.0, 130416.0, 164889.0, 166008.3399166017, 166248.0, 166601.0, 1214.799558072908, 1216.9756604867853, 1236.2273579013117, 1241.8820212080018, 1246.0592252849042, 19805.504862378555, 20036.699449507978, 20791.272305469236, 20828.27101051456, 21172.835494047977, 69944.30055699378, 70916.58166836531, 71611.64194178996, 73565.26434735584, 73875.0, 96209.0, 99962.50018749859, 100263.0, 100329.4983525107, 100712.0, 10536.473176341306, 12204.0, 12429.564965226175, 12453.875461245272, 12456.190347627375, 13324.0, 14404.783928241295, 14882.925585372004, 15017.0, 15034.548963531111, 11849.229800062969, 14969.251537422868, 15731.056136631838, 15757.369705211662, 15849.68300634004, 13804.861951380759, 14002.159870407808, 14234.86121110288, 14345.426182952982, 14767.852321476648, 16149.354025838835, 16444.342226310815, 16728.247228874487, 16749.162541873102, 16764.077975711454, 12052.09609279325, 12215.022798176302, 12233.0, 12422.37888105573, 12450.439730212343, 12319.44562494672, 15588.68822623564, 15658.295376707847, 15859.603509912344, 16253.918730406273], "value": 12343.551088244025, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1275.6236910111515, 1541.814982202136, 1575.3226112771504, 1578.8316645682198, 1586.5002524204872, 14192.148471091741, 14846.331915063834, 14864.959452838306, 14884.404623815035, 14927.626809329773, 14221.857781422183, 14952.775708364383, 15016.849831501682, 15029.62425939352, 15030.624234394145, 2936.721011503904, 2946.1456177708437, 2976.940461190775, 2979.2551862034493, 2986.6266716660416, 2902.3034471726737, 2916.8541572921376, 2918.1099764571795, 2940.0, 2940.1326608650497, 2767.9169624911283, 2802.929926751827, 2935.7357837794593, 2979.1509419815357, 2982.597349357831, 2925.3856690095104, 2939.2211064068038, 2942.6600808082767, 2954.896578619738, 2958.704129587046, 2882.610847535577, 2891.6530016397955, 2902.7097290270804, 2909.6944820793724, 2956.9556456653168, 14244.430222791076, 14365.425382984773, 14455.132692038405, 14505.27473626325, 14525.12849229039, 7678.463709014061, 8006.479578827358, 8108.918910810876, 8124.146964568694, 8155.225253600898, 4909.803607855682, 5048.419431765357, 5093.5415812576675, 5139.820106296262, 5156.896862062776, 1465.8607432294023, 1487.3381345301345, 1503.6316102554836, 1511.493649627376, 1515.2196618741357, 21759.67360489594, 21900.23349182771, 21908.780912190832, 21909.780902190934, 21912.671309930363, 6830.521863469509, 6872.10662613857, 6872.625474904939, 7017.05269788572, 7113.502054856111, 15050.32273547693, 15209.163496007826, 15226.162561059027, 15239.619009524851, 15253.694926101416, 7297.233790451926, 7473.738419155412, 7518.323350898446, 7523.172451030382, 7532.7740167795055, 6530.608163510205, 6736.292689267654, 6898.586084834826, 6938.791836244921, 6957.339052789976, 5113.53978141969, 5260.947390526122, 5278.4721527846905, 5297.735113244323, 5402.51377376038, 1353.9323033848268, 1579.6998570271612, 1620.7892973913315, 1632.3307443947924, 1640.2946732904966, 695.9408450281783, 839.7186942374373, 842.7977285451448, 864.7967727584013, 897.8114595934927, 7828.843423131506, 7964.800879977931, 8093.1961977870305, 8561.14388561139, 8629.784255393666, 1004.3572113847143, 1006.7885743993697, 1010.2726037253166, 1016.2479764974014, 1025.2310766924807, 3886.6356954993043, 5173.405058418255, 5200.479952004845, 5417.322834645669, 5470.863228419242, 18730.625387492175, 19001.239950402105, 19584.412467625993, 19599.706004409945, 19830.008499574964, 19581.412557623295, 19885.204591816164, 19890.0, 19929.402117936486, 19934.102965366594, 9728.075832795874, 10202.897971020344, 10254.58981640741, 10363.378197308186, 10499.212559058102, 6376.458001069961, 8371.869797577248, 8420.200080992296, 8567.828643427221, 8573.399862009848, 17274.740878887078, 17709.468715938543, 17970.730439043688, 18184.636307273515, 18219.0, 17283.06510661821, 18070.277188912296, 18514.0, 18576.071196440393, 18821.341253056078, 11571.5949941755, 11815.881841181477, 11825.408729563316, 11842.76314473723, 11870.703232419259, 11390.690070641982, 12760.553380631633, 13078.476860925457, 13230.867691323348, 13278.203307801563, 10480.161587073168, 10545.209109316986, 10709.357438553712, 10795.892041079489, 10954.616588419685, 10108.797824043624, 10126.13927816144, 10577.576896924345, 10596.841047384141, 10751.516181771683, 9566.760830979281, 9584.712458626253, 9638.614455421704, 9654.95172524161, 9658.951705241428, 6890.689918953559, 7040.0, 7054.858902821812, 7284.672189751368, 7293.38006269473, 6940.271271516418, 7048.436125110082, 7224.566526008456, 7306.123265208209, 7318.96340518294], "value": 6493.963528239467, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.2782067471588, 425.5064125614288, 457.9404677391943, 473.2191883392402, 479.53485119434157, 5298.549623282016, 6545.672716364181, 6604.834879128024, 6627.204735431743, 6633.767818126366, 6239.005277466818, 6482.675866206682, 6484.286728459878, 6510.934890651092, 6517.849109793769, 4957.900841983158, 4959.305697202394, 4964.5780108690715, 4983.950160498385, 5000.874978125539, 4222.493300803908, 4849.193383194193, 4963.305137280783, 5005.849824505252, 5011.724355160465, 3821.388577827548, 4768.998510312838, 4928.753562321869, 4946.233333833262, 4958.231474121499, 3721.4975978242996, 4898.387701537308, 4910.288008238786, 4922.187839006559, 4970.826021089262, 4086.366613174963, 4174.0, 4318.157959197958, 4330.35044743291, 4345.217860785057, 5520.282363292787, 6001.85964666712, 6289.685515724242, 6308.2745484269435, 6357.696672182192, 5791.797287094973, 5799.855003624901, 5806.970965145148, 5810.215620891167, 5825.563082768809, 6042.546808989299, 6924.619145946971, 7019.543159894048, 7023.192332881733, 7027.613481258529, 4874.415814860149, 4916.680415772963, 4928.743170491538, 4939.8024079036795, 4942.233953737176, 6869.793906182823, 7218.989341492195, 7359.448041396863, 7440.218777028443, 7445.553266803955, 4071.2671719090254, 4342.740605224512, 4876.5126636375535, 5017.9247311290355, 5049.01544198885, 4645.419322584677, 4920.507949205051, 5136.972605478919, 5142.5628821549835, 5145.6621278467865, 5095.541401273829, 5216.956608678203, 5653.9151912721345, 5801.216835727208, 5807.94192058074, 5451.890962180734, 7021.122359705037, 7024.719011239493, 7032.226455089936, 7045.512471326726, 1614.7566373892273, 1677.521906256711, 1744.0843557132607, 1749.2449392207325, 1757.5078977886212, 1083.4528563075596, 1105.9612913547987, 1164.138537482257, 1176.5529098942518, 1215.914885957992, 6607.372299631527, 6634.488547787454, 6635.469162466991, 6650.434713049348, 6667.299933506912, 7121.643917804089, 7234.131904171533, 7253.709851605877, 7269.164046134656, 7269.620480844243, 5775.902578510069, 6554.934450655528, 6568.934310656832, 6592.978088396306, 6627.734890604418, 3255.788373755698, 3288.868445262163, 3380.256343604403, 3740.625937406292, 3758.3986562149926, 7288.416926645856, 7425.774747166765, 7464.760571828539, 7474.813129671802, 7493.962530187314, 6171.333739890205, 6407.423331900151, 6773.525853190328, 7371.778846634609, 7435.141214696326, 5431.4840090191365, 6276.6547839868335, 6815.556988795713, 7392.297731715478, 7465.664045117983, 7148.678309475982, 7155.177154627077, 7168.856622867618, 7183.497155199086, 7221.927780722336, 5565.0, 6486.481081513373, 7030.789076327718, 7046.032547054417, 7084.36240738336, 5816.505597024131, 6332.30344662096, 6461.418472337512, 6933.618650974245, 7031.1211098612675, 4399.228486053766, 4978.551930326288, 5003.649744517848, 5006.924896126634, 5111.952049829851, 5384.7576859040655, 7019.051568644196, 7225.963870180615, 7243.927560724325, 7244.384227340524, 6755.831104222434, 6884.4148247397525, 6953.548019378724, 6964.512484126062, 6966.895496567454, 5979.581429300081, 6945.236024037251, 6952.895706564506, 6980.232174460907, 6992.4406047517095, 5939.4357536034, 6796.431873266377, 6808.82977925536, 6839.213490448463, 6857.862842743217, 5138.97430512845, 5430.565554755531, 6299.338569450325, 6778.0, 6970.337817907291, 6057.0, 6658.8679924412, 6747.224069231905, 6804.9319506804295, 6827.419669327964], "value": 5094.941822723602, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [838.7399906029129, 868.2272777228264, 909.5452273863068, 916.3036092569645, 920.6363486422863, 17821.01984390858, 18119.72820407693, 19810.900945495287, 20405.387838364837, 21117.472063198427, 19323.613527729438, 19463.0, 19634.70547941782, 19694.803051969513, 20091.497712557193, 994.8706668133133, 1041.3543602966172, 1078.811208038594, 1081.2053140941402, 1081.621432498623, 1070.0209308482729, 1076.9407682577455, 1085.3108276244564, 1088.9183311251647, 1089.4661615808236, 1116.430620383606, 1149.793037253294, 1150.7986102432083, 1151.9078473722082, 1152.4065106470175, 1078.8219943709278, 1124.1063354633004, 1127.1152145565773, 1130.8982191602754, 1132.2131118872387, 813.7151996801132, 822.411975437565, 825.335604838105, 825.9964143565537, 832.2177153475714, 18204.362847300414, 18639.81360186394, 18675.066246687747, 18684.25262989479, 18887.716684249746, 18360.63278734431, 18494.2602295908, 18545.62908741831, 18731.812681873143, 18803.717944230848, 15580.376784928589, 15612.687746245125, 15682.0, 15732.842671573253, 15735.606609834731, 3801.657850793442, 3806.8286927088348, 3838.9232215355537, 3863.903402414934, 3873.0704630888386, 117883.0, 131511.6848831509, 140183.1963360737, 141011.0, 144513.0, 14208.289585520683, 14274.643133921527, 14308.49920252786, 14445.85554144445, 14446.927765361315, 22665.660015099787, 27354.589681154797, 27375.45249095007, 27405.72594274032, 27474.450510989667, 7884.487508311941, 8057.31512821405, 8077.798055048671, 8087.0, 8176.141505141994, 4008.3386241270446, 4089.9795501022304, 4093.7543747375253, 4098.995746042238, 4102.815373308149, 4814.638902082358, 4880.389951256093, 4906.435759887659, 4908.803647854046, 4910.754462276872, 1013.0123129948241, 1032.5921261101823, 1033.4625994482976, 1035.2649618770606, 1035.2649618770606, 673.9258681545026, 677.1298880938069, 679.9796006119824, 696.7665831946355, 719.910011248594, 11896.286222826659, 12080.275183489019, 12283.447244873823, 12705.745885082431, 12869.613911582668, 585.5637550025258, 593.2880543348, 602.4126476685284, 606.3936063936064, 616.6115347331242, 4729.739864307496, 4811.254255590389, 5706.9714651426475, 5864.8533786654825, 5912.083627037816, 138907.0, 142875.85686214507, 144916.0, 146074.26962865118, 147690.0, 77016.61491692506, 117943.41028294804, 123445.0, 128783.35608321898, 129102.70897291094, 79444.0, 95204.0, 100859.99140008505, 101516.0, 103173.48413258036, 9499.335046546952, 10165.695029149138, 11213.551457941918, 11339.829902551633, 11434.656960291204, 47168.764156179, 47204.29193562168, 49623.255651165986, 50185.996280073465, 50304.74847625885, 48341.758291208316, 50246.748766255936, 59129.40870591411, 64369.67815160894, 67537.98693019708, 13718.38267277955, 14074.648133796736, 14285.42858285657, 14957.476488322856, 15347.693046139239, 16406.589835254214, 16774.0, 16959.660806784043, 17114.914425427793, 17257.827421725622, 15335.079895206323, 15377.846221537642, 15525.37898484048, 15549.455769048029, 15579.688406232039, 14935.327910244283, 15297.31162097686, 15310.617234569225, 15413.306401211748, 15758.975666581635, 13961.371738271599, 14731.0, 14940.925295373454, 14968.101913885203, 14973.700525989638, 10927.398993055458, 10988.835167472334, 11018.0, 11317.603883864025, 11374.829377559508, 11570.768584628091, 11570.88429115698, 11593.24643898144, 11681.766364672829, 11974.982126518995], "value": 8819.311597631002, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1509.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 105.0, 116.0, 122.0, 118.0, 115.0, 102.0, 116.0, 119.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 141.0, 175.0, 132.0, 129.0, 112.0, 133.0, 188.0, 139.0, 141.0], "value": 146.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 199.0, 200.0, 201.0, 200.0, 196.0, 215.0, 196.0, 223.0, 204.0], "value": 203.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 79.0, 73.0, 143.0, 56.0, 48.0, 70.0, 79.0, 122.0, 55.0], "value": 78.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 106.0, 70.0, 69.0, 82.0, 106.0, 70.0, 71.0, 117.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 109.0, 89.0, 89.0, 91.0, 87.0, 79.0, 136.0, 87.0, 88.0], "value": 94.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 95.0, 105.0, 109.0, 153.0, 124.0, 153.0, 94.0, 130.0], "value": 115.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 89.0, 91.0, 92.0, 92.0, 93.0, 93.0, 89.0, 88.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 46.0, 49.0, 48.0, 49.0, 52.0, 62.0, 75.0, 79.0, 81.0], "value": 58.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 56.0, 57.0, 70.0, 79.0, 69.0, 55.0, 54.0, 55.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 78.0, 81.0, 78.0, 79.0, 78.0, 78.0, 76.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 139.0, 131.0, 127.0, 141.0, 132.0, 130.0, 136.0, 136.0, 156.0], "value": 136.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 173.0, 178.0, 178.0, 176.0, 186.0, 217.0, 212.0, 170.0, 177.0], "value": 184.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 68.0, 65.0, 69.0, 65.0, 64.0, 65.0, 61.0, 63.0], "value": 64.7, "unit": "score"}]}]} 08:27:45 INFO - Return code: 0 08:27:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:27:46 INFO - # TBPL SUCCESS # 08:27:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:27:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:27:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:27:46 INFO - 2017-06-15 08:27:46.042 screenresolution[1672:10960] starting screenresolution argv=screenresolution get 08:27:46 INFO - 2017-06-15 08:27:46.044 screenresolution[1672:10960] Display 0: 1600x1200x32@60 08:27:46 INFO - 2017-06-15 08:27:46.050 screenresolution[1673:10963] starting screenresolution argv=screenresolution list 08:27:46 INFO - Available Modes on Display 0 08:27:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:27:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:27:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:27:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:27:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:27:46 INFO - Intel Iris: 08:27:46 INFO - Chipset Model: Intel Iris 08:27:46 INFO - Type: GPU 08:27:46 INFO - Bus: Built-In 08:27:46 INFO - VRAM (Dynamic, Max): 1536 MB 08:27:46 INFO - Vendor: Intel (0x8086) 08:27:46 INFO - Device ID: 0x0a2e 08:27:46 INFO - Revision ID: 0x0009 08:27:46 INFO - Displays: 08:27:46 INFO - Display: 08:27:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:27:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:27:46 INFO - Display Serial Number: 1600x1200 60 08:27:46 INFO - Main Display: Yes 08:27:46 INFO - Mirror: Off 08:27:46 INFO - Online: Yes 08:27:46 INFO - Rotation: Supported 08:27:46 INFO - Return code: 0 08:27:46 INFO - Running post-action listener: _resource_record_post_action 08:27:46 INFO - [mozharness: 2017-06-15 15:27:46.286675Z] Finished run-tests step (success) 08:27:46 INFO - Running post-run listener: _resource_record_post_run 08:27:46 INFO - Total resource usage - Wall time: 1246s; CPU: 53.0%; Read bytes: 50674176; Write bytes: 472023552; Read time: 560; Write time: 1585 08:27:46 INFO - TinderboxPrint: CPU usage
53.1% 08:27:46 INFO - TinderboxPrint: I/O read bytes / time
50,674,176 / 560 08:27:46 INFO - TinderboxPrint: I/O write bytes / time
472,023,552 / 1,585 08:27:46 INFO - TinderboxPrint: CPU idle
2,340.4 (46.9%) 08:27:46 INFO - TinderboxPrint: CPU system
1,010.2 (20.3%) 08:27:46 INFO - TinderboxPrint: CPU user
1,635.1 (32.8%) 08:27:46 INFO - TinderboxPrint: Swap in / out
195,948,544 / 0 08:27:46 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 136458752; Write bytes: 138135040; Read time: 10828; Write time: 293 08:27:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:27:46 INFO - run-tests - Wall time: 1231s; CPU: 53.0%; Read bytes: 49650176; Write bytes: 315444224; Read time: 544; Write time: 1245 08:27:46 INFO - Running post-run listener: _upload_blobber_files 08:27:46 INFO - Blob upload gear active. 08:27:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:27:46 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 08:27:46 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'] 08:27:46 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 08:27:47 INFO - (blobuploader) - INFO - Open directory for files ... 08:27:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:27:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:27:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:48 INFO - (blobuploader) - INFO - Done attempting. 08:27:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:27:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:27:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:48 INFO - (blobuploader) - INFO - Done attempting. 08:27:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:27:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:27:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:49 INFO - (blobuploader) - INFO - Done attempting. 08:27:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:27:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:27:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:27:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:51 INFO - (blobuploader) - INFO - Done attempting. 08:27:51 INFO - (blobuploader) - INFO - Iteration through files over. 08:27:51 INFO - Return code: 0 08:27:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:27:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:27:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f208ebe5a09168d061d2160edadfd1cb208146564b1c53e8f5418b65cc236365b2acb854e899ba505adccf5b8d97b7eb0f27f2e22843d4642f9a6e21f8d54756", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e0c78ca35a4263f69bb1c04f097e496b660b94aa5b2402efc8ea86fffceada3721c0386755c4bd00eaa2d2e04ad755c0ddb76ffedbbb19dd09add91ca866593", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eb5128e69cc0d851efa86a4c80ae2c3c9d32494038bd9a1b5c74ac99648003f64ca83e5fbbf425da0e430ab278d26d91d553ca1c877ed7558487b69332b603f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b027c0051d95ab0bb298fa3d1a9b7f5c6ad2751481428cc4f6a12dee9a8797f3521724fbc0cba99d866fc25806baad85424c98d6cbf84787383dcf0111bd679a"} 08:27:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:27:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:27:51 INFO - Contents: 08:27:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f208ebe5a09168d061d2160edadfd1cb208146564b1c53e8f5418b65cc236365b2acb854e899ba505adccf5b8d97b7eb0f27f2e22843d4642f9a6e21f8d54756", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e0c78ca35a4263f69bb1c04f097e496b660b94aa5b2402efc8ea86fffceada3721c0386755c4bd00eaa2d2e04ad755c0ddb76ffedbbb19dd09add91ca866593", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eb5128e69cc0d851efa86a4c80ae2c3c9d32494038bd9a1b5c74ac99648003f64ca83e5fbbf425da0e430ab278d26d91d553ca1c877ed7558487b69332b603f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b027c0051d95ab0bb298fa3d1a9b7f5c6ad2751481428cc4f6a12dee9a8797f3521724fbc0cba99d866fc25806baad85424c98d6cbf84787383dcf0111bd679a"} 08:27:51 INFO - Running post-run listener: copy_logs_to_upload_dir 08:27:51 INFO - Copying logs to upload dir... 08:27:51 INFO - mkdir: /builds/slave/test/build/upload/logs 08:27:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1328.366126 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2017-06-15 08:27:51.991166) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-15 08:27:51.994739) ========= 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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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/f208ebe5a09168d061d2160edadfd1cb208146564b1c53e8f5418b65cc236365b2acb854e899ba505adccf5b8d97b7eb0f27f2e22843d4642f9a6e21f8d54756", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e0c78ca35a4263f69bb1c04f097e496b660b94aa5b2402efc8ea86fffceada3721c0386755c4bd00eaa2d2e04ad755c0ddb76ffedbbb19dd09add91ca866593", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eb5128e69cc0d851efa86a4c80ae2c3c9d32494038bd9a1b5c74ac99648003f64ca83e5fbbf425da0e430ab278d26d91d553ca1c877ed7558487b69332b603f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b027c0051d95ab0bb298fa3d1a9b7f5c6ad2751481428cc4f6a12dee9a8797f3521724fbc0cba99d866fc25806baad85424c98d6cbf84787383dcf0111bd679a"} build_url:https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011165 build_url: 'https://queue.taskcluster.net/v1/task/e1EiLJb0SXuJLhcDZBMFNQ/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/f208ebe5a09168d061d2160edadfd1cb208146564b1c53e8f5418b65cc236365b2acb854e899ba505adccf5b8d97b7eb0f27f2e22843d4642f9a6e21f8d54756", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e0c78ca35a4263f69bb1c04f097e496b660b94aa5b2402efc8ea86fffceada3721c0386755c4bd00eaa2d2e04ad755c0ddb76ffedbbb19dd09add91ca866593", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eb5128e69cc0d851efa86a4c80ae2c3c9d32494038bd9a1b5c74ac99648003f64ca83e5fbbf425da0e430ab278d26d91d553ca1c877ed7558487b69332b603f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b027c0051d95ab0bb298fa3d1a9b7f5c6ad2751481428cc4f6a12dee9a8797f3521724fbc0cba99d866fc25806baad85424c98d6cbf84787383dcf0111bd679a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-15 08:27:52.031706) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 08:27:52.032070) ========= 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.5InYrVQ8C3/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.kUIRF8bb7P/Listeners TMPDIR=/var/folders/6j/h2_l2tgj0h5b111_ddr5tl_h00000w/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.004996 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 08:27:52.085111) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-15 08:27:52.085857) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-15 08:27:52.086547) ========= ========= Total master_lag: 0.17 =========