Skip to content

Conversation

@qn895
Copy link
Member

@qn895 qn895 commented Sep 1, 2021

Summary

This PR fixes an issue in the Anomaly explorer view where the charts show up as empty, which was introduced in #94396. This happens if the job is defined using multiple indices, and currently we use only the first index to search for data points within the time range. Thus, if the data points happen to be in the other indices, the chart will end up as blank. Steps to reproduce:

  • Create an index pattern with multiple names (e.g. apm-7.11*,apm-7.12*, apm-*error*)
  • Create an anomaly detection job with the that index pattern
  • Navigate to Anomaly explorer and click on cells with anomalies

Expected behavior:
The chart should show up correctly with all the data points

Before

Screen Shot 2021-09-01 at 15 32 52

After

Screen Shot 2021-09-01 at 15 33 00

Checklist

Risk Matrix

Delete this section if it is not applicable to this PR.

Before closing this PR, invite QA, stakeholders, and other developers to identify risks that should be tested prior to the change/feature release.

When forming the risk matrix, consider some of the following examples and how they may potentially impact the change:

Risk Probability Severity Mitigation/Notes
Multiple Spaces—unexpected behavior in non-default Kibana Space. Low High Integration tests will verify that all features are still supported in non-default Kibana Space and when user switches between spaces.
Multiple nodes—Elasticsearch polling might have race conditions when multiple Kibana nodes are polling for the same tasks. High Low Tasks are idempotent, so executing them multiple times will not result in logical error, but will degrade performance. To test for this case we add plenty of unit tests around this logic and document manual testing procedure.
Code should gracefully handle cases when feature X or plugin Y are disabled. Medium High Unit tests will verify that any feature flag or plugin combination still results in our service operational.
See more potential risk examples

For maintainers

@qn895 qn895 added bug Fixes for quality problems that affect the customer experience release_note:fix :ml Feature:Anomaly Detection ML anomaly detection v8.0.0 v7.16.0 v7.13.5 v7.14.2 v7.15.1 labels Sep 1, 2021
@qn895 qn895 requested a review from a team as a code owner September 1, 2021 20:45
@qn895 qn895 self-assigned this Sep 1, 2021
@elasticmachine
Copy link
Contributor

Pinging @elastic/ml-ui (:ml)

Copy link
Contributor

@darnautov darnautov left a comment

Choose a reason for hiding this comment

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

LGTM

Copy link
Contributor

@walterra walterra left a comment

Choose a reason for hiding this comment

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

Tested and Code LGTM

Copy link
Contributor

@peteharverson peteharverson left a comment

Choose a reason for hiding this comment

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

Tested against the job in the APM module, and a population job against the traces-apm*,logs-apm*,metrics-apm*,apm-* pattern, and LGTM.

@qn895
Copy link
Member Author

qn895 commented Sep 2, 2021

@elasticmachine merge upstream

@qn895 qn895 added the auto-backport Deprecated - use backport:version if exact versions are needed label Sep 2, 2021
@qn895 qn895 enabled auto-merge (squash) September 2, 2021 14:21
@qn895
Copy link
Member Author

qn895 commented Sep 2, 2021

@elasticmachine merge upstream

@qn895
Copy link
Member Author

qn895 commented Sep 2, 2021

@elasticmachine merge upstream

@qn895
Copy link
Member Author

qn895 commented Sep 3, 2021

@elasticmachine merge upstream

@qn895 qn895 merged commit 167b876 into elastic:master Sep 3, 2021
kibanamachine added a commit to kibanamachine/kibana that referenced this pull request Sep 3, 2021
…ices (elastic#110899)

Co-authored-by: Kibana Machine <42973632+kibanamachine@users.noreply.github.com>
kibanamachine added a commit to kibanamachine/kibana that referenced this pull request Sep 3, 2021
…ices (elastic#110899)

Co-authored-by: Kibana Machine <42973632+kibanamachine@users.noreply.github.com>
kibanamachine added a commit to kibanamachine/kibana that referenced this pull request Sep 3, 2021
…ices (elastic#110899)

Co-authored-by: Kibana Machine <42973632+kibanamachine@users.noreply.github.com>
@kibanamachine
Copy link
Contributor

💚 Backport successful

Status Branch Result
7.15
7.x
7.14

The backport PRs will be merged automatically after passing CI.

kibanamachine added a commit that referenced this pull request Sep 3, 2021
…ices (#110899) (#111190)

Co-authored-by: Kibana Machine <42973632+kibanamachine@users.noreply.github.com>

Co-authored-by: Quynh Nguyen <43350163+qn895@users.noreply.github.com>
kibanamachine added a commit that referenced this pull request Sep 3, 2021
…ices (#110899) (#111189)

Co-authored-by: Kibana Machine <42973632+kibanamachine@users.noreply.github.com>

Co-authored-by: Quynh Nguyen <43350163+qn895@users.noreply.github.com>
kibanamachine added a commit that referenced this pull request Sep 3, 2021
…ices (#110899) (#111188)

Co-authored-by: Kibana Machine <42973632+kibanamachine@users.noreply.github.com>

Co-authored-by: Quynh Nguyen <43350163+qn895@users.noreply.github.com>
@peteharverson peteharverson changed the title [ML] Fix Anomaly Explorer data points not showing up because of missing indices [ML] Fix Anomaly Explorer data points not showing up when index pattern has multiple indices Sep 16, 2021
@kibanamachine
Copy link
Contributor

kibanamachine commented Sep 16, 2021

💔 Build Failed

Failed CI Steps


Test Failures

Kibana Pipeline / general / Chrome UI Functional Tests.test/functional/apps/management/_scripted_fields_filter·js.management filter scripted fields "before all" hook for "should filter scripted fields"

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: management
[00:00:00]           └-> "before all" hook in "management"
[00:00:00]           └-> "before all" hook in "management"
[00:00:00]             │ info [test/functional/fixtures/es_archiver/logstash_functional] Unloading indices from "mappings.json"
[00:00:00]             │ info [test/functional/fixtures/es_archiver/logstash_functional] Unloading indices from "data.json.gz"
[00:00:01]             │ info [test/functional/fixtures/es_archiver/makelogs] Loading "mappings.json"
[00:00:01]             │ info [test/functional/fixtures/es_archiver/makelogs] Loading "data.json.gz"
[00:00:01]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.17] creating index, cause [api], templates [], shards [1]/[0]
[00:00:01]             │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.17][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.17][0]]"
[00:00:01]             │ info [test/functional/fixtures/es_archiver/makelogs] Created index "logstash-2015.09.17"
[00:00:01]             │ debg [test/functional/fixtures/es_archiver/makelogs] "logstash-2015.09.17" 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:01]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.18] creating index, cause [api], templates [], shards [1]/[0]
[00:00:01]             │ info [o.e.c.r.a.AllocationService] [node-01] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.18][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.18][0]]"
[00:00:01]             │ info [test/functional/fixtures/es_archiver/makelogs] Created index "logstash-2015.09.18"
[00:00:01]             │ debg [test/functional/fixtures/es_archiver/makelogs] "logstash-2015.09.18" 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:02]             │ info [test/functional/fixtures/es_archiver/makelogs] Indexed 101 docs into "logstash-2015.09.17"
[00:00:02]             │ info [test/functional/fixtures/es_archiver/makelogs] Indexed 301 docs into "logstash-2015.09.18"
[00:00:02]           └-: 
[00:00:02]             └-> "before all" hook in ""
[00:00:35]             └-: filter scripted fields
[00:00:35]               └-> "before all" hook for "should filter scripted fields"
[00:00:35]               └-> "before all" hook for "should filter scripted fields"
[00:00:35]                 │ info [test/functional/fixtures/es_archiver/management] Loading "mappings.json"
[00:00:35]                 │ info [test/functional/fixtures/es_archiver/management] Loading "data.json.gz"
[00:00:35]                 │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_8.0.0_001/_hzI4iEiStmGtquWHEebbA] deleting index
[00:00:35]                 │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_task_manager_8.0.0_001/3qltLoN8SE2pJd1Y59_ARg] deleting index
[00:00:35]                 │ info [test/functional/fixtures/es_archiver/management] Deleted existing index ".kibana_8.0.0_001"
[00:00:35]                 │ info [test/functional/fixtures/es_archiver/management] Deleted existing index ".kibana_task_manager_8.0.0_001"
[00:00:35]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1]
[00:00:35]                 │ info [test/functional/fixtures/es_archiver/management] Created index ".kibana_1"
[00:00:35]                 │ debg [test/functional/fixtures/es_archiver/management] ".kibana_1" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:00:35]                 │ info [test/functional/fixtures/es_archiver/management] Indexed 2 docs into ".kibana"
[00:00:35]                 │ debg Migrating saved objects
[00:00:35]                 │ proc [kibana]   log   [09:11:50.675] [info][savedobjects-service] [.kibana_task_manager] INIT -> CREATE_NEW_TARGET. took: 14ms.
[00:00:35]                 │ proc [kibana]   log   [09:11:50.677] [info][savedobjects-service] [.kibana] INIT -> WAIT_FOR_YELLOW_SOURCE. took: 17ms.
[00:00:35]                 │ proc [kibana]   log   [09:11:50.682] [info][savedobjects-service] [.kibana] WAIT_FOR_YELLOW_SOURCE -> CHECK_UNKNOWN_DOCUMENTS. took: 5ms.
[00:00:35]                 │ 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:35]                 │ 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:35]                 │ proc [kibana]   log   [09:11:50.691] [info][savedobjects-service] [.kibana] CHECK_UNKNOWN_DOCUMENTS -> SET_SOURCE_WRITE_BLOCK. took: 9ms.
[00:00:35]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_1/KWhKjwjsQjeccNIzpWYlmA]]
[00:00:35]                 │ERROR browser[SEVERE] http://localhost:61141/api/index_patterns/has_user_index_pattern - Failed to load resource: the server responded with a status of 400 (Bad Request)
[00:00:35]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_1]
[00:00:35]                 │ proc [kibana]   log   [09:11:50.768] [info][savedobjects-service] [.kibana_task_manager] CREATE_NEW_TARGET -> MARK_VERSION_INDEX_READY. took: 93ms.
[00:00:36]                 │ proc [kibana]   log   [09:11:50.790] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CALCULATE_EXCLUDE_FILTERS. took: 99ms.
[00:00:36]                 │ proc [kibana]   log   [09:11:50.797] [info][savedobjects-service] [.kibana] CALCULATE_EXCLUDE_FILTERS -> CREATE_REINDEX_TEMP. took: 7ms.
[00:00:36]                 │ proc [kibana]   log   [09:11:50.817] [info][savedobjects-service] [.kibana_task_manager] MARK_VERSION_INDEX_READY -> DONE. took: 49ms.
[00:00:36]                 │ proc [kibana]   log   [09:11:50.818] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 157ms
[00:00:36]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_reindex_temp] creating index, cause [api], templates [], shards [1]/[1]
[00:00:36]                 │ 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:36]                 │ proc [kibana]   log   [09:11:50.894] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP_OPEN_PIT. took: 97ms.
[00:00:36]                 │ proc [kibana]   log   [09:11:50.921] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_OPEN_PIT -> REINDEX_SOURCE_TO_TEMP_READ. took: 27ms.
[00:00:36]                 │ proc [kibana]   log   [09:11:50.931] [info][savedobjects-service] [.kibana] Starting to process 2 documents.
[00:00:36]                 │ proc [kibana]   log   [09:11:50.931] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_INDEX. took: 10ms.
[00:00:36]                 │ proc [kibana]   log   [09:11:50.941] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX -> REINDEX_SOURCE_TO_TEMP_INDEX_BULK. took: 10ms.
[00:00:36]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_reindex_temp/8lDsBKH7TWKQMpamX8GazA] update_mapping [_doc]
[00:00:36]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_reindex_temp/8lDsBKH7TWKQMpamX8GazA] update_mapping [_doc]
[00:00:36]                 │ proc [kibana]   log   [09:11:51.012] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX_BULK -> REINDEX_SOURCE_TO_TEMP_READ. took: 71ms.
[00:00:36]                 │ proc [kibana]   log   [09:11:51.022] [info][savedobjects-service] [.kibana] Processed 2 documents out of 2.
[00:00:36]                 │ proc [kibana]   log   [09:11:51.023] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_CLOSE_PIT. took: 10ms.
[00:00:36]                 │ proc [kibana]   log   [09:11:51.027] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_CLOSE_PIT -> SET_TEMP_WRITE_BLOCK. took: 5ms.
[00:00:36]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_8.0.0_reindex_temp/8lDsBKH7TWKQMpamX8GazA]]
[00:00:36]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_8.0.0_reindex_temp]
[00:00:36]                 │ proc [kibana]   log   [09:11:51.076] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET. took: 49ms.
[00:00:36]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] applying create index request using existing index [.kibana_8.0.0_reindex_temp] metadata
[00:00:36]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_001] creating index, cause [clone_index], templates [], shards [1]/[1]
[00:00:36]                 │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_8.0.0_001]
[00:00:36]                 │ info [o.e.c.r.a.AllocationService] [node-01] current.health="RED" message="Cluster health status changed from [YELLOW] to [RED] (reason: [index [.kibana_8.0.0_001] created])." previous.health="YELLOW" reason="index [.kibana_8.0.0_001] created"
[00:00:46]                 │ proc [kibana]   log   [09:12:01.107] [warning][environment] Detected an unhandled Promise rejection.
[00:00:46]                 │ proc [kibana] {"error":{"root_cause":[{"type":"index_not_found_exception","reason":"no such index [.kibana_8.0.0] and [require_alias] request flag is [true] and [.kibana_8.0.0] is not an alias","index_uuid":"_na_","index":".kibana_8.0.0"}],"type":"index_not_found_exception","reason":"no such index [.kibana_8.0.0] and [require_alias] request flag is [true] and [.kibana_8.0.0] is not an alias","index_uuid":"_na_","index":".kibana_8.0.0"},"status":404}
[00:01:06]                 │ proc [kibana]   log   [09:12:21.081] [error][savedobjects-service] [.kibana] Action failed with 'Request timed out'. Retrying attempt 1 in 2 seconds.
[00:01:06]                 │ proc [kibana]   log   [09:12:21.082] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> CLONE_TEMP_TO_TARGET. took: 30005ms.
[00:02:35]                 │ERROR [migrate saved objects] request failed (attempt=1/5): socket hang up
[00:02:36]                 │ proc [kibana]   log   [09:13:51.701] [info][savedobjects-service] [.kibana] INIT -> WAIT_FOR_YELLOW_SOURCE. took: 5ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.703] [info][savedobjects-service] [.kibana_task_manager] INIT -> OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT. took: 6ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.704] [info][savedobjects-service] [.kibana] WAIT_FOR_YELLOW_SOURCE -> CHECK_UNKNOWN_DOCUMENTS. took: 4ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.706] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT -> OUTDATED_DOCUMENTS_SEARCH_READ. took: 3ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.712] [info][savedobjects-service] [.kibana] CHECK_UNKNOWN_DOCUMENTS -> SET_SOURCE_WRITE_BLOCK. took: 8ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.714] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_READ -> OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT. took: 8ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.715] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CALCULATE_EXCLUDE_FILTERS. took: 3ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.717] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT -> UPDATE_TARGET_MAPPINGS. took: 3ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.722] [info][savedobjects-service] [.kibana] CALCULATE_EXCLUDE_FILTERS -> CREATE_REINDEX_TEMP. took: 7ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.732] [info][savedobjects-service] [.kibana_task_manager] UPDATE_TARGET_MAPPINGS -> UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK. took: 15ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.734] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP_OPEN_PIT. took: 12ms.
[00:02:36]                 │ info [o.e.t.LoggingTaskListener] [node-01] 1825 finished with response BulkByScrollResponse[took=2.6ms,timed_out=false,sliceId=null,updated=0,created=0,deleted=0,batches=0,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:02:36]                 │ proc [kibana]   log   [09:13:51.738] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_OPEN_PIT -> REINDEX_SOURCE_TO_TEMP_READ. took: 4ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.746] [info][savedobjects-service] [.kibana] Starting to process 2 documents.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.747] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_INDEX. took: 8ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.750] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX -> REINDEX_SOURCE_TO_TEMP_INDEX_BULK. took: 4ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.756] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX_BULK -> REINDEX_SOURCE_TO_TEMP_CLOSE_PIT. took: 6ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.760] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_CLOSE_PIT -> SET_TEMP_WRITE_BLOCK. took: 4ms.
[00:02:36]                 │ proc [kibana]   log   [09:13:51.764] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET. took: 4ms.
[00:02:37]                 │ proc [kibana]   log   [09:13:51.838] [info][savedobjects-service] [.kibana_task_manager] UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK -> DONE. took: 106ms.
[00:02:37]                 │ proc [kibana]   log   [09:13:51.838] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 141ms
[00:03:08]                 │ proc [kibana]   log   [09:14:23.094] [error][savedobjects-service] [.kibana] Action failed with 'Request timed out'. Retrying attempt 2 in 4 seconds.
[00:03:08]                 │ proc [kibana]   log   [09:14:23.095] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> CLONE_TEMP_TO_TARGET. took: 122013ms.
[00:04:36]                 │ERROR [migrate saved objects] request failed (attempt=2/5): socket hang up
[00:04:37]                 │ proc [kibana]   log   [09:15:51.775] [error][savedobjects-service] [.kibana] Action failed with 'Request timed out'. Retrying attempt 1 in 2 seconds.
[00:04:37]                 │ proc [kibana]   log   [09:15:51.776] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> CLONE_TEMP_TO_TARGET. took: 120011ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.720] [info][savedobjects-service] [.kibana_task_manager] INIT -> OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT. took: 3ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.722] [info][savedobjects-service] [.kibana] INIT -> WAIT_FOR_YELLOW_SOURCE. took: 6ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.724] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT -> OUTDATED_DOCUMENTS_SEARCH_READ. took: 4ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.725] [info][savedobjects-service] [.kibana] WAIT_FOR_YELLOW_SOURCE -> CHECK_UNKNOWN_DOCUMENTS. took: 3ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.730] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_READ -> OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT. took: 6ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.732] [info][savedobjects-service] [.kibana] CHECK_UNKNOWN_DOCUMENTS -> SET_SOURCE_WRITE_BLOCK. took: 7ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.734] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT -> UPDATE_TARGET_MAPPINGS. took: 4ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.736] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CALCULATE_EXCLUDE_FILTERS. took: 4ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.741] [info][savedobjects-service] [.kibana] CALCULATE_EXCLUDE_FILTERS -> CREATE_REINDEX_TEMP. took: 5ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.747] [info][savedobjects-service] [.kibana_task_manager] UPDATE_TARGET_MAPPINGS -> UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK. took: 13ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.749] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP_OPEN_PIT. took: 8ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.751] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_OPEN_PIT -> REINDEX_SOURCE_TO_TEMP_READ. took: 2ms.
[00:04:38]                 │ info [o.e.t.LoggingTaskListener] [node-01] 2374 finished with response BulkByScrollResponse[took=2.1ms,timed_out=false,sliceId=null,updated=0,created=0,deleted=0,batches=0,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:04:38]                 │ proc [kibana]   log   [09:15:53.758] [info][savedobjects-service] [.kibana] Starting to process 2 documents.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.758] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_INDEX. took: 7ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.761] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX -> REINDEX_SOURCE_TO_TEMP_INDEX_BULK. took: 3ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.765] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX_BULK -> REINDEX_SOURCE_TO_TEMP_CLOSE_PIT. took: 4ms.
[00:04:38]                 │ proc [kibana]   log   [09:15:53.768] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_CLOSE_PIT -> SET_TEMP_WRITE_BLOCK. took: 3ms.
[00:04:39]                 │ proc [kibana]   log   [09:15:53.773] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET. took: 5ms.
[00:04:39]                 │ proc [kibana]   log   [09:15:53.852] [info][savedobjects-service] [.kibana_task_manager] UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK -> DONE. took: 105ms.
[00:04:39]                 │ proc [kibana]   log   [09:15:53.853] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 136ms
[00:04:47]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_task_manager_8.0.0_001/CjBRFnWETKmEzlVgEjbPaQ] update_mapping [_doc]
[00:05:12]                 │ proc [kibana]   log   [09:16:27.105] [error][savedobjects-service] [.kibana] Action failed with 'Request timed out'. Retrying attempt 3 in 8 seconds.
[00:05:12]                 │ proc [kibana]   log   [09:16:27.106] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> CLONE_TEMP_TO_TARGET. took: 124011ms.
[00:06:35]                 └- ✖ fail: management  filter scripted fields "before all" hook for "should filter scripted fields"
[00:06:35]                 │      Error: Timeout of 360000ms exceeded. For async tests and hooks, ensure "done()" is called; if returning a Promise, ensure it resolves. (/dev/shm/workspace/parallel/14/kibana/test/functional/apps/management/_scripted_fields_filter.js)
[00:06:35]                 │       at listOnTimeout (internal/timers.js:557:17)
[00:06:35]                 │       at processTimers (internal/timers.js:500:7)
[00:06:35]                 │ 
[00:06:35]                 │ 

Stack Trace

Error: Timeout of 360000ms exceeded. For async tests and hooks, ensure "done()" is called; if returning a Promise, ensure it resolves. (/dev/shm/workspace/parallel/14/kibana/test/functional/apps/management/_scripted_fields_filter.js)
    at listOnTimeout (internal/timers.js:557:17)
    at processTimers (internal/timers.js:500:7)

Kibana Pipeline / general / Chrome UI Functional Tests.test/functional/apps/dashboard/panel_context_menu·ts.dashboard app using legacy data dashboard panel context menu "before all" hook for "are hidden in view mode"

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: dashboard app
[00:00:00]           └-> "before all" hook in "dashboard app"
[00:00:00]           └-: using legacy data
[00:00:00]             └-> "before all" hook in "using legacy data"
[00:00:00]             └-> "before all" hook: loadLogstash in "using legacy data"
[00:00:00]               │ info [test/functional/fixtures/es_archiver/logstash_functional] Loading "mappings.json"
[00:00:00]               │ info [test/functional/fixtures/es_archiver/logstash_functional] Loading "data.json.gz"
[00:00:00]               │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0]
[00:00:00]               │ info [o.e.c.r.a.AllocationService] [node-01] 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 [test/functional/fixtures/es_archiver/logstash_functional] Created index "logstash-2015.09.22"
[00:00:00]               │ 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:00]               │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0]
[00:00:00]               │ info [o.e.c.r.a.AllocationService] [node-01] 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 [test/functional/fixtures/es_archiver/logstash_functional] Created index "logstash-2015.09.20"
[00:00:00]               │ 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:00]               │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0]
[00:00:00]               │ info [o.e.c.r.a.AllocationService] [node-01] 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 [test/functional/fixtures/es_archiver/logstash_functional] Created index "logstash-2015.09.21"
[00:00:00]               │ 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:10]               │ info progress: 9921
[00:00:13]               │ info [test/functional/fixtures/es_archiver/logstash_functional] Indexed 4633 docs into "logstash-2015.09.22"
[00:00:13]               │ info [test/functional/fixtures/es_archiver/logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:00:13]               │ info [test/functional/fixtures/es_archiver/logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:13:16]             └-: dashboard panel context menu
[00:13:16]               └-> "before all" hook for "are hidden in view mode"
[00:13:16]               └-> "before all" hook for "are hidden in view mode"
[00:13:16]                 │ debg load kibana index with visualizations and log data
[00:13:16]                 │ info [test/functional/fixtures/es_archiver/dashboard/legacy] Loading "mappings.json"
[00:13:16]                 │ info [test/functional/fixtures/es_archiver/dashboard/legacy] Loading "data.json.gz"
[00:13:17]                 │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_task_manager_8.0.0_001/TvUxwtyPTEC2GKB71zEZTg] deleting index
[00:13:17]                 │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_8.0.0_001/1QaghVDBQ32ufmg0gf5mJw] deleting index
[00:13:17]                 │ info [test/functional/fixtures/es_archiver/dashboard/legacy] Deleted existing index ".kibana_8.0.0_001"
[00:13:17]                 │ info [test/functional/fixtures/es_archiver/dashboard/legacy] Deleted existing index ".kibana_task_manager_8.0.0_001"
[00:13:17]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1]
[00:13:17]                 │ info [test/functional/fixtures/es_archiver/dashboard/legacy] Created index ".kibana_1"
[00:13:17]                 │ debg [test/functional/fixtures/es_archiver/dashboard/legacy] ".kibana_1" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:13:17]                 │ info [test/functional/fixtures/es_archiver/dashboard/legacy] Indexed 9 docs into ".kibana"
[00:13:17]                 │ debg Migrating saved objects
[00:13:17]                 │ proc [kibana]   log   [09:12:41.253] [info][savedobjects-service] [.kibana_task_manager] INIT -> CREATE_NEW_TARGET. took: 5ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.256] [info][savedobjects-service] [.kibana] INIT -> WAIT_FOR_YELLOW_SOURCE. took: 9ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.259] [info][savedobjects-service] [.kibana] WAIT_FOR_YELLOW_SOURCE -> CHECK_UNKNOWN_DOCUMENTS. took: 3ms.
[00:13:17]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_task_manager_8.0.0_001] creating index, cause [api], templates [], shards [1]/[1]
[00:13:17]                 │ 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:13:17]                 │ proc [kibana]   log   [09:12:41.265] [info][savedobjects-service] [.kibana] CHECK_UNKNOWN_DOCUMENTS -> SET_SOURCE_WRITE_BLOCK. took: 6ms.
[00:13:17]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_1/ib83I1vxQwCki8vvfu4Tdg]]
[00:13:17]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_1]
[00:13:17]                 │ proc [kibana]   log   [09:12:41.322] [info][savedobjects-service] [.kibana_task_manager] CREATE_NEW_TARGET -> MARK_VERSION_INDEX_READY. took: 69ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.336] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CALCULATE_EXCLUDE_FILTERS. took: 71ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.340] [info][savedobjects-service] [.kibana] CALCULATE_EXCLUDE_FILTERS -> CREATE_REINDEX_TEMP. took: 4ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.353] [info][savedobjects-service] [.kibana_task_manager] MARK_VERSION_INDEX_READY -> DONE. took: 31ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.353] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 105ms
[00:13:17]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_reindex_temp] creating index, cause [api], templates [], shards [1]/[1]
[00:13:17]                 │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_8.0.0_reindex_temp]
[00:13:17]                 │ proc [kibana]   log   [09:12:41.400] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP_OPEN_PIT. took: 60ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.407] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_OPEN_PIT -> REINDEX_SOURCE_TO_TEMP_READ. took: 7ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.414] [info][savedobjects-service] [.kibana] Starting to process 9 documents.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.415] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_INDEX. took: 7ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.432] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX -> REINDEX_SOURCE_TO_TEMP_INDEX_BULK. took: 18ms.
[00:13:17]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_reindex_temp/re0F7yd6SGSZYYgb-e4pQw] update_mapping [_doc]
[00:13:17]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_reindex_temp/re0F7yd6SGSZYYgb-e4pQw] update_mapping [_doc]
[00:13:17]                 │ proc [kibana]   log   [09:12:41.485] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX_BULK -> REINDEX_SOURCE_TO_TEMP_READ. took: 53ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.490] [info][savedobjects-service] [.kibana] Processed 9 documents out of 9.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.490] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_CLOSE_PIT. took: 5ms.
[00:13:17]                 │ proc [kibana]   log   [09:12:41.493] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_CLOSE_PIT -> SET_TEMP_WRITE_BLOCK. took: 3ms.
[00:13:17]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_8.0.0_reindex_temp/re0F7yd6SGSZYYgb-e4pQw]]
[00:13:17]                 │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_8.0.0_reindex_temp]
[00:13:17]                 │ proc [kibana]   log   [09:12:41.529] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET. took: 36ms.
[00:13:17]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] applying create index request using existing index [.kibana_8.0.0_reindex_temp] metadata
[00:13:17]                 │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_8.0.0_001] creating index, cause [clone_index], templates [], shards [1]/[1]
[00:13:17]                 │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_8.0.0_001]
[00:13:17]                 │ info [o.e.c.r.a.AllocationService] [node-01] current.health="RED" message="Cluster health status changed from [YELLOW] to [RED] (reason: [index [.kibana_8.0.0_001] created])." previous.health="YELLOW" reason="index [.kibana_8.0.0_001] created"
[00:13:47]                 │ proc [kibana]   log   [09:13:11.533] [error][savedobjects-service] [.kibana] Action failed with 'Request timed out'. Retrying attempt 1 in 2 seconds.
[00:13:47]                 │ proc [kibana]   log   [09:13:11.534] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> CLONE_TEMP_TO_TARGET. took: 30004ms.
[00:14:47]                 │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_task_manager_8.0.0_001/4ELxiV6SRlKOgc9QRSc3Pw] update_mapping [_doc]
[00:15:17]                 │ERROR [migrate saved objects] request failed (attempt=1/5): socket hang up
[00:15:18]                 │ proc [kibana]   log   [09:14:42.269] [info][savedobjects-service] [.kibana] INIT -> WAIT_FOR_YELLOW_SOURCE. took: 4ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.271] [info][savedobjects-service] [.kibana_task_manager] INIT -> OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT. took: 5ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.272] [info][savedobjects-service] [.kibana] WAIT_FOR_YELLOW_SOURCE -> CHECK_UNKNOWN_DOCUMENTS. took: 3ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.275] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT -> OUTDATED_DOCUMENTS_SEARCH_READ. took: 4ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.278] [info][savedobjects-service] [.kibana] CHECK_UNKNOWN_DOCUMENTS -> SET_SOURCE_WRITE_BLOCK. took: 6ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.279] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_READ -> OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT. took: 4ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.281] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CALCULATE_EXCLUDE_FILTERS. took: 3ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.282] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT -> UPDATE_TARGET_MAPPINGS. took: 3ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.285] [info][savedobjects-service] [.kibana] CALCULATE_EXCLUDE_FILTERS -> CREATE_REINDEX_TEMP. took: 4ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.293] [info][savedobjects-service] [.kibana_task_manager] UPDATE_TARGET_MAPPINGS -> UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK. took: 11ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.295] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP_OPEN_PIT. took: 10ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.297] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_OPEN_PIT -> REINDEX_SOURCE_TO_TEMP_READ. took: 2ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.303] [info][savedobjects-service] [.kibana] Starting to process 9 documents.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.303] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_INDEX. took: 6ms.
[00:15:18]                 │ info [o.e.t.LoggingTaskListener] [node-01] 11218 finished with response BulkByScrollResponse[took=16.8ms,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:15:18]                 │ proc [kibana]   log   [09:14:42.326] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX -> REINDEX_SOURCE_TO_TEMP_INDEX_BULK. took: 23ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.334] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX_BULK -> REINDEX_SOURCE_TO_TEMP_CLOSE_PIT. took: 8ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.338] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_CLOSE_PIT -> SET_TEMP_WRITE_BLOCK. took: 4ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.341] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET. took: 3ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.398] [info][savedobjects-service] [.kibana_task_manager] UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK -> DONE. took: 105ms.
[00:15:18]                 │ proc [kibana]   log   [09:14:42.399] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 133ms
[00:15:49]                 │ proc [kibana]   log   [09:15:13.548] [error][savedobjects-service] [.kibana] Action failed with 'Request timed out'. Retrying attempt 2 in 4 seconds.
[00:15:49]                 │ proc [kibana]   log   [09:15:13.548] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> CLONE_TEMP_TO_TARGET. took: 122015ms.
[00:17:18]                 │ERROR [migrate saved objects] request failed (attempt=2/5): socket hang up
[00:17:18]                 │ proc [kibana]   log   [09:16:42.350] [error][savedobjects-service] [.kibana] Action failed with 'Request timed out'. Retrying attempt 1 in 2 seconds.
[00:17:18]                 │ proc [kibana]   log   [09:16:42.351] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> CLONE_TEMP_TO_TARGET. took: 120009ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.290] [info][savedobjects-service] [.kibana_task_manager] INIT -> OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT. took: 3ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.295] [info][savedobjects-service] [.kibana] INIT -> WAIT_FOR_YELLOW_SOURCE. took: 9ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.296] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT -> OUTDATED_DOCUMENTS_SEARCH_READ. took: 6ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.298] [info][savedobjects-service] [.kibana] WAIT_FOR_YELLOW_SOURCE -> CHECK_UNKNOWN_DOCUMENTS. took: 3ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.302] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_READ -> OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT. took: 6ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.304] [info][savedobjects-service] [.kibana] CHECK_UNKNOWN_DOCUMENTS -> SET_SOURCE_WRITE_BLOCK. took: 6ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.305] [info][savedobjects-service] [.kibana_task_manager] OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT -> UPDATE_TARGET_MAPPINGS. took: 3ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.307] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CALCULATE_EXCLUDE_FILTERS. took: 3ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.313] [info][savedobjects-service] [.kibana] CALCULATE_EXCLUDE_FILTERS -> CREATE_REINDEX_TEMP. took: 6ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.318] [info][savedobjects-service] [.kibana_task_manager] UPDATE_TARGET_MAPPINGS -> UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK. took: 13ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.320] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP_OPEN_PIT. took: 7ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.324] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_OPEN_PIT -> REINDEX_SOURCE_TO_TEMP_READ. took: 4ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.346] [info][savedobjects-service] [.kibana] Starting to process 9 documents.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.346] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_INDEX. took: 22ms.
[00:17:20]                 │ info [o.e.t.LoggingTaskListener] [node-01] 11805 finished with response BulkByScrollResponse[took=30.9ms,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:17:20]                 │ proc [kibana]   log   [09:16:44.364] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX -> REINDEX_SOURCE_TO_TEMP_INDEX_BULK. took: 18ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.368] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX_BULK -> REINDEX_SOURCE_TO_TEMP_CLOSE_PIT. took: 4ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.372] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_CLOSE_PIT -> SET_TEMP_WRITE_BLOCK. took: 4ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.377] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET. took: 5ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.423] [info][savedobjects-service] [.kibana_task_manager] UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK -> DONE. took: 105ms.
[00:17:20]                 │ proc [kibana]   log   [09:16:44.424] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 137ms
[00:17:53]                 │ proc [kibana]   log   [09:17:17.557] [error][savedobjects-service] [.kibana] Action failed with 'Request timed out'. Retrying attempt 3 in 8 seconds.
[00:17:53]                 │ proc [kibana]   log   [09:17:17.558] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> CLONE_TEMP_TO_TARGET. took: 124009ms.
[00:19:16]                 └- ✖ fail: dashboard app using legacy data dashboard panel context menu "before all" hook for "are hidden in view mode"
[00:19:16]                 │      Error: Timeout of 360000ms exceeded. For async tests and hooks, ensure "done()" is called; if returning a Promise, ensure it resolves. (/dev/shm/workspace/parallel/4/kibana/test/functional/apps/dashboard/panel_context_menu.ts)
[00:19:16]                 │       at listOnTimeout (internal/timers.js:557:17)
[00:19:16]                 │       at processTimers (internal/timers.js:500:7)
[00:19:16]                 │ 
[00:19:16]                 │ 

Stack Trace

Error: Timeout of 360000ms exceeded. For async tests and hooks, ensure "done()" is called; if returning a Promise, ensure it resolves. (/dev/shm/workspace/parallel/4/kibana/test/functional/apps/dashboard/panel_context_menu.ts)
    at listOnTimeout (internal/timers.js:557:17)
    at processTimers (internal/timers.js:500:7)

Kibana Pipeline / general / Chrome X-Pack UI Functional Tests.x-pack/test/functional/apps/ml/anomaly_detection/saved_search_job·ts.machine learning anomaly detection saved search with lucene query job creation runs the job and displays it correctly in the job list

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: machine learning
[00:00:00]           └-> "before all" hook in "machine learning"
[00:00:00]           └-: 
[00:00:00]             └-> "before all" hook in ""
[00:00:00]             └-> "before all" hook in ""
[00:00:00]               │ debg creating role ft_ml_source
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_ml_source]
[00:00:00]               │ debg creating role ft_ml_source_readonly
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_ml_source_readonly]
[00:00:00]               │ debg creating role ft_ml_dest
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_ml_dest]
[00:00:00]               │ debg creating role ft_ml_dest_readonly
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_ml_dest_readonly]
[00:00:00]               │ debg creating role ft_ml_ui_extras
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_ml_ui_extras]
[00:00:00]               │ debg creating role ft_default_space_ml_all
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_default_space_ml_all]
[00:00:00]               │ debg creating role ft_default_space1_ml_all
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_default_space1_ml_all]
[00:00:00]               │ debg creating role ft_all_spaces_ml_all
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_all_spaces_ml_all]
[00:00:00]               │ debg creating role ft_default_space_ml_read
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_default_space_ml_read]
[00:00:00]               │ debg creating role ft_default_space1_ml_read
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_default_space1_ml_read]
[00:00:00]               │ debg creating role ft_all_spaces_ml_read
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_all_spaces_ml_read]
[00:00:00]               │ debg creating role ft_default_space_ml_none
[00:00:00]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [node-01] added role [ft_default_space_ml_none]
[00:00:00]               │ debg creating user ft_ml_poweruser
[00:00:00]               │ info [o.e.x.s.a.u.TransportPutUserAction] [node-01] added user [ft_ml_poweruser]
[00:00:00]               │ debg created user ft_ml_poweruser
[00:00:00]               │ debg creating user ft_ml_poweruser_spaces
[00:00:00]               │ info [o.e.x.s.a.u.TransportPutUserAction] [node-01] added user [ft_ml_poweruser_spaces]
[00:00:00]               │ debg created user ft_ml_poweruser_spaces
[00:00:00]               │ debg creating user ft_ml_poweruser_space1
[00:00:00]               │ info [o.e.x.s.a.u.TransportPutUserAction] [node-01] added user [ft_ml_poweruser_space1]
[00:00:00]               │ debg created user ft_ml_poweruser_space1
[00:00:00]               │ debg creating user ft_ml_poweruser_all_spaces
[00:00:00]               │ info [o.e.x.s.a.u.TransportPutUserAction] [node-01] added user [ft_ml_poweruser_all_spaces]
[00:00:00]               │ debg created user ft_ml_poweruser_all_spaces
[00:00:00]               │ debg creating user ft_ml_viewer
[00:00:01]               │ info [o.e.x.s.a.u.TransportPutUserAction] [node-01] added user [ft_ml_viewer]
[00:00:01]               │ debg created user ft_ml_viewer
[00:00:01]               │ debg creating user ft_ml_viewer_spaces
[00:00:01]               │ info [o.e.x.s.a.u.TransportPutUserAction] [node-01] added user [ft_ml_viewer_spaces]
[00:00:01]               │ debg created user ft_ml_viewer_spaces
[00:00:01]               │ debg creating user ft_ml_viewer_space1
[00:00:01]               │ info [o.e.x.s.a.u.TransportPutUserAction] [node-01] added user [ft_ml_viewer_space1]
[00:00:01]               │ debg created user ft_ml_viewer_space1
[00:00:01]               │ debg creating user ft_ml_viewer_all_spaces
[00:00:01]               │ info [o.e.x.s.a.u.TransportPutUserAction] [node-01] added user [ft_ml_viewer_all_spaces]
[00:00:01]               │ debg created user ft_ml_viewer_all_spaces
[00:00:01]               │ debg creating user ft_ml_unauthorized
[00:00:01]               │ info [o.e.x.s.a.u.TransportPutUserAction] [node-01] added user [ft_ml_unauthorized]
[00:00:01]               │ debg created user ft_ml_unauthorized
[00:00:01]               │ debg creating user ft_ml_unauthorized_spaces
[00:00:01]               │ info [o.e.x.s.a.u.TransportPutUserAction] [node-01] added user [ft_ml_unauthorized_spaces]
[00:00:01]               │ debg created user ft_ml_unauthorized_spaces
[00:09:04]             └-: anomaly detection
[00:09:04]               └-> "before all" hook in "anomaly detection"
[00:17:51]               └-: saved search
[00:17:51]                 └-> "before all" hook in "saved search"
[00:17:51]                 └-> "before all" hook in "saved search"
[00:17:51]                   │ info [x-pack/test/functional/es_archives/ml/farequote] Loading "mappings.json"
[00:17:51]                   │ info [x-pack/test/functional/es_archives/ml/farequote] Loading "data.json.gz"
[00:17:51]                   │ info [x-pack/test/functional/es_archives/ml/farequote] Skipped restore for existing index "ft_farequote"
[00:17:52]                   │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:17:52]                   │ debg  > Found 'f3a403e0-16ca-11ec-99ac-319bcace294a'
[00:17:52]                   │ debg Index pattern with title 'ft_farequote' already exists. Nothing to create.
[00:17:52]                   │ debg Searching for 'search' with title 'ft_farequote_filter'...
[00:17:52]                   │ debg  > Not found
[00:17:52]                   │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:17:52]                   │ debg  > Found 'f3a403e0-16ca-11ec-99ac-319bcace294a'
[00:17:52]                   │ debg Creating saved search with title 'ft_farequote_filter'
[00:17:52]                   │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_8.0.0_001/z3_o1kLoTDyyMrtK7zY0dw] update_mapping [_doc]
[00:17:52]                   │ debg Waiting up to 5000ms for search with title 'ft_farequote_filter' to exist...
[00:17:52]                   │ debg Searching for 'search' with title 'ft_farequote_filter'...
[00:17:52]                   │ debg  > Found '4bc87d60-16cd-11ec-99ac-319bcace294a'
[00:17:52]                   │ debg  > Created with id '4bc87d60-16cd-11ec-99ac-319bcace294a'
[00:17:52]                   │ debg Searching for 'search' with title 'ft_farequote_lucene'...
[00:17:52]                   │ debg  > Not found
[00:17:52]                   │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:17:52]                   │ debg  > Found 'f3a403e0-16ca-11ec-99ac-319bcace294a'
[00:17:52]                   │ debg Creating saved search with title 'ft_farequote_lucene'
[00:17:53]                   │ debg Waiting up to 5000ms for search with title 'ft_farequote_lucene' to exist...
[00:17:53]                   │ debg Searching for 'search' with title 'ft_farequote_lucene'...
[00:17:53]                   │ debg  > Found '4c02a030-16cd-11ec-99ac-319bcace294a'
[00:17:53]                   │ debg  > Created with id '4c02a030-16cd-11ec-99ac-319bcace294a'
[00:17:53]                   │ debg Searching for 'search' with title 'ft_farequote_kuery'...
[00:17:53]                   │ debg  > Not found
[00:17:53]                   │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:17:53]                   │ debg  > Found 'f3a403e0-16ca-11ec-99ac-319bcace294a'
[00:17:53]                   │ debg Creating saved search with title 'ft_farequote_kuery'
[00:17:54]                   │ debg Waiting up to 5000ms for search with title 'ft_farequote_kuery' to exist...
[00:17:54]                   │ debg Searching for 'search' with title 'ft_farequote_kuery'...
[00:17:54]                   │ debg  > Found '4c9b36b0-16cd-11ec-99ac-319bcace294a'
[00:17:54]                   │ debg  > Created with id '4c9b36b0-16cd-11ec-99ac-319bcace294a'
[00:17:54]                   │ debg Searching for 'search' with title 'ft_farequote_filter_and_lucene'...
[00:17:54]                   │ debg  > Not found
[00:17:54]                   │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:17:54]                   │ debg  > Found 'f3a403e0-16ca-11ec-99ac-319bcace294a'
[00:17:54]                   │ debg Creating saved search with title 'ft_farequote_filter_and_lucene'
[00:17:55]                   │ debg Waiting up to 5000ms for search with title 'ft_farequote_filter_and_lucene' to exist...
[00:17:55]                   │ debg Searching for 'search' with title 'ft_farequote_filter_and_lucene'...
[00:17:55]                   │ debg  > Found '4d38fd50-16cd-11ec-99ac-319bcace294a'
[00:17:55]                   │ debg  > Created with id '4d38fd50-16cd-11ec-99ac-319bcace294a'
[00:17:55]                   │ debg Searching for 'search' with title 'ft_farequote_filter_and_kuery'...
[00:17:55]                   │ debg  > Not found
[00:17:55]                   │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:17:55]                   │ debg  > Found 'f3a403e0-16ca-11ec-99ac-319bcace294a'
[00:17:55]                   │ debg Creating saved search with title 'ft_farequote_filter_and_kuery'
[00:17:56]                   │ debg Waiting up to 5000ms for search with title 'ft_farequote_filter_and_kuery' to exist...
[00:17:56]                   │ debg Searching for 'search' with title 'ft_farequote_filter_and_kuery'...
[00:17:56]                   │ debg  > Found '4dd27e30-16cd-11ec-99ac-319bcace294a'
[00:17:56]                   │ debg  > Created with id '4dd27e30-16cd-11ec-99ac-319bcace294a'
[00:17:56]                   │ debg applying update to kibana config: {"dateFormat:tz":"UTC"}
[00:17:57]                   │ debg SecurityPage.forceLogout
[00:17:57]                   │ debg Find.existsByDisplayedByCssSelector('.login-form') with timeout=100
[00:17:57]                   │ debg --- retry.tryForTime error: .login-form is not displayed
[00:17:58]                   │ debg Redirecting to /logout to force the logout
[00:17:58]                   │ debg Waiting on the login form to appear
[00:17:58]                   │ debg Waiting for Login Page to appear.
[00:17:58]                   │ debg Waiting up to 100000ms for login page...
[00:17:58]                   │ debg browser[INFO] http://localhost:61111/logout?_t=1631783159750 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:17:58]                   │
[00:17:58]                   │ debg browser[INFO] http://localhost:61111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:17:58]                   │ debg Find.existsByDisplayedByCssSelector('.login-form') with timeout=2500
[00:18:01]                   │ debg browser[INFO] http://localhost:61111/login?msg=LOGGED_OUT 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:18:01]                   │
[00:18:01]                   │ debg browser[INFO] http://localhost:61111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:18:01]                   │ debg --- retry.tryForTime error: .login-form is not displayed
[00:18:01]                   │ERROR browser[SEVERE] http://localhost:61111/api/licensing/info - Failed to load resource: the server responded with a status of 401 (Unauthorized)
[00:18:02]                   │ debg Find.existsByDisplayedByCssSelector('.login-form') with timeout=2500
[00:18:02]                   │ debg TestSubjects.exists(loginForm)
[00:18:02]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="loginForm"]') with timeout=2500
[00:18:02]                   │ debg Waiting for Login Form to appear.
[00:18:02]                   │ debg Waiting up to 100000ms for login form...
[00:18:02]                   │ debg TestSubjects.exists(loginForm)
[00:18:02]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="loginForm"]') with timeout=2500
[00:18:02]                   │ debg TestSubjects.setValue(loginUsername, ft_ml_poweruser)
[00:18:02]                   │ debg TestSubjects.click(loginUsername)
[00:18:02]                   │ debg Find.clickByCssSelector('[data-test-subj="loginUsername"]') with timeout=10000
[00:18:02]                   │ debg Find.findByCssSelector('[data-test-subj="loginUsername"]') with timeout=10000
[00:18:02]                   │ debg TestSubjects.setValue(loginPassword, mlp001)
[00:18:02]                   │ debg TestSubjects.click(loginPassword)
[00:18:02]                   │ debg Find.clickByCssSelector('[data-test-subj="loginPassword"]') with timeout=10000
[00:18:02]                   │ debg Find.findByCssSelector('[data-test-subj="loginPassword"]') with timeout=10000
[00:18:02]                   │ debg TestSubjects.click(loginSubmit)
[00:18:02]                   │ debg Find.clickByCssSelector('[data-test-subj="loginSubmit"]') with timeout=10000
[00:18:02]                   │ debg Find.findByCssSelector('[data-test-subj="loginSubmit"]') with timeout=10000
[00:18:03]                   │ debg Waiting for login result, expected: chrome.
[00:18:03]                   │ debg Find.findByCssSelector('[data-test-subj="userMenuAvatar"]') with timeout=20000
[00:18:03]                   │ proc [kibana]   log   [09:06:04.515] [info][plugins][routes][security] Logging in with provider "basic" (basic)
[00:18:06]                   │ debg browser[INFO] http://localhost:61111/app/home 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:18:06]                   │
[00:18:06]                   │ debg browser[INFO] http://localhost:61111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:18:06]                   │ debg Finished login process currentUrl = http://localhost:61111/app/home#/
[00:18:06]                   │ debg Waiting up to 20000ms for logout button visible...
[00:18:06]                   │ debg TestSubjects.exists(userMenuButton)
[00:18:06]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenuButton"]') with timeout=2500
[00:18:06]                   │ debg TestSubjects.exists(userMenu)
[00:18:06]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenu"]') with timeout=2500
[00:18:08]                   │ debg --- retry.tryForTime error: [data-test-subj="userMenu"] is not displayed
[00:18:09]                   │ debg TestSubjects.click(userMenuButton)
[00:18:09]                   │ debg Find.clickByCssSelector('[data-test-subj="userMenuButton"]') with timeout=10000
[00:18:09]                   │ debg Find.findByCssSelector('[data-test-subj="userMenuButton"]') with timeout=10000
[00:18:09]                   │ debg TestSubjects.exists(userMenu)
[00:18:09]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenu"]') with timeout=120000
[00:18:09]                   │ debg TestSubjects.exists(userMenu > logoutLink)
[00:18:09]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenu"] [data-test-subj="logoutLink"]') with timeout=2500
[00:19:18]                 └-:  with lucene query
[00:19:18]                   └-> "before all" hook for "job creation loads the multi metric wizard for the source data"
[00:19:18]                   └-> job creation loads the multi metric wizard for the source data
[00:19:18]                     └-> "before each" hook: global before each for "job creation loads the multi metric wizard for the source data"
[00:19:18]                     │ debg === TEST STEP === job creation loads the job management page
[00:19:18]                     │ debg navigating to ml url: http://localhost:61111/app/ml
[00:19:18]                     │ debg navigate to: http://localhost:61111/app/ml
[00:19:18]                     │ debg browser[INFO] http://localhost:61111/app/ml?_t=1631783239616 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:19:18]                     │
[00:19:18]                     │ debg browser[INFO] http://localhost:61111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:19:18]                     │ debg ... sleep(700) start
[00:19:19]                     │ debg ... sleep(700) end
[00:19:19]                     │ debg returned from get, calling refresh
[00:19:20]                     │ debg browser[INFO] http://localhost:61111/app/ml?_t=1631783239616 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:19:20]                     │
[00:19:20]                     │ debg browser[INFO] http://localhost:61111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:19:20]                     │ debg currentUrl = http://localhost:61111/app/ml
[00:19:20]                     │          appUrl = http://localhost:61111/app/ml
[00:19:20]                     │ debg TestSubjects.find(kibanaChrome)
[00:19:20]                     │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:19:20]                     │ debg ... sleep(501) start
[00:19:21]                     │ debg ... sleep(501) end
[00:19:21]                     │ debg in navigateTo url = http://localhost:61111/app/ml/overview
[00:19:21]                     │ debg --- retry.tryForTime error: URL changed, waiting for it to settle
[00:19:21]                     │ debg ... sleep(501) start
[00:19:22]                     │ debg ... sleep(501) end
[00:19:22]                     │ debg in navigateTo url = http://localhost:61111/app/ml/overview
[00:19:22]                     │ debg TestSubjects.exists(mlApp)
[00:19:22]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlApp"]') with timeout=2000
[00:19:22]                     │ debg TestSubjects.click(~mlMainTab & ~anomalyDetection)
[00:19:22]                     │ debg Find.clickByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"]') with timeout=10000
[00:19:22]                     │ debg Find.findByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"]') with timeout=10000
[00:19:22]                     │ debg TestSubjects.exists(~mlMainTab & ~anomalyDetection & ~selected)
[00:19:22]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"][data-test-subj~="selected"]') with timeout=120000
[00:19:22]                     │ debg TestSubjects.exists(mlPageJobManagement)
[00:19:22]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageJobManagement"]') with timeout=120000
[00:19:22]                     │ debg === TEST STEP === job creation loads the new job source selection page
[00:19:22]                     │ debg TestSubjects.clickWhenNotDisabled(mlCreateNewJobButton)
[00:19:22]                     │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlCreateNewJobButton"]') with timeout=10000
[00:19:22]                     │ debg Find.findByCssSelector('[data-test-subj="mlCreateNewJobButton"]') with timeout=10000
[00:19:22]                     │ debg TestSubjects.exists(mlPageSourceSelection)
[00:19:22]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageSourceSelection"]') with timeout=120000
[00:19:23]                     │ debg === TEST STEP === job creation loads the job type selection page
[00:19:23]                     │ debg TestSubjects.setValue(savedObjectFinderSearchInput, ft_farequote_lucene)
[00:19:23]                     │ debg TestSubjects.click(savedObjectFinderSearchInput)
[00:19:23]                     │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:19:23]                     │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:19:23]                     │ debg TestSubjects.exists(savedObjectTitleft_farequote_lucene)
[00:19:23]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectTitleft_farequote_lucene"]') with timeout=120000
[00:19:23]                     │ debg TestSubjects.clickWhenNotDisabled(savedObjectTitleft_farequote_lucene)
[00:19:23]                     │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="savedObjectTitleft_farequote_lucene"]') with timeout=10000
[00:19:23]                     │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitleft_farequote_lucene"]') with timeout=10000
[00:19:24]                     │ debg TestSubjects.exists(mlPageJobTypeSelection)
[00:19:24]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageJobTypeSelection"]') with timeout=10000
[00:19:24]                     │ debg === TEST STEP === job creation loads the multi metric job wizard page
[00:19:24]                     │ debg TestSubjects.clickWhenNotDisabled(mlJobTypeLinkMultiMetricJob)
[00:19:24]                     │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobTypeLinkMultiMetricJob"]') with timeout=10000
[00:19:24]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobTypeLinkMultiMetricJob"]') with timeout=10000
[00:19:24]                     │ debg TestSubjects.exists(mlPageJobWizard multi_metric)
[00:19:24]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageJobWizard multi_metric"]') with timeout=120000
[00:19:25]                     └- ✓ pass  (7.1s) "machine learning  anomaly detection saved search  with lucene query job creation loads the multi metric wizard for the source data"
[00:19:25]                   └-> job creation navigates through the multi metric wizard and sets all needed fields
[00:19:25]                     └-> "before each" hook: global before each for "job creation navigates through the multi metric wizard and sets all needed fields"
[00:19:25]                     │ debg === TEST STEP === job creation displays the time range step
[00:19:25]                     │ debg TestSubjects.exists(mlJobWizardStepTitleTimeRange)
[00:19:25]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardStepTitleTimeRange"]') with timeout=5000
[00:19:25]                     │ debg === TEST STEP === job creation sets the time range
[00:19:25]                     │ debg TestSubjects.clickWhenNotDisabled(mlButtonUseFullData)
[00:19:25]                     │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlButtonUseFullData"]') with timeout=10000
[00:19:25]                     │ debg Find.findByCssSelector('[data-test-subj="mlButtonUseFullData"]') with timeout=10000
[00:19:25]                     │ debg TestSubjects.find(mlJobWizardDateRange)
[00:19:25]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardDateRange"]') with timeout=10000
[00:19:25]                     │ debg --- retry.tryForTime error: Expected start date to be 'Feb 7, 2016 @ 00:00:00.000' (got 'Sep 16, 2021 @ 08:52:26.649')
[00:19:26]                     │ debg TestSubjects.find(mlJobWizardDateRange)
[00:19:26]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardDateRange"]') with timeout=10000
[00:19:26]                     │ debg === TEST STEP === job creation displays the event rate chart
[00:19:26]                     │ debg TestSubjects.exists(~mlEventRateChart)
[00:19:26]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlEventRateChart"]') with timeout=120000
[00:19:26]                     │ debg TestSubjects.exists(mlEventRateChart withData)
[00:19:26]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlEventRateChart withData"]') with timeout=120000
[00:19:26]                     │ debg === TEST STEP === job creation displays the pick fields step
[00:19:26]                     │ debg TestSubjects.exists(mlJobWizardNavButtonNext)
[00:19:26]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=120000
[00:19:26]                     │ debg TestSubjects.clickWhenNotDisabled(mlJobWizardNavButtonNext)
[00:19:26]                     │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:19:26]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:19:26]                     │ debg TestSubjects.exists(mlJobWizardStepTitlePickFields)
[00:19:26]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardStepTitlePickFields"]') with timeout=5000
[00:19:26]                     │ debg === TEST STEP === job creation selects detectors and displays detector previews
[00:19:26]                     │ debg TestSubjects.exists(mlJobWizardAggSelection > comboBoxInput)
[00:19:26]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAggSelection"] [data-test-subj="comboBoxInput"]') with timeout=120000
[00:19:26]                     │ debg comboBox.set, comboBoxSelector: mlJobWizardAggSelection > comboBoxInput
[00:19:26]                     │ debg TestSubjects.find(mlJobWizardAggSelection > comboBoxInput)
[00:19:26]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardAggSelection"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:26]                     │ debg comboBox.setElement, value: Mean(responsetime)
[00:19:26]                     │ debg comboBox.isOptionSelected, value: Mean(responsetime)
[00:19:29]                     │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:29]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:29]                     │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="Mean(responsetime)"]') with timeout=2500
[00:19:29]                     │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:29]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:32]                     │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:19:32]                     │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlJobWizardAggSelection > comboBoxInput
[00:19:32]                     │ debg TestSubjects.find(mlJobWizardAggSelection > comboBoxInput)
[00:19:32]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardAggSelection"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:32]                     │ debg TestSubjects.exists(mlDetector 0)
[00:19:32]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlDetector 0"]') with timeout=120000
[00:19:32]                     │ debg TestSubjects.exists(mlDetector 0 > mlDetectorTitle)
[00:19:32]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlDetector 0"] [data-test-subj="mlDetectorTitle"]') with timeout=120000
[00:19:32]                     │ debg TestSubjects.getVisibleText(mlDetector 0 > mlDetectorTitle)
[00:19:32]                     │ debg TestSubjects.find(mlDetector 0 > mlDetectorTitle)
[00:19:32]                     │ debg Find.findByCssSelector('[data-test-subj="mlDetector 0"] [data-test-subj="mlDetectorTitle"]') with timeout=10000
[00:19:32]                     │ debg === TEST STEP === job creation inputs the split field and displays split cards
[00:19:32]                     │ debg TestSubjects.exists(mlMultiMetricSplitFieldSelect > comboBoxInput)
[00:19:32]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlMultiMetricSplitFieldSelect"] [data-test-subj="comboBoxInput"]') with timeout=120000
[00:19:32]                     │ debg comboBox.set, comboBoxSelector: mlMultiMetricSplitFieldSelect > comboBoxInput
[00:19:32]                     │ debg TestSubjects.find(mlMultiMetricSplitFieldSelect > comboBoxInput)
[00:19:32]                     │ debg Find.findByCssSelector('[data-test-subj="mlMultiMetricSplitFieldSelect"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:32]                     │ debg comboBox.setElement, value: airline
[00:19:32]                     │ debg comboBox.isOptionSelected, value: airline
[00:19:34]                     │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:34]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:34]                     │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="airline"]') with timeout=2500
[00:19:35]                     │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:35]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:37]                     │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:19:38]                     │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlMultiMetricSplitFieldSelect > comboBoxInput
[00:19:38]                     │ debg TestSubjects.find(mlMultiMetricSplitFieldSelect > comboBoxInput)
[00:19:38]                     │ debg Find.findByCssSelector('[data-test-subj="mlMultiMetricSplitFieldSelect"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:38]                     │ debg TestSubjects.exists(mlDataSplit > mlDataSplitTitle airline)
[00:19:38]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlDataSplit"] [data-test-subj="mlDataSplitTitle airline"]') with timeout=120000
[00:19:38]                     │ debg TestSubjects.exists(mlDataSplit > mlSplitCard front)
[00:19:38]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlDataSplit"] [data-test-subj="mlSplitCard front"]') with timeout=120000
[00:19:38]                     │ debg TestSubjects.getVisibleText(mlDataSplit > mlSplitCard front > mlSplitCardTitle)
[00:19:38]                     │ debg TestSubjects.find(mlDataSplit > mlSplitCard front > mlSplitCardTitle)
[00:19:38]                     │ debg Find.findByCssSelector('[data-test-subj="mlDataSplit"] [data-test-subj="mlSplitCard front"] [data-test-subj="mlSplitCardTitle"]') with timeout=10000
[00:19:38]                     │ debg TestSubjects.findAll(mlDataSplit > mlSplitCard back)
[00:19:38]                     │ debg Find.allByCssSelector('[data-test-subj="mlDataSplit"] [data-test-subj="mlSplitCard back"]') with timeout=10000
[00:19:38]                     │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlInfluencerSelect > comboBoxInput
[00:19:38]                     │ debg TestSubjects.find(mlInfluencerSelect > comboBoxInput)
[00:19:38]                     │ debg Find.findByCssSelector('[data-test-subj="mlInfluencerSelect"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:38]                     │ debg === TEST STEP === job creation displays the influencer field
[00:19:38]                     │ debg TestSubjects.exists(mlInfluencerSelect > comboBoxInput)
[00:19:38]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlInfluencerSelect"] [data-test-subj="comboBoxInput"]') with timeout=120000
[00:19:38]                     │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlInfluencerSelect > comboBoxInput
[00:19:38]                     │ debg TestSubjects.find(mlInfluencerSelect > comboBoxInput)
[00:19:38]                     │ debg Find.findByCssSelector('[data-test-subj="mlInfluencerSelect"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:38]                     │ debg === TEST STEP === job creation inputs the bucket span
[00:19:38]                     │ debg TestSubjects.exists(mlJobWizardInputBucketSpan)
[00:19:38]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardInputBucketSpan"]') with timeout=120000
[00:19:38]                     │ debg TestSubjects.setValueWithChecks(mlJobWizardInputBucketSpan, 15m)
[00:19:38]                     │ debg TestSubjects.click(mlJobWizardInputBucketSpan)
[00:19:38]                     │ debg Find.clickByCssSelector('[data-test-subj="mlJobWizardInputBucketSpan"]') with timeout=10000
[00:19:38]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardInputBucketSpan"]') with timeout=10000
[00:19:39]                     │ debg TestSubjects.getAttribute(mlJobWizardInputBucketSpan, value, tryTimeout=120000, findTimeout=10000)
[00:19:39]                     │ debg TestSubjects.find(mlJobWizardInputBucketSpan)
[00:19:39]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardInputBucketSpan"]') with timeout=10000
[00:19:39]                     │ debg === TEST STEP === job creation displays the job details step
[00:19:39]                     │ debg TestSubjects.exists(mlJobWizardNavButtonNext)
[00:19:39]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=120000
[00:19:39]                     │ debg TestSubjects.clickWhenNotDisabled(mlJobWizardNavButtonNext)
[00:19:39]                     │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:19:39]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:19:40]                     │ debg TestSubjects.exists(mlJobWizardStepTitleJobDetails)
[00:19:40]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardStepTitleJobDetails"]') with timeout=5000
[00:19:40]                     │ debg === TEST STEP === job creation inputs the job id
[00:19:40]                     │ debg TestSubjects.exists(mlJobWizardInputJobId)
[00:19:40]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardInputJobId"]') with timeout=120000
[00:19:40]                     │ debg TestSubjects.setValueWithChecks(mlJobWizardInputJobId, fq_saved_search_2_1631782080216)
[00:19:40]                     │ debg TestSubjects.click(mlJobWizardInputJobId)
[00:19:40]                     │ debg Find.clickByCssSelector('[data-test-subj="mlJobWizardInputJobId"]') with timeout=10000
[00:19:40]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardInputJobId"]') with timeout=10000
[00:19:42]                     │ debg TestSubjects.getAttribute(mlJobWizardInputJobId, value, tryTimeout=120000, findTimeout=10000)
[00:19:42]                     │ debg TestSubjects.find(mlJobWizardInputJobId)
[00:19:42]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardInputJobId"]') with timeout=10000
[00:19:42]                     │ debg === TEST STEP === job creation inputs the job description
[00:19:42]                     │ debg TestSubjects.exists(mlJobWizardInputJobDescription)
[00:19:42]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardInputJobDescription"]') with timeout=120000
[00:19:42]                     │ debg TestSubjects.setValueWithChecks(mlJobWizardInputJobDescription, Create multi metric job based on a saved search with lucene query)
[00:19:42]                     │ debg TestSubjects.click(mlJobWizardInputJobDescription)
[00:19:42]                     │ debg Find.clickByCssSelector('[data-test-subj="mlJobWizardInputJobDescription"]') with timeout=10000
[00:19:42]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardInputJobDescription"]') with timeout=10000
[00:19:46]                     │ debg TestSubjects.getVisibleText(mlJobWizardInputJobDescription)
[00:19:46]                     │ debg TestSubjects.find(mlJobWizardInputJobDescription)
[00:19:46]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardInputJobDescription"]') with timeout=10000
[00:19:46]                     │ debg === TEST STEP === job creation inputs job groups
[00:19:46]                     │ debg TestSubjects.exists(mlJobWizardComboBoxJobGroups > comboBoxInput)
[00:19:46]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardComboBoxJobGroups"] [data-test-subj="comboBoxInput"]') with timeout=120000
[00:19:46]                     │ debg comboBox.setCustom, comboBoxSelector: mlJobWizardComboBoxJobGroups > comboBoxInput, value: automated
[00:19:46]                     │ debg TestSubjects.find(mlJobWizardComboBoxJobGroups > comboBoxInput)
[00:19:46]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardComboBoxJobGroups"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:49]                     │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:49]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:49]                     │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlJobWizardComboBoxJobGroups > comboBoxInput
[00:19:49]                     │ debg TestSubjects.find(mlJobWizardComboBoxJobGroups > comboBoxInput)
[00:19:49]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardComboBoxJobGroups"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:49]                     │ debg comboBox.setCustom, comboBoxSelector: mlJobWizardComboBoxJobGroups > comboBoxInput, value: farequote
[00:19:49]                     │ debg TestSubjects.find(mlJobWizardComboBoxJobGroups > comboBoxInput)
[00:19:49]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardComboBoxJobGroups"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:51]                     │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:51]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:51]                     │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlJobWizardComboBoxJobGroups > comboBoxInput
[00:19:51]                     │ debg TestSubjects.find(mlJobWizardComboBoxJobGroups > comboBoxInput)
[00:19:51]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardComboBoxJobGroups"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:51]                     │ debg comboBox.setCustom, comboBoxSelector: mlJobWizardComboBoxJobGroups > comboBoxInput, value: multi-metric
[00:19:51]                     │ debg TestSubjects.find(mlJobWizardComboBoxJobGroups > comboBoxInput)
[00:19:51]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardComboBoxJobGroups"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:54]                     │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:54]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:54]                     │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlJobWizardComboBoxJobGroups > comboBoxInput
[00:19:54]                     │ debg TestSubjects.find(mlJobWizardComboBoxJobGroups > comboBoxInput)
[00:19:54]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardComboBoxJobGroups"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:54]                     │ debg comboBox.setCustom, comboBoxSelector: mlJobWizardComboBoxJobGroups > comboBoxInput, value: saved-search
[00:19:54]                     │ debg TestSubjects.find(mlJobWizardComboBoxJobGroups > comboBoxInput)
[00:19:54]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardComboBoxJobGroups"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:56]                     │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:56]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:56]                     │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlJobWizardComboBoxJobGroups > comboBoxInput
[00:19:56]                     │ debg TestSubjects.find(mlJobWizardComboBoxJobGroups > comboBoxInput)
[00:19:56]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardComboBoxJobGroups"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:56]                     │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlJobWizardComboBoxJobGroups > comboBoxInput
[00:19:56]                     │ debg TestSubjects.find(mlJobWizardComboBoxJobGroups > comboBoxInput)
[00:19:56]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardComboBoxJobGroups"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:19:56]                     │ debg === TEST STEP === job creation opens the advanced section
[00:19:56]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection)
[00:19:56]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"]') with timeout=2500
[00:19:56]                     │ debg --- retry.tryForTime error: [data-test-subj="mlJobWizardAdvancedSection"] is not displayed
[00:19:57]                     │ debg --- retry.tryForTime failed again with the same message...
[00:19:57]                     │ debg --- retry.tryForTime failed again with the same message...
[00:19:58]                     │ debg --- retry.tryForTime failed again with the same message...
[00:19:58]                     │ debg --- retry.tryForTime failed again with the same message...
[00:19:59]                     │ debg TestSubjects.click(mlJobWizardToggleAdvancedSection)
[00:19:59]                     │ debg Find.clickByCssSelector('[data-test-subj="mlJobWizardToggleAdvancedSection"]') with timeout=10000
[00:19:59]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardToggleAdvancedSection"]') with timeout=10000
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection)
[00:19:59]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"]') with timeout=1000
[00:19:59]                     │ debg === TEST STEP === job creation displays the model plot switch
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection)
[00:19:59]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"]') with timeout=2500
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection > mlJobWizardSwitchModelPlot)
[00:19:59]                     │ debg Find.existsByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"] [data-test-subj="mlJobWizardSwitchModelPlot"]') with timeout=120000
[00:19:59]                     │ debg === TEST STEP === job creation enables the dedicated index switch
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection)
[00:19:59]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"]') with timeout=2500
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection > mlJobWizardSwitchUseDedicatedIndex)
[00:19:59]                     │ debg Find.existsByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"] [data-test-subj="mlJobWizardSwitchUseDedicatedIndex"]') with timeout=120000
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection)
[00:19:59]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"]') with timeout=2500
[00:19:59]                     │ debg TestSubjects.getAttribute(mlJobWizardSwitchUseDedicatedIndex, aria-checked, tryTimeout=120000, findTimeout=10000)
[00:19:59]                     │ debg TestSubjects.find(mlJobWizardSwitchUseDedicatedIndex)
[00:19:59]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardSwitchUseDedicatedIndex"]') with timeout=10000
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection)
[00:19:59]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"]') with timeout=2500
[00:19:59]                     │ debg TestSubjects.clickWhenNotDisabled(mlJobWizardAdvancedSection > mlJobWizardSwitchUseDedicatedIndex)
[00:19:59]                     │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobWizardAdvancedSection"] [data-test-subj="mlJobWizardSwitchUseDedicatedIndex"]') with timeout=10000
[00:19:59]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"] [data-test-subj="mlJobWizardSwitchUseDedicatedIndex"]') with timeout=10000
[00:19:59]                     │ debg TestSubjects.getAttribute(mlJobWizardSwitchUseDedicatedIndex, aria-checked, tryTimeout=120000, findTimeout=10000)
[00:19:59]                     │ debg TestSubjects.find(mlJobWizardSwitchUseDedicatedIndex)
[00:19:59]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardSwitchUseDedicatedIndex"]') with timeout=10000
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection)
[00:19:59]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"]') with timeout=2500
[00:19:59]                     │ debg === TEST STEP === job creation inputs the model memory limit
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection)
[00:19:59]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"]') with timeout=2500
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection > mlJobWizardInputModelMemoryLimit)
[00:19:59]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"] [data-test-subj="mlJobWizardInputModelMemoryLimit"]') with timeout=120000
[00:19:59]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection)
[00:19:59]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"]') with timeout=2500
[00:19:59]                     │ debg TestSubjects.setValueWithChecks(mlJobWizardAdvancedSection > mlJobWizardInputModelMemoryLimit, 20mb)
[00:19:59]                     │ debg TestSubjects.click(mlJobWizardAdvancedSection > mlJobWizardInputModelMemoryLimit)
[00:19:59]                     │ debg Find.clickByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"] [data-test-subj="mlJobWizardInputModelMemoryLimit"]') with timeout=10000
[00:19:59]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"] [data-test-subj="mlJobWizardInputModelMemoryLimit"]') with timeout=10000
[00:20:00]                     │ debg TestSubjects.exists(mlJobWizardAdvancedSection)
[00:20:00]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"]') with timeout=2500
[00:20:00]                     │ debg TestSubjects.getAttribute(mlJobWizardAdvancedSection > mlJobWizardInputModelMemoryLimit, value, tryTimeout=120000, findTimeout=10000)
[00:20:00]                     │ debg TestSubjects.find(mlJobWizardAdvancedSection > mlJobWizardInputModelMemoryLimit)
[00:20:00]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardAdvancedSection"] [data-test-subj="mlJobWizardInputModelMemoryLimit"]') with timeout=10000
[00:20:00]                     │ debg === TEST STEP === job creation displays the validation step
[00:20:00]                     │ debg TestSubjects.exists(mlJobWizardNavButtonNext)
[00:20:00]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=120000
[00:20:00]                     │ debg TestSubjects.clickWhenNotDisabled(mlJobWizardNavButtonNext)
[00:20:00]                     │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:20:00]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:20:01]                     │ debg TestSubjects.exists(mlJobWizardStepTitleValidation)
[00:20:01]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardStepTitleValidation"]') with timeout=5000
[00:20:01]                     │ debg === TEST STEP === job creation displays the summary step
[00:20:01]                     │ debg TestSubjects.exists(mlJobWizardNavButtonNext)
[00:20:01]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=120000
[00:20:01]                     │ debg TestSubjects.clickWhenNotDisabled(mlJobWizardNavButtonNext)
[00:20:01]                     │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:20:01]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:20:01]                     │ debg TestSubjects.exists(mlJobWizardStepTitleSummary)
[00:20:01]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardStepTitleSummary"]') with timeout=5000
[00:20:01]                     └- ✓ pass  (36.2s) "machine learning  anomaly detection saved search  with lucene query job creation navigates through the multi metric wizard and sets all needed fields"
[00:20:01]                   └-> job creation runs the job and displays it correctly in the job list
[00:20:01]                     └-> "before each" hook: global before each for "job creation runs the job and displays it correctly in the job list"
[00:20:01]                     │ debg === TEST STEP === job creation creates the job and finishes processing
[00:20:01]                     │ debg TestSubjects.exists(mlJobWizardButtonCreateJob)
[00:20:01]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardButtonCreateJob"]') with timeout=120000
[00:20:01]                     │ debg TestSubjects.clickWhenNotDisabled(mlJobWizardButtonCreateJob)
[00:20:01]                     │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobWizardButtonCreateJob"]') with timeout=10000
[00:20:01]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardButtonCreateJob"]') with timeout=10000
[00:20:01]                     │ debg TestSubjects.exists(mlJobWizardButtonRunInRealTime)
[00:20:01]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardButtonRunInRealTime"]') with timeout=120000
[00:20:01]                     │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.ml-anomalies-custom-fq_saved_search_2_1631782080216] creating index, cause [api], templates [.ml-anomalies-], shards [1]/[1]
[00:20:01]                     │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.ml-anomalies-custom-fq_saved_search_2_1631782080216]
[00:20:01]                     │ info [o.e.c.m.MetadataMappingService] [node-01] [.ml-anomalies-custom-fq_saved_search_2_1631782080216/d96mi1JgS1aSyMVXrb-WRw] update_mapping [_doc]
[00:20:03]                     │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [node-01] Opening job [fq_saved_search_2_1631782080216]
[00:20:03]                     │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [node-01] [fq_saved_search_2_1631782080216] Loading model snapshot [N/A], job latest_record_timestamp [N/A]
[00:20:04]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [node-01] [fq_saved_search_2_1631782080216] [autodetect/134476] [CResourceMonitor.cc@82] Setting model memory limit to 20 MB
[00:20:04]                     │ info [o.e.x.m.d.DatafeedJob] [node-01] [fq_saved_search_2_1631782080216] Datafeed started (from: 2016-02-07T00:00:00.000Z to: 2016-02-11T23:59:54.001Z) with frequency [450000ms]
[00:20:04]                     │ debg browser[INFO] http://localhost:61111/45981/bundles/plugin/ml/8.0.0/ml.chunk.2.js 2:14406 "Response for job query:" Object
[00:20:04]                     │ debg browser[INFO] http://localhost:61111/45981/bundles/plugin/ml/8.0.0/ml.chunk.2.js 2:14616 "checkSaveResponse(): save successful"
[00:20:04]                     │ debg --- retry.tryForTime error: [data-test-subj="mlJobWizardButtonRunInRealTime"] is not displayed
[00:20:04]                     │ info [o.e.c.m.MetadataMappingService] [node-01] [.ml-anomalies-custom-fq_saved_search_2_1631782080216/d96mi1JgS1aSyMVXrb-WRw] update_mapping [_doc]
[00:20:04]                     │ info [o.e.x.m.j.p.DataCountsReporter] [node-01] [fq_saved_search_2_1631782080216] 10000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:20:05]                     │ info [o.e.x.m.j.p.DataCountsReporter] [node-01] [fq_saved_search_2_1631782080216] 20000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:20:05]                     │ info [o.e.x.m.j.p.DataCountsReporter] [node-01] [fq_saved_search_2_1631782080216] 30000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:20:07]                     │ debg --- retry.tryForTime failed again with the same message...
[00:20:07]                     │ info [o.e.x.m.d.DatafeedJob] [node-01] [fq_saved_search_2_1631782080216] Lookback has finished
[00:20:07]                     │ info [o.e.x.m.d.DatafeedRunner] [node-01] [no_realtime] attempt to stop datafeed [datafeed-fq_saved_search_2_1631782080216] for job [fq_saved_search_2_1631782080216]
[00:20:07]                     │ info [o.e.x.m.d.DatafeedRunner] [node-01] [no_realtime] try lock [20s] to stop datafeed [datafeed-fq_saved_search_2_1631782080216] for job [fq_saved_search_2_1631782080216]...
[00:20:07]                     │ info [o.e.x.m.d.DatafeedRunner] [node-01] [no_realtime] stopping datafeed [datafeed-fq_saved_search_2_1631782080216] for job [fq_saved_search_2_1631782080216], acquired [true]...
[00:20:07]                     │ info [o.e.x.m.d.DatafeedRunner] [node-01] [no_realtime] datafeed [datafeed-fq_saved_search_2_1631782080216] for job [fq_saved_search_2_1631782080216] has been stopped
[00:20:07]                     │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [node-01] Closing job [fq_saved_search_2_1631782080216], because [close job (api)]
[00:20:07]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [node-01] [fq_saved_search_2_1631782080216] [autodetect/134476] [CCmdSkeleton.cc@66] Handled 34415 records
[00:20:07]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [node-01] [fq_saved_search_2_1631782080216] [autodetect/134476] [CAnomalyJob.cc@1601] Pruning obsolete models
[00:20:07]                     │ info [o.e.x.m.p.AbstractNativeProcess] [node-01] [fq_saved_search_2_1631782080216] State output finished
[00:20:07]                     │ info [o.e.c.m.MetadataMappingService] [node-01] [.ml-anomalies-custom-fq_saved_search_2_1631782080216/d96mi1JgS1aSyMVXrb-WRw] update_mapping [_doc]
[00:20:07]                     │ info [o.e.x.m.j.p.a.o.AutodetectResultProcessor] [node-01] [fq_saved_search_2_1631782080216] 480 buckets parsed from autodetect output
[00:20:08]                     │ info [o.e.x.m.j.p.a.AutodetectCommunicator] [node-01] [fq_saved_search_2_1631782080216] autodetect connection for job closed
[00:20:09]                     │ debg === TEST STEP === job creation displays the created job in the job list
[00:20:09]                     │ debg navigating to ml url: http://localhost:61111/app/ml
[00:20:09]                     │ debg navigate to: http://localhost:61111/app/ml
[00:20:09]                     │ debg browser[INFO] http://localhost:61111/app/ml?_t=1631783290504 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:20:09]                     │
[00:20:09]                     │ debg browser[INFO] http://localhost:61111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:20:09]                     │ debg ... sleep(700) start
[00:20:09]                     │ debg ... sleep(700) end
[00:20:09]                     │ debg returned from get, calling refresh
[00:20:10]                     │ debg browser[INFO] http://localhost:61111/app/ml?_t=1631783290504 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:20:10]                     │
[00:20:10]                     │ debg browser[INFO] http://localhost:61111/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:20:11]                     │ debg currentUrl = http://localhost:61111/app/ml
[00:20:11]                     │          appUrl = http://localhost:61111/app/ml
[00:20:11]                     │ debg TestSubjects.find(kibanaChrome)
[00:20:11]                     │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:20:11]                     │ debg ... sleep(501) start
[00:20:11]                     │ debg ... sleep(501) end
[00:20:11]                     │ debg in navigateTo url = http://localhost:61111/app/ml/overview
[00:20:11]                     │ debg --- retry.tryForTime error: URL changed, waiting for it to settle
[00:20:12]                     │ debg ... sleep(501) start
[00:20:12]                     │ debg ... sleep(501) end
[00:20:12]                     │ debg in navigateTo url = http://localhost:61111/app/ml/overview
[00:20:12]                     │ debg TestSubjects.exists(mlApp)
[00:20:12]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlApp"]') with timeout=2000
[00:20:12]                     │ debg TestSubjects.click(~mlMainTab & ~anomalyDetection)
[00:20:12]                     │ debg Find.clickByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"]') with timeout=10000
[00:20:12]                     │ debg Find.findByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"]') with timeout=10000
[00:20:13]                     │ debg TestSubjects.exists(~mlMainTab & ~anomalyDetection & ~selected)
[00:20:13]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"][data-test-subj~="selected"]') with timeout=120000
[00:20:13]                     │ debg TestSubjects.exists(mlPageJobManagement)
[00:20:13]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageJobManagement"]') with timeout=120000
[00:20:13]                     │ debg TestSubjects.exists(~mlJobListTable)
[00:20:13]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=60000
[00:20:13]                     │ debg TestSubjects.exists(mlJobListTable loaded)
[00:20:13]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobListTable loaded"]') with timeout=30000
[00:20:13]                     │ debg TestSubjects.exists(~mlJobListTable)
[00:20:13]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=60000
[00:20:13]                     │ debg TestSubjects.exists(mlJobListTable loaded)
[00:20:13]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobListTable loaded"]') with timeout=30000
[00:20:13]                     │ debg TestSubjects.find(mlJobListSearchBar)
[00:20:13]                     │ debg Find.findByCssSelector('[data-test-subj="mlJobListSearchBar"]') with timeout=10000
[00:20:14]                     │ debg TestSubjects.find(~mlJobListTable)
[00:20:14]                     │ debg Find.findByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=10000
[00:20:14]                     │ debg === TEST STEP === job creation displays details for the created job in the job list
[00:20:14]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:14]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:14]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:14]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:14]                     │ debg TestSubjects.click(~mlRefreshJobListButton)
[00:20:14]                     │ debg Find.clickByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:14]                     │ debg Find.findByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:14]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:14]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:14]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:14]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:14]                     │ debg TestSubjects.exists(~mlJobListTable)
[00:20:14]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=60000
[00:20:14]                     │ debg TestSubjects.exists(mlJobListTable loaded)
[00:20:14]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobListTable loaded"]') with timeout=30000
[00:20:14]                     │ debg TestSubjects.find(~mlJobListTable)
[00:20:14]                     │ debg Find.findByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=10000
[00:20:14]                     │ debg --- retry.tryForTime error: Expected job row to be '{"id":"fq_saved_search_2_1631782080216","description":"Create multi metric job based on a saved search with lucene query","jobGroups":["automated","farequote","multi-metric","saved-search"],"recordCount":"34,416","memoryStatus":"ok","jobState":"closed","datafeedState":"stopped","latestTimestamp":"2016-02-11 23:59:54"}' (got '{"id":"fq_saved_search_2_1631782080216","description":"Create multi metric job based on a saved search with lucene query","jobGroups":["automated","farequote","multi-metric","saved-search"],"recordCount":"34,415","memoryStatus":"ok","jobState":"closed","datafeedState":"stopped","latestTimestamp":"2016-02-11 23:59:54"}')
[00:20:15]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:15]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:15]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:15]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:15]                     │ debg TestSubjects.click(~mlRefreshJobListButton)
[00:20:15]                     │ debg Find.clickByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:15]                     │ debg Find.findByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:15]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:15]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:15]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:15]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:15]                     │ debg TestSubjects.exists(~mlJobListTable)
[00:20:15]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=60000
[00:20:15]                     │ debg TestSubjects.exists(mlJobListTable loaded)
[00:20:15]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobListTable loaded"]') with timeout=30000
[00:20:15]                     │ debg TestSubjects.find(~mlJobListTable)
[00:20:15]                     │ debg Find.findByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=10000
[00:20:15]                     │ debg --- retry.tryForTime failed again with the same message...
[00:20:16]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:16]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:16]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:16]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:16]                     │ debg TestSubjects.click(~mlRefreshJobListButton)
[00:20:16]                     │ debg Find.clickByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:16]                     │ debg Find.findByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:16]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:16]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:16]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:16]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:16]                     │ debg TestSubjects.exists(~mlJobListTable)
[00:20:16]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=60000
[00:20:16]                     │ debg TestSubjects.exists(mlJobListTable loaded)
[00:20:16]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobListTable loaded"]') with timeout=30000
[00:20:16]                     │ debg TestSubjects.find(~mlJobListTable)
[00:20:16]                     │ debg Find.findByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=10000
[00:20:16]                     │ debg --- retry.tryForTime failed again with the same message...
[00:20:16]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:16]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:17]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:17]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:17]                     │ debg TestSubjects.click(~mlRefreshJobListButton)
[00:20:17]                     │ debg Find.clickByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:17]                     │ debg Find.findByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:17]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:17]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:17]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:17]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:17]                     │ debg TestSubjects.exists(~mlJobListTable)
[00:20:17]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=60000
[00:20:17]                     │ debg TestSubjects.exists(mlJobListTable loaded)
[00:20:17]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobListTable loaded"]') with timeout=30000
[00:20:17]                     │ debg TestSubjects.find(~mlJobListTable)
[00:20:17]                     │ debg Find.findByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=10000
[00:20:17]                     │ debg --- retry.tryForTime failed again with the same message...
[00:20:17]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:17]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:17]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:17]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:17]                     │ debg TestSubjects.click(~mlRefreshJobListButton)
[00:20:17]                     │ debg Find.clickByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:17]                     │ debg Find.findByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:17]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:17]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:18]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:18]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:18]                     │ debg TestSubjects.exists(~mlJobListTable)
[00:20:18]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=60000
[00:20:18]                     │ debg TestSubjects.exists(mlJobListTable loaded)
[00:20:18]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobListTable loaded"]') with timeout=30000
[00:20:18]                     │ debg TestSubjects.find(~mlJobListTable)
[00:20:18]                     │ debg Find.findByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=10000
[00:20:18]                     │ debg --- retry.tryForTime failed again with the same message...
[00:20:18]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:18]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:18]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:18]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:18]                     │ debg TestSubjects.click(~mlRefreshJobListButton)
[00:20:18]                     │ debg Find.clickByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:18]                     │ debg Find.findByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:18]                     │ debg TestSubjects.exists(~mlRefreshJobListButton)
[00:20:18]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlRefreshJobListButton"]') with timeout=10000
[00:20:18]                     │ debg TestSubjects.exists(mlRefreshJobListButton loaded)
[00:20:18]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlRefreshJobListButton loaded"]') with timeout=30000
[00:20:19]                     │ debg TestSubjects.exists(~mlJobListTable)
[00:20:19]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=60000
[00:20:19]                     │ debg TestSubjects.exists(mlJobListTable loaded)
[00:20:19]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobListTable loaded"]') with timeout=30000
[00:20:19]                     │ debg TestSubjects.find(~mlJobListTable)
[00:20:19]                     │ debg Find.findByCssSelector('[data-test-subj~="mlJobListTable"]') with timeout=10000
[00:20:19]                     │ debg --- retry.tryForTime failed again with the same message...
[00:20:19]                     │ info Taking screenshot "/dev/shm/workspace/parallel/11/kibana/x-pack/test/functional/screenshots/failure/machine learning  anomaly detection saved search  with lucene query job creation runs the job and displays it correctly in the job list.png"
[00:20:19]                     │ info Current URL is: http://localhost:61111/app/ml/jobs?_g=(refreshInterval%3A(pause%3A!f%2Cvalue%3A30000))&_a=(jobs%3A(pageIndex%3A0%2CpageSize%3A10%2CqueryText%3Afq_saved_search_2_1631782080216%2CsortDirection%3Aasc%2CsortField%3Aid))
[00:20:19]                     │ info Saving page source to: /dev/shm/workspace/parallel/11/kibana/x-pack/test/functional/failure_debug/html/machine learning  anomaly detection saved search  with lucene query job creation runs the job and displays it correctly in the job list.html
[00:20:19]                     └- ✖ fail: machine learning  anomaly detection saved search  with lucene query job creation runs the job and displays it correctly in the job list
[00:20:19]                     │      retry.tryForTime timeout: Error: Expected job row to be '{"id":"fq_saved_search_2_1631782080216","description":"Create multi metric job based on a saved search with lucene query","jobGroups":["automated","farequote","multi-metric","saved-search"],"recordCount":"34,416","memoryStatus":"ok","jobState":"closed","datafeedState":"stopped","latestTimestamp":"2016-02-11 23:59:54"}' (got '{"id":"fq_saved_search_2_1631782080216","description":"Create multi metric job based on a saved search with lucene query","jobGroups":["automated","farequote","multi-metric","saved-search"],"recordCount":"34,415","memoryStatus":"ok","jobState":"closed","datafeedState":"stopped","latestTimestamp":"2016-02-11 23:59:54"}')
[00:20:19]                     │     at Assertion.assert (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/expect/expect.js:100:11)
[00:20:19]                     │     at Assertion.eql (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/expect/expect.js:244:8)
[00:20:19]                     │     at /dev/shm/workspace/parallel/11/kibana/x-pack/test/functional/services/ml/job_table.ts:241:27
[00:20:19]                     │     at runMicrotasks (<anonymous>)
[00:20:19]                     │     at processTicksAndRejections (internal/process/task_queues.js:95:5)
[00:20:19]                     │     at runAttempt (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry_for_success.ts:27:15)
[00:20:19]                     │     at retryForSuccess (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry_for_success.ts:66:21)
[00:20:19]                     │     at RetryService.tryForTime (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry.ts:22:12)
[00:20:19]                     │     at MlJobTable.assertJobRowFields (/dev/shm/workspace/parallel/11/kibana/x-pack/test/functional/services/ml/job_table.ts:237:7)
[00:20:19]                     │     at Context.<anonymous> (/dev/shm/workspace/parallel/11/kibana/x-pack/test/functional/apps/ml/anomaly_detection/saved_search_job.ts:418:11)
[00:20:19]                     │     at Object.apply (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)
[00:20:19]                     │   Error: retry.tryForTime timeout: Error: Expected job row to be '{"id":"fq_saved_search_2_1631782080216","description":"Create multi metric job based on a saved search with lucene query","jobGroups":["automated","farequote","multi-metric","saved-search"],"recordCount":"34,416","memoryStatus":"ok","jobState":"closed","datafeedState":"stopped","latestTimestamp":"2016-02-11 23:59:54"}' (got '{"id":"fq_saved_search_2_1631782080216","description":"Create multi metric job based on a saved search with lucene query","jobGroups":["automated","farequote","multi-metric","saved-search"],"recordCount":"34,415","memoryStatus":"ok","jobState":"closed","datafeedState":"stopped","latestTimestamp":"2016-02-11 23:59:54"}')
[00:20:19]                     │       at Assertion.assert (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/expect/expect.js:100:11)
[00:20:19]                     │       at Assertion.eql (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/expect/expect.js:244:8)
[00:20:19]                     │       at /dev/shm/workspace/parallel/11/kibana/x-pack/test/functional/services/ml/job_table.ts:241:27
[00:20:19]                     │       at runMicrotasks (<anonymous>)
[00:20:19]                     │       at processTicksAndRejections (internal/process/task_queues.js:95:5)
[00:20:19]                     │       at runAttempt (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry_for_success.ts:27:15)
[00:20:19]                     │       at retryForSuccess (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry_for_success.ts:66:21)
[00:20:19]                     │       at RetryService.tryForTime (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry.ts:22:12)
[00:20:19]                     │       at MlJobTable.assertJobRowFields (test/functional/services/ml/job_table.ts:237:7)
[00:20:19]                     │       at Context.<anonymous> (test/functional/apps/ml/anomaly_detection/saved_search_job.ts:418:11)
[00:20:19]                     │       at Object.apply (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)
[00:20:19]                     │       at onFailure (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry_for_success.ts:17:9)
[00:20:19]                     │       at retryForSuccess (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry_for_success.ts:57:13)
[00:20:19]                     │       at RetryService.tryForTime (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry.ts:22:12)
[00:20:19]                     │       at MlJobTable.assertJobRowFields (test/functional/services/ml/job_table.ts:237:7)
[00:20:19]                     │       at Context.<anonymous> (test/functional/apps/ml/anomaly_detection/saved_search_job.ts:418:11)
[00:20:19]                     │       at Object.apply (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)
[00:20:19]                     │ 
[00:20:19]                     │ 

Stack Trace

Error: retry.tryForTime timeout: Error: Expected job row to be '{"id":"fq_saved_search_2_1631782080216","description":"Create multi metric job based on a saved search with lucene query","jobGroups":["automated","farequote","multi-metric","saved-search"],"recordCount":"34,416","memoryStatus":"ok","jobState":"closed","datafeedState":"stopped","latestTimestamp":"2016-02-11 23:59:54"}' (got '{"id":"fq_saved_search_2_1631782080216","description":"Create multi metric job based on a saved search with lucene query","jobGroups":["automated","farequote","multi-metric","saved-search"],"recordCount":"34,415","memoryStatus":"ok","jobState":"closed","datafeedState":"stopped","latestTimestamp":"2016-02-11 23:59:54"}')
    at Assertion.assert (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/expect/expect.js:100:11)
    at Assertion.eql (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/expect/expect.js:244:8)
    at /dev/shm/workspace/parallel/11/kibana/x-pack/test/functional/services/ml/job_table.ts:241:27
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:95:5)
    at runAttempt (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry_for_success.ts:27:15)
    at retryForSuccess (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry_for_success.ts:66:21)
    at RetryService.tryForTime (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry.ts:22:12)
    at MlJobTable.assertJobRowFields (test/functional/services/ml/job_table.ts:237:7)
    at Context.<anonymous> (test/functional/apps/ml/anomaly_detection/saved_search_job.ts:418:11)
    at Object.apply (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)
    at onFailure (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry_for_success.ts:17:9)
    at retryForSuccess (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry_for_success.ts:57:13)
    at RetryService.tryForTime (/dev/shm/workspace/parallel/11/kibana/test/common/services/retry/retry.ts:22:12)
    at MlJobTable.assertJobRowFields (test/functional/services/ml/job_table.ts:237:7)
    at Context.<anonymous> (test/functional/apps/ml/anomaly_detection/saved_search_job.ts:418:11)
    at Object.apply (/dev/shm/workspace/parallel/11/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)

and 4 more failures, only showing the first 3.

Metrics [docs]

Async chunks

Total size of all lazy-loaded chunks that will be downloaded as the user navigates the app

id before after diff
ml 6.0MB 6.0MB +8.0B

History

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

cc @qn895

@qn895 qn895 deleted the ml-fix-ad-indices-index-patterns branch October 6, 2021 17:38
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 bug Fixes for quality problems that affect the customer experience Feature:Anomaly Detection ML anomaly detection :ml release_note:fix v7.14.2 v7.15.0 v7.16.0 v8.0.0

Projects

None yet

Development

Successfully merging this pull request may close these issues.

6 participants