Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[task manager] provide better diagnostics when task manager performance is degraded #109741

Merged
merged 9 commits into from
Sep 1, 2021

Conversation

pmuellr
Copy link
Member

@pmuellr pmuellr commented Aug 23, 2021

Summary

resolves #109095
resolves #106854
resolves #110332

changes to task manager:

  • adds debug logging whenever a Health status of error or warning is generated, to identify the cause
  • adds debug logging when the derived (from core) and task manager status changes
  • change the check for "hot health" stats to change the required freshness from pollInterval to pollInterval * 3, to allow for slightly longer transient networking issues to not negatively update the health status
  • change the "Detected potential performance issue" message to provide more context, and link to Kibana doc
  • also change that message to log at the debug level, not warning
    • will help "hide" transient warnings - we have multiple issues open regarding this message, which appear to be transient
    • some of the diagnostic data we're generating, for example, "observed Kibana instances", doesn't match reality, so some of the triggers for this could be false alarms
    • we may want to promote this back to an "warn" level once we have a better handle on the false positives and transient triggering, but for right now, it seems to be causing more support issues than it is helping
  • previously, task manager would set it's plugin status to unavailable if it found a Health status of error, which caused Kibana itself to claim it was unavailable. It no longer does this, and will instead set it's status to degraded, which is also reflected as the Kibana status. There have been cases of users seeing the unavailable status and misunderstanding that Kibana crashed, which it did not.

changes to alerting:

  • like task manager, alerting now never uses the unavailable plugin status, changing all references of that to degraded

Checklist

Delete any items that are not applicable to this PR.

Release note

Improves Task Manager performance monitoring to log more diagnostic data in debug logs, provide a more accurate logged message when performance degradation is noticed, and never set the plugin status of "unavailable", using "degraded" instead.

@pmuellr pmuellr changed the title resolves https://github.com/elastic/kibana/issues/109095 [task manager] turn down task manager perf warning to debug, and change task manager health status to only go to degraded, never unavailable Aug 23, 2021
@pmuellr pmuellr added Feature:Task Manager release_note:enhancement Team:ResponseOps Label for the ResponseOps team (formerly the Cases and Alerting teams) v7.14.1 v7.15.0 v7.16.0 v8.0.0 labels Aug 24, 2021
@pmuellr pmuellr changed the title [task manager] turn down task manager perf warning to debug, and change task manager health status to only go to degraded, never unavailable [task manager] provide better diagnostics when task manager performance is degraded Aug 24, 2021
@pmuellr pmuellr marked this pull request as ready for review August 24, 2021 17:57
@pmuellr pmuellr requested a review from a team as a code owner August 24, 2021 17:57
@elasticmachine
Copy link
Contributor

Pinging @elastic/kibana-alerting-services (Team:Alerting Services)

Copy link
Contributor

@mikecote mikecote left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Changes LGTM once using docLinks plugin 👍 great work!

if (statusWithoutCapacity === HealthStatus.Warning) {
logLevel = LogLevel.Warn;
} else if (statusWithoutCapacity === HealthStatus.Error && !isEmpty(monitoredHealth.stats)) {
logLevel = LogLevel.Error;
}

const message = `Latest Monitored Stats: ${JSON.stringify(monitoredHealth)}`;
const detectedProblemMessage = `Task Manager detected a degradation in performance. This is usually temporary, and Kibana can recover automatically. If the problem persists, check the docs for troubleshooting information: https://www.elastic.co/guide/en/kibana/current/task-manager-health-monitoring.html .`;
Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

We should probably generate the URL using the docLinks plugin. (example PR https://github.com/elastic/kibana/pull/92953/files).

Copy link
Member Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

woops - I meant to leave a TODO for this (which would be easy to miss anyway), but there is no server-side docLinks plugin, yet - I did just add it as a TODO though :-)

To Do

open issue to change the new performance warning error to use server-side docLinks instead of the current reference, once we have server-side doclinks via this issue: [core] docLinks server-side service #95389 - the new issue will be blocked on that

Open for other ideas, felt like hard-coding current was going to be the best story for now ...

Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Ahh that's true, it's only public.

What about something like the following?

import { kibanaPackageJson } from '@kbn/utils';

https://www.elastic.co/guide/en/kibana/${kibanaPackageJson.branch}/task-manager-health-monitoring.html

Copy link
Member Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

I opened #109937 to track this (and deleted the TODO in the top comment).

Copy link
Member Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Ya, it does look like the existing docLink stuff makes use of the branch property, so seems doable. Up to you - we'll need to thread it through all the calls ... :-) I think long-term we still want docLinks for this, to handle cases when the URLs change (I assume it deals with that).

Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Do we have a url shortening service? I vaguely remember using one in the past

Copy link
Member Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

A URL shortener to make the doc link shorter? I'm not sure I want that - I'd prefer the user see that we're pointing them to doc, vs pointing them to the great unknown :-).

Copy link
Member Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Turns out using @kbn/utils to get the patch version is absurdly easy: in commit e8c9b08

Thanks for the tip, Mike! I want to use more packages like this :-)

Copy link
Contributor

@ymao1 ymao1 left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

LGTM! Left one comment about any potential follow up issues.

logger.warn(
`Detected potential performance issue with Task Manager. Set 'xpack.task_manager.monitored_stats_health_verbose_log.enabled: true' in your Kibana.yml to enable debug logging`
);
logger.debug(detectedProblemMessage);
Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

I understand why we're changing it back to debug for this PR, but we're effectively undoing this issue that was done for O11y of Alerting, where the intent was to try to have task manager self-introspect when it might be having problems and let the user know so they could turn on debug logging easily. Are we officially giving up on that part of O11y of Alerting because we've determined it's too noisy and too many false positives or will there be another follow up issue to try to achieve it in a less noisy way?

Copy link
Member Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Yeah, that's it in a nutshell - it's too noisy and too many false positives. I'll open a new issue to track this, note some of the issues we've already seen. Thanks!

Copy link
Member Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

opened #109941 to track this

@pmuellr
Copy link
Member Author

pmuellr commented Aug 25, 2021

@elasticmachine merge upstream

@pmuellr
Copy link
Member Author

pmuellr commented Aug 29, 2021

@elasticmachine merge upstream

@pmuellr
Copy link
Member Author

pmuellr commented Sep 1, 2021

@elasticmachine merge upstream

