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

[Fleet] Set all keyword and text fields for index.query.default_field index template setting #91791

Merged
merged 19 commits into from
Mar 4, 2021

Conversation

jen-huang
Copy link
Contributor

@jen-huang jen-huang commented Feb 18, 2021

Summary

Resolves #89357.

Previously we populate index.query.default_field setting to ['message'] for all data stream index templates we install. This causes problems when querying indices that don't have the message field, like metric indices, and even some log indices too.

Looking at the code, I think we intended to go back to this area to "add all keyword and text fields" for this setting instead of using only message across the board.

This PR changes to behavior to what we intended: it looks at all the data stream fields (the same ones that we use to generate the template mappings), extracts fields which are of keyword or text type, and adds those fields to index.query.default_field. If it finds no matching fields, we do not populate that setting at all (then, the ES default behavior kicks in, [*] - querying on all fields).

Example of a metrics index template, metrics-system.core, settings after this change:

image

Logs example, logs-system.auth:

image

@jen-huang jen-huang added release_note:fix v8.0.0 Team:Fleet Team label for Observability Data Collection Fleet team v7.13.0 labels Feb 18, 2021
@jen-huang jen-huang self-assigned this Feb 18, 2021
@jen-huang jen-huang requested a review from a team as a code owner February 18, 2021 03:37
@elasticmachine
Copy link
Contributor

Pinging @elastic/fleet (Team:Fleet)

@jen-huang
Copy link
Contributor Author

jen-huang commented Feb 18, 2021

@simianhacker Could you help test this and give input on whether this is a good solution?

@ruflin Would like your input here too as I haven't worked on this part of the code much. Is it common for log data streams to not have message field? When I tested with system, system, security, and application log data streams did not appear to have that field defined. Another thing that comes to mind is, users won't get this change for already installed packages until the packages get upgraded (or manual reinstall).

@jen-huang jen-huang changed the title [Fleet] Set all keyword and text fields for index.query.default_field setting [Fleet] Set all keyword and text fields for index.query.default_field index template setting Feb 18, 2021
@ruflin
Copy link
Member

ruflin commented Feb 19, 2021

In the metrics case, it is common that no message field exists. In Beats we have at the moment the problem, that there are too many default_fields and we hit the limit. This problem should not happen anymore with the new indexing strategy I assume. So +1 on this change.

The more general question is: What is the user expectation when just typing in a query, what should it search on?

Pinging @andrewkroh as he has been dealing with this in the past and would be great to get his feedback on this.

@andrewkroh
Copy link
Member

We should set the index.query.default_field option rather than relying on * because it protects users from certain query exceptions so +1. A few questions and thoughts...

While the new indexing strategy should keep us safe, I don't want to drive without guardrails. Package testing must identify (and fail) data streams that are at the limit of 1024 keywords and text fields (and maybe warn when you get to ~900).

How should Fleet behave when a package's data stream declares more than 1024 text and keyword fields? This will cause the "field expansion matches too many fields" query shard exception unless Elasticsearch has been modified. Fleet could fail, reduce the list in some way, or do nothing and let the failure occur later. Even if we test all of our packages to prevent this I imagine at some point Fleet will allow loading third-party packages which may not have gone through the same rigorous testing as our own packages.

Should packages have a way to specify their default_field list in order to override the default behavior? Is there a strong use-case other than work-around the 1024 field limit? I think some escape hatch should be available to package developers for when a package data stream does reach the limit.

Allowing wildcards into the list (like fields.* or labels.*) introduces unpredictability into the number of matching fields so I recommend against this (just in case we were considering it). Beats includes fields.* and users of fields can get burnt.

@jen-huang
Copy link
Contributor Author

jen-huang commented Feb 19, 2021

Thanks a lot for the feedback @andrewkroh @ruflin, the additional context is useful. I wasn't aware of the 1024 field list limitation for this setting. It sounds like hitting that would be a rare exception, but I agree we should have some guards.

How should Fleet behave when a package's data stream declares more than 1024 text and keyword fields? This will cause the "field expansion matches too many fields" query shard exception unless Elasticsearch has been modified. Fleet could fail, reduce the list in some way, or do nothing and let the failure occur later.

Should packages have a way to specify their default_field list in order to override the default behavior? Is there a strong use-case other than work-around the 1024 field limit? I think some escape hatch should be available to package developers for when a package data stream does reach the limit.

I could see two guards helping with both of these questions:

  • First, we add validation for our package authors to detect and warn them when they are approaching or over the limit. I think this is a simpler first step than allowing packages to define their own default fields. If we want that level of granularity in the future, it might be worth considering allowing packages to ship their own index templates. I recall we had some recent package spec & Kibana changes to conditionally modify the generated index template generated by Kibana (see: changing the matching index pattern and template priority), that kind of case could be solved with packages shipping their own templates too.
  • Second, we add guards to Fleet in this PR in case the limit is surpassed. I'm in favor of doing it via limiting to the first 1024 fields. This will allow the data stream to still be useful in most cases. We can add Kibana warning logs if this happens too.

WDYT?

@ruflin
Copy link
Member

ruflin commented Feb 22, 2021

To get started, I would add a general field limit in packages per data_stream. In general, the number of fields per data_stream should stay low to have compact mappings. I'm thinking of starting with something like 128 or 256 and increase it only if we see good reasons for it. This will encourage package devs to think about which fields are added.

@ruflin
Copy link
Member

ruflin commented Feb 22, 2021

@ycombinator For your awareness, as this would be in the package-spec ^

@ycombinator
Copy link
Contributor

To get started, I would add a general field limit in packages per data_stream. In general, the number of fields per data_stream should stay low to have compact mappings. I'm thinking of starting with something like 128 or 256 and increase it only if we see good reasons for it. This will encourage package devs to think about which fields are added.

If we do this, wouldn't package developers just keep bumping up the limits until the validation passes? Or maybe this is actually desired to give them the escape hatch that @andrewkroh mentioned?

By the way, where were you thinking of having validation against this new limit field be implemented?

I was thinking it could be implemented as part of elastic-package lint or maybe elastic-package test even, depending on whether we can simply analyze a data stream's field definition files (fields.yml, etc.) or we need to install the package so it's data streams' index templates also get installed and then analyze the fields in the template's mapping. Of course, as @andrewkroh also pointed out, any packages that don't use elastic-package would escape such validation then.

If we are okay with implementation the validation as part of elastic-package lint or elastic-package test, then maybe we could also consider hardcoding a limit in elastic-package code rather than giving package developers the ability to set the limit per data stream? That would ensure that when package developers start to hit the limit, they don't silently bump it up but have to file an issue against elastic-package, which then gives us an opportunity to figure out why they need the higher limit and maybe suggest some alternatives. Again, any packages that don't use elastic-package would escape this validation.

@ruflin
Copy link
Member

ruflin commented Feb 25, 2021

I was not thinking of giving the devs the freedom to set it per data stream but the hard code approach you proposed to make it a much higher hurdle to have "too many fields". Even if elastic-package lint is used, it could still be part of the package-spec I assume?

@jen-huang
Copy link
Contributor Author

Thanks for the discourse @ycombinator and @ruflin. I've opened elastic/elastic-package#266 for further discussion on the package authoring side.

For this PR, I added a safeguard so that if there are more than 1024 keyword/text fields detected, Kibana will log a warning and only set the first 1024 fields.

@jen-huang jen-huang added the auto-backport Deprecated - use backport:version if exact versions are needed label Mar 1, 2021
@skh
Copy link
Contributor

skh commented Mar 2, 2021

@elasticmachine merge upstream

Copy link
Contributor

@skh skh left a comment

Choose a reason for hiding this comment

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

One inline comment about variable naming.

How I tested:

  • run this branch and open the Fleet UI to let the setup calls do their thing
  • look at the index templates for the system package
  • verify that index.query.default_field is set as described in the description of this PR

LGTM (when tests are fixed) 🚀

@kibanamachine
Copy link
Contributor

💛 Build succeeded, but was flaky


Test Failures

Kibana Pipeline / general / Chrome X-Pack UI Functional Tests.x-pack/test/functional/apps/maps/auto_fit_to_bounds·js.maps app auto fit map to bounds without joins should automatically fit to bounds when query is applied

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: maps app
[00:00:00]           └-> "before all" hook in "maps app"
[00:00:00]           └-> "before all" hook in "maps app"
[00:00:00]             │ info [logstash_functional] Loading "mappings.json"
[00:00:00]             │ info [logstash_functional] Loading "data.json.gz"
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.22][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.22][0]]"
[00:00:00]             │ info [logstash_functional] Created index "logstash-2015.09.22"
[00:00:00]             │ debg [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:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.20][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.20][0]]"
[00:00:00]             │ info [logstash_functional] Created index "logstash-2015.09.20"
[00:00:00]             │ debg [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:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.21][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.21][0]]"
[00:00:00]             │ info [logstash_functional] Created index "logstash-2015.09.21"
[00:00:00]             │ debg [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:10]             │ info progress: 11416
[00:00:12]             │ info [logstash_functional] Indexed 4634 docs into "logstash-2015.09.22"
[00:00:12]             │ info [logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:00:12]             │ info [logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:00:12]             │ info [maps/data] Loading "mappings.json"
[00:00:12]             │ info [maps/data] Loading "data.json"
[00:00:12]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [geo_shapes] creating index, cause [api], templates [], shards [1]/[0]
[00:00:12]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[geo_shapes][0]]])." previous.health="YELLOW" reason="shards started [[geo_shapes][0]]"
[00:00:12]             │ info [maps/data] Created index "geo_shapes"
[00:00:12]             │ debg [maps/data] "geo_shapes" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:12]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [meta_for_geo_shapes] creating index, cause [api], templates [], shards [1]/[0]
[00:00:12]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[meta_for_geo_shapes][0]]])." previous.health="YELLOW" reason="shards started [[meta_for_geo_shapes][0]]"
[00:00:12]             │ info [maps/data] Created index "meta_for_geo_shapes"
[00:00:12]             │ debg [maps/data] "meta_for_geo_shapes" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:12]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [antimeridian_points] creating index, cause [api], templates [], shards [1]/[0]
[00:00:12]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[antimeridian_points][0]]])." previous.health="YELLOW" reason="shards started [[antimeridian_points][0]]"
[00:00:12]             │ info [maps/data] Created index "antimeridian_points"
[00:00:12]             │ debg [maps/data] "antimeridian_points" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:12]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [antimeridian_shapes] creating index, cause [api], templates [], shards [1]/[0]
[00:00:12]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[antimeridian_shapes][0]]])." previous.health="YELLOW" reason="shards started [[antimeridian_shapes][0]]"
[00:00:12]             │ info [maps/data] Created index "antimeridian_shapes"
[00:00:12]             │ debg [maps/data] "antimeridian_shapes" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:12]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [flights] creating index, cause [api], templates [], shards [1]/[0]
[00:00:12]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[flights][0]]])." previous.health="YELLOW" reason="shards started [[flights][0]]"
[00:00:12]             │ info [maps/data] Created index "flights"
[00:00:12]             │ debg [maps/data] "flights" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:12]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [connections] creating index, cause [api], templates [], shards [1]/[0]
[00:00:12]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[connections][0]]])." previous.health="YELLOW" reason="shards started [[connections][0]]"
[00:00:12]             │ info [maps/data] Created index "connections"
[00:00:12]             │ debg [maps/data] "connections" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:13]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [meta_for_geo_shapes/O24NtrEPRouGISMYTf4_uA] update_mapping [_doc]
[00:00:13]             │ info [maps/data] Indexed 4 docs into "geo_shapes"
[00:00:13]             │ info [maps/data] Indexed 6 docs into "meta_for_geo_shapes"
[00:00:13]             │ info [maps/data] Indexed 3 docs into "antimeridian_points"
[00:00:13]             │ info [maps/data] Indexed 3 docs into "antimeridian_shapes"
[00:00:13]             │ info [maps/data] Indexed 3 docs into "flights"
[00:00:13]             │ info [maps/data] Indexed 4 docs into "connections"
[00:00:13]             │ info [maps/kibana] Loading "mappings.json"
[00:00:13]             │ info [maps/kibana] Loading "data.json"
[00:00:13]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_task_manager_8.0.0_001/KT-BUDzeRr6vWlWCtCErQQ] deleting index
[00:00:13]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_001/GiJQI4SITE-4rC8_0ObF9A] deleting index
[00:00:13]             │ info [maps/kibana] Deleted existing index ".kibana_8.0.0_001"
[00:00:13]             │ info [maps/kibana] Deleted existing index ".kibana_task_manager_8.0.0_001"
[00:00:13]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana] creating index, cause [api], templates [], shards [1]/[0]
[00:00:13]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana][0]]])." previous.health="YELLOW" reason="shards started [[.kibana][0]]"
[00:00:13]             │ info [maps/kibana] Created index ".kibana"
[00:00:13]             │ debg [maps/kibana] ".kibana" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:13]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana/6i3kmfceSJS9c678XrGInQ] update_mapping [_doc]
[00:00:13]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana/6i3kmfceSJS9c678XrGInQ] update_mapping [_doc]
[00:00:13]             │ info [maps/kibana] Indexed 33 docs into ".kibana"
[00:00:13]             │ debg Migrating saved objects
[00:00:13]             │ proc [kibana]   log   [20:14:13.190] [info][savedobjects-service] [.kibana_task_manager] INIT -> CREATE_NEW_TARGET
[00:00:13]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_task_manager_8.0.0_001] creating index, cause [api], templates [], shards [1]/[1]
[00:00:13]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] updating number_of_replicas to [0] for indices [.kibana_task_manager_8.0.0_001]
[00:00:13]             │ proc [kibana]   log   [20:14:13.203] [info][savedobjects-service] [.kibana] INIT -> LEGACY_SET_WRITE_BLOCK
[00:00:13]             │ info [o.e.c.m.MetadataIndexStateService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] adding block write to indices [[.kibana/6i3kmfceSJS9c678XrGInQ]]
[00:00:13]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_task_manager_8.0.0_001][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_task_manager_8.0.0_001][0]]"
[00:00:13]             │ info [o.e.c.m.MetadataIndexStateService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] completed adding block write to indices [.kibana]
[00:00:13]             │ proc [kibana]   log   [20:14:13.296] [info][savedobjects-service] [.kibana_task_manager] CREATE_NEW_TARGET -> MARK_VERSION_INDEX_READY
[00:00:13]             │ proc [kibana]   log   [20:14:13.306] [info][savedobjects-service] [.kibana] LEGACY_SET_WRITE_BLOCK -> LEGACY_CREATE_REINDEX_TARGET
[00:00:13]             │ proc [kibana]   log   [20:14:13.332] [info][savedobjects-service] [.kibana_task_manager] MARK_VERSION_INDEX_READY -> DONE
[00:00:13]             │ proc [kibana]   log   [20:14:13.333] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 149ms
[00:00:13]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_pre6.5.0_001] creating index, cause [api], templates [], shards [1]/[1]
[00:00:13]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] updating number_of_replicas to [0] for indices [.kibana_pre6.5.0_001]
[00:00:13]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_pre6.5.0_001][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_pre6.5.0_001][0]]"
[00:00:13]             │ proc [kibana]   log   [20:14:13.398] [info][savedobjects-service] [.kibana] LEGACY_CREATE_REINDEX_TARGET -> LEGACY_REINDEX
[00:00:13]             │ proc [kibana]   log   [20:14:13.419] [info][savedobjects-service] [.kibana] LEGACY_REINDEX -> LEGACY_REINDEX_WAIT_FOR_TASK
[00:00:14]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.tasks] creating index, cause [auto(bulk api)], templates [], shards [1]/[1]
[00:00:14]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] updating number_of_replicas to [0] for indices [.tasks]
[00:00:14]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.tasks][0]]])." previous.health="YELLOW" reason="shards started [[.tasks][0]]"
[00:00:14]             │ info [o.e.t.LoggingTaskListener] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] 2204 finished with response BulkByScrollResponse[took=137.6ms,timed_out=false,sliceId=null,updated=0,created=33,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:00:14]             │ proc [kibana]   log   [20:14:13.647] [info][savedobjects-service] [.kibana] LEGACY_REINDEX_WAIT_FOR_TASK -> LEGACY_DELETE
[00:00:14]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana/6i3kmfceSJS9c678XrGInQ] deleting index
[00:00:14]             │ proc [kibana]   log   [20:14:13.694] [info][savedobjects-service] [.kibana] LEGACY_DELETE -> SET_SOURCE_WRITE_BLOCK
[00:00:14]             │ info [o.e.c.m.MetadataIndexStateService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] adding block write to indices [[.kibana_pre6.5.0_001/kL6Ac0BASkeUmTvJ5h6m6A]]
[00:00:14]             │ info [o.e.c.m.MetadataIndexStateService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] completed adding block write to indices [.kibana_pre6.5.0_001]
[00:00:14]             │ proc [kibana]   log   [20:14:13.740] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CREATE_REINDEX_TEMP
[00:00:14]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_reindex_temp] creating index, cause [api], templates [], shards [1]/[1]
[00:00:14]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] updating number_of_replicas to [0] for indices [.kibana_8.0.0_reindex_temp]
[00:00:14]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_8.0.0_reindex_temp][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_8.0.0_reindex_temp][0]]"
[00:00:14]             │ proc [kibana]   log   [20:14:13.804] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP
[00:00:14]             │ proc [kibana]   log   [20:14:13.813] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP -> REINDEX_SOURCE_TO_TEMP_WAIT_FOR_TASK
[00:00:14]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_reindex_temp/UKw-lnMzSt-Vb7Z9xdYkbg] update_mapping [_doc]
[00:00:14]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_reindex_temp/UKw-lnMzSt-Vb7Z9xdYkbg] update_mapping [_doc]
[00:00:14]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_reindex_temp/UKw-lnMzSt-Vb7Z9xdYkbg] update_mapping [_doc]
[00:00:14]             │ info [o.e.t.LoggingTaskListener] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] 2249 finished with response BulkByScrollResponse[took=113.1ms,timed_out=false,sliceId=null,updated=0,created=33,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:00:14]             │ proc [kibana]   log   [20:14:14.058] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_WAIT_FOR_TASK -> SET_TEMP_WRITE_BLOCK
[00:00:14]             │ info [o.e.c.m.MetadataIndexStateService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] adding block write to indices [[.kibana_8.0.0_reindex_temp/UKw-lnMzSt-Vb7Z9xdYkbg]]
[00:00:14]             │ info [o.e.c.m.MetadataIndexStateService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] completed adding block write to indices [.kibana_8.0.0_reindex_temp]
[00:00:14]             │ proc [kibana]   log   [20:14:14.118] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET
[00:00:14]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] applying create index request using existing index [.kibana_8.0.0_reindex_temp] metadata
[00:00:14]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_001] creating index, cause [clone_index], templates [], shards [1]/[1]
[00:00:14]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] updating number_of_replicas to [0] for indices [.kibana_8.0.0_001]
[00:00:14]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_001/okJIQIUMSFqXaswLu4YSHw] create_mapping
[00:00:14]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_8.0.0_001][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_8.0.0_001][0]]"
[00:00:14]             │ proc [kibana]   log   [20:14:14.271] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> OUTDATED_DOCUMENTS_SEARCH
[00:00:14]             │ proc [kibana]   log   [20:14:14.302] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH -> OUTDATED_DOCUMENTS_TRANSFORM
[00:00:14]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_001/okJIQIUMSFqXaswLu4YSHw] update_mapping [_doc]
[00:00:15]             │ proc [kibana]   log   [20:14:15.190] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_TRANSFORM -> OUTDATED_DOCUMENTS_SEARCH
[00:00:15]             │ proc [kibana]   log   [20:14:15.206] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH -> UPDATE_TARGET_MAPPINGS
[00:00:15]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_001/okJIQIUMSFqXaswLu4YSHw] update_mapping [_doc]
[00:00:15]             │ proc [kibana]   log   [20:14:15.284] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS -> UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK
[00:00:15]             │ info [o.e.t.LoggingTaskListener] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] 2317 finished with response BulkByScrollResponse[took=42.7ms,timed_out=false,sliceId=null,updated=33,created=0,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:00:15]             │ proc [kibana]   log   [20:14:15.395] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK -> MARK_VERSION_INDEX_READY
[00:00:15]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_reindex_temp/UKw-lnMzSt-Vb7Z9xdYkbg] deleting index
[00:00:15]             │ proc [kibana]   log   [20:14:15.438] [info][savedobjects-service] [.kibana] MARK_VERSION_INDEX_READY -> DONE
[00:00:15]             │ proc [kibana]   log   [20:14:15.439] [info][savedobjects-service] [.kibana] Migration completed after 2259ms
[00:00:15]             │ debg [maps/kibana] Migrated Kibana index after loading Kibana data
[00:00:15]             │ debg [maps/kibana] Ensured that default space exists in .kibana
[00:00:15]             │ debg applying update to kibana config: {"accessibility:disableAnimations":true,"dateFormat:tz":"UTC","visualization:visualize:legacyChartsLibrary":true}
[00:00:16]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1614886306480616258] [.kibana_8.0.0_001/okJIQIUMSFqXaswLu4YSHw] update_mapping [_doc]
[00:00:17]             │ debg replacing kibana config doc: {"defaultIndex":"c698b940-e149-11e8-a35a-370a8516603a"}
[00:00:18]           └-: 
[00:00:18]             └-> "before all" hook in ""
[00:14:44]             └-: auto fit map to bounds
[00:14:44]               └-> "before all" hook in "auto fit map to bounds"
[00:15:17]               └-: without joins
[00:15:17]                 └-> "before all" hook for "should automatically fit to bounds when query is applied"
[00:15:17]                 └-> "before all" hook for "should automatically fit to bounds when query is applied"
[00:15:17]                   │ debg Load Saved Map document example
[00:15:17]                   │ debg searchForMapWithName: document example
[00:15:17]                   │ debg gotoMapListingPage
[00:15:17]                   │ debg onMapListingPage
[00:15:17]                   │ debg TestSubjects.exists(mapLandingPage)
[00:15:17]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapLandingPage"]') with timeout=5000
[00:15:20]                   │ debg --- retry.tryForTime error: [data-test-subj="mapLandingPage"] is not displayed
[00:15:24]                   │ debg --- retry.tryForTime failed again with the same message...
[00:15:24]                   │ debg navigateToActualUrl http://localhost:6111/app/maps/
[00:15:24]                   │ debg browser[INFO] http://localhost:6111/app/maps/?_t=1614889764217 341 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:15:24]                   │
[00:15:24]                   │ debg browser[INFO] http://localhost:6111/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:15:24]                   │ debg currentUrl = http://localhost:6111/app/maps/
[00:15:24]                   │          appUrl = http://localhost:6111/app/maps/
[00:15:24]                   │ debg TestSubjects.find(kibanaChrome)
[00:15:24]                   │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:15:26]                   │ debg onMapListingPage
[00:15:26]                   │ debg TestSubjects.exists(mapLandingPage)
[00:15:26]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapLandingPage"]') with timeout=5000
[00:15:26]                   │ debg searchForItemWithName: document example
[00:15:26]                   │ debg TestSubjects.find(tableListSearchBox)
[00:15:26]                   │ debg Find.findByCssSelector('[data-test-subj="tableListSearchBox"]') with timeout=10000
[00:15:27]                   │ debg isGlobalLoadingIndicatorVisible
[00:15:27]                   │ debg TestSubjects.exists(globalLoadingIndicator)
[00:15:27]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:15:28]                   │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:15:29]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:15:29]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:15:29]                   │ debg isGlobalLoadingIndicatorVisible
[00:15:29]                   │ debg TestSubjects.exists(globalLoadingIndicator)
[00:15:29]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:15:30]                   │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:15:31]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:15:31]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:15:31]                   │ debg TestSubjects.click(mapListingTitleLink-document-example)
[00:15:31]                   │ debg Find.clickByCssSelector('[data-test-subj="mapListingTitleLink-document-example"]') with timeout=10000
[00:15:31]                   │ debg Find.findByCssSelector('[data-test-subj="mapListingTitleLink-document-example"]') with timeout=10000
[00:15:31]                   │ debg isGlobalLoadingIndicatorVisible
[00:15:31]                   │ debg TestSubjects.exists(globalLoadingIndicator)
[00:15:31]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:15:32]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:15:32]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:15:33]                   │ debg TestSubjects.missingOrFail(mapLandingPage)
[00:15:33]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="mapLandingPage"]') with timeout=10000
[00:15:34]                   │ debg Wait for layers to load
[00:15:34]                   │ debg TestSubjects.find(mapLayerTOC)
[00:15:34]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:15:36]                   │ debg TestSubjects.click(openSettingsButton)
[00:15:36]                   │ debg Find.clickByCssSelector('[data-test-subj="openSettingsButton"]') with timeout=10000
[00:15:36]                   │ debg Find.findByCssSelector('[data-test-subj="openSettingsButton"]') with timeout=10000
[00:15:36]                   │ debg TestSubjects.getAttribute(autoFitToDataBoundsSwitch, checked, tryTimeout=120000, findTimeout=10000)
[00:15:36]                   │ debg TestSubjects.find(autoFitToDataBoundsSwitch)
[00:15:36]                   │ debg Find.findByCssSelector('[data-test-subj="autoFitToDataBoundsSwitch"]') with timeout=10000
[00:15:37]                   │ debg TestSubjects.click(autoFitToDataBoundsSwitch)
[00:15:37]                   │ debg Find.clickByCssSelector('[data-test-subj="autoFitToDataBoundsSwitch"]') with timeout=10000
[00:15:37]                   │ debg Find.findByCssSelector('[data-test-subj="autoFitToDataBoundsSwitch"]') with timeout=10000
[00:15:37]                   │ debg TestSubjects.getAttribute(autoFitToDataBoundsSwitch, checked, tryTimeout=120000, findTimeout=10000)
[00:15:37]                   │ debg TestSubjects.find(autoFitToDataBoundsSwitch)
[00:15:37]                   │ debg Find.findByCssSelector('[data-test-subj="autoFitToDataBoundsSwitch"]') with timeout=10000
[00:15:37]                   │ debg TestSubjects.click(mapSettingSubmitButton)
[00:15:37]                   │ debg Find.clickByCssSelector('[data-test-subj="mapSettingSubmitButton"]') with timeout=10000
[00:15:37]                   │ debg Find.findByCssSelector('[data-test-subj="mapSettingSubmitButton"]') with timeout=10000
[00:15:37]                 └-> should automatically fit to bounds when query is applied
[00:15:37]                   └-> "before each" hook: global before each for "should automatically fit to bounds when query is applied"
[00:15:37]                   │ debg Set view lat: -15, lon: -100, zoom: 6
[00:15:37]                   │ debg setting menu open state [name=SetView Popover] [state=open]
[00:15:37]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:37]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=1000
[00:15:38]                   │ debg --- retry.tryForTime error: [data-test-subj="mapSetViewForm"] is not displayed
[00:15:39]                   │ debg TestSubjects.click(toggleSetViewVisibilityButton)
[00:15:39]                   │ debg Find.clickByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:39]                   │ debg Find.findByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:39]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:39]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=10000
[00:15:39]                   │ debg TestSubjects.setValue(latitudeInput, -15)
[00:15:39]                   │ debg TestSubjects.click(latitudeInput)
[00:15:39]                   │ debg Find.clickByCssSelector('[data-test-subj="latitudeInput"]') with timeout=10000
[00:15:39]                   │ debg Find.findByCssSelector('[data-test-subj="latitudeInput"]') with timeout=10000
[00:15:39]                   │ debg TestSubjects.setValue(longitudeInput, -100)
[00:15:39]                   │ debg TestSubjects.click(longitudeInput)
[00:15:39]                   │ debg Find.clickByCssSelector('[data-test-subj="longitudeInput"]') with timeout=10000
[00:15:39]                   │ debg Find.findByCssSelector('[data-test-subj="longitudeInput"]') with timeout=10000
[00:15:39]                   │ debg TestSubjects.setValue(zoomInput, 6)
[00:15:39]                   │ debg TestSubjects.click(zoomInput)
[00:15:39]                   │ debg Find.clickByCssSelector('[data-test-subj="zoomInput"]') with timeout=10000
[00:15:39]                   │ debg Find.findByCssSelector('[data-test-subj="zoomInput"]') with timeout=10000
[00:15:39]                   │ debg TestSubjects.click(submitViewButton)
[00:15:39]                   │ debg Find.clickByCssSelector('[data-test-subj="submitViewButton"]') with timeout=10000
[00:15:39]                   │ debg Find.findByCssSelector('[data-test-subj="submitViewButton"]') with timeout=10000
[00:15:39]                   │ debg Waiting for map pan and zoom to complete
[00:15:39]                   │ debg Get view
[00:15:39]                   │ debg setting menu open state [name=SetView Popover] [state=open]
[00:15:39]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:39]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=1000
[00:15:40]                   │ debg --- retry.tryForTime error: stale element reference: element is not attached to the page document
[00:15:40]                   │        (Session info: headless chrome=89.0.4389.72)
[00:15:41]                   │ debg TestSubjects.click(toggleSetViewVisibilityButton)
[00:15:41]                   │ debg Find.clickByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:41]                   │ debg Find.findByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:41]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:41]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=10000
[00:15:41]                   │ debg TestSubjects.getAttribute(latitudeInput, value, tryTimeout=5000, findTimeout=1000)
[00:15:41]                   │ debg TestSubjects.find(latitudeInput)
[00:15:41]                   │ debg Find.findByCssSelector('[data-test-subj="latitudeInput"]') with timeout=1000
[00:15:41]                   │ debg TestSubjects.getAttribute(longitudeInput, value, tryTimeout=5000, findTimeout=1000)
[00:15:41]                   │ debg TestSubjects.find(longitudeInput)
[00:15:41]                   │ debg Find.findByCssSelector('[data-test-subj="longitudeInput"]') with timeout=1000
[00:15:41]                   │ debg TestSubjects.getAttribute(zoomInput, value, tryTimeout=5000, findTimeout=1000)
[00:15:41]                   │ debg TestSubjects.find(zoomInput)
[00:15:41]                   │ debg Find.findByCssSelector('[data-test-subj="zoomInput"]') with timeout=1000
[00:15:41]                   │ debg setting menu open state [name=SetView Popover] [state=closed]
[00:15:41]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:41]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=1000
[00:15:41]                   │ debg TestSubjects.click(toggleSetViewVisibilityButton)
[00:15:41]                   │ debg Find.clickByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:41]                   │ debg Find.findByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:41]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=10000
[00:15:42]                   │ debg ... sleep(1000) start
[00:15:43]                   │ debg ... sleep(1000) end
[00:15:43]                   │ debg Get view
[00:15:43]                   │ debg setting menu open state [name=SetView Popover] [state=open]
[00:15:43]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:43]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=1000
[00:15:44]                   │ debg --- retry.tryForTime error: [data-test-subj="mapSetViewForm"] is not displayed
[00:15:44]                   │ debg TestSubjects.click(toggleSetViewVisibilityButton)
[00:15:44]                   │ debg Find.clickByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:44]                   │ debg Find.findByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:44]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:44]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=10000
[00:15:45]                   │ debg TestSubjects.getAttribute(latitudeInput, value, tryTimeout=5000, findTimeout=1000)
[00:15:45]                   │ debg TestSubjects.find(latitudeInput)
[00:15:45]                   │ debg Find.findByCssSelector('[data-test-subj="latitudeInput"]') with timeout=1000
[00:15:45]                   │ debg TestSubjects.getAttribute(longitudeInput, value, tryTimeout=5000, findTimeout=1000)
[00:15:45]                   │ debg TestSubjects.find(longitudeInput)
[00:15:45]                   │ debg Find.findByCssSelector('[data-test-subj="longitudeInput"]') with timeout=1000
[00:15:45]                   │ debg TestSubjects.getAttribute(zoomInput, value, tryTimeout=5000, findTimeout=1000)
[00:15:45]                   │ debg TestSubjects.find(zoomInput)
[00:15:45]                   │ debg Find.findByCssSelector('[data-test-subj="zoomInput"]') with timeout=1000
[00:15:45]                   │ debg setting menu open state [name=SetView Popover] [state=closed]
[00:15:45]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:45]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=1000
[00:15:45]                   │ debg TestSubjects.click(toggleSetViewVisibilityButton)
[00:15:45]                   │ debg Find.clickByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:45]                   │ debg Find.findByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:45]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=10000
[00:15:46]                   │ debg Wait for layers to load
[00:15:46]                   │ debg TestSubjects.find(mapLayerTOC)
[00:15:46]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:15:47]                   │ debg Get view
[00:15:47]                   │ debg setting menu open state [name=SetView Popover] [state=open]
[00:15:47]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:47]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=1000
[00:15:48]                   │ debg --- retry.tryForTime error: [data-test-subj="mapSetViewForm"] is not displayed
[00:15:48]                   │ debg TestSubjects.click(toggleSetViewVisibilityButton)
[00:15:48]                   │ debg Find.clickByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:48]                   │ debg Find.findByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:48]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:48]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=10000
[00:15:48]                   │ debg TestSubjects.getAttribute(latitudeInput, value, tryTimeout=5000, findTimeout=1000)
[00:15:48]                   │ debg TestSubjects.find(latitudeInput)
[00:15:48]                   │ debg Find.findByCssSelector('[data-test-subj="latitudeInput"]') with timeout=1000
[00:15:48]                   │ debg TestSubjects.getAttribute(longitudeInput, value, tryTimeout=5000, findTimeout=1000)
[00:15:48]                   │ debg TestSubjects.find(longitudeInput)
[00:15:48]                   │ debg Find.findByCssSelector('[data-test-subj="longitudeInput"]') with timeout=1000
[00:15:48]                   │ debg TestSubjects.getAttribute(zoomInput, value, tryTimeout=5000, findTimeout=1000)
[00:15:48]                   │ debg TestSubjects.find(zoomInput)
[00:15:48]                   │ debg Find.findByCssSelector('[data-test-subj="zoomInput"]') with timeout=1000
[00:15:48]                   │ debg setting menu open state [name=SetView Popover] [state=closed]
[00:15:48]                   │ debg TestSubjects.exists(mapSetViewForm)
[00:15:48]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=1000
[00:15:48]                   │ debg TestSubjects.click(toggleSetViewVisibilityButton)
[00:15:48]                   │ debg Find.clickByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:48]                   │ debg Find.findByCssSelector('[data-test-subj="toggleSetViewVisibilityButton"]') with timeout=10000
[00:15:48]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="mapSetViewForm"]') with timeout=10000
[00:15:49]                   │ debg QueryBar.setQuery(machine.os.raw : "ios")
[00:15:49]                   │ debg TestSubjects.click(queryInput)
[00:15:49]                   │ debg Find.clickByCssSelector('[data-test-subj="queryInput"]') with timeout=10000
[00:15:49]                   │ debg Find.findByCssSelector('[data-test-subj="queryInput"]') with timeout=10000
[00:15:50]                   │ debg TestSubjects.getAttribute(queryInput, value, tryTimeout=120000, findTimeout=10000)
[00:15:50]                   │ debg TestSubjects.find(queryInput)
[00:15:50]                   │ debg Find.findByCssSelector('[data-test-subj="queryInput"]') with timeout=10000
[00:15:50]                   │ debg QueryBar.submitQuery
[00:15:50]                   │ debg TestSubjects.click(queryInput)
[00:15:50]                   │ debg Find.clickByCssSelector('[data-test-subj="queryInput"]') with timeout=10000
[00:15:50]                   │ debg Find.findByCssSelector('[data-test-subj="queryInput"]') with timeout=10000
[00:15:50]                   │ debg isGlobalLoadingIndicatorVisible
[00:15:50]                   │ debg TestSubjects.exists(globalLoadingIndicator)
[00:15:50]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:15:52]                   │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:15:52]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:15:52]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:15:52]                   │ debg Wait for layers to load
[00:15:52]                   │ debg TestSubjects.find(mapLayerTOC)
[00:15:52]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:16:02]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:16:02]                   │      Wait timed out after 10067ms
[00:16:03]                   │ debg TestSubjects.find(mapLayerTOC)
[00:16:03]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:16:13]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:16:13]                   │      Wait timed out after 10139ms
[00:16:14]                   │ debg TestSubjects.find(mapLayerTOC)
[00:16:14]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:16:24]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:16:24]                   │      Wait timed out after 10129ms
[00:16:24]                   │ debg TestSubjects.find(mapLayerTOC)
[00:16:24]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:16:34]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:16:34]                   │      Wait timed out after 10108ms
[00:16:35]                   │ debg TestSubjects.find(mapLayerTOC)
[00:16:35]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:16:45]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:16:45]                   │      Wait timed out after 10142ms
[00:16:46]                   │ debg TestSubjects.find(mapLayerTOC)
[00:16:46]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:16:56]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:16:56]                   │      Wait timed out after 10131ms
[00:16:56]                   │ERROR browser[SEVERE] http://localhost:6111/api/ui_counters/_report - Failed to load resource: the server responded with a status of 400 (Bad Request)
[00:16:56]                   │ debg TestSubjects.find(mapLayerTOC)
[00:16:56]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:17:06]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:17:06]                   │      Wait timed out after 10118ms
[00:17:07]                   │ debg TestSubjects.find(mapLayerTOC)
[00:17:07]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:17:17]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:17:17]                   │      Wait timed out after 10150ms
[00:17:17]                   │ debg TestSubjects.find(mapLayerTOC)
[00:17:17]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:17:28]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:17:28]                   │      Wait timed out after 10149ms
[00:17:28]                   │ debg TestSubjects.find(mapLayerTOC)
[00:17:28]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:17:38]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:17:38]                   │      Wait timed out after 10136ms
[00:17:39]                   │ debg TestSubjects.find(mapLayerTOC)
[00:17:39]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:17:49]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:17:49]                   │      Wait timed out after 10102ms
[00:17:49]                   │ debg TestSubjects.find(mapLayerTOC)
[00:17:49]                   │ debg Find.findByCssSelector('[data-test-subj="mapLayerTOC"]') with timeout=10000
[00:18:00]                   │ debg --- retry.try error: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:18:00]                   │      Wait timed out after 10123ms
[00:18:00]                   │ info Taking screenshot "/dev/shm/workspace/parallel/1/kibana/x-pack/test/functional/screenshots/failure/maps app  auto fit map to bounds without joins should automatically fit to bounds when query is applied.png"
[00:18:00]                   │ info Current URL is: http://localhost:6111/app/maps/map/d2e73f40-e14a-11e8-a35a-370a8516603a#?_g=(filters:!(),refreshInterval:(pause:!t,value:1000),time:(from:%272015-09-20T00:00:00.000Z%27,to:%272015-09-20T01:00:00.000Z%27))&_a=(filters:!(),query:(language:kuery,query:%27machine.os.raw%20:%20%22ios%22%27))
[00:18:00]                   │ info Saving page source to: /dev/shm/workspace/parallel/1/kibana/x-pack/test/functional/failure_debug/html/maps app  auto fit map to bounds without joins should automatically fit to bounds when query is applied.html
[00:18:00]                   └- ✖ fail: maps app  auto fit map to bounds without joins should automatically fit to bounds when query is applied
[00:18:00]                   │      Error: retry.try timeout: TimeoutError: The element with .euiLoadingSpinner selector was still present after 10000 sec.
[00:18:00]                   │ Wait timed out after 10123ms
[00:18:00]                   │     at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
[00:18:00]                   │     at runMicrotasks (<anonymous>)
[00:18:00]                   │     at processTicksAndRejections (internal/process/task_queues.js:93:5)
[00:18:00]                   │       at onFailure (/dev/shm/workspace/parallel/1/kibana/test/common/services/retry/retry_for_success.ts:17:9)
[00:18:00]                   │       at retryForSuccess (/dev/shm/workspace/parallel/1/kibana/test/common/services/retry/retry_for_success.ts:57:13)
[00:18:00]                   │       at Retry.try (/dev/shm/workspace/parallel/1/kibana/test/common/services/retry/retry.ts:32:14)
[00:18:00]                   │       at GisPage.waitForLayersToLoad (test/functional/page_objects/gis_page.ts:104:7)
[00:18:00]                   │       at GisPage.setAndSubmitQuery (test/functional/page_objects/gis_page.ts:56:7)
[00:18:00]                   │       at Context.<anonymous> (test/functional/apps/maps/auto_fit_to_bounds.js:49:9)
[00:18:00]                   │       at Object.apply (/dev/shm/workspace/parallel/1/kibana/packages/kbn-test/src/functional_test_runner/lib/mocha/wrap_function.js:73:16)
[00:18:00]                   │ 
[00:18:00]                   │ 

Stack Trace

Error: retry.try timeout: TimeoutError: The element with .euiLoadingSpinner selector was still present after 10000 sec.
Wait timed out after 10123ms
    at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:93:5)
    at onFailure (/dev/shm/workspace/parallel/1/kibana/test/common/services/retry/retry_for_success.ts:17:9)
    at retryForSuccess (/dev/shm/workspace/parallel/1/kibana/test/common/services/retry/retry_for_success.ts:57:13)
    at Retry.try (/dev/shm/workspace/parallel/1/kibana/test/common/services/retry/retry.ts:32:14)
    at GisPage.waitForLayersToLoad (test/functional/page_objects/gis_page.ts:104:7)
    at GisPage.setAndSubmitQuery (test/functional/page_objects/gis_page.ts:56:7)
    at Context.<anonymous> (test/functional/apps/maps/auto_fit_to_bounds.js:49:9)
    at Object.apply (/dev/shm/workspace/parallel/1/kibana/packages/kbn-test/src/functional_test_runner/lib/mocha/wrap_function.js:73:16)

Metrics [docs]

✅ unchanged

History

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

cc @jen-huang

@jen-huang jen-huang merged commit 0d4ca46 into elastic:master Mar 4, 2021
@jen-huang jen-huang deleted the epm/default-field branch March 4, 2021 21:37
kibanamachine pushed a commit to kibanamachine/kibana that referenced this pull request Mar 4, 2021
…d` index template setting (elastic#91791)

* Set all keyword and text fields for `index.query.default_field` setting

* Update tests and snapshots

* Fix test

* Add default field limit safeguard

* Add logging when beyond limit

* Update tests to mock app context (because I added logger usage)

* Update api integration test

* Rename consts
@kibanamachine
Copy link
Contributor

💚 Backport successful

7.x / #93695

Successful backport PRs will be merged automatically after passing CI.

gmmorris added a commit to gmmorris/kibana that referenced this pull request Mar 4, 2021
…into actions/terminology-api

* 'actions/terminology-api' of github.com:gmmorris/kibana:
  Make Dashboard Unsaved Changes Space Specific (elastic#92680)
  Hide Value and Funtional boost for geolocation (elastic#93683)
  [Fleet] Set all keyword and text fields for `index.query.default_field` index template setting (elastic#91791)
  can not query the world (elastic#93556)
  [Security Solutions] Sets our default date time to be "today" instead of "Last 24 hours" to enable cachability and fixes one date math bug in the URL  (elastic#93548)
  [Security Solution][Endpoint][Admin][Policy] Register as AV os restrictions tooltip note  (elastic#93306)
  fix agg config sub agg dsl generation (elastic#93276)
kibanamachine added a commit that referenced this pull request Mar 4, 2021
…d` index template setting (#91791) (#93695)

* Set all keyword and text fields for `index.query.default_field` setting

* Update tests and snapshots

* Fix test

* Add default field limit safeguard

* Add logging when beyond limit

* Update tests to mock app context (because I added logger usage)

* Update api integration test

* Rename consts

Co-authored-by: Jen Huang <[email protected]>
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
auto-backport Deprecated - use backport:version if exact versions are needed release_note:fix Team:Fleet Team label for Observability Data Collection Fleet team v7.13.0 v8.0.0
Projects
None yet
Development

Successfully merging this pull request may close these issues.

[Fleet] query_string queries fail because index.query.default_field set to message for metric indices
7 participants