@pmuellr pmuellr merged commit dfa6924 into elastic:master Sep 1, 2021
pmuellr added a commit to pmuellr/kibana that referenced this pull request Sep 1, 2021
…ce is degraded (elastic#109741)

resolves elastic#109095
resolves elastic#106854

Changes the way task manager and alerting perform their health / status
checks:

- no longer sets an `unavailable` status; now uses `degraded` instead
- change task manager "hot stats freshness" calculation to allow for
  staler data before signalling a problem
- Changed the "Detected potential performance issue" message to sound
  less scary, include a doc link to task manager health monitoring, and
  log a debug instead of warning level
- add additional debug logging when task manager sets a status that's
  not `available`, indicating why it's setting that status (in the code,
  it's when task manager uses HealthStatus.Warning or Error)
pmuellr added a commit to pmuellr/kibana that referenced this pull request Sep 1, 2021
…ce is degraded (elastic#109741)

resolves elastic#109095
resolves elastic#106854

Changes the way task manager and alerting perform their health / status
checks:

- no longer sets an `unavailable` status; now uses `degraded` instead
- change task manager "hot stats freshness" calculation to allow for
  staler data before signalling a problem
- Changed the "Detected potential performance issue" message to sound
  less scary, include a doc link to task manager health monitoring, and
  log a debug instead of warning level
- add additional debug logging when task manager sets a status that's
  not `available`, indicating why it's setting that status (in the code,
  it's when task manager uses HealthStatus.Warning or Error)
pmuellr added a commit to pmuellr/kibana that referenced this pull request Sep 1, 2021
…ce is degraded (elastic#109741)

resolves elastic#109095
resolves elastic#106854

Changes the way task manager and alerting perform their health / status
checks:

- no longer sets an `unavailable` status; now uses `degraded` instead
- change task manager "hot stats freshness" calculation to allow for
  staler data before signalling a problem
- Changed the "Detected potential performance issue" message to sound
  less scary, include a doc link to task manager health monitoring, and
  log a debug instead of warning level
- add additional debug logging when task manager sets a status that's
  not `available`, indicating why it's setting that status (in the code,
  it's when task manager uses HealthStatus.Warning or Error)

# Conflicts:
#	x-pack/plugins/task_manager/server/monitoring/capacity_estimation.ts
#	x-pack/plugins/task_manager/server/monitoring/task_run_statistics.test.ts
#	x-pack/plugins/task_manager/server/routes/health.test.ts
pmuellr added a commit that referenced this pull request Sep 1, 2021
…ce is degraded (#109741) (#110870)

resolves #109095
resolves #106854

Changes the way task manager and alerting perform their health / status
checks:

- no longer sets an `unavailable` status; now uses `degraded` instead
- change task manager "hot stats freshness" calculation to allow for
  staler data before signalling a problem
- Changed the "Detected potential performance issue" message to sound
  less scary, include a doc link to task manager health monitoring, and
  log a debug instead of warning level
- add additional debug logging when task manager sets a status that's
  not `available`, indicating why it's setting that status (in the code,
  it's when task manager uses HealthStatus.Warning or Error)
pmuellr added a commit that referenced this pull request Sep 1, 2021
…ce is degraded (#109741) (#110869)

resolves #109095
resolves #106854

Changes the way task manager and alerting perform their health / status
checks:

- no longer sets an `unavailable` status; now uses `degraded` instead
- change task manager "hot stats freshness" calculation to allow for
  staler data before signalling a problem
- Changed the "Detected potential performance issue" message to sound
  less scary, include a doc link to task manager health monitoring, and
  log a debug instead of warning level
- add additional debug logging when task manager sets a status that's
  not `available`, indicating why it's setting that status (in the code,
  it's when task manager uses HealthStatus.Warning or Error)
pmuellr added a commit that referenced this pull request Sep 1, 2021
…rformance is degraded (#109741) (#110875)

* [task manager] provide better diagnostics when task manager performance is degraded (#109741)

resolves #109095
resolves #106854

Changes the way task manager and alerting perform their health / status
checks:

- no longer sets an `unavailable` status; now uses `degraded` instead
- change task manager "hot stats freshness" calculation to allow for
  staler data before signalling a problem
- Changed the "Detected potential performance issue" message to sound
  less scary, include a doc link to task manager health monitoring, and
  log a debug instead of warning level
- add additional debug logging when task manager sets a status that's
  not `available`, indicating why it's setting that status (in the code,
  it's when task manager uses HealthStatus.Warning or Error)

# Conflicts:
#	x-pack/plugins/task_manager/server/monitoring/capacity_estimation.ts
#	x-pack/plugins/task_manager/server/monitoring/task_run_statistics.test.ts
#	x-pack/plugins/task_manager/server/routes/health.test.ts

* fix backport to remove post-7.14 stuff
@kibanamachine
Copy link
Contributor

kibanamachine commented Oct 19, 2021

💔 Build Failed

Failed CI Steps


Test Failures

Kibana Pipeline / general / task-queue-process-15 / X-Pack Endpoint API Integration Tests.x-pack/test/security_solution_endpoint_api_int/apis/metadata·ts.Endpoint plugin test metadata api POST /api/endpoint/metadata when index is not empty metadata api should return one entry for each host with default paging

Link to Jenkins

Standard Out

Failed Tests Reporter:
  - Test has failed 35 times on tracked branches: https://github.com/elastic/kibana/issues/106051

[00:00:00]       │
[00:00:00]         └-: Endpoint plugin
[00:00:00]           └-> "before all" hook in "Endpoint plugin"
[00:00:00]           └-> "before all" hook in "Endpoint plugin"
[00:00:00]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/wfVsk5SsSu6l7m9IdqyIOw] update_mapping [_doc]
[00:00:00]             │ info [o.e.x.i.IndexLifecycleTransition] [node-01] moving index [.ds-ilm-history-5-2021.10.19-000001] from [{"phase":"new","action":"complete","name":"complete"}] to [{"phase":"hot","action":"unfollow","name":"branch-check-unfollow-prerequisites"}] in policy [ilm-history-ilm-policy]
[00:00:00]             │ info [o.e.x.i.IndexLifecycleTransition] [node-01] moving index [.ds-ilm-history-5-2021.10.19-000001] from [{"phase":"hot","action":"unfollow","name":"branch-check-unfollow-prerequisites"}] to [{"phase":"hot","action":"rollover","name":"check-rollover-ready"}] in policy [ilm-history-ilm-policy]
[00:00:00]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [.fleet_component_template-1]
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:14 source.ip: 172.17.0.1:41344, url.original: /search?package=fleet_server&internal=true&experimental=true
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:14 source.ip: 172.17.0.1:41346, url.original: /search?package=endpoint&internal=true&experimental=true
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:14 source.ip: 172.17.0.1:41348, url.original: /search?package=system&internal=true&experimental=true
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:14 source.ip: 172.17.0.1:41350, url.original: /search?package=elastic_agent&internal=true&experimental=true
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41366, url.original: /search?package=elastic_agent&internal=true&experimental=true
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41362, url.original: /search?package=endpoint&internal=true&experimental=true
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41364, url.original: /search?package=system&internal=true&experimental=true
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41370, url.original: /search?package=fleet_server&internal=true&experimental=true
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41374, url.original: /package/endpoint/0.19.1
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41378, url.original: /package/system/0.13.3
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41382, url.original: /package/fleet_server/0.9.1
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41386, url.original: /package/elastic_agent/0.0.7
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41390, url.original: /package/endpoint/0.19.1/
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41394, url.original: /package/system/0.13.3/
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41398, url.original: /package/fleet_server/0.9.1/
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41402, url.original: /package/elastic_agent/0.0.7/
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41414, url.original: /epr/fleet_server/fleet_server-0.9.1.zip
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41412, url.original: /epr/elastic_agent/elastic_agent-0.0.7.zip
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41406, url.original: /epr/endpoint/endpoint-0.19.1.zip
[00:00:00]             │ info [docker:registry] 2021/10/19 12:22:15 source.ip: 172.17.0.1:41418, url.original: /epr/system/system-0.13.3.zip
[00:00:00]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/wfVsk5SsSu6l7m9IdqyIOw] update_mapping [_doc]
[00:00:02]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/wfVsk5SsSu6l7m9IdqyIOw] update_mapping [_doc]
[00:00:02]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/wfVsk5SsSu6l7m9IdqyIOw] update_mapping [_doc]
[00:00:02]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/wfVsk5SsSu6l7m9IdqyIOw] update_mapping [_doc]
[00:00:03]             │ info [o.e.x.i.a.TransportPutLifecycleAction] [node-01] adding index lifecycle policy [logs-endpoint.collection-diagnostic]
[00:00:04]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-elastic_agent.elastic_agent@mappings]
[00:00:04]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-elastic_agent.elastic_agent@custom]
[00:00:04]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-elastic_agent.elastic_agent] for index patterns [metrics-elastic_agent.elastic_agent-*]
[00:00:05]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-system.auth@custom]
[00:00:05]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-system.application@custom]
[00:00:05]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.fsstat@custom]
[00:00:05]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.network@custom]
[00:00:05]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.uptime@custom]
[00:00:05]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-system.syslog@custom]
[00:00:05]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.socket_summary@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.memory@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.filesystem@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.cpu@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-system.system@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.process.summary@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.load@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.diskio@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-system.security@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.process@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-system.core@custom]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-system.auth] for index patterns [logs-system.auth-*]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-system.application] for index patterns [logs-system.application-*]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.fsstat] for index patterns [metrics-system.fsstat-*]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.network] for index patterns [metrics-system.network-*]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.uptime] for index patterns [metrics-system.uptime-*]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-system.syslog] for index patterns [logs-system.syslog-*]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.socket_summary] for index patterns [metrics-system.socket_summary-*]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.memory] for index patterns [metrics-system.memory-*]
[00:00:06]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.filesystem] for index patterns [metrics-system.filesystem-*]
[00:00:07]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.cpu] for index patterns [metrics-system.cpu-*]
[00:00:07]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-system.system] for index patterns [logs-system.system-*]
[00:00:07]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.process.summary] for index patterns [metrics-system.process.summary-*]
[00:00:07]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.load] for index patterns [metrics-system.load-*]
[00:00:07]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.diskio] for index patterns [metrics-system.diskio-*]
[00:00:07]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-system.security] for index patterns [logs-system.security-*]
[00:00:07]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.process] for index patterns [metrics-system.process-*]
[00:00:07]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-system.core] for index patterns [metrics-system.core-*]
[00:00:07]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-metadata-current] for index patterns [metrics-endpoint.metadata_current_*]
[00:00:08]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-endpoint.metrics@mappings]
[00:00:08]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.library@mappings]
[00:00:08]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.registry@mappings]
[00:00:08]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [.logs-endpoint.diagnostic.collection@mappings]
[00:00:08]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.alerts@mappings]
[00:00:08]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.security@mappings]
[00:00:08]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-endpoint.policy@mappings]
[00:00:08]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.file@mappings]
[00:00:08]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.process@mappings]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.network@mappings]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-endpoint.metadata@mappings]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-endpoint.metrics@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.library@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.registry@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [.logs-endpoint.diagnostic.collection@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.alerts@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.security@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-endpoint.policy@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.file@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.process@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [logs-endpoint.events.network@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding component template [metrics-endpoint.metadata@custom]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-endpoint.metrics] for index patterns [metrics-endpoint.metrics-*]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-endpoint.events.library] for index patterns [logs-endpoint.events.library-*]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-endpoint.events.registry] for index patterns [logs-endpoint.events.registry-*]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [.logs-endpoint.diagnostic.collection] for index patterns [.logs-endpoint.diagnostic.collection-*]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-endpoint.alerts] for index patterns [logs-endpoint.alerts-*]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-endpoint.events.security] for index patterns [logs-endpoint.events.security-*]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-endpoint.policy] for index patterns [metrics-endpoint.policy-*]
[00:00:09]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-endpoint.events.file] for index patterns [logs-endpoint.events.file-*]
[00:00:10]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-endpoint.events.process] for index patterns [logs-endpoint.events.process-*]
[00:00:10]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [logs-endpoint.events.network] for index patterns [logs-endpoint.events.network-*]
[00:00:10]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [metrics-endpoint.metadata] for index patterns [metrics-endpoint.metadata-*]
[00:00:11]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.transform-internal-007] creating index, cause [auto(bulk api)], templates [], shards [1]/[1]
[00:00:11]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.transform-internal-007]
[00:00:11]             │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.transform-internal-007][0]]])." previous.health="YELLOW" reason="shards started [[.transform-internal-007][0]]"
[00:00:11]             │ info [o.e.c.m.MetadataIndexTemplateService] [node-01] adding index template [.transform-notifications-000002] for index patterns [.transform-notifications-*]
[00:00:11]             │ info [o.e.x.c.c.n.AbstractAuditor] [node-01] Auditor template [.transform-notifications-000002] successfully installed
[00:00:11]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [metrics-endpoint.metadata_current_default] creating index, cause [api], templates [metrics-metadata-current], shards [1]/[1]
[00:00:11]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [metrics-endpoint.metadata_current_default]
[00:00:11]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.transform-notifications-000002] creating index, cause [auto(bulk api)], templates [.transform-notifications-000002], shards [1]/[1]
[00:00:11]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.transform-notifications-000002]
[00:00:11]             │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[metrics-endpoint.metadata_current_default][0], [.transform-notifications-000002][0]]])." previous.health="YELLOW" reason="shards started [[metrics-endpoint.metadata_current_default][0], [.transform-notifications-000002][0]]"
[00:00:11]             │ info [o.e.x.t.t.TransformTask] [node-01] [endpoint.metadata_current-default-0.19.1] updating state for transform to [{"task_state":"started","indexer_state":"stopped","checkpoint":0,"progress":{"docs_indexed":0,"docs_processed":0},"should_stop_at_checkpoint":false}].
[00:00:11]             │ info [o.e.x.t.t.TransformPersistentTasksExecutor] [node-01] [endpoint.metadata_current-default-0.19.1] successfully completed and scheduled task in node operation
[00:00:11]             │ info [o.e.x.t.t.ClientTransformIndexer] [node-01] [endpoint.metadata_current-default-0.19.1] Failed to create a point in time reader, falling back to normal search.
[00:00:11]             │      java.lang.NullPointerException: Point in time parameter must be not null
[00:00:11]             │      	at java.util.Objects.requireNonNull(Objects.java:233) ~[?:?]
[00:00:11]             │      	at org.elasticsearch.action.search.OpenPointInTimeResponse.<init>(OpenPointInTimeResponse.java:38) ~[elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportOpenPointInTimeAction.lambda$doExecute$1(TransportOpenPointInTimeAction.java:98) ~[elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$MappedActionListener.onResponse(ActionListener.java:95) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$RunAfterActionListener.onResponse(ActionListener.java:339) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.AbstractSearchAsyncAction.start(AbstractSearchAsyncAction.java:186) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.executeSearch(TransportSearchAction.java:754) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.executeLocalSearch(TransportSearchAction.java:542) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.lambda$executeRequest$6(TransportSearchAction.java:335) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.index.query.Rewriteable.rewriteAndFetch(Rewriteable.java:103) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.index.query.Rewriteable.rewriteAndFetch(Rewriteable.java:76) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.executeRequest(TransportSearchAction.java:376) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.executeRequest(TransportSearchAction.java:276) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportOpenPointInTimeAction.doExecute(TransportOpenPointInTimeAction.java:77) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportOpenPointInTimeAction.doExecute(TransportOpenPointInTimeAction.java:37) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.TransportAction$RequestFilterChain.proceed(TransportAction.java:77) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ActionFilter$Simple.apply(ActionFilter.java:42) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.TransportAction$RequestFilterChain.proceed(TransportAction.java:75) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.lambda$applyInternal$3(SecurityActionFilter.java:143) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$DelegatingFailureActionListener.onResponse(ActionListener.java:217) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:481) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.BulkShardRequestInterceptor.intercept(BulkShardRequestInterceptor.java:76) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.ResizeRequestInterceptor.intercept(ResizeRequestInterceptor.java:78) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.FieldAndDocumentLevelSecurityRequestInterceptor.intercept(FieldAndDocumentLevelSecurityRequestInterceptor.java:75) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.ShardSearchRequestInterceptor.intercept(ShardSearchRequestInterceptor.java:26) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.FieldAndDocumentLevelSecurityRequestInterceptor.intercept(FieldAndDocumentLevelSecurityRequestInterceptor.java:75) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.SearchRequestInterceptor.intercept(SearchRequestInterceptor.java:26) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.DlsFlsLicenseRequestInterceptor.intercept(DlsFlsLicenseRequestInterceptor.java:82) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.IndicesAliasesRequestInterceptor.intercept(IndicesAliasesRequestInterceptor.java:100) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.FieldAndDocumentLevelSecurityRequestInterceptor.intercept(FieldAndDocumentLevelSecurityRequestInterceptor.java:75) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.UpdateRequestInterceptor.intercept(UpdateRequestInterceptor.java:27) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.runRequestInterceptors(AuthorizationService.java:474) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.handleIndexActionAuthorizationResult(AuthorizationService.java:464) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.lambda$authorizeAction$11(AuthorizationService.java:395) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService
[00:00:11]             │ info $AuthorizationResultListener.onResponse(AuthorizationService.java:781) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$AuthorizationResultListener.onResponse(AuthorizationService.java:756) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ContextPreservingActionListener.onResponse(ContextPreservingActionListener.java:31) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.RBACEngine.lambda$authorizeIndexAction$3(RBACEngine.java:325) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.ListenableFuture.notifyListenerDirectly(ListenableFuture.java:113) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.ListenableFuture.addListener(ListenableFuture.java:55) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.ListenableFuture.addListener(ListenableFuture.java:41) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$CachingAsyncSupplier.getAsync(AuthorizationService.java:828) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.RBACEngine.authorizeIndexAction(RBACEngine.java:318) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.authorizeAction(AuthorizationService.java:393) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.maybeAuthorizeRunAs(AuthorizationService.java:326) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.lambda$authorize$1(AuthorizationService.java:227) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ContextPreservingActionListener.onResponse(ContextPreservingActionListener.java:31) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.RBACEngine.lambda$resolveAuthorizationInfo$1(RBACEngine.java:128) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.store.CompositeRolesStore.getRoles(CompositeRolesStore.java:268) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.RBACEngine.getRoles(RBACEngine.java:134) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.RBACEngine.resolveAuthorizationInfo(RBACEngine.java:122) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.authorize(AuthorizationService.java:229) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.lambda$applyInternal$4(SecurityActionFilter.java:142) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authc.AuthenticatorChain.authenticateAsync(AuthenticatorChain.java:85) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authc.AuthenticationService.authenticate(AuthenticationService.java:153) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.applyInternal(SecurityActionFilter.java:137) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.apply(SecurityActionFilter.java:101) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.TransportAction$RequestFilterChain.proceed(TransportAction.java:75) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.TransportAction.execute(TransportAction.java:53) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.tasks.TaskManager.registerAndExecute(TaskManager.java:163) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.node.NodeClient.executeLocally(NodeClient.java:100) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.node.NodeClient.doExecute(NodeClient.java:80) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.support.AbstractClient.execute(AbstractClient.java:375) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.FilterClient.doExecute(FilterClient.java:54) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.ParentTaskAssigningClient.doExecute(ParentTaskAssigningClient.java:52) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.support.AbstractClient.execute(AbstractClient.java:375) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.core.ClientHelper.executeWithHeadersAsync(ClientHelper.java:195) [x-pack-core-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.transform.transforms.ClientTransformIndexer.injectPointInTimeIfNeeded(ClientTransformIndexer.java:412) [transform-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.transform.transforms.ClientTransformIndexer.doNextSearch(ClientTransformIndexer.java:128) [transform-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.core.indexing.AsyncTwoPhaseIndexer.triggerNextSearch(AsyncTwoPhaseIndexer.java:605) [x-pack-core-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.core.indexing.AsyncTwoPhaseIndexer.nextSearch(AsyncTwoPhaseIndexer.java:592) [x-pack-core-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.core.indexing.AsyncTwoPhaseIndexer.lambda$maybeTriggerAsyncJob$4(AsyncTwoPhaseIndexer.java:216) [x-pack-core-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.transform.transforms.TransformIndexer.lambda$onStart$4(TransformIndexer.java:267) [transform-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.transform.transforms.TransformIndexer.lambda$onStart$5(TransformIndexer.java:303) [transform-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.transform.transforms.common.AbstractCompositeAggFunction.getInitialProgressFromResponse(AbstractCompositeAggFunction.java:187) [transform-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.transform.transforms.TransformIndexer.lambda$onStart$7(TransformIndexer.java:300) [transform-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ContextPreservingActionListener.onResponse(ContextPreservingActionListener.java:31) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.node.NodeClient.lambda$executeLocally$0(NodeClient.java:103) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.tasks.TaskManager$1.onResponse(TaskManager.java:169) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.tasks.TaskManager$1.onResponse(TaskManager.java:163) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ContextPreservingActionListener.onResponse(ContextPreservingActionListener.java:31) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.lambda$applyInternal$2(SecurityActionFilter.java:146) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$DelegatingFailureActionListener.onResponse(ActionListener.java:217) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$RunAfterActionListener.onResponse(ActionListener.java:339) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.AbstractSearchAsyncAction.start(AbstractSearchAsyncAction.java:186) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.executeSearch(TransportSearchAction.java:754) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.executeLocalSearch(TransportSearchAction.java:542) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.lambda$executeRequest$6(TransportSearchAction.java:335) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.index.query.Rewriteable.rewriteAndFetch(Rewriteable.java:103) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.index.query.Rewriteable.rewriteAndFetch(Rewriteable.java:76) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.executeRequest(TransportSearchAction.java:376) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.doExecute(TransportSearchAction.java:265) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.search.TransportSearchAction.doExecute(TransportSearchAction.java:99) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.TransportAction$RequestFilterChain.proceed(TransportAction.java:77) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ActionFilter$Simple.apply(ActionFilter.java:42) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.TransportAction$RequestFilterChain.proceed(TransportAction.java:75) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.lambda$applyInternal$3(SecurityActionFilter.java:143) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$DelegatingFailureActionListener.onResponse(ActionListener.java:217) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:481) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.BulkShardRequestInterceptor.intercept(BulkShardRequestInterceptor.java:76) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.ResizeRequestInterceptor.intercept(ResizeRequestInterceptor.java:78) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.FieldAndDocumentLevelSecurityRequestInterceptor.intercept(FieldAndDocumentLevelSecurityRequestInterceptor.java:75) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.ShardSearchRequestInterceptor.intercept(ShardSearchRequestInterceptor.java:26) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.FieldAndDocumentLevelSecurityRequestInterceptor.intercept(FieldAndDocumentLevelSecurityRequestInterceptor.java:75) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.SearchRequestInterceptor.intercept(SearchRequestInterceptor.java:26) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.DlsFlsLicenseRequestInterceptor.intercept(DlsFlsLicenseRequestInterceptor.java:82) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.IndicesAliasesRequestInterceptor.intercept(IndicesAliasesRequestInterceptor.java:100) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:479) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$1.onResponse(AuthorizationService.java:475) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.FieldAndDocumentLevelSecurityRequestInterceptor.intercept(FieldAndDocumentLevelSecurityRequestInterceptor.java:75) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.interceptor.UpdateRequestInterceptor.intercept(UpdateRequestInterceptor.java:27) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.runRequestInterceptors(AuthorizationService.java:474) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.handleIndexActionAuthorizationResult(AuthorizationService.java:464) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.lambda$authorizeAction$11(AuthorizationService.java:395) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$AuthorizationResultListener.onResponse(AuthorizationService.java:781) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$AuthorizationResultListener.onResponse(AuthorizationService.java:756) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ContextPreservingActionListener.onResponse(ContextPreservingActionListener.java:31) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.RBACEngine.lambda$authorizeIndexAction$3(RBACEngine.java:325) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.ListenableFuture.notifyListenerDirectly(ListenableFuture.java:113) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.ListenableFuture.addListener(ListenableFuture.java:55) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.ListenableFuture.addListener(ListenableFuture.java:41) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService$CachingAsyncSupplier.getAsync(AuthorizationService.java:828) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.RBACEngine.authorizeIndexAction(RBACEngine.java:318) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.authorizeAction(AuthorizationService.java:393) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.maybeAuthorizeRunAs(AuthorizationService.java:326) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.lambda$authorize$1(AuthorizationService.java:227) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ContextPreservingActionListener.onResponse(ContextPreservingActionListener.java:31) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.RBACEngine.lambda$resolveAuthorizationInfo$1(RBACEngine.java:128) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.store.CompositeRolesStore.getRoles(CompositeRolesStore.java:268) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.RBACEngine.getRoles(RBACEngine.java:134) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.RBACEngine.resolveAuthorizationInfo(RBACEngine.java:122) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authz.AuthorizationService.authorize(AuthorizationService.java:229) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.lambda$applyInternal$4(SecurityActionFilter.java:142) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authc.AuthenticatorChain.authenticateAsync(AuthenticatorChain.java:85) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.authc.AuthenticationService.authenticate(AuthenticationService.java:153) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.applyInternal(SecurityActionFilter.java:137) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.apply(SecurityActionFilter.java:101) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.TransportAction$RequestFilterChain.proceed(TransportAction.java:75) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.TransportAction.execute(TransportAction.java:53) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.tasks.TaskManager.registerAndExecute(TaskManager.java:163) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.node.NodeClient.executeLocally(NodeClient.java:100) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.node.NodeClient.doExecute(NodeClient.java:80) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.support.AbstractClient.execute(AbstractClient.java:375) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.FilterClient.doExecute(FilterClient.java:54) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.ParentTaskAssigningClient.doExecute(ParentTaskAssigningClient.java:52) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.support.AbstractClient.execute(AbstractClient.java:375) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.core.ClientHelper.executeWithHeadersAsync(ClientHelper.java:195) [x-pack-core-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.transform.transforms.ClientTransformIndexer.doGetInitialProgress(ClientTransformIndexer.java:243) [transform-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.transform.transforms.TransformIndexer.lambda$onStart$9(TransformIndexer.java:299) [transform-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.transform.transforms.TransformIndexer.lambda$createCheckpoint$0(TransformIndexer.java:228) [transform-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.transform.persistence.IndexBasedTransformConfigManager.lambda$putTransformCheckpoint$0(IndexBasedTransformConfigManager.java:127) [transform-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ContextPreservingActionListener.onResponse(ContextPreservingActionListener.java:31) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.node.NodeClient.lambda$executeLocally$0(NodeClient.java:103) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.tasks.TaskManager$1.onResponse(TaskManager.java:169) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.tasks.TaskManager$1.onResponse(TaskManager.java:163) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ContextPreservingActionListener.onResponse(ContextPreservingActionListener.java:31) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.lambda$applyInternal$2(SecurityActionFilter.java:146) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$DelegatingFailureActionListener.onResponse(ActionListener.java:217) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.bulk.TransportSingleItemBulkWriteAction.lambda$wrapBulkResponse$0(TransportSingleItemBulkWriteAction.java:51) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ContextPreservingActionListener.onResponse(ContextPreservingActionListener.java:31) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.lambda$applyInternal$2(SecurityActionFilter.java:146) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$DelegatingFailureActionListener.onResponse(ActionListener.java:217) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$RunBeforeActionListener.onResponse(ActionListener.java:387) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.bulk.TransportBulkAction$BulkOperation$1.finishHim(TransportBulkAction.java:591) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.bulk.TransportBulkAction$BulkOperation$1.onResponse(TransportBulkAction.java:572) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.bulk.TransportBulkAction$BulkOperation$1.onResponse(TransportBulkAction.java:561) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.client.node.NodeClient.lambda$executeLocally$0(NodeClient.java:103) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.tasks.TaskManager$1.onResponse(TaskManager.java:169) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.tasks.TaskManager$1.onResponse(TaskManager.java:163) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ContextPreservingActionListener.onResponse(ContextPreservingActionListener.java:31) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.xpack.security.action.filter.SecurityActionFilter.lambda$applyInternal$2(SecurityActionFilter.java:146) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$DelegatingFailureActionListener.onResponse(ActionListener.java:217) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.TransportReplicationAction$ReroutePhase.finishOnSuccess(TransportReplicationAction.java:877) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.TransportReplicationAction$ReroutePhase$1.handleResponse(TransportReplicationAction.java:796) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.TransportReplicationAction$ReroutePhase$1.handleResponse(TransportReplicationAction.java:787) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.transport.TransportService$4.handleResponse(TransportService.java:622) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.transport.TransportService$ContextRestoreResponseHandler.handleResponse(TransportService.java:1184) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.transport.TransportService$DirectResponseChannel.processResponse(TransportService.java:1262) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.transport.TransportService$DirectResponseChannel.sendResponse(TransportService.java:1242) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.transport.TaskTransportChannel.sendResponse(TaskTransportChannel.java:41) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ChannelActionListener.onResponse(ChannelActionListener.java:32) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.ChannelActionListener.onResponse(ChannelActionListener.java:16) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$RunBeforeActionListener.onResponse(ActionListener.java:387) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.TransportReplicationAction$AsyncPrimaryAction.lambda$runWithPrimaryShardReference$2(TransportReplicationAction.java:413) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$MappedActionListener.onResponse(ActionListener.java:101) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.ReplicationOperation.finish(ReplicationOperation.java:336) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.ReplicationOperation.decPendingAndFinishIfNeeded(ReplicationOperation.java:317) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.ReplicationOperation$1.onResponse(ReplicationOperation.java:147) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.ReplicationOperation$1.onResponse(ReplicationOperation.java:139) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.TransportWriteAction$WritePrimaryResult$1.onSuccess(TransportWriteAction.java:255) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.TransportWriteAction$AsyncAfterWriteAction.maybeFinish(TransportWriteAction.java:390) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.TransportWriteAction$AsyncAfterWriteAction.lambda$run$1(TransportWriteAction.java:421) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.AsyncIOProcessor.notifyList(AsyncIOProcessor.java:111) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.AsyncIOProcessor.drainAndProcessAndRelease(AsyncIOProcessor.java:89) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.AsyncIOProcessor.put(AsyncIOProcessor.java:73) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.index.shard.IndexShard.sync(IndexShard.java:3287) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.TransportWriteAction$AsyncAfterWriteAction.run(TransportWriteAction.java:419) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.TransportWriteAction$WritePrimaryResult.runPostReplicationActions(TransportWriteAction.java:262) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.ReplicationOperation.handlePrimaryResult(ReplicationOperation.java:139) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:134) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.ActionListener.completeWith(ActionListener.java:445) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.bulk.TransportShardBulkAction$2.finishRequest(TransportShardBulkAction.java:207) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.bulk.TransportShardBulkAction$2.doRun(TransportShardBulkAction.java:176) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.AbstractRunnable.run(AbstractRunnable.java:26) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.bulk.TransportShardBulkAction.performOnPrimary(TransportShardBulkAction.java:212) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.bulk.TransportShardBulkAction.dispatchedShardOperationOnPrimary(TransportShardBulkAction.java:110) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.bulk.TransportShardBulkAction.dispatchedShardOperationOnPrimary(TransportShardBulkAction.java:74) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.action.support.replication.TransportWriteAction$1.doRun(TransportWriteAction.java:181) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.ThreadContext$ContextPreservingAbstractRunnable.doRun(ThreadContext.java:737) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at org.elasticsearch.common.util.concurrent.AbstractRunnable.run(AbstractRunnable.java:26) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:11]             │      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
[00:00:11]             │      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
[00:00:11]             │      	at java.lang.Thread.run(Thread.java:833) [?:?]
[00:00:13]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/wfVsk5SsSu6l7m9IdqyIOw] update_mapping [_doc]
[00:00:14]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/wfVsk5SsSu6l7m9IdqyIOw] update_mapping [_doc]
[00:00:15]             │ info [docker:registry] 2021/10/19 12:22:30 source.ip: 172.17.0.1:41500, url.original: /search?package=system&internal=true&experimental=true
[00:00:15]             │ info [docker:registry] 2021/10/19 12:22:30 source.ip: 172.17.0.1:41504, url.original: /search?package=system&internal=true&experimental=true
[00:00:15]             │ info [docker:registry] 2021/10/19 12:22:30 source.ip: 172.17.0.1:41508, url.original: /search?package=system&internal=true&experimental=true
[00:00:15]             │ info [docker:registry] 2021/10/19 12:22:30 source.ip: 172.17.0.1:41512, url.original: /package/system/0.13.3
[00:00:15]             │ info [docker:registry] 2021/10/19 12:22:30 source.ip: 172.17.0.1:41516, url.original: /package/system/0.13.3/
[00:00:15]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/wfVsk5SsSu6l7m9IdqyIOw] update_mapping [_doc]
[00:00:17]             │ info [docker:registry] 2021/10/19 12:22:32 source.ip: 172.17.0.1:41524, url.original: /search?package=fleet_server&internal=true&experimental=true
[00:00:17]             │ info [docker:registry] 2021/10/19 12:22:32 source.ip: 172.17.0.1:41528, url.original: /search?package=fleet_server&internal=true&experimental=true
[00:00:17]             │ info [docker:registry] 2021/10/19 12:22:32 source.ip: 172.17.0.1:41532, url.original: /search?package=fleet_server&internal=true&experimental=true
[00:00:17]             │ info [docker:registry] 2021/10/19 12:22:32 source.ip: 172.17.0.1:41536, url.original: /package/fleet_server/0.9.1
[00:00:17]             │ info [docker:registry] 2021/10/19 12:22:32 source.ip: 172.17.0.1:41540, url.original: /package/fleet_server/0.9.1/
[00:00:19]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.fleet-enrollment-api-keys-7] creating index, cause [auto(bulk api)], templates [], shards [1]/[1]
[00:00:19]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.fleet-enrollment-api-keys-7]
[00:00:19]             │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.fleet-enrollment-api-keys-7][0]]])." previous.health="YELLOW" reason="shards started [[.fleet-enrollment-api-keys-7][0]]"
[00:00:21]             │ info [docker:registry] 2021/10/19 12:22:35 source.ip: 172.17.0.1:41554, url.original: /search?package=fleet_server&internal=true&experimental=true
[00:00:21]             │ info [docker:registry] 2021/10/19 12:22:35 source.ip: 172.17.0.1:41552, url.original: /search?package=system&internal=true&experimental=true
[00:00:21]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.fleet-policies-7] creating index, cause [auto(bulk api)], templates [], shards [1]/[1]
[00:00:21]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.fleet-policies-7]
[00:00:21]             │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.fleet-policies-7][0]]])." previous.health="YELLOW" reason="shards started [[.fleet-policies-7][0]]"
[00:00:25]           └-: test metadata api
[00:00:25]             └-> "before all" hook in "test metadata api"
[00:00:25]             └-: POST /api/endpoint/metadata when index is not empty
[00:00:25]               └-> "before all" hook for "metadata api should return one entry for each host with default paging"
[00:00:25]               └-> "before all" hook for "metadata api should return one entry for each host with default paging"
[00:00:25]                 │ info [x-pack/test/functional/es_archives/endpoint/metadata/api_feature] Loading "data.json"
[00:00:25]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.ds-metrics-endpoint.metadata-default-2021.10.19-000001] creating index, cause [initialize_data_stream], templates [metrics-endpoint.metadata], shards [1]/[1]
[00:00:25]                 │ info [o.e.c.m.MetadataCreateDataStreamService] [node-01] adding data stream [metrics-endpoint.metadata-default] with write index [.ds-metrics-endpoint.metadata-default-2021.10.19-000001], backing indices [], and aliases []
[00:00:25]                 │ info [o.e.x.i.IndexLifecycleTransition] [node-01] moving index [.ds-metrics-endpoint.metadata-default-2021.10.19-000001] from [null] to [{"phase":"new","action":"complete","name":"complete"}] in policy [metrics]
[00:00:25]                 │ info [x-pack/test/functional/es_archives/endpoint/metadata/api_feature] Indexed 9 docs into "metrics-endpoint.metadata-default"
[00:00:25]                 │ info [o.e.x.i.IndexLifecycleTransition] [node-01] moving index [.ds-metrics-endpoint.metadata-default-2021.10.19-000001] from [{"phase":"new","action":"complete","name":"complete"}] to [{"phase":"hot","action":"unfollow","name":"branch-check-unfollow-prerequisites"}] in policy [metrics]
[00:00:25]                 │ info [o.e.x.i.IndexLifecycleTransition] [node-01] moving index [.ds-metrics-endpoint.metadata-default-2021.10.19-000001] from [{"phase":"hot","action":"unfollow","name":"branch-check-unfollow-prerequisites"}] to [{"phase":"hot","action":"rollover","name":"check-rollover-ready"}] in policy [metrics]
[00:02:25]               └-> metadata api should return one entry for each host with default paging
[00:02:25]                 └-> "before each" hook: global before each for "metadata api should return one entry for each host with default paging"
[00:02:25]                 └- ✖ fail: Endpoint plugin test metadata api POST /api/endpoint/metadata when index is not empty metadata api should return one entry for each host with default paging
[00:02:25]                 │       Error: expected 0 to sort of equal 3
[00:02:25]                 │       + expected - actual
[00:02:25]                 │ 
[00:02:25]                 │       -0
[00:02:25]                 │       +3
[00:02:25]                 │       
[00:02:25]                 │       at Assertion.assert (/dev/shm/workspace/kibana/node_modules/@kbn/expect/expect.js:100:11)
[00:02:25]                 │       at Assertion.eql (/dev/shm/workspace/kibana/node_modules/@kbn/expect/expect.js:244:8)
[00:02:25]                 │       at Context.<anonymous> (test/security_solution_endpoint_api_int/apis/metadata.ts:65:31)
[00:02:25]                 │       at runMicrotasks (<anonymous>)
[00:02:25]                 │       at processTicksAndRejections (internal/process/task_queues.js:95:5)
[00:02:25]                 │       at Object.apply (/dev/shm/workspace/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)
[00:02:25]                 │ 
[00:02:25]                 │ 

Stack Trace

Error: expected 0 to sort of equal 3
    at Assertion.assert (/dev/shm/workspace/kibana/node_modules/@kbn/expect/expect.js:100:11)
    at Assertion.eql (/dev/shm/workspace/kibana/node_modules/@kbn/expect/expect.js:244:8)
    at Context.<anonymous> (test/security_solution_endpoint_api_int/apis/metadata.ts:65:31)
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:95:5)
    at Object.apply (/dev/shm/workspace/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16) {
  actual: '0',
  expected: '3',
  showDiff: true
}

Kibana Pipeline / general / Plugin Functional Tests.test/plugin_functional/test_suites/data_plugin/session·ts.data plugin Session management Dashboard "after each" hook for "on load there is a single session"

Link to Jenkins

Standard Out

Failed Tests Reporter:
  - Test has not failed recently on tracked branches

[00:00:00]       │
[00:07:47]         └-: data plugin
[00:07:47]           └-> "before all" hook in "data plugin"
[00:07:47]           └-> "before all" hook in "data plugin"
[00:07:47]             │ info [test/functional/fixtures/es_archiver/getting_started/shakespeare] Loading "mappings.json"
[00:07:47]             │ info [test/functional/fixtures/es_archiver/getting_started/shakespeare] Loading "data.json.gz"
[00:07:47]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [shakespeare] creating index, cause [api], templates [], shards [5]/[1]
[00:07:47]             │ info [test/functional/fixtures/es_archiver/getting_started/shakespeare] Created index "shakespeare"
[00:07:47]             │ debg [test/functional/fixtures/es_archiver/getting_started/shakespeare] "shakespeare" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:07:47]             │ info [o.e.c.m.MetadataMappingService] [node-01] [shakespeare/uLmYBWb7Sx69CN67o5z2tQ] update_mapping [_doc]
[00:07:57]             │ info [test/functional/fixtures/es_archiver/getting_started/shakespeare] Indexed 111396 docs into "shakespeare"
[00:07:57]             │ debg navigating to settings url: http://localhost:6111/app/management
[00:07:57]             │ debg navigate to: http://localhost:6111/app/management
[00:07:57]             │ debg browser[DEBUG] http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 349699:16 "Reporting application usage for applink_start, main"
[00:07:57]             │ debg browser[INFO] http://localhost:6111/app/management?_t=1634646203624 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:07:57]             │
[00:07:57]             │ debg browser[INFO] http://localhost:6111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:07:57]             │ debg ... sleep(700) start
[00:07:58]             │ debg ... sleep(700) end
[00:07:58]             │ debg returned from get, calling refresh
[00:07:59]             │ debg browser[INFO] http://localhost:6111/9007199254740991/bundles/core/core.entry.js 18935:18 "Plugin visTypeTable is using asynchronous setup lifecycle. Asynchronous plugins support will be removed in a later version."
[00:07:59]             │ debg browser[INFO] http://localhost:6111/app/management?_t=1634646203624 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:07:59]             │
[00:07:59]             │ debg browser[INFO] http://localhost:6111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:07:59]             │ debg currentUrl = http://localhost:6111/app/management
[00:07:59]             │          appUrl = http://localhost:6111/app/management
[00:07:59]             │ debg TestSubjects.find(kibanaChrome)
[00:07:59]             │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:08:01]             │ debg browser[INFO] http://localhost:6111/9007199254740991/bundles/core/core.entry.js 18935:18 "Plugin visTypeTable is using asynchronous setup lifecycle. Asynchronous plugins support will be removed in a later version."
[00:08:01]             │ debg browser[INFO] http://localhost:6111/9007199254740991/bundles/core/core.entry.js 18935:18 "Plugin globalSearchBar is using asynchronous setup lifecycle. Asynchronous plugins support will be removed in a later version."
[00:08:01]             │ debg browser[DEBUG] http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 349699:16 "Reporting user-agent."
[00:08:01]             │ debg ... sleep(501) start
[00:08:01]             │ debg ... sleep(501) end
[00:08:01]             │ debg in navigateTo url = http://localhost:6111/app/management
[00:08:01]             │ debg isGlobalLoadingIndicatorVisible
[00:08:01]             │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:01]             │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:03]             │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:08:03]             │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:03]             │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:03]             │ debg clickKibanaIndexPatterns link
[00:08:03]             │ debg TestSubjects.click(indexPatterns)
[00:08:03]             │ debg Find.clickByCssSelector('[data-test-subj="indexPatterns"]') with timeout=10000
[00:08:03]             │ debg Find.findByCssSelector('[data-test-subj="indexPatterns"]') with timeout=10000
[00:08:04]             │ debg isGlobalLoadingIndicatorVisible
[00:08:04]             │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:04]             │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:05]             │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:08:06]             │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:06]             │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:06]             │ debg Find.existsByLinkText('shakespeare')  with timeout=2500
[00:08:08]             │ debg isGlobalLoadingIndicatorVisible
[00:08:08]             │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:08]             │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:10]             │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:08:10]             │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:10]             │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:10]             │ debg TestSubjects.exists(createAnyway)
[00:08:10]             │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="createAnyway"]') with timeout=2500
[00:08:13]             │ debg --- retry.tryForTime error: [data-test-subj="createAnyway"] is not displayed
[00:08:13]             │ debg TestSubjects.exists(createIndexPatternButtonFlyout)
[00:08:13]             │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="createIndexPatternButtonFlyout"]') with timeout=2500
[00:08:13]             │ debg TestSubjects.click(createIndexPatternButtonFlyout)
[00:08:13]             │ debg Find.clickByCssSelector('[data-test-subj="createIndexPatternButtonFlyout"]') with timeout=10000
[00:08:13]             │ debg Find.findByCssSelector('[data-test-subj="createIndexPatternButtonFlyout"]') with timeout=10000
[00:08:13]             │ debg isGlobalLoadingIndicatorVisible
[00:08:13]             │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:13]             │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:15]             │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:08:16]             │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:16]             │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:16]             │ debg setIndexPatternField(shakespeare)
[00:08:16]             │ debg TestSubjects.find(createIndexPatternNameInput)
[00:08:16]             │ debg Find.findByCssSelector('[data-test-subj="createIndexPatternNameInput"]') with timeout=10000
[00:08:17]             │ debg setIndexPatternField set to shakespeare
[00:08:17]             │ debg ... sleep(2000) start
[00:08:19]             │ debg ... sleep(2000) end
[00:08:19]             │ debg TestSubjects.find(saveIndexPatternButton)
[00:08:19]             │ debg Find.findByCssSelector('[data-test-subj="saveIndexPatternButton"]') with timeout=10000
[00:08:20]             │ debg isGlobalLoadingIndicatorVisible
[00:08:20]             │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:20]             │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:20]             │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:20]             │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:20]             │ info currentUrl http://localhost:6111/app/management/kibana/indexPatterns/patterns/67f84af0-30d7-11ec-9198-0d0497fc8b30#/?_a=(tab:indexedFields)
[00:08:20]             │ debg Index pattern created: http://localhost:6111/app/management/kibana/indexPatterns/patterns/67f84af0-30d7-11ec-9198-0d0497fc8b30#/?_a=(tab:indexedFields)
[00:08:20]             │ debg index pattern ID:  ?_a=(tab:indexedFields)
[00:08:20]           └-: Session management
[00:08:20]             └-> "before all" hook in "Session management"
[00:08:59]             └-: Dashboard
[00:08:59]               └-> "before all" hook for "on load there is a single session"
[00:08:59]               └-> "before all" hook for "on load there is a single session"
[00:08:59]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Loading "mappings.json"
[00:08:59]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Loading "data.json.gz"
[00:08:59]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [animals-dogs-2018-01-01] creating index, cause [api], templates [], shards [5]/[1]
[00:08:59]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Created index "animals-dogs-2018-01-01"
[00:08:59]                 │ debg [test/functional/fixtures/es_archiver/dashboard/current/data] "animals-dogs-2018-01-01" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:08:59]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [animals-dogs-2018-04-10] creating index, cause [api], templates [], shards [5]/[1]
[00:09:00]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Created index "animals-dogs-2018-04-10"
[00:09:00]                 │ debg [test/functional/fixtures/es_archiver/dashboard/current/data] "animals-dogs-2018-04-10" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:09:00]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [animals-cats-2018-01-01] creating index, cause [api], templates [], shards [5]/[1]
[00:09:00]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Created index "animals-cats-2018-01-01"
[00:09:00]                 │ debg [test/functional/fixtures/es_archiver/dashboard/current/data] "animals-cats-2018-01-01" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:09:00]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [animals-cats-2018-04-10] creating index, cause [api], templates [], shards [5]/[1]
[00:09:00]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Created index "animals-cats-2018-04-10"
[00:09:00]                 │ debg [test/functional/fixtures/es_archiver/dashboard/current/data] "animals-cats-2018-04-10" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:09:00]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [dogbreeds] creating index, cause [api], templates [], shards [5]/[1]
[00:09:00]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Created index "dogbreeds"
[00:09:00]                 │ debg [test/functional/fixtures/es_archiver/dashboard/current/data] "dogbreeds" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:09:00]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-0] creating index, cause [api], templates [], shards [1]/[0]
[00:09:00]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Created index "logstash-0"
[00:09:00]                 │ debg [test/functional/fixtures/es_archiver/dashboard/current/data] "logstash-0" settings {"index":{"analysis":{"analyzer":{"makelogs_url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:09:09]                 │ info progress: 12636
[00:09:10]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 6 docs into "animals-dogs-2018-01-01"
[00:09:10]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 6 docs into "animals-dogs-2018-04-10"
[00:09:10]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 6 docs into "animals-cats-2018-01-01"
[00:09:10]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 5 docs into "animals-cats-2018-04-10"
[00:09:10]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 6 docs into "dogbreeds"
[00:09:10]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 14005 docs into "logstash-0"
[00:09:10]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/kibana] Loading "mappings.json"
[00:09:10]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/kibana] Loading "data.json"
[00:09:10]                 │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_task_manager_8.0.0_001/zRYx24eQSW-Xu4WYMq1ZSw] deleting index
[00:09:10]                 │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_8.0.0_001/4iIB4_A2Rdu8-y535yzI4Q] deleting index
[00:09:10]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/kibana] Deleted existing index ".kibana_8.0.0_001"
[00:09:10]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/kibana] Deleted existing index ".kibana_task_manager_8.0.0_001"
[00:09:10]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_001] creating index, cause [api], templates [], shards [1]/[0]
[00:09:11]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/kibana] Created index ".kibana_8.0.0_001"
[00:09:11]                 │ debg [test/functional/fixtures/es_archiver/dashboard/current/kibana] ".kibana_8.0.0_001" settings {"index":{"auto_expand_replicas":"0-1","number_of_replicas":"0","number_of_shards":"1","priority":"10","refresh_interval":"1s","routing_partition_size":"1"}}
[00:09:11]                 │ info [test/functional/fixtures/es_archiver/dashboard/current/kibana] Indexed 96 docs into ".kibana"
[00:09:11]                 │ debg Migrating saved objects
[00:09:11]                 │ proc [kibana]   log   [12:24:37.261] [info][savedobjects-service] [.kibana_task_manager] INIT -> CREATE_NEW_TARGET. took: 3ms.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.267] [info][savedobjects-service] [.kibana] INIT -> OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT. took: 12ms.
[00:09:11]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_task_manager_8.0.0_001] creating index, cause [api], templates [], shards [1]/[1]
[00:09:11]                 │ proc [kibana]   log   [12:24:37.272] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT -> OUTDATED_DOCUMENTS_SEARCH_READ. took: 5ms.
[00:09:11]                 │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_task_manager_8.0.0_001]
[00:09:11]                 │ proc [kibana]   log   [12:24:37.285] [info][savedobjects-service] [.kibana] Starting to process 33 documents.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.286] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_READ -> OUTDATED_DOCUMENTS_TRANSFORM. took: 13ms.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.300] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_TRANSFORM -> TRANSFORMED_DOCUMENTS_BULK_INDEX. took: 15ms.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.310] [info][savedobjects-service] [.kibana] TRANSFORMED_DOCUMENTS_BULK_INDEX -> OUTDATED_DOCUMENTS_SEARCH_READ. took: 10ms.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.318] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_READ -> OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT. took: 8ms.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.323] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT -> OUTDATED_DOCUMENTS_REFRESH. took: 5ms.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.337] [info][savedobjects-service] [.kibana_task_manager] CREATE_NEW_TARGET -> MARK_VERSION_INDEX_READY. took: 76ms.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.340] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_REFRESH -> UPDATE_TARGET_MAPPINGS. took: 17ms.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.365] [info][savedobjects-service] [.kibana_task_manager] MARK_VERSION_INDEX_READY -> DONE. took: 28ms.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.366] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 108ms
[00:09:11]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/1vHlBCtISea7N_9VsU3-Kw] update_mapping [_doc]
[00:09:11]                 │ proc [kibana]   log   [12:24:37.438] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS -> UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK. took: 98ms.
[00:09:11]                 │ info [o.e.t.LoggingTaskListener] [node-01] 12929 finished with response BulkByScrollResponse[took=34.2ms,timed_out=false,sliceId=null,updated=96,created=0,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:09:11]                 │ proc [kibana]   log   [12:24:37.543] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK -> DONE. took: 105ms.
[00:09:11]                 │ proc [kibana]   log   [12:24:37.543] [info][savedobjects-service] [.kibana] Migration completed after 288ms
[00:09:11]                 │ debg [test/functional/fixtures/es_archiver/dashboard/current/kibana] Migrated Kibana index after loading Kibana data
[00:09:11]                 │ debg [test/functional/fixtures/es_archiver/dashboard/current/kibana] Ensured that default space exists in .kibana
[00:09:11]                 │ debg navigating to dashboard url: http://localhost:6111/app/dashboards#/list
[00:09:11]                 │ debg navigate to: http://localhost:6111/app/dashboards#/list
[00:09:12]                 │ debg browser[DEBUG] http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 349699:16 "Reporting application usage for discover, main"
[00:09:12]                 │ debg browser[INFO] http://localhost:6111/app/dashboards?_t=1634646278159#/list 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:09:12]                 │
[00:09:12]                 │ debg browser[INFO] http://localhost:6111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:09:12]                 │ debg ... sleep(700) start
[00:09:12]                 │ debg ... sleep(700) end
[00:09:12]                 │ debg returned from get, calling refresh
[00:09:14]                 │ debg browser[INFO] http://localhost:6111/9007199254740991/bundles/core/core.entry.js 18935:18 "Plugin visTypeTable is using asynchronous setup lifecycle. Asynchronous plugins support will be removed in a later version."
[00:09:14]                 │ debg browser[INFO] http://localhost:6111/9007199254740991/bundles/core/core.entry.js 18935:18 "Plugin globalSearchBar is using asynchronous setup lifecycle. Asynchronous plugins support will be removed in a later version."
[00:09:14]                 │ERROR browser[SEVERE] http://localhost:6111/9007199254740991/bundles/core/core.entry.js 13981:18 TypeError: Failed to fetch
[00:09:14]                 │          at Fetch.fetchResponse (http://localhost:6111/9007199254740991/bundles/core/core.entry.js:14532:13)
[00:09:14]                 │          at async interceptResponse (http://localhost:6111/9007199254740991/bundles/core/core.entry.js:14916:10)
[00:09:14]                 │          at async http://localhost:6111/9007199254740991/bundles/core/core.entry.js:14455:39
[00:09:14]                 │ debg browser[INFO] http://localhost:6111/app/dashboards?_t=1634646278159#/list 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:09:14]                 │
[00:09:14]                 │ debg browser[INFO] http://localhost:6111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:09:14]                 │ debg currentUrl = http://localhost:6111/app/dashboards#/list
[00:09:14]                 │          appUrl = http://localhost:6111/app/dashboards#/list
[00:09:14]                 │ debg TestSubjects.find(kibanaChrome)
[00:09:14]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:09:16]                 │ debg browser[INFO] http://localhost:6111/9007199254740991/bundles/core/core.entry.js 18935:18 "Plugin visTypeTable is using asynchronous setup lifecycle. Asynchronous plugins support will be removed in a later version."
[00:09:16]                 │ debg browser[INFO] http://localhost:6111/9007199254740991/bundles/core/core.entry.js 18935:18 "Plugin globalSearchBar is using asynchronous setup lifecycle. Asynchronous plugins support will be removed in a later version."
[00:09:16]                 │ debg browser[DEBUG] http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 349699:16 "Reporting user-agent."
[00:09:16]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/1vHlBCtISea7N_9VsU3-Kw] update_mapping [_doc]
[00:09:16]                 │ debg ... sleep(501) start
[00:09:16]                 │ debg ... sleep(501) end
[00:09:16]                 │ debg in navigateTo url = http://localhost:6111/app/dashboards#/list?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:09:16]                 │ debg --- retry.tryForTime error: URL changed, waiting for it to settle
[00:09:17]                 │ debg ... sleep(501) start
[00:09:17]                 │ debg ... sleep(501) end
[00:09:18]                 │ debg in navigateTo url = http://localhost:6111/app/dashboards#/list?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:09:18]                 │ debg Load Saved Dashboard dashboard with filter
[00:09:18]                 │ debg gotoDashboardLandingPage
[00:09:18]                 │ debg onDashboardLandingPage
[00:09:18]                 │ debg TestSubjects.exists(dashboardLandingPage)
[00:09:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardLandingPage"]') with timeout=5000
[00:09:18]                 │ debg searchForItemWithName: dashboard with filter
[00:09:18]                 │ debg TestSubjects.find(tableListSearchBox)
[00:09:18]                 │ debg Find.findByCssSelector('[data-test-subj="tableListSearchBox"]') with timeout=10000
[00:09:19]                 │ debg isGlobalLoadingIndicatorVisible
[00:09:19]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:09:19]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:09:20]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:09:21]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:09:21]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:09:21]                 │ debg TestSubjects.click(dashboardListingTitleLink-dashboard-with-filter)
[00:09:21]                 │ debg Find.clickByCssSelector('[data-test-subj="dashboardListingTitleLink-dashboard-with-filter"]') with timeout=10000
[00:09:21]                 │ debg Find.findByCssSelector('[data-test-subj="dashboardListingTitleLink-dashboard-with-filter"]') with timeout=10000
[00:09:21]                 │ debg isGlobalLoadingIndicatorVisible
[00:09:21]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:09:21]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:09:21]                 │ debg browser[DEBUG] http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 349699:16 "Reporting application usage for dashboards, main"
[00:09:21]                 │ debg browser[INFO] http://localhost:6111/app/dashboards#/view/61c58ad0-3dd3-11e8-b2b9-5d5dc1715159?_g=(filters:!()) 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:09:21]                 │
[00:09:21]                 │ debg browser[INFO] http://localhost:6111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:09:22]                 │ debg browser[INFO] http://localhost:6111/9007199254740991/bundles/core/core.entry.js 18935:18 "Plugin visTypeTable is using asynchronous setup lifecycle. Asynchronous plugins support will be removed in a later version."
[00:09:22]                 │ debg browser[INFO] http://localhost:6111/9007199254740991/bundles/core/core.entry.js 18935:18 "Plugin globalSearchBar is using asynchronous setup lifecycle. Asynchronous plugins support will be removed in a later version."
[00:09:22]                 │ debg browser[DEBUG] http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 349699:16 "Reporting user-agent."
[00:09:22]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:09:22]                 │ debg browser[WARNING] http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 13993:20 "Deprecation warning: value provided is not in a recognized RFC2822 or ISO format. moment construction falls back to js Date(), which is not reliable across all browsers and versions. Non RFC2822/ISO date formats are discouraged and will be removed in an upcoming major release. Please refer to http://momentjs.com/guides/#/warnings/js-date/ for more info.
[00:09:22]                 │      Arguments: 
[00:09:22]                 │      [0] _isAMomentObject: true, _isUTC: false, _useUTC: false, _l: undefined, _i: Mon Apr 09 2018 17:56:08 GMT-0400, _f: undefined, _strict: undefined, _locale: [object Object]
[00:09:22]                 │      Error
[00:09:22]                 │          at Function.createFromInputFallback (http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:14030:25)
[00:09:22]                 │          at configFromString (http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:16247:19)
[00:09:22]                 │          at configFromInput (http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:16689:13)
[00:09:22]                 │          at prepareConfig (http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:16672:13)
[00:09:22]                 │          at createFromConfig (http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:16639:44)
[00:09:22]                 │          at createLocalOrUTC (http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:16733:16)
[00:09:22]                 │          at createLocal (http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:16737:16)
[00:09:22]                 │          at hooks (http://localhost:6111/9007199254740991/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:13727:29)
[00:09:22]                 │          at Object.parse (http://localhost:6111/9007199254740991/bundles/plugin/data/kibana/data.plugin.js:1040:12)
[00:09:22]                 │          at validateTimeRange (http://localhost:6111/9007199254740991/bundles/plugin/data/kibana/data.plugin.js:40392:83)"
[00:09:23]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:09:23]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:09:23]                 │ debg TestSubjects.missingOrFail(dashboardLandingPage)
[00:09:23]                 │ debg Find.waitForDeletedByCssSelector('[data-test-subj="dashboardLandingPage"]') with timeout=10000
[00:09:23]                 │ debg isGlobalLoadingIndicatorVisible
[00:09:23]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:09:23]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:09:24]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/1vHlBCtISea7N_9VsU3-Kw] update_mapping [_doc]
[00:09:25]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:09:25]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:09:25]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:09:25]               └-> on load there is a single session
[00:09:25]                 └-> "before each" hook: global before each for "on load there is a single session"
[00:09:25]                 │ debg TestSubjects.find(showSessionsButton)
[00:09:25]                 │ debg Find.findByCssSelector('[data-test-subj="showSessionsButton"]') with timeout=10000
[00:09:26]                 │ debg TestSubjects.find(globalToastList)
[00:09:26]                 │ debg Find.findByCssSelector('[data-test-subj="globalToastList"]') with timeout=10000
[00:09:26]                 └- ✓ pass  (128ms) "data plugin Session management Dashboard on load there is a single session"
[00:09:26]               └-> "after each" hook for "on load there is a single session"
[00:09:26]                 │ debg TestSubjects.click(clearSessionsButton)
[00:09:26]                 │ debg Find.clickByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:09:26]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:09:27]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:27]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:27]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:09:28]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/1vHlBCtISea7N_9VsU3-Kw] update_mapping [_doc]
[00:09:28]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:28]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:28]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:09:29]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:29]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:29]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:09:31]                 │ debg --- retry.try error: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:31]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:31]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:09:32]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:32]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:32]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:09:33]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:33]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:33]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:09:35]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:35]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:35]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:09:36]                 │ debg --- retry.try failed again with the same message...
[00:09:36]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:09:37]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:37]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:37]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:09:39]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:39]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:39]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:09:40]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:40]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:40]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:09:41]                 │ debg --- retry.try failed again with the same message...
[00:09:42]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:09:43]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:43]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:43]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:09:44]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:44]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:44]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:09:45]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:45]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:45]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:09:46]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_task_manager_8.0.0_001/Yu8IZ82STg2pjrgPaYCbjQ] update_mapping [_doc]
[00:09:47]                 │ debg --- retry.try failed again with the same message...
[00:09:47]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:09:48]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:48]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:48]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:09:50]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:50]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:50]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:09:51]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:51]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:51]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:09:52]                 │ debg --- retry.try failed again with the same message...
[00:09:53]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:09:54]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:54]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:54]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:09:55]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:55]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:55]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:09:56]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:56]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:56]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:09:57]                 │ debg --- retry.try failed again with the same message...
[00:09:58]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:09:59]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:59]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:59]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:00]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:00]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:00]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:02]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:02]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:02]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:03]                 │ debg --- retry.try failed again with the same message...
[00:10:03]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:04]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:04]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:04]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:06]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:06]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:06]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:07]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:07]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:07]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:08]                 │ debg --- retry.try failed again with the same message...
[00:10:09]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:10]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:10]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:10]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:11]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:11]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:11]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:12]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:12]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:12]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:14]                 │ debg --- retry.try failed again with the same message...
[00:10:14]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:15]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:15]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:15]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:16]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:16]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:16]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:18]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:18]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:18]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:19]                 │ debg --- retry.try failed again with the same message...
[00:10:19]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:21]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:21]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:21]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:22]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:22]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:22]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:23]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:23]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:23]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:24]                 │ debg --- retry.try failed again with the same message...
[00:10:25]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:26]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:26]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:26]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:27]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:27]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:27]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:28]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:28]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:28]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:30]                 │ debg --- retry.try failed again with the same message...
[00:10:30]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:31]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:31]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:31]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:33]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:33]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:33]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:34]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:34]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:34]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:35]                 │ debg --- retry.try failed again with the same message...
[00:10:36]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:37]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:37]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:37]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:38]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:38]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:38]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:39]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:39]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:39]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:40]                 │ debg --- retry.try failed again with the same message...
[00:10:41]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:42]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:42]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:42]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:43]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:43]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:43]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:45]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:45]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:45]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:46]                 │ debg --- retry.try failed again with the same message...
[00:10:46]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:47]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:47]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:47]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:49]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:49]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:49]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:50]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:50]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:50]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:51]                 │ debg --- retry.try failed again with the same message...
[00:10:52]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:53]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:53]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:53]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:54]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:54]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:54]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:10:55]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:55]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:55]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:10:57]                 │ debg --- retry.try failed again with the same message...
[00:10:57]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:10:58]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:58]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:58]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:10:59]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:10:59]                 │        (Session info: headless chrome=94.0.4606.81)
[00:10:59]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:11:01]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:01]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:01]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:11:02]                 │ debg --- retry.try failed again with the same message...
[00:11:03]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:11:04]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:04]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:04]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:11:05]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:05]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:05]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:11:06]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:06]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:06]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:11:07]                 │ debg --- retry.try failed again with the same message...
[00:11:08]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:11:09]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:09]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:09]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:11:10]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:10]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:10]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:11:11]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:11]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:11]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:11:13]                 │ debg --- retry.try failed again with the same message...
[00:11:13]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:11:14]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:14]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:14]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:11:16]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:16]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:16]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:11:17]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:17]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:17]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:11:18]                 │ debg --- retry.try failed again with the same message...
[00:11:19]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:11:20]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:20]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:20]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:11:21]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:21]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:21]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:11:22]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:22]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:22]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:11:23]                 │ debg --- retry.try failed again with the same message...
[00:11:24]                 │ debg Find.findByCssSelector('[data-test-subj="clearSessionsButton"]') with timeout=10000
[00:11:25]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:25]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:25]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 2 attempts left
[00:11:26]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:26]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:26]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 1 attempts left
[00:11:28]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:28]                 │        (Session info: headless chrome=94.0.4606.81)
[00:11:28]                 │ debg finding element 'By(css selector, [data-test-subj="clearSessionsButton"])' again, 0 attempts left
[00:11:29]                 │ debg --- retry.try failed again with the same message...
[00:11:29]                 │ info Taking screenshot "/dev/shm/workspace/parallel/1/kibana/test/functional/screenshots/failure/data plugin Session management Dashboard _after each_ hook for _on load there is a single session_.png"
[00:11:30]                 │ info Current URL is: http://localhost:6111/app/dashboards#/view/61c58ad0-3dd3-11e8-b2b9-5d5dc1715159?_g=(filters:!())
[00:11:30]                 │ info Saving page source to: /dev/shm/workspace/parallel/1/kibana/test/plugin_functional/failure_debug/html/data plugin Session management Dashboard _after each_ hook for _on load there is a single session_.html
[00:11:30]                 └- ✖ fail: data plugin Session management Dashboard "after each" hook for "on load there is a single session"
[00:11:30]                 │      retry.try timeout: ElementClickInterceptedError: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:30]                 │   (Session info: headless chrome=94.0.4606.81)
[00:11:30]                 │     at Object.throwDecodedError (/dev/shm/workspace/parallel/1/kibana/node_modules/selenium-webdriver/lib/error.js:550:15)
[00:11:30]                 │     at parseHttpResponse (/dev/shm/workspace/parallel/1/kibana/node_modules/selenium-webdriver/lib/http.js:565:13)
[00:11:30]                 │     at Executor.execute (/dev/shm/workspace/parallel/1/kibana/node_modules/selenium-webdriver/lib/http.js:491:26)
[00:11:30]                 │     at runMicrotasks (<anonymous>)
[00:11:30]                 │     at processTicksAndRejections (internal/process/task_queues.js:95:5)
[00:11:30]                 │     at Task.exec (/dev/shm/workspace/parallel/1/kibana/test/functional/services/remote/prevent_parallel_calls.ts:28:20)
[00:11:30]                 │   Error: retry.try timeout: ElementClickInterceptedError: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:11:30]                 │     (Session info: headless chrome=94.0.4606.81)
[00:11:30]                 │       at Object.throwDecodedError (node_modules/selenium-webdriver/lib/error.js:550:15)
[00:11:30]                 │       at parseHttpResponse (node_modules/selenium-webdriver/lib/http.js:565:13)
[00:11:30]                 │       at Executor.execute (node_modules/selenium-webdriver/lib/http.js:491:26)
[00:11:30]                 │       at runMicrotasks (<anonymous>)
[00:11:30]                 │       at processTicksAndRejections (internal/process/task_queues.js:95:5)
[00:11:30]                 │       at Task.exec (test/functional/services/remote/prevent_parallel_calls.ts:28:20)
[00:11:30]                 │       at onFailure (test/common/services/retry/retry_for_success.ts:17:9)
[00:11:30]                 │       at retryForSuccess (test/common/services/retry/retry_for_success.ts:57:13)
[00:11:30]                 │       at RetryService.try (test/common/services/retry/retry.ts:31:12)
[00:11:30]                 │       at Proxy.clickByCssSelector (test/functional/services/common/find.ts:360:5)
[00:11:30]                 │       at TestSubjects.click (test/functional/services/common/test_subjects.ts:105:5)
[00:11:30]                 │       at Context.<anonymous> (test/plugin_functional/test_suites/data_plugin/session.ts:85:9)
[00:11:30]                 │       at Object.apply (node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)
[00:11:30]                 │ 
[00:11:30]                 │ 

Stack Trace

Error: retry.try timeout: ElementClickInterceptedError: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="clearSessionsButton">...</button> is not clickable at point (1380, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
  (Session info: headless chrome=94.0.4606.81)
    at Object.throwDecodedError (node_modules/selenium-webdriver/lib/error.js:550:15)
    at parseHttpResponse (node_modules/selenium-webdriver/lib/http.js:565:13)
    at Executor.execute (node_modules/selenium-webdriver/lib/http.js:491:26)
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:95:5)
    at Task.exec (test/functional/services/remote/prevent_parallel_calls.ts:28:20)
    at onFailure (test/common/services/retry/retry_for_success.ts:17:9)
    at retryForSuccess (test/common/services/retry/retry_for_success.ts:57:13)
    at RetryService.try (test/common/services/retry/retry.ts:31:12)
    at Proxy.clickByCssSelector (test/functional/services/common/find.ts:360:5)
    at TestSubjects.click (test/functional/services/common/test_subjects.ts:105:5)
    at Context.<anonymous> (test/plugin_functional/test_suites/data_plugin/session.ts:85:9)
    at Object.apply (node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)

Kibana Pipeline / general / Chrome UI Functional Tests.test/functional/apps/visualize/_heatmap_chart·ts.visualize app visualize ciGroup10 heatmap chart should show 6 color ranges if changed on options

Link to Jenkins

Standard Out

Failed Tests Reporter:
  - Test has not failed recently on tracked branches

[00:00:00]       │
[00:00:00]         └-: visualize app
[00:00:00]           └-> "before all" hook in "visualize app"
[00:00:00]           └-> "before all" hook in "visualize app"
[00:00:00]             │ debg Starting visualize before method
[00:00:00]             │ info [test/functional/fixtures/es_archiver/empty_kibana] Loading "mappings.json"
[00:00:00]             │ info [test/functional/fixtures/es_archiver/empty_kibana] Loading "data.json.gz"
[00:00:00]             │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_task_manager_8.0.0_001/3vRBdDKITeeX_TjuYzCUGA] deleting index
[00:00:00]             │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_8.0.0_001/aF2VYuqaQ_O_yXfsHrTt_w] deleting index
[00:00:00]             │ info [test/functional/fixtures/es_archiver/empty_kibana] Deleted existing index ".kibana_8.0.0_001"
[00:00:00]             │ info [test/functional/fixtures/es_archiver/empty_kibana] Deleted existing index ".kibana_task_manager_8.0.0_001"
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1]
[00:00:00]             │ info [test/functional/fixtures/es_archiver/empty_kibana] Created index ".kibana_1"
[00:00:00]             │ debg [test/functional/fixtures/es_archiver/empty_kibana] ".kibana_1" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:00:00]             │ info [test/functional/fixtures/es_archiver/empty_kibana] Indexed 1 docs into ".kibana"
[00:00:00]             │ debg Migrating saved objects
[00:00:00]             │ proc [kibana]   log   [12:29:49.577] [info][savedobjects-service] [.kibana_task_manager] INIT -> CREATE_NEW_TARGET. took: 3ms.
[00:00:00]             │ proc [kibana]   log   [12:29:49.581] [info][savedobjects-service] [.kibana] INIT -> WAIT_FOR_YELLOW_SOURCE. took: 9ms.
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_task_manager_8.0.0_001] creating index, cause [api], templates [], shards [1]/[1]
[00:00:00]             │ proc [kibana]   log   [12:29:49.587] [info][savedobjects-service] [.kibana] WAIT_FOR_YELLOW_SOURCE -> CHECK_UNKNOWN_DOCUMENTS. took: 6ms.
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_task_manager_8.0.0_001]
[00:00:00]             │ proc [kibana]   log   [12:29:49.599] [info][savedobjects-service] [.kibana] CHECK_UNKNOWN_DOCUMENTS -> SET_SOURCE_WRITE_BLOCK. took: 12ms.
[00:00:00]             │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_1/HsleprXvSAC6outjXJQDWw]]
[00:00:00]             │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_1]
[00:00:00]             │ proc [kibana]   log   [12:29:49.680] [info][savedobjects-service] [.kibana_task_manager] CREATE_NEW_TARGET -> MARK_VERSION_INDEX_READY. took: 103ms.
[00:00:00]             │ proc [kibana]   log   [12:29:49.703] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CALCULATE_EXCLUDE_FILTERS. took: 104ms.
[00:00:00]             │ proc [kibana]   log   [12:29:49.711] [info][savedobjects-service] [.kibana] CALCULATE_EXCLUDE_FILTERS -> CREATE_REINDEX_TEMP. took: 8ms.
[00:00:00]             │ proc [kibana]   log   [12:29:49.725] [info][savedobjects-service] [.kibana_task_manager] MARK_VERSION_INDEX_READY -> DONE. took: 45ms.
[00:00:00]             │ proc [kibana]   log   [12:29:49.726] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 152ms
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_reindex_temp] creating index, cause [api], templates [], shards [1]/[1]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_8.0.0_reindex_temp]
[00:00:00]             │ proc [kibana]   log   [12:29:49.786] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP_OPEN_PIT. took: 75ms.
[00:00:00]             │ proc [kibana]   log   [12:29:49.798] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_OPEN_PIT -> REINDEX_SOURCE_TO_TEMP_READ. took: 12ms.
[00:00:00]             │ proc [kibana]   log   [12:29:49.813] [info][savedobjects-service] [.kibana] Starting to process 1 documents.
[00:00:00]             │ proc [kibana]   log   [12:29:49.813] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_INDEX. took: 15ms.
[00:00:00]             │ proc [kibana]   log   [12:29:49.816] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX -> REINDEX_SOURCE_TO_TEMP_INDEX_BULK. took: 3ms.
[00:00:00]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_reindex_temp/B25gLD9lRlGPRq-gHUFHrA] update_mapping [_doc]
[00:00:00]             │ proc [kibana]   log   [12:29:49.858] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX_BULK -> REINDEX_SOURCE_TO_TEMP_READ. took: 42ms.
[00:00:00]             │ proc [kibana]   log   [12:29:49.868] [info][savedobjects-service] [.kibana] Processed 1 documents out of 1.
[00:00:00]             │ proc [kibana]   log   [12:29:49.869] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_CLOSE_PIT. took: 10ms.
[00:00:00]             │ proc [kibana]   log   [12:29:49.876] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_CLOSE_PIT -> SET_TEMP_WRITE_BLOCK. took: 8ms.
[00:00:00]             │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_8.0.0_reindex_temp/B25gLD9lRlGPRq-gHUFHrA]]
[00:00:00]             │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_8.0.0_reindex_temp]
[00:00:00]             │ proc [kibana]   log   [12:29:49.923] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET. took: 47ms.
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] applying create index request using existing index [.kibana_8.0.0_reindex_temp] metadata
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_001] creating index, cause [clone_index], templates [], shards [1]/[1]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_8.0.0_001]
[00:00:00]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/xA5RgflVR0aL_yJruCZHNQ] create_mapping
[00:00:00]             │ proc [kibana]   log   [12:29:50.057] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> REFRESH_TARGET. took: 134ms.
[00:00:00]             │ proc [kibana]   log   [12:29:50.063] [info][savedobjects-service] [.kibana] REFRESH_TARGET -> OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT. took: 6ms.
[00:00:00]             │ proc [kibana]   log   [12:29:50.067] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT -> OUTDATED_DOCUMENTS_SEARCH_READ. took: 4ms.
[00:00:00]             │ proc [kibana]   log   [12:29:50.077] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_READ -> OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT. took: 10ms.
[00:00:00]             │ proc [kibana]   log   [12:29:50.081] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT -> UPDATE_TARGET_MAPPINGS. took: 4ms.
[00:00:00]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/xA5RgflVR0aL_yJruCZHNQ] update_mapping [_doc]
[00:00:01]             │ proc [kibana]   log   [12:29:50.165] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS -> UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK. took: 84ms.
[00:00:01]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.tasks] creating index, cause [auto(bulk api)], templates [], shards [1]/[1]
[00:00:01]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.tasks]
[00:00:01]             │ info [o.e.t.LoggingTaskListener] [node-01] 845 finished with response BulkByScrollResponse[took=28ms,timed_out=false,sliceId=null,updated=1,created=0,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:00:01]             │ proc [kibana]   log   [12:29:50.282] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK -> MARK_VERSION_INDEX_READY. took: 117ms.
[00:00:01]             │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_8.0.0_reindex_temp/B25gLD9lRlGPRq-gHUFHrA] deleting index
[00:00:01]             │ proc [kibana]   log   [12:29:50.324] [info][savedobjects-service] [.kibana] MARK_VERSION_INDEX_READY -> DONE. took: 42ms.
[00:00:01]             │ proc [kibana]   log   [12:29:50.324] [info][savedobjects-service] [.kibana] Migration completed after 752ms
[00:00:01]             │ debg [test/functional/fixtures/es_archiver/empty_kibana] Migrated Kibana index after loading Kibana data
[00:00:01]             │ debg [test/functional/fixtures/es_archiver/empty_kibana] Ensured that default space exists in .kibana
[00:00:01]             │ debg applying update to kibana config: {"accessibility:disableAnimations":true,"dateFormat:tz":"UTC","visualization:visualize:legacyChartsLibrary":true,"visualization:visualize:legacyPieChartsLibrary":true}
[00:00:03]             │ info [test/functional/fixtures/es_archiver/logstash_functional] Loading "mappings.json"
[00:00:03]             │ info [test/functional/fixtures/es_archiver/logstash_functional] Loading "data.json.gz"
[00:00:03]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0]
[00:00:03]             │ info [test/functional/fixtures/es_archiver/logstash_functional] Created index "logstash-2015.09.22"
[00:00:03]             │ debg [test/functional/fixtures/es_archiver/logstash_functional] "logstash-2015.09.22" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:03]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0]
[00:00:03]             │ info [test/functional/fixtures/es_archiver/logstash_functional] Created index "logstash-2015.09.20"
[00:00:03]             │ debg [test/functional/fixtures/es_archiver/logstash_functional] "logstash-2015.09.20" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:04]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0]
[00:00:04]             │ info [test/functional/fixtures/es_archiver/logstash_functional] Created index "logstash-2015.09.21"
[00:00:04]             │ debg [test/functional/fixtures/es_archiver/logstash_functional] "logstash-2015.09.21" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:13]             │ info progress: 11721
[00:00:15]             │ info [test/functional/fixtures/es_archiver/logstash_functional] Indexed 4633 docs into "logstash-2015.09.22"
[00:00:15]             │ info [test/functional/fixtures/es_archiver/logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:00:15]             │ info [test/functional/fixtures/es_archiver/logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:00:15]             │ info [test/functional/fixtures/es_archiver/long_window_logstash] Loading "mappings.json"
[00:00:15]             │ info [test/functional/fixtures/es_archiver/long_window_logstash] Loading "data.json.gz"
[00:00:15]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [long-window-logstash-0] creating index, cause [api], templates [], shards [1]/[0]
[00:00:15]             │ info [test/functional/fixtures/es_archiver/long_window_logstash] Created index "long-window-logstash-0"
[00:00:15]             │ debg [test/functional/fixtures/es_archiver/long_window_logstash] "long-window-logstash-0" settings {"index":{"analysis":{"analyzer":{"makelogs_url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:25]             │ info progress: 12040
[00:00:27]             │ info [test/functional/fixtures/es_archiver/long_window_logstash] Indexed 14005 docs into "long-window-logstash-0"
[00:00:27]           └-: visualize ciGroup10
[00:00:27]             └-> "before all" hook in "visualize ciGroup10"
[00:07:34]             └-: heatmap chart
[00:07:34]               └-> "before all" hook for "should save and load"
[00:07:34]               └-> "before all" hook for "should save and load"
[00:07:34]                 │ debg Cleaning all saved objects { space: undefined }
[00:07:34]                 │ info deleting batch of 8 objects
[00:07:34]                 │ succ deleted 8 objects
[00:07:34]                 │ debg resolved import for test/functional/fixtures/kbn_archiver/visualize.json to /dev/shm/workspace/parallel/10/kibana/test/functional/fixtures/kbn_archiver/visualize.json
[00:07:34]                 │ info importing 13 saved objects { space: undefined }
[00:07:35]                 │ succ import success
[00:07:35]                 │ debg replacing kibana config doc: {"defaultIndex":"logstash-*","format:bytes:defaultPattern":"0,0.[000]b","visualization:visualize:legacyChartsLibrary":true,"visualization:visualize:legacyPieChartsLibrary":true}
[00:07:36]                 │ debg navigateToApp visualize
[00:07:36]                 │ debg navigating to visualize url: http://localhost:61101/app/visualize#/
[00:07:36]                 │ debg navigate to: http://localhost:61101/app/visualize#/
[00:07:36]                 │ debg browser[INFO] http://localhost:61101/app/visualize?_t=1634647046009#/ 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:07:36]                 │
[00:07:36]                 │ debg browser[INFO] http://localhost:61101/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:07:36]                 │ debg ... sleep(700) start
[00:07:37]                 │ debg ... sleep(700) end
[00:07:37]                 │ debg returned from get, calling refresh
[00:07:38]                 │ debg browser[INFO] http://localhost:61101/app/visualize?_t=1634647046009#/ 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:07:38]                 │
[00:07:38]                 │ debg browser[INFO] http://localhost:61101/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:07:38]                 │ debg currentUrl = http://localhost:61101/app/visualize#/
[00:07:38]                 │          appUrl = http://localhost:61101/app/visualize#/
[00:07:38]                 │ debg TestSubjects.find(kibanaChrome)
[00:07:38]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:07:39]                 │ debg ... sleep(501) start
[00:07:39]                 │ debg ... sleep(501) end
[00:07:39]                 │ debg in navigateTo url = http://localhost:61101/app/visualize#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:07:39]                 │ debg --- retry.tryForTime error: URL changed, waiting for it to settle
[00:07:40]                 │ debg ... sleep(501) start
[00:07:40]                 │ debg ... sleep(501) end
[00:07:40]                 │ debg in navigateTo url = http://localhost:61101/app/visualize#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:07:40]                 │ debg isGlobalLoadingIndicatorVisible
[00:07:40]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:07:40]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:07:42]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:07:42]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:07:42]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:07:42]                 │ debg TestSubjects.exists(newItemButton)
[00:07:42]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:07:42]                 │ debg TestSubjects.click(newItemButton)
[00:07:42]                 │ debg Find.clickByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:07:42]                 │ debg Find.findByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:07:42]                 │ debg TestSubjects.click(visGroupAggBasedExploreLink)
[00:07:42]                 │ debg Find.clickByCssSelector('[data-test-subj="visGroupAggBasedExploreLink"]') with timeout=10000
[00:07:42]                 │ debg Find.findByCssSelector('[data-test-subj="visGroupAggBasedExploreLink"]') with timeout=10000
[00:07:42]                 │ debg TestSubjects.find(visNewDialogTypes)
[00:07:42]                 │ debg Find.findByCssSelector('[data-test-subj="visNewDialogTypes"]') with timeout=10000
[00:07:42]                 │ debg clickHeatmapChart
[00:07:42]                 │ debg TestSubjects.click(visType-heatmap)
[00:07:42]                 │ debg Find.clickByCssSelector('[data-test-subj="visType-heatmap"]') with timeout=10000
[00:07:42]                 │ debg Find.findByCssSelector('[data-test-subj="visType-heatmap"]') with timeout=10000
[00:07:42]                 │ debg isGlobalLoadingIndicatorVisible
[00:07:42]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:07:42]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:07:44]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:07:45]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:07:45]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:07:45]                 │ debg TestSubjects.click(savedObjectTitlelogstash-*)
[00:07:45]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitlelogstash-*"]') with timeout=10000
[00:07:45]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitlelogstash-*"]') with timeout=10000
[00:07:45]                 │ debg isGlobalLoadingIndicatorVisible
[00:07:45]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:07:45]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:07:45]                 │ debg browser[INFO] http://localhost:61101/app/visualize#/create?type=heatmap&indexPattern=logstash-* 281 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:07:45]                 │
[00:07:45]                 │ debg browser[INFO] http://localhost:61101/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:07:46]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:07:47]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:07:47]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:07:47]                 │ debg Setting absolute range to Sep 19, 2015 @ 06:31:44.000 to Sep 23, 2015 @ 18:31:44.000
[00:07:47]                 │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:07:47]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:07:47]                 │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:07:47]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:07:47]                 │ debg TestSubjects.click(superDatePickerShowDatesButton)
[00:07:47]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:07:47]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:07:47]                 │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:07:47]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:07:47]                 │ debg TestSubjects.click(superDatePickerendDatePopoverButton)
[00:07:47]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:07:47]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:07:47]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:07:47]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:07:47]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:07:47]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:07:47]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:07:47]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:07:47]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:07:48]                 │ warn WebElementWrapper.click: element click intercepted: Element <input type="text" id="i57198711-30d9-11ec-a2cb-39ac89d59809" class="euiFieldText euiFieldText--compressed euiFieldText--inGroup" data-test-subj="superDatePickerAbsoluteDateInput" value="Oct 19, 2021 @ 12:37:36.865"> is not clickable at point (980, 492). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="0">...</div>
[00:07:48]                 │        (Session info: headless chrome=94.0.4606.81)
[00:07:48]                 │ debg finding element 'By(css selector, [data-test-subj="superDatePickerAbsoluteDateInput"])' again, 2 attempts left
[00:07:50]                 │ warn WebElementWrapper.click: element click intercepted: Element <input type="text" id="i57198711-30d9-11ec-a2cb-39ac89d59809" class="euiFieldText euiFieldText--compressed euiFieldText--inGroup" data-test-subj="superDatePickerAbsoluteDateInput" value="Oct 19, 2021 @ 12:37:36.865"> is not clickable at point (980, 492). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="0">...</div>
[00:07:50]                 │        (Session info: headless chrome=94.0.4606.81)
[00:07:50]                 │ debg finding element 'By(css selector, [data-test-subj="superDatePickerAbsoluteDateInput"])' again, 1 attempts left
[00:07:51]                 │ warn WebElementWrapper.click: element click intercepted: Element <input type="text" id="i57198711-30d9-11ec-a2cb-39ac89d59809" class="euiFieldText euiFieldText--compressed euiFieldText--inGroup" data-test-subj="superDatePickerAbsoluteDateInput" value="Oct 19, 2021 @ 12:37:36.865"> is not clickable at point (980, 492). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="0">...</div>
[00:07:51]                 │        (Session info: headless chrome=94.0.4606.81)
[00:07:51]                 │ debg finding element 'By(css selector, [data-test-subj="superDatePickerAbsoluteDateInput"])' again, 0 attempts left
[00:07:52]                 │ debg --- retry.try error: element click intercepted: Element <input type="text" id="i57198711-30d9-11ec-a2cb-39ac89d59809" class="euiFieldText euiFieldText--compressed euiFieldText--inGroup" data-test-subj="superDatePickerAbsoluteDateInput" value="Oct 19, 2021 @ 12:37:36.865"> is not clickable at point (980, 492). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="0">...</div>
[00:07:52]                 │        (Session info: headless chrome=94.0.4606.81)
[00:07:53]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:07:54]                 │ warn WebElementWrapper.click: element click intercepted: Element <input type="text" id="i57198711-30d9-11ec-a2cb-39ac89d59809" class="euiFieldText euiFieldText--compressed euiFieldText--inGroup" data-test-subj="superDatePickerAbsoluteDateInput" value="Oct 19, 2021 @ 12:37:36.865"> is not clickable at point (980, 492). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="0">...</div>
[00:07:54]                 │        (Session info: headless chrome=94.0.4606.81)
[00:07:54]                 │ debg finding element 'By(css selector, [data-test-subj="superDatePickerAbsoluteDateInput"])' again, 2 attempts left
[00:07:55]                 │ warn WebElementWrapper.click: element click intercepted: Element <input type="text" id="i57198711-30d9-11ec-a2cb-39ac89d59809" class="euiFieldText euiFieldText--compressed euiFieldText--inGroup" data-test-subj="superDatePickerAbsoluteDateInput" value="Oct 19, 2021 @ 12:37:36.865"> is not clickable at point (980, 492). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="0">...</div>
[00:07:55]                 │        (Session info: headless chrome=94.0.4606.81)
[00:07:55]                 │ debg finding element 'By(css selector, [data-test-subj="superDatePickerAbsoluteDateInput"])' again, 1 attempts left
[00:07:56]                 │ warn WebElementWrapper.click: element click intercepted: Element <input type="text" id="i57198711-30d9-11ec-a2cb-39ac89d59809" class="euiFieldText euiFieldText--compressed euiFieldText--inGroup" data-test-subj="superDatePickerAbsoluteDateInput" value="Oct 19, 2021 @ 12:37:36.865"> is not clickable at point (980, 492). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="0">...</div>
[00:07:56]                 │        (Session info: headless chrome=94.0.4606.81)
[00:07:56]                 │ debg finding element 'By(css selector, [data-test-subj="superDatePickerAbsoluteDateInput"])' again, 0 attempts left
[00:07:57]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 23, 2015 @ 18:31:44.000)
[00:07:57]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:07:57]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:07:57]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:07:57]                 │ debg TestSubjects.click(superDatePickerstartDatePopoverButton)
[00:07:57]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:07:57]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:07:57]                 │ debg Find.waitForElementStale with timeout=10000
[00:07:57]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:07:57]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:07:57]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:07:57]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:07:58]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:07:58]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:07:58]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:07:58]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 19, 2015 @ 06:31:44.000)
[00:07:58]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:07:58]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:07:58]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:07:58]                 │ debg Waiting up to 20000ms for Timepicker popover to close...
[00:07:58]                 │ debg TestSubjects.exists(superDatePickerAbsoluteDateInput)
[00:07:58]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=2500
[00:07:58]                 │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerAbsoluteDateInput"] is not displayed
[00:08:01]                 │ debg --- retry.tryForTime failed again with the same message...
[00:08:02]                 │ debg TestSubjects.exists(superDatePickerApplyTimeButton)
[00:08:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerApplyTimeButton"]') with timeout=2500
[00:08:04]                 │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerApplyTimeButton"] is not displayed
[00:08:05]                 │ debg TestSubjects.click(querySubmitButton)
[00:08:05]                 │ debg Find.clickByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:08:05]                 │ debg Find.findByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:08:05]                 │ debg Find.waitForElementStale with timeout=10000
[00:08:05]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:05]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:05]                 │ debg Bucket = X-Axis
[00:08:05]                 │ debg TestSubjects.click(visEditorAdd_buckets)
[00:08:05]                 │ debg Find.clickByCssSelector('[data-test-subj="visEditorAdd_buckets"]') with timeout=10000
[00:08:05]                 │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_buckets"]') with timeout=10000
[00:08:06]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small" type="button" data-test-subj="visEditorAdd_buckets" aria-label="Add bucket">...</button> is not clickable at point (1092, 444). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:08:06]                 │        (Session info: headless chrome=94.0.4606.81)
[00:08:06]                 │ debg finding element 'By(css selector, [data-test-subj="visEditorAdd_buckets"])' again, 2 attempts left
[00:08:07]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small" type="button" data-test-subj="visEditorAdd_buckets" aria-label="Add bucket">...</button> is not clickable at point (1092, 444). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:08:07]                 │        (Session info: headless chrome=94.0.4606.81)
[00:08:07]                 │ debg finding element 'By(css selector, [data-test-subj="visEditorAdd_buckets"])' again, 1 attempts left
[00:08:08]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small" type="button" data-test-subj="visEditorAdd_buckets" aria-label="Add bucket">...</button> is not clickable at point (1092, 444). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:08:08]                 │        (Session info: headless chrome=94.0.4606.81)
[00:08:08]                 │ debg finding element 'By(css selector, [data-test-subj="visEditorAdd_buckets"])' again, 0 attempts left
[00:08:10]                 │ debg --- retry.try error: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small" type="button" data-test-subj="visEditorAdd_buckets" aria-label="Add bucket">...</button> is not clickable at point (1092, 444). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:08:10]                 │        (Session info: headless chrome=94.0.4606.81)
[00:08:10]                 │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_buckets"]') with timeout=10000
[00:08:11]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small" type="button" data-test-subj="visEditorAdd_buckets" aria-label="Add bucket">...</button> is not clickable at point (1092, 444). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:08:11]                 │        (Session info: headless chrome=94.0.4606.81)
[00:08:11]                 │ debg finding element 'By(css selector, [data-test-subj="visEditorAdd_buckets"])' again, 2 attempts left
[00:08:13]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small" type="button" data-test-subj="visEditorAdd_buckets" aria-label="Add bucket">...</button> is not clickable at point (1092, 444). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:08:13]                 │        (Session info: headless chrome=94.0.4606.81)
[00:08:13]                 │ debg finding element 'By(css selector, [data-test-subj="visEditorAdd_buckets"])' again, 1 attempts left
[00:08:14]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small" type="button" data-test-subj="visEditorAdd_buckets" aria-label="Add bucket">...</button> is not clickable at point (1092, 444). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:08:14]                 │        (Session info: headless chrome=94.0.4606.81)
[00:08:14]                 │ debg finding element 'By(css selector, [data-test-subj="visEditorAdd_buckets"])' again, 0 attempts left
[00:08:15]                 │ debg TestSubjects.click(visEditorAdd_buckets_X-axis)
[00:08:15]                 │ debg Find.clickByCssSelector('[data-test-subj="visEditorAdd_buckets_X-axis"]') with timeout=10000
[00:08:15]                 │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_buckets_X-axis"]') with timeout=10000
[00:08:15]                 │ debg Aggregation = Date Histogram
[00:08:15]                 │ debg Find.findByCssSelector('
[00:08:15]                 │              [data-test-subj="bucketsAggGroup"]
[00:08:15]                 │              [data-test-subj^="visEditorAggAccordion"].euiAccordion-isOpen
[00:08:15]                 │              
[00:08:15]                 │              [data-test-subj="defaultEditorAggSelect"]
[00:08:15]                 │            ') with timeout=10000
[00:08:15]                 │ debg comboBox.setElement, value: Date Histogram
[00:08:15]                 │ debg comboBox.isOptionSelected, value: Date Histogram
[00:08:18]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:08:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:08:18]                 │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="Date Histogram"]') with timeout=2500
[00:08:18]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:08:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:08:21]                 │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:08:21]                 │ debg ... sleep(500) start
[00:08:22]                 │ debg ... sleep(500) end
[00:08:22]                 │ debg Field = @timestamp
[00:08:22]                 │ debg selectField @timestamp
[00:08:22]                 │ debg Find.findByCssSelector('
[00:08:22]                 │              [data-test-subj="bucketsAggGroup"]
[00:08:22]                 │              [data-test-subj^="visEditorAggAccordion"].euiAccordion-isOpen
[00:08:22]                 │              [data-test-subj="visAggEditorParams"]
[00:08:22]                 │              
[00:08:22]                 │              [data-test-subj="visDefaultEditorField"]
[00:08:22]                 │            ') with timeout=10000
[00:08:22]                 │ debg comboBox.setElement, value: @timestamp
[00:08:22]                 │ debg comboBox.isOptionSelected, value: @timestamp
[00:08:22]                 │ debg -- isNewChartsLibraryEnabled = false
[00:08:22]                 │ debg TestSubjects.find(visualizationLoader)
[00:08:22]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:08:22]                 │ debg Before Rendering count 6
[00:08:22]                 │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:08:22]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:08:22]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:08:22]                 │ debg Waiting up to 20000ms for rendering count to be greater than or equal to [7]...
[00:08:22]                 │ debg TestSubjects.find(visualizationLoader)
[00:08:22]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:08:22]                 │ debg -- currentRenderingCount=6
[00:08:22]                 │ debg -- expectedCount=7
[00:08:23]                 │ debg TestSubjects.find(visualizationLoader)
[00:08:23]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:08:23]                 │ debg -- currentRenderingCount=10
[00:08:23]                 │ debg -- expectedCount=7
[00:08:23]               └-> should save and load
[00:08:23]                 └-> "before each" hook: global before each for "should save and load"
[00:08:23]                 │ debg ensureSavePanelOpen
[00:08:23]                 │ debg isGlobalLoadingIndicatorVisible
[00:08:23]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:23]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:24]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:08:25]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:25]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:25]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:08:25]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=5000
[00:08:27]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:08:30]                 │ debg --- retry.tryForTime failed again with the same message...
[00:08:31]                 │ debg TestSubjects.click(visualizeSaveButton)
[00:08:31]                 │ debg Find.clickByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:08:31]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:08:31]                 │ debg TestSubjects.setValue(savedObjectTitle, Visualization HeatmapChart)
[00:08:31]                 │ debg TestSubjects.click(savedObjectTitle)
[00:08:31]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:08:31]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:08:31]                 │ debg TestSubjects.exists(saveAsNewCheckbox)
[00:08:31]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="saveAsNewCheckbox"]') with timeout=2500
[00:08:34]                 │ debg --- retry.tryForTime error: [data-test-subj="saveAsNewCheckbox"] is not displayed
[00:08:34]                 │ debg TestSubjects.exists(returnToOriginModeSwitch)
[00:08:34]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="returnToOriginModeSwitch"]') with timeout=2500
[00:08:37]                 │ debg --- retry.tryForTime error: [data-test-subj="returnToOriginModeSwitch"] is not displayed
[00:08:37]                 │ debg TestSubjects.exists(add-to-dashboard-options)
[00:08:37]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="add-to-dashboard-options"]') with timeout=2500
[00:08:37]                 │ debg save modal dashboard selector, choosing option: add-to-library-option
[00:08:37]                 │ debg TestSubjects.find(add-to-dashboard-options)
[00:08:37]                 │ debg Find.findByCssSelector('[data-test-subj="add-to-dashboard-options"]') with timeout=10000
[00:08:37]                 │ debg Click Save Visualization button
[00:08:37]                 │ debg TestSubjects.click(confirmSaveSavedObjectButton)
[00:08:37]                 │ debg Find.clickByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:08:37]                 │ debg Find.findByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:08:39]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButton euiButton--primary euiButton--fill" type="submit" data-test-subj="confirmSaveSavedObjectButton">...</button> is not clickable at point (908, 562). Other element would receive the click: <div aria-live="polite" role="region" class="euiGlobalToastList euiGlobalToastList--right" aria-label="Notification message list" data-test-subj="globalToastList">...</div>
[00:08:39]                 │        (Session info: headless chrome=94.0.4606.81)
[00:08:39]                 │ debg finding element 'By(css selector, [data-test-subj="confirmSaveSavedObjectButton"])' again, 2 attempts left
[00:08:40]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButton euiButton--primary euiButton--fill" type="submit" data-test-subj="confirmSaveSavedObjectButton">...</button> is not clickable at point (908, 562). Other element would receive the click: <div aria-live="polite" role="region" class="euiGlobalToastList euiGlobalToastList--right" aria-label="Notification message list" data-test-subj="globalToastList">...</div>
[00:08:40]                 │        (Session info: headless chrome=94.0.4606.81)
[00:08:40]                 │ debg finding element 'By(css selector, [data-test-subj="confirmSaveSavedObjectButton"])' again, 1 attempts left
[00:08:41]                 │ debg TestSubjects.exists(saveVisualizationSuccess)
[00:08:41]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="saveVisualizationSuccess"]') with timeout=120000
[00:08:42]                 │ debg Find.findByCssSelector('.euiToast') with timeout=60000
[00:08:42]                 │ debg Find.findByCssSelector('.euiToastHeader__title') with timeout=10000
[00:08:42]                 │ debg Find.clickByCssSelector('.euiToast__closeButton') with timeout=10000
[00:08:42]                 │ debg Find.findByCssSelector('.euiToast__closeButton') with timeout=10000
[00:08:42]                 │ debg isGlobalLoadingIndicatorVisible
[00:08:42]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:42]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:44]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:08:44]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:44]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:44]                 │ debg Waiting for save modal to close
[00:08:44]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:08:44]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=2500
[00:08:47]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:08:47]                 │ debg Waiting up to 20000ms for last breadcrumb to have new vis name...
[00:08:47]                 │ debg TestSubjects.getVisibleText(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:08:47]                 │ debg TestSubjects.find(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:08:47]                 │ debg Find.findByCssSelector('[data-test-subj="headerGlobalNav"] [data-test-subj="breadcrumbs"] [data-test-subj~="breadcrumb"][data-test-subj~="last"]') with timeout=10000
[00:08:47]                 │ debg Find.clickByCssSelector('[href="#/"]') with timeout=10000
[00:08:47]                 │ debg Find.findByCssSelector('[href="#/"]') with timeout=10000
[00:08:47]                 │ debg searchForItemWithName: Visualization HeatmapChart
[00:08:47]                 │ debg TestSubjects.find(tableListSearchBox)
[00:08:47]                 │ debg Find.findByCssSelector('[data-test-subj="tableListSearchBox"]') with timeout=10000
[00:08:48]                 │ debg isGlobalLoadingIndicatorVisible
[00:08:48]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:48]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:50]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:08:50]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:50]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:50]                 │ debg TestSubjects.click(visListingTitleLink-Visualization-HeatmapChart)
[00:08:50]                 │ debg Find.clickByCssSelector('[data-test-subj="visListingTitleLink-Visualization-HeatmapChart"]') with timeout=20000
[00:08:50]                 │ debg Find.findByCssSelector('[data-test-subj="visListingTitleLink-Visualization-HeatmapChart"]') with timeout=20000
[00:08:51]                 │ debg isGlobalLoadingIndicatorVisible
[00:08:51]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:08:51]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:08:52]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:08:53]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:08:53]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:08:53]                 │ debg Waiting up to 20000ms for rendering count to stabilize...
[00:08:53]                 │ debg TestSubjects.find(visualizationLoader)
[00:08:53]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:08:53]                 │ debg -- firstCount=2
[00:08:53]                 │ debg ... sleep(2000) start
[00:08:55]                 │ debg ... sleep(2000) end
[00:08:55]                 │ debg TestSubjects.find(visualizationLoader)
[00:08:55]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:08:55]                 │ debg -- secondCount=2
[00:08:55]                 │ debg -- isNewChartsLibraryEnabled = false
[00:08:55]                 │ debg -- isNewLibraryChart = false
[00:08:55]                 │ debg Find.findByCssSelector('.visualization') with timeout=10000
[00:08:55]                 └- ✓ pass  (32.1s) "visualize app visualize ciGroup10 heatmap chart should save and load"
[00:08:55]               └-> should have inspector enabled
[00:08:55]                 └-> "before each" hook: global before each for "should have inspector enabled"
[00:08:55]                 │ debg TestSubjects.getAttribute(openInspectorButton, disabled, tryTimeout=120000, findTimeout=10000)
[00:08:55]                 │ debg TestSubjects.find(openInspectorButton)
[00:08:55]                 │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:08:55]                 └- ✓ pass  (25ms) "visualize app visualize ciGroup10 heatmap chart should have inspector enabled"
[00:08:55]               └-> should show correct data
[00:08:55]                 └-> "before each" hook: global before each for "should show correct data"
[00:08:55]                 │ debg Inspector.open
[00:08:55]                 │ debg TestSubjects.exists(inspectorPanel)
[00:08:55]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:08:57]                 │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:08:58]                 │ debg TestSubjects.click(openInspectorButton)
[00:08:58]                 │ debg Find.clickByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:08:58]                 │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:08:59]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="openInspectorButton">...</button> is not clickable at point (1039, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:08:59]                 │        (Session info: headless chrome=94.0.4606.81)
[00:08:59]                 │ debg finding element 'By(css selector, [data-test-subj="openInspectorButton"])' again, 2 attempts left
[00:09:00]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButtonEmpty euiButtonEmpty--primary euiButtonEmpty--small euiHeaderLink" type="button" data-test-subj="openInspectorButton">...</button> is not clickable at point (1039, 71). Other element would receive the click: <span class="euiToastHeader__title">...</span>
[00:09:00]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:00]                 │ debg finding element 'By(css selector, [data-test-subj="openInspectorButton"])' again, 1 attempts left
[00:09:01]                 │ debg TestSubjects.exists(inspectorPanel)
[00:09:01]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:09:02]                 │ debg Inspector.expectTableData(2015-09-20 00:00,37,2015-09-20 03:00,202,2015-09-20 06:00,740,2015-09-20 09:00,1,437,2015-09-20 12:00,1,371,2015-09-20 15:00,751,2015-09-20 18:00,188,2015-09-20 21:00,31,2015-09-21 00:00,42,2015-09-21 03:00,202,2015-09-21 06:00,683,2015-09-21 09:00,1,361,2015-09-21 12:00,1,415,2015-09-21 15:00,707,2015-09-21 18:00,177,2015-09-21 21:00,27,2015-09-22 00:00,32,2015-09-22 03:00,175,2015-09-22 06:00,707,2015-09-22 09:00,1,408)
[00:09:02]                 │ debg TestSubjects.find(inspectorPanel)
[00:09:02]                 │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:09:02]                 │ debg Close Inspector
[00:09:02]                 │ debg TestSubjects.exists(inspectorPanel)
[00:09:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:09:02]                 │ debg Closing flyout inspectorPanel
[00:09:02]                 │ debg TestSubjects.find(inspectorPanel)
[00:09:02]                 │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:09:02]                 │ debg Waiting up to 20000ms for flyout closed...
[00:09:02]                 │ debg TestSubjects.exists(inspectorPanel)
[00:09:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=1000
[00:09:03]                 │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:09:03]                 │ debg TestSubjects.exists(inspectorPanel)
[00:09:03]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:09:06]                 │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:09:06]                 └- ✓ pass  (11.6s) "visualize app visualize ciGroup10 heatmap chart should show correct data"
[00:09:06]               └-> should show 4 color ranges as default colorNumbers param
[00:09:06]                 └-> "before each" hook: global before each for "should show 4 color ranges as default colorNumbers param"
[00:09:06]                 │ debg -- isNewChartsLibraryEnabled = false
[00:09:06]                 │ debg -- isNewLibraryChart = false
[00:09:06]                 │ debg -- isNewChartsLibraryEnabled = false
[00:09:06]                 │ debg -- isNewLibraryChart = false
[00:09:06]                 │ debg Find.allByCssSelector('.visLegend__button') with timeout=20000
[00:09:06]                 └- ✓ pass  (96ms) "visualize app visualize ciGroup10 heatmap chart should show 4 color ranges as default colorNumbers param"
[00:09:06]               └-> should show 6 color ranges if changed on options
[00:09:06]                 └-> "before each" hook: global before each for "should show 6 color ranges if changed on options"
[00:09:06]                 │ debg TestSubjects.click(visEditorTab__options)
[00:09:06]                 │ debg Find.clickByCssSelector('[data-test-subj="visEditorTab__options"]') with timeout=10000
[00:09:06]                 │ debg Find.findByCssSelector('[data-test-subj="visEditorTab__options"]') with timeout=10000
[00:09:07]                 │ debg TestSubjects.setValue(heatmapColorsNumber, 6)
[00:09:07]                 │ debg TestSubjects.click(heatmapColorsNumber)
[00:09:07]                 │ debg Find.clickByCssSelector('[data-test-subj="heatmapColorsNumber"]') with timeout=10000
[00:09:07]                 │ debg Find.findByCssSelector('[data-test-subj="heatmapColorsNumber"]') with timeout=10000
[00:09:07]                 │ debg -- isNewChartsLibraryEnabled = false
[00:09:07]                 │ debg TestSubjects.find(visualizationLoader)
[00:09:07]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:09:07]                 │ debg Before Rendering count 6
[00:09:07]                 │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:09:07]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:09:07]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:09:08]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButton euiButton--primary euiButton--small euiButton--fill" type="button" data-test-subj="visualizeEditorRenderButton">...</button> is not clickable at point (1200, 775). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="7">...</div>
[00:09:08]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:08]                 │ debg finding element 'By(css selector, [data-test-subj="visualizeEditorRenderButton"])' again, 2 attempts left
[00:09:09]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButton euiButton--primary euiButton--small euiButton--fill" type="button" data-test-subj="visualizeEditorRenderButton">...</button> is not clickable at point (1200, 775). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="7">...</div>
[00:09:09]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:09]                 │ debg finding element 'By(css selector, [data-test-subj="visualizeEditorRenderButton"])' again, 1 attempts left
[00:09:11]                 │ warn WebElementWrapper.click: element click intercepted: Element <button class="euiButton euiButton--primary euiButton--small euiButton--fill" type="button" data-test-subj="visualizeEditorRenderButton">...</button> is not clickable at point (1200, 775). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="7">...</div>
[00:09:11]                 │        (Session info: headless chrome=94.0.4606.81)
[00:09:11]                 │ debg finding element 'By(css selector, [data-test-subj="visualizeEditorRenderButton"])' again, 0 attempts left
[00:09:12]                 │ info Taking screenshot "/dev/shm/workspace/parallel/10/kibana/test/functional/screenshots/failure/visualize app visualize ciGroup10 heatmap chart should show 6 color ranges if changed on options.png"
[00:09:12]                 │ info Current URL is: http://localhost:61101/app/visualize#/edit/773066e0-30d9-11ec-a6d9-a378144cbca5?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:%272015-09-19T06:31:44.000Z%27,to:%272015-09-23T18:31:44.000Z%27))&_a=(filters:!(),linked:!f,query:(language:kuery,query:%27%27),uiState:(),vis:(aggs:!((enabled:!t,id:%271%27,params:(),schema:metric,type:count),(enabled:!t,id:%272%27,params:(drop_partials:!f,extended_bounds:(),field:%27@timestamp%27,interval:auto,min_doc_count:1,scaleMetricValues:!f,timeRange:(from:%272015-09-19T06:31:44.000Z%27,to:%272015-09-23T18:31:44.000Z%27),useNormalizedEsInterval:!t,used_interval:%273h%27),schema:segment,type:date_histogram)),params:(addLegend:!t,addTooltip:!t,colorSchema:Greens,colorsNumber:4,colorsRange:!(),enableHover:!f,invertColors:!f,legendPosition:right,percentageMode:!f,setColorRange:!f,times:!(),type:heatmap,valueAxes:!((id:ValueAxis-1,labels:(color:black,overwriteColor:!f,rotate:0,show:!f),scale:(defaultYExtents:!f,type:linear),show:!f,type:value))),title:%27Visualization%20HeatmapChart%27,type:heatmap))
[00:09:12]                 │ info Saving page source to: /dev/shm/workspace/parallel/10/kibana/test/functional/failure_debug/html/visualize app visualize ciGroup10 heatmap chart should show 6 color ranges if changed on options.html
[00:09:12]                 └- ✖ fail: visualize app visualize ciGroup10 heatmap chart should show 6 color ranges if changed on options
[00:09:12]                 │      ElementClickInterceptedError: element click intercepted: Element <button class="euiButton euiButton--primary euiButton--small euiButton--fill" type="button" data-test-subj="visualizeEditorRenderButton">...</button> is not clickable at point (1200, 775). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="7">...</div>
[00:09:12]                 │   (Session info: headless chrome=94.0.4606.81)
[00:09:12]                 │       at Object.throwDecodedError (node_modules/selenium-webdriver/lib/error.js:550:15)
[00:09:12]                 │       at parseHttpResponse (node_modules/selenium-webdriver/lib/http.js:565:13)
[00:09:12]                 │       at Executor.execute (node_modules/selenium-webdriver/lib/http.js:491:26)
[00:09:12]                 │       at runMicrotasks (<anonymous>)
[00:09:12]                 │       at processTicksAndRejections (internal/process/task_queues.js:95:5)
[00:09:12]                 │       at Task.exec (test/functional/services/remote/prevent_parallel_calls.ts:28:20)
[00:09:12]                 │ 
[00:09:12]                 │ 

Stack Trace

ElementClickInterceptedError: element click intercepted: Element <button class="euiButton euiButton--primary euiButton--small euiButton--fill" type="button" data-test-subj="visualizeEditorRenderButton">...</button> is not clickable at point (1200, 775). Other element would receive the click: <div class="euiToast euiToast--warning euiGlobalToastListItem" id="7">...</div>
  (Session info: headless chrome=94.0.4606.81)
    at Object.throwDecodedError (node_modules/selenium-webdriver/lib/error.js:550:15)
    at parseHttpResponse (node_modules/selenium-webdriver/lib/http.js:565:13)
    at Executor.execute (node_modules/selenium-webdriver/lib/http.js:491:26)
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:95:5)
    at Task.exec (test/functional/services/remote/prevent_parallel_calls.ts:28:20) {
  remoteStacktrace: '#0 0x55fb8c18d8f3 <unknown>\n' +
    '#1 0x55fb8bc72ba8 <unknown>\n' +
    '#2 0x55fb8bcaff0a <unknown>\n' +
    '#3 0x55fb8bcad9b4 <unknown>\n' +
    '#4 0x55fb8bcab13a <unknown>\n' +
    '#5 0x55fb8bca9aec <unknown>\n' +
    '#6 0x55fb8bc9d818 <unknown>\n' +
    '#7 0x55fb8bcc5e72 <unknown>\n' +
    '#8 0x55fb8bc9d593 <unknown>\n' +
    '#9 0x55fb8bcc5f7e <unknown>\n' +
    '#10 0x55fb8bcd8dac <unknown>\n' +
    '#11 0x55fb8bcc5d63 <unknown>\n' +
    '#12 0x55fb8bc9c144 <unknown>\n' +
    '#13 0x55fb8bc9d135 <unknown>\n' +
    '#14 0x55fb8c1bcc3e <unknown>\n' +
    '#15 0x55fb8c1d26b7 <unknown>\n' +
    '#16 0x55fb8c1bdb95 <unknown>\n' +
    '#17 0x55fb8c1d3b05 <unknown>\n' +
    '#18 0x55fb8c1b22ab <unknown>\n' +
    '#19 0x55fb8c1ee248 <unknown>\n' +
    '#20 0x55fb8c1ee3c8 <unknown>\n' +
    '#21 0x55fb8c20933d <unknown>\n' +
    '#22 0x7f594dc366ba start_thread\n'
}

and 24 more failures, only showing the first 3.

Metrics [docs]

✅ unchanged

History

To update your PR or re-run it, just comment with:
@elasticmachine merge upstream

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
6 participants