Skip to content
New issue

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

Sign up for GitHub

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

Already on GitHub? Sign in to your account

Jump to bottom

[7.x] migrationsv2: handle 413 errors and log the request details for unexpected ES failures (#108213) #108650

Merged
merged 1 commit into from
Aug 16, 2021
Merged

[7.x] migrationsv2: handle 413 errors and log the request details for unexpected ES failures (#108213) #108650

merged 1 commit into from
Aug 16, 2021

Conversation

rudolf
Copy link
Contributor

@rudolf rudolf commented Aug 16, 2021

Backports the following commits to 7.x:

@rudolf @kibanamachine
migrationsv2: handle 413 errors and log the request details for unexp…
c4d3eee 
…ected ES failures (elastic#108213)

* Log the failing request and response code when an action throws a response error

* Provide useful log message when migrations fail due to ES 413 Request Entity Too Large

* Don't log request body for unexpected ES request failures

* Fix types

* CR feedback: fix order of ES request debug log

Co-authored-by: Kibana Machine <[email protected]>
@rudolf rudolf enabled auto-merge (squash) August 16, 2021 08:04
@kibanamachine
Copy link
Contributor

💛 Build succeeded, but was flaky

Test Failures

Kibana Pipeline / general / Chrome X-Pack UI Functional Tests.x-pack/test/functional/apps/lens/runtime_fields·ts.lens app lens runtime fields should able to edit field

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:10:10]         └-: lens app
[00:10:10]           └-> "before all" hook in "lens app"
[00:10:10]           └-> "before all" hook in "lens app"
[00:10:10]             │ debg Starting lens before method
[00:10:10]             │ info [x-pack/test/functional/es_archives/logstash_functional] Loading "mappings.json"
[00:10:11]             │ info [x-pack/test/functional/es_archives/logstash_functional] Loading "data.json.gz"
[00:10:11]             │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [logstash-2015.09.22/KAQ6z4V6SimCwPYuMQ4qng] deleting index
[00:10:11]             │ info [x-pack/test/functional/es_archives/logstash_functional] Deleted existing index "logstash-2015.09.22"
[00:10:11]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0]
[00:10:11]             │ info [x-pack/test/functional/es_archives/logstash_functional] Created index "logstash-2015.09.22"
[00:10:11]             │ debg [x-pack/test/functional/es_archives/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:10:11]             │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [logstash-2015.09.20/2vvBG-o5TK-IFbYVqeWYmw] deleting index
[00:10:11]             │ info [x-pack/test/functional/es_archives/logstash_functional] Deleted existing index "logstash-2015.09.20"
[00:10:11]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0]
[00:10:11]             │ info [x-pack/test/functional/es_archives/logstash_functional] Created index "logstash-2015.09.20"
[00:10:11]             │ debg [x-pack/test/functional/es_archives/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:10:11]             │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [logstash-2015.09.21/vIhfOzwoRi2ZwZBsxkNOPg] deleting index
[00:10:11]             │ info [x-pack/test/functional/es_archives/logstash_functional] Deleted existing index "logstash-2015.09.21"
[00:10:11]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0]
[00:10:11]             │ info [x-pack/test/functional/es_archives/logstash_functional] Created index "logstash-2015.09.21"
[00:10:11]             │ debg [x-pack/test/functional/es_archives/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:10:11]             │ info [o.e.c.m.MetadataMappingService] [node-01] [logstash-2015.09.21/yzKCsfEfR3u-8c2Y70UAaw] update_mapping [_doc]
[00:10:14]             │ info [o.e.c.m.MetadataMappingService] [node-01] [logstash-2015.09.20/DiNYOF2nTp-YSUx1xWWNSA] update_mapping [_doc]
[00:10:21]             │ info progress: 12886
[00:10:21]             │ info [x-pack/test/functional/es_archives/logstash_functional] Indexed 4634 docs into "logstash-2015.09.22"
[00:10:21]             │ info [x-pack/test/functional/es_archives/logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:10:21]             │ info [x-pack/test/functional/es_archives/logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:10:22]             │ info [x-pack/test/functional/es_archives/lens/basic] Loading "mappings.json"
[00:10:22]             │ info [x-pack/test/functional/es_archives/lens/basic] Loading "data.json"
[00:10:22]             │ info [x-pack/test/functional/es_archives/lens/basic] Loading "data.json.gz"
[00:10:22]             │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_task_manager_7.15.0_001/TYcPN6wBQTyw5pv5BL42CA] deleting index
[00:10:22]             │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_1/RC-SzfHYQKmKmgDFLkx69Q] deleting index
[00:10:22]             │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_7.15.0_001/0UDVumP3QpGqnwoXOnsu5Q] deleting index
[00:10:22]             │ info [x-pack/test/functional/es_archives/lens/basic] Deleted existing index ".kibana_7.15.0_001"
[00:10:22]             │ info [x-pack/test/functional/es_archives/lens/basic] Deleted existing index ".kibana_task_manager_7.15.0_001"
[00:10:22]             │ info [x-pack/test/functional/es_archives/lens/basic] Deleted existing index ".kibana_1"
[00:10:22]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_1] creating index, cause [api], templates [], shards [1]/[0]
[00:10:22]             │ info [x-pack/test/functional/es_archives/lens/basic] Created index ".kibana_1"
[00:10:22]             │ debg [x-pack/test/functional/es_archives/lens/basic] ".kibana_1" settings {"index":{"auto_expand_replicas":"0-1","number_of_replicas":"0","number_of_shards":"1"}}
[00:10:22]             │ info [x-pack/test/functional/es_archives/lens/basic] Indexed 24 docs into ".kibana_1"
[00:10:22]             │ debg Migrating saved objects
[00:10:22]             │ proc [kibana]   log   [08:46:36.479] [info][savedobjects-service] [.kibana_task_manager] INIT -> CREATE_NEW_TARGET. took: 4ms.
[00:10:22]             │ proc [kibana]   log   [08:46:36.483] [info][savedobjects-service] [.kibana] INIT -> WAIT_FOR_YELLOW_SOURCE. took: 10ms.
[00:10:22]             │ proc [kibana]   log   [08:46:36.485] [info][savedobjects-service] [.kibana] WAIT_FOR_YELLOW_SOURCE -> CHECK_UNKNOWN_DOCUMENTS. took: 2ms.
[00:10:22]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_task_manager_7.15.0_001] creating index, cause [api], templates [], shards [1]/[1]
[00:10:22]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_task_manager_7.15.0_001]
[00:10:22]             │ proc [kibana]   log   [08:46:36.491] [info][savedobjects-service] [.kibana] CHECK_UNKNOWN_DOCUMENTS -> SET_SOURCE_WRITE_BLOCK. took: 6ms.
[00:10:22]             │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_1/7AL9BiBNQUah-DO5tmLfVg]]
[00:10:22]             │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_1]
[00:10:22]             │ proc [kibana]   log   [08:46:36.562] [info][savedobjects-service] [.kibana_task_manager] CREATE_NEW_TARGET -> MARK_VERSION_INDEX_READY. took: 83ms.
[00:10:22]             │ proc [kibana]   log   [08:46:36.576] [info][savedobjects-service] [.kibana] SET_SOURCE_WRITE_BLOCK -> CALCULATE_EXCLUDE_FILTERS. took: 85ms.
[00:10:22]             │ proc [kibana]   log   [08:46:36.582] [info][savedobjects-service] [.kibana] CALCULATE_EXCLUDE_FILTERS -> CREATE_REINDEX_TEMP. took: 6ms.
[00:10:22]             │ proc [kibana]   log   [08:46:36.594] [info][savedobjects-service] [.kibana_task_manager] MARK_VERSION_INDEX_READY -> DONE. took: 32ms.
[00:10:22]             │ proc [kibana]   log   [08:46:36.594] [info][savedobjects-service] [.kibana_task_manager] Migration completed after 119ms
[00:10:22]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_7.15.0_reindex_temp] creating index, cause [api], templates [], shards [1]/[1]
[00:10:22]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_7.15.0_reindex_temp]
[00:10:22]             │ proc [kibana]   log   [08:46:36.639] [info][savedobjects-service] [.kibana] CREATE_REINDEX_TEMP -> REINDEX_SOURCE_TO_TEMP_OPEN_PIT. took: 57ms.
[00:10:22]             │ proc [kibana]   log   [08:46:36.644] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_OPEN_PIT -> REINDEX_SOURCE_TO_TEMP_READ. took: 5ms.
[00:10:22]             │ proc [kibana]   log   [08:46:36.652] [info][savedobjects-service] [.kibana] Starting to process 12 documents.
[00:10:22]             │ proc [kibana]   log   [08:46:36.652] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_INDEX. took: 7ms.
[00:10:22]             │ proc [kibana]   log   [08:46:36.684] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX -> REINDEX_SOURCE_TO_TEMP_INDEX_BULK. took: 33ms.
[00:10:22]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_7.15.0_reindex_temp/2JTSB42_RTOtLGAeOhUidA] update_mapping [_doc]
[00:10:22]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_7.15.0_reindex_temp/2JTSB42_RTOtLGAeOhUidA] update_mapping [_doc]
[00:10:22]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_7.15.0_reindex_temp/2JTSB42_RTOtLGAeOhUidA] update_mapping [_doc]
[00:10:23]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_7.15.0_reindex_temp/2JTSB42_RTOtLGAeOhUidA] update_mapping [_doc]
[00:10:23]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_7.15.0_reindex_temp/2JTSB42_RTOtLGAeOhUidA] update_mapping [_doc]
[00:10:23]             │ proc [kibana]   log   [08:46:36.816] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_INDEX_BULK -> REINDEX_SOURCE_TO_TEMP_READ. took: 132ms.
[00:10:23]             │ proc [kibana]   log   [08:46:36.821] [info][savedobjects-service] [.kibana] Processed 12 documents out of 12.
[00:10:23]             │ proc [kibana]   log   [08:46:36.822] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_READ -> REINDEX_SOURCE_TO_TEMP_CLOSE_PIT. took: 5ms.
[00:10:23]             │ proc [kibana]   log   [08:46:36.824] [info][savedobjects-service] [.kibana] REINDEX_SOURCE_TO_TEMP_CLOSE_PIT -> SET_TEMP_WRITE_BLOCK. took: 3ms.
[00:10:23]             │ info [o.e.c.m.MetadataIndexStateService] [node-01] adding block write to indices [[.kibana_7.15.0_reindex_temp/2JTSB42_RTOtLGAeOhUidA]]
[00:10:23]             │ info [o.e.c.m.MetadataIndexStateService] [node-01] completed adding block write to indices [.kibana_7.15.0_reindex_temp]
[00:10:23]             │ proc [kibana]   log   [08:46:36.861] [info][savedobjects-service] [.kibana] SET_TEMP_WRITE_BLOCK -> CLONE_TEMP_TO_TARGET. took: 37ms.
[00:10:23]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] applying create index request using existing index [.kibana_7.15.0_reindex_temp] metadata
[00:10:23]             │ info [o.e.c.m.MetadataCreateIndexService] [node-01] [.kibana_7.15.0_001] creating index, cause [clone_index], templates [], shards [1]/[1]
[00:10:23]             │ info [o.e.c.r.a.AllocationService] [node-01] updating number_of_replicas to [0] for indices [.kibana_7.15.0_001]
[00:10:23]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_7.15.0_001/rgDkci2jRqeSKMqoNGHPXA] create_mapping [_doc]
[00:10:23]             │ proc [kibana]   log   [08:46:36.951] [info][savedobjects-service] [.kibana] CLONE_TEMP_TO_TARGET -> REFRESH_TARGET. took: 90ms.
[00:10:23]             │ proc [kibana]   log   [08:46:36.955] [info][savedobjects-service] [.kibana] REFRESH_TARGET -> OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT. took: 4ms.
[00:10:23]             │ proc [kibana]   log   [08:46:36.958] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_OPEN_PIT -> OUTDATED_DOCUMENTS_SEARCH_READ. took: 3ms.
[00:10:23]             │ proc [kibana]   log   [08:46:36.964] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_READ -> OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT. took: 6ms.
[00:10:23]             │ proc [kibana]   log   [08:46:36.967] [info][savedobjects-service] [.kibana] OUTDATED_DOCUMENTS_SEARCH_CLOSE_PIT -> UPDATE_TARGET_MAPPINGS. took: 3ms.
[00:10:23]             │ info [o.e.c.m.MetadataMappingService] [node-01] [.kibana_7.15.0_001/rgDkci2jRqeSKMqoNGHPXA] update_mapping [_doc]
[00:10:23]             │ proc [kibana]   log   [08:46:37.024] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS -> UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK. took: 57ms.
[00:10:23]             │ info [o.e.t.LoggingTaskListener] [node-01] 21137 finished with response BulkByScrollResponse[took=24.5ms,timed_out=false,sliceId=null,updated=12,created=0,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:10:23]             │ proc [kibana]   log   [08:46:37.129] [info][savedobjects-service] [.kibana] UPDATE_TARGET_MAPPINGS_WAIT_FOR_TASK -> MARK_VERSION_INDEX_READY. took: 105ms.
[00:10:23]             │ info [o.e.c.m.MetadataDeleteIndexService] [node-01] [.kibana_7.15.0_reindex_temp/2JTSB42_RTOtLGAeOhUidA] deleting index
[00:10:23]             │ proc [kibana]   log   [08:46:37.173] [info][savedobjects-service] [.kibana] MARK_VERSION_INDEX_READY -> DONE. took: 44ms.
[00:10:23]             │ proc [kibana]   log   [08:46:37.173] [info][savedobjects-service] [.kibana] Migration completed after 700ms
[00:10:23]             │ debg [x-pack/test/functional/es_archives/lens/basic] Migrated Kibana index after loading Kibana data
[00:10:23]             │ debg [x-pack/test/functional/es_archives/lens/basic] Ensured that default space exists in .kibana
[00:10:23]             │ debg applying update to kibana config: {"accessibility:disableAnimations":true,"dateFormat:tz":"UTC","visualization:visualize:legacyChartsLibrary":true,"visualization:visualize:legacyPieChartsLibrary":true}
[00:10:25]           └-: 
[00:10:25]             └-> "before all" hook in ""
[00:47:55]             └-: lens runtime fields
[00:47:55]               └-> "before all" hook for "should be able to add runtime field and use it"
[00:47:55]               └-> should be able to add runtime field and use it
[00:47:55]                 └-> "before each" hook: global before each for "should be able to add runtime field and use it"
[00:47:55]                 │ debg navigating to visualize url: http://localhost:61151/app/visualize#/
[00:47:55]                 │ debg navigate to: http://localhost:61151/app/visualize#/
[00:47:55]                 │ debg ... sleep(700) start
[00:47:55]                 │ debg browser[INFO] http://localhost:61151/app/visualize?_t=1629105849239#/ 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:47:55]                 │
[00:47:55]                 │ debg browser[INFO] http://localhost:61151/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:47:56]                 │ debg ... sleep(700) end
[00:47:56]                 │ debg returned from get, calling refresh
[00:47:57]                 │ debg browser[INFO] http://localhost:61151/app/visualize?_t=1629105849239#/ 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:47:57]                 │
[00:47:57]                 │ debg browser[INFO] http://localhost:61151/bootstrap.js 41:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:47:57]                 │ debg currentUrl = http://localhost:61151/app/visualize#/
[00:47:57]                 │          appUrl = http://localhost:61151/app/visualize#/
[00:47:57]                 │ debg TestSubjects.find(kibanaChrome)
[00:47:57]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:47:58]                 │ debg ... sleep(501) start
[00:47:58]                 │ debg ... sleep(501) end
[00:47:58]                 │ debg in navigateTo url = http://localhost:61151/app/visualize#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:47:58]                 │ debg --- retry.tryForTime error: URL changed, waiting for it to settle
[00:47:59]                 │ debg ... sleep(501) start
[00:47:59]                 │ debg ... sleep(501) end
[00:47:59]                 │ debg in navigateTo url = http://localhost:61151/app/visualize#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:47:59]                 │ debg isGlobalLoadingIndicatorVisible
[00:47:59]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:47:59]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:48:01]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:48:01]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:48:01]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:48:01]                 │ debg TestSubjects.exists(newItemButton)
[00:48:01]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:48:01]                 │ debg TestSubjects.click(newItemButton)
[00:48:01]                 │ debg Find.clickByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:48:01]                 │ debg Find.findByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:48:01]                 │ debg TestSubjects.find(visNewDialogGroups)
[00:48:01]                 │ debg Find.findByCssSelector('[data-test-subj="visNewDialogGroups"]') with timeout=10000
[00:48:02]                 │ debg TestSubjects.click(visType-lens)
[00:48:02]                 │ debg Find.clickByCssSelector('[data-test-subj="visType-lens"]') with timeout=10000
[00:48:02]                 │ debg Find.findByCssSelector('[data-test-subj="visType-lens"]') with timeout=10000
[00:48:02]                 │ debg isGlobalLoadingIndicatorVisible
[00:48:02]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:48:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:48:03]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:48:04]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:48:04]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:48:04]                 │ debg lensPage.goToTimeRange()
[00:48:04]                 │ debg TestSubjects.exists(noDataPopoverDismissButton)
[00:48:04]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="noDataPopoverDismissButton"]') with timeout=2500
[00:48:04]                 │ debg TestSubjects.click(noDataPopoverDismissButton)
[00:48:04]                 │ debg Find.clickByCssSelector('[data-test-subj="noDataPopoverDismissButton"]') with timeout=10000
[00:48:04]                 │ debg Find.findByCssSelector('[data-test-subj="noDataPopoverDismissButton"]') with timeout=10000
[00:48:04]                 │ debg Setting absolute range to Sep 19, 2015 @ 06:31:44.000 to Sep 23, 2015 @ 18:31:44.000
[00:48:04]                 │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:48:04]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:48:04]                 │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:48:04]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:48:04]                 │ debg TestSubjects.click(superDatePickerShowDatesButton)
[00:48:04]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:48:04]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:48:04]                 │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:48:04]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:48:04]                 │ debg TestSubjects.click(superDatePickerendDatePopoverButton)
[00:48:04]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:48:04]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:48:04]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:48:04]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:48:04]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:48:04]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:48:04]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:48:04]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:48:04]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:48:04]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 23, 2015 @ 18:31:44.000)
[00:48:04]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:48:04]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:48:04]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:48:05]                 │ debg TestSubjects.click(superDatePickerstartDatePopoverButton)
[00:48:05]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:48:05]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:48:05]                 │ debg Find.waitForElementStale with timeout=10000
[00:48:05]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:48:05]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:48:05]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:48:05]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:48:05]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:48:05]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:48:05]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:48:05]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 19, 2015 @ 06:31:44.000)
[00:48:05]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:48:05]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:48:05]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:48:06]                 │ debg Waiting up to 20000ms for Timepicker popover to close...
[00:48:06]                 │ debg TestSubjects.exists(superDatePickerAbsoluteDateInput)
[00:48:06]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=2500
[00:48:06]                 │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerAbsoluteDateInput"] is not displayed
[00:48:09]                 │ debg --- retry.tryForTime failed again with the same message...
[00:48:09]                 │ debg TestSubjects.exists(superDatePickerApplyTimeButton)
[00:48:09]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerApplyTimeButton"]') with timeout=2500
[00:48:12]                 │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerApplyTimeButton"] is not displayed
[00:48:12]                 │ debg TestSubjects.click(querySubmitButton)
[00:48:12]                 │ debg Find.clickByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:48:12]                 │ debg Find.findByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:48:13]                 │ debg Find.waitForElementStale with timeout=10000
[00:48:13]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:48:13]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:48:13]                 │ debg lensPage.switchToVisualization('lnsDatatable')
[00:48:13]                 │ debg lensPage.openChartSwitchPopover()
[00:48:13]                 │ debg TestSubjects.exists(lnsChartSwitchList)
[00:48:13]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="lnsChartSwitchList"]') with timeout=2500
[00:48:15]                 │ debg --- retry.tryForTime error: [data-test-subj="lnsChartSwitchList"] is not displayed
[00:48:16]                 │ debg TestSubjects.click(lnsChartSwitchPopover)
[00:48:16]                 │ debg Find.clickByCssSelector('[data-test-subj="lnsChartSwitchPopover"]') with timeout=10000
[00:48:16]                 │ debg Find.findByCssSelector('[data-test-subj="lnsChartSwitchPopover"]') with timeout=10000
[00:48:16]                 │ debg TestSubjects.exists(lnsChartSwitchList)
[00:48:16]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="lnsChartSwitchList"]') with timeout=120000
[00:48:16]                 │ debg lensPage.searchOnChartSwitch('lnsDatatable', )
[00:48:16]                 │ debg TestSubjects.setValue(lnsChartSwitchSearch, ble)
[00:48:16]                 │ debg TestSubjects.click(lnsChartSwitchSearch)
[00:48:16]                 │ debg Find.clickByCssSelector('[data-test-subj="lnsChartSwitchSearch"]') with timeout=10000
[00:48:16]                 │ debg Find.findByCssSelector('[data-test-subj="lnsChartSwitchSearch"]') with timeout=10000
[00:48:16]                 │ debg TestSubjects.click(lnsChartSwitchPopover_lnsDatatable)
[00:48:16]                 │ debg Find.clickByCssSelector('[data-test-subj="lnsChartSwitchPopover_lnsDatatable"]') with timeout=10000
[00:48:16]                 │ debg Find.findByCssSelector('[data-test-subj="lnsChartSwitchPopover_lnsDatatable"]') with timeout=10000
[00:48:16]                 │ debg isGlobalLoadingIndicatorVisible
[00:48:16]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:48:16]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:48:18]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:48:18]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:48:18]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:48:18]                 │ debg lensPage.clickAddField()
[00:48:18]                 │ debg TestSubjects.click(lnsIndexPatternActions)
[00:48:18]                 │ debg Find.clickByCssSelector('[data-test-subj="lnsIndexPatternActions"]') with timeout=10000
[00:48:18]                 │ debg Find.findByCssSelector('[data-test-subj="lnsIndexPatternActions"]') with timeout=10000
[00:48:18]                 │ debg TestSubjects.exists(indexPattern-add-field)
[00:48:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="indexPattern-add-field"]') with timeout=120000
[00:48:18]                 │ debg TestSubjects.click(indexPattern-add-field)
[00:48:18]                 │ debg Find.clickByCssSelector('[data-test-subj="indexPattern-add-field"]') with timeout=10000
[00:48:18]                 │ debg Find.findByCssSelector('[data-test-subj="indexPattern-add-field"]') with timeout=10000
[00:48:19]                 │ debg TestSubjects.setValue(nameField > input, runtimefield)
[00:48:19]                 │ debg TestSubjects.click(nameField > input)
[00:48:19]                 │ debg Find.clickByCssSelector('[data-test-subj="nameField"] [data-test-subj="input"]') with timeout=10000
[00:48:19]                 │ debg Find.findByCssSelector('[data-test-subj="nameField"] [data-test-subj="input"]') with timeout=10000
[00:48:19]                 │ debg TestSubjects.find(valueRow > toggle)
[00:48:19]                 │ debg Find.findByCssSelector('[data-test-subj="valueRow"] [data-test-subj="toggle"]') with timeout=10000
[00:48:19]                 │ debg updating checkbox valueRow > toggle from false to true
[00:48:19]                 │ debg TestSubjects.click(valueRow > toggle)
[00:48:19]                 │ debg Find.clickByCssSelector('[data-test-subj="valueRow"] [data-test-subj="toggle"]') with timeout=10000
[00:48:19]                 │ debg Find.findByCssSelector('[data-test-subj="valueRow"] [data-test-subj="toggle"]') with timeout=10000
[00:48:20]                 │ debg TestSubjects.find(valueRow)
[00:48:20]                 │ debg Find.findByCssSelector('[data-test-subj="valueRow"]') with timeout=10000
[00:48:21]                 │ debg TestSubjects.click(fieldSaveButton)
[00:48:21]                 │ debg Find.clickByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:48:21]                 │ debg Find.findByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:48:21]                 │ debg lensPage.searchField('runtime')
[00:48:21]                 │ debg TestSubjects.setValue(lnsIndexPatternFieldSearch, runtime)
[00:48:21]                 │ debg TestSubjects.click(lnsIndexPatternFieldSearch)
[00:48:21]                 │ debg Find.clickByCssSelector('[data-test-subj="lnsIndexPatternFieldSearch"]') with timeout=10000
[00:48:21]                 │ debg Find.findByCssSelector('[data-test-subj="lnsIndexPatternFieldSearch"]') with timeout=10000
[00:48:22]                 │ warn WebElementWrapper.click: element click intercepted: Element <input class="euiFieldText euiFieldText--fullWidth lnsInnerIndexPatternDataPanel__textField" data-test-subj="lnsIndexPatternFieldSearch" placeholder="Search field names" aria-label="Search field names" aria-describedby="datapanel_ba044bf1-fe73-11eb-87e5-d7089cd3558e" value=""> is not clickable at point (152, 281). Other element would receive the click: <div class="euiOverlayMask euiOverlayMask--belowHeader">...</div>
[00:48:22]                 │        (Session info: headless chrome=92.0.4515.131)
[00:48:22]                 │ debg finding element 'By(css selector, [data-test-subj="lnsIndexPatternFieldSearch"])' again, 2 attempts left
[00:48:23]                 │ debg lensPage.waitForField('runtimefield')
[00:48:23]                 │ debg TestSubjects.exists(lnsFieldListPanelField-runtimefield)
[00:48:23]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="lnsFieldListPanelField-runtimefield"]') with timeout=120000
[00:48:23]                 │ debg lensPage.dragFieldToWorkspace('runtimefield')
[00:48:23]                 │ debg lensPage.waitForLensDragDropToFinish()
[00:48:23]                 │ debg Find.existsByCssSelector('.lnsDragDrop-isActiveGroup') with timeout=2500
[00:48:26]                 │ debg isGlobalLoadingIndicatorVisible
[00:48:26]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:48:26]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:48:28]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:48:28]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:48:28]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:48:28]                 │ debg lensPage.waitForVisualization()
[00:48:28]                 │ debg isGlobalLoadingIndicatorVisible
[00:48:28]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:48:28]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:48:30]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:48:30]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:48:30]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:48:30]                 │ debg Waiting up to 20000ms for rendering count to stabilize...
[00:48:30]                 │ debg TestSubjects.find(lnsVisualizationContainer)
[00:48:30]                 │ debg Find.findByCssSelector('[data-test-subj="lnsVisualizationContainer"]') with timeout=10000
[00:48:30]                 │ debg ... sleep(1000) start
[00:48:31]                 │ debg ... sleep(1000) end
[00:48:31]                 │ debg TestSubjects.find(lnsVisualizationContainer)
[00:48:31]                 │ debg Find.findByCssSelector('[data-test-subj="lnsVisualizationContainer"]') with timeout=10000
[00:48:31]                 │ debg lensPage.getDatatableHeaderText(0)
[00:48:31]                 │ debg lensPage.getDatatableHeader(0)
[00:48:31]                 │ debg Find.findByCssSelector('[data-test-subj="lnsDataTable"] [data-test-subj="dataGridHeader"] [role=columnheader]:nth-child(1)') with timeout=10000
[00:48:31]                 │ debg lensPage.getDatatableCellText(0, 0)
[00:48:31]                 │ debg lensPage.getDatatableCell(0, 0)
[00:48:31]                 │ debg Find.findByCssSelector('.euiDataGrid') with timeout=10000
[00:48:31]                 │ debg Find.findByCssSelector('[data-test-subj="lnsDataTable"] [data-test-subj="dataGridRowCell"]:nth-child(2)') with timeout=10000
[00:48:31]                 └- ✓ pass  (36.5s) "lens app  lens runtime fields should be able to add runtime field and use it"
[00:48:31]               └-> should able to filter runtime fields
[00:48:31]                 └-> "before each" hook: global before each for "should able to filter runtime fields"
[00:48:31]                 │ debg lensPage.clickTableCellAction(0, 0, 'lensDatatableFilterOut')
[00:48:31]                 │ debg lensPage.getDatatableCell(0, 0)
[00:48:31]                 │ debg Find.findByCssSelector('.euiDataGrid') with timeout=10000
[00:48:31]                 │ debg Find.findByCssSelector('[data-test-subj="lnsDataTable"] [data-test-subj="dataGridRowCell"]:nth-child(2)') with timeout=10000
[00:48:32]                 │ debg lensPage.waitForVisualization()
[00:48:32]                 │ debg isGlobalLoadingIndicatorVisible
[00:48:32]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:48:32]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:48:33]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:48:34]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:48:34]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:48:34]                 │ debg Waiting up to 20000ms for rendering count to stabilize...
[00:48:34]                 │ debg TestSubjects.find(lnsVisualizationContainer)
[00:48:34]                 │ debg Find.findByCssSelector('[data-test-subj="lnsVisualizationContainer"]') with timeout=10000
[00:48:34]                 │ debg ... sleep(1000) start
[00:48:35]                 │ debg ... sleep(1000) end
[00:48:35]                 │ debg TestSubjects.find(lnsVisualizationContainer)
[00:48:35]                 │ debg Find.findByCssSelector('[data-test-subj="lnsVisualizationContainer"]') with timeout=10000
[00:48:35]                 │ debg lensPage.isShowingNoResults()
[00:48:35]                 │ debg TestSubjects.find(lnsWorkspace)
[00:48:35]                 │ debg Find.findByCssSelector('[data-test-subj="lnsWorkspace"]') with timeout=10000
[00:48:35]                 │ debg --- retry.try error: expected false to equal true
[00:48:35]                 │ debg lensPage.clickTableCellAction(0, 0, 'lensDatatableFilterOut')
[00:48:35]                 │ debg lensPage.getDatatableCell(0, 0)
[00:48:35]                 │ debg Find.findByCssSelector('.euiDataGrid') with timeout=10000
[00:48:35]                 │ debg Find.findByCssSelector('[data-test-subj="lnsDataTable"] [data-test-subj="dataGridRowCell"]:nth-child(2)') with timeout=10000
[00:48:35]                 │ debg lensPage.waitForVisualization()
[00:48:35]                 │ debg isGlobalLoadingIndicatorVisible
[00:48:35]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:48:35]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:48:37]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:48:38]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:48:38]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:48:38]                 │ debg Waiting up to 20000ms for rendering count to stabilize...
[00:48:38]                 │ debg TestSubjects.find(lnsVisualizationContainer)
[00:48:38]                 │ debg Find.findByCssSelector('[data-test-subj="lnsVisualizationContainer"]') with timeout=10000
[00:48:38]                 │ debg ... sleep(1000) start
[00:48:39]                 │ debg ... sleep(1000) end
[00:48:39]                 │ debg TestSubjects.find(lnsVisualizationContainer)
[00:48:39]                 │ debg Find.findByCssSelector('[data-test-subj="lnsVisualizationContainer"]') with timeout=10000
[00:48:39]                 │ debg lensPage.isShowingNoResults()
[00:48:39]                 │ debg TestSubjects.find(lnsWorkspace)
[00:48:39]                 │ debg Find.findByCssSelector('[data-test-subj="lnsWorkspace"]') with timeout=10000
[00:48:39]                 │ debg TestSubjects.click(showFilterActions)
[00:48:39]                 │ debg Find.clickByCssSelector('[data-test-subj="showFilterActions"]') with timeout=10000
[00:48:39]                 │ debg Find.findByCssSelector('[data-test-subj="showFilterActions"]') with timeout=10000
[00:48:39]                 │ debg TestSubjects.click(removeAllFilters)
[00:48:39]                 │ debg Find.clickByCssSelector('[data-test-subj="removeAllFilters"]') with timeout=10000
[00:48:39]                 │ debg Find.findByCssSelector('[data-test-subj="removeAllFilters"]') with timeout=10000
[00:48:39]                 │ debg isGlobalLoadingIndicatorVisible
[00:48:39]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:48:39]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:48:40]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:48:41]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:48:41]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:48:41]                 │ debg lensPage.waitForVisualization()
[00:48:41]                 │ debg isGlobalLoadingIndicatorVisible
[00:48:41]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:48:41]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:48:42]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:48:43]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:48:43]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:48:43]                 │ debg Waiting up to 20000ms for rendering count to stabilize...
[00:48:43]                 │ debg TestSubjects.find(lnsVisualizationContainer)
[00:48:43]                 │ debg Find.findByCssSelector('[data-test-subj="lnsVisualizationContainer"]') with timeout=10000
[00:48:43]                 │ debg ... sleep(1000) start
[00:48:44]                 │ debg ... sleep(1000) end
[00:48:44]                 │ debg TestSubjects.find(lnsVisualizationContainer)
[00:48:44]                 │ debg Find.findByCssSelector('[data-test-subj="lnsVisualizationContainer"]') with timeout=10000
[00:48:44]                 └- ✓ pass  (12.6s) "lens app  lens runtime fields should able to filter runtime fields"
[00:48:44]               └-> should able to edit field
[00:48:44]                 └-> "before each" hook: global before each for "should able to edit field"
[00:48:44]                 │ debg lensPage.clickField('runtimefield')
[00:48:44]                 │ debg TestSubjects.click(lnsFieldListPanelField-runtimefield)
[00:48:44]                 │ debg Find.clickByCssSelector('[data-test-subj="lnsFieldListPanelField-runtimefield"]') with timeout=10000
[00:48:44]                 │ debg Find.findByCssSelector('[data-test-subj="lnsFieldListPanelField-runtimefield"]') with timeout=10000
[00:48:44]                 │ debg lensPage.editField()
[00:48:44]                 │ debg TestSubjects.click(lnsFieldListPanelEdit)
[00:48:44]                 │ debg Find.clickByCssSelector('[data-test-subj="lnsFieldListPanelEdit"]') with timeout=10000
[00:48:44]                 │ debg Find.findByCssSelector('[data-test-subj="lnsFieldListPanelEdit"]') with timeout=10000
[00:48:45]                 │ debg TestSubjects.missingOrFail(lnsFieldListPanelEdit)
[00:48:45]                 │ debg Find.waitForDeletedByCssSelector('[data-test-subj="lnsFieldListPanelEdit"]') with timeout=2500
[00:48:46]                 │ debg TestSubjects.setValue(nameField > input, runtimefield2)
[00:48:46]                 │ debg TestSubjects.click(nameField > input)
[00:48:46]                 │ debg Find.clickByCssSelector('[data-test-subj="nameField"] [data-test-subj="input"]') with timeout=10000
[00:48:46]                 │ debg Find.findByCssSelector('[data-test-subj="nameField"] [data-test-subj="input"]') with timeout=10000
[00:48:46]                 │ debg TestSubjects.click(fieldSaveButton)
[00:48:46]                 │ debg Find.clickByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:48:46]                 │ debg Find.findByCssSelector('[data-test-subj="fieldSaveButton"]') with timeout=10000
[00:48:46]                 │ debg TestSubjects.setValue(saveModalConfirmText, change)
[00:48:46]                 │ debg TestSubjects.click(saveModalConfirmText)
[00:48:46]                 │ debg Find.clickByCssSelector('[data-test-subj="saveModalConfirmText"]') with timeout=10000
[00:48:46]                 │ debg Find.findByCssSelector('[data-test-subj="saveModalConfirmText"]') with timeout=10000
[00:48:47]                 │ debg TestSubjects.click(confirmModalConfirmButton)
[00:48:47]                 │ debg Find.clickByCssSelector('[data-test-subj="confirmModalConfirmButton"]') with timeout=10000
[00:48:47]                 │ debg Find.findByCssSelector('[data-test-subj="confirmModalConfirmButton"]') with timeout=10000
[00:48:47]                 │ debg lensPage.searchField('runtime')
[00:48:47]                 │ debg TestSubjects.setValue(lnsIndexPatternFieldSearch, runtime)
[00:48:47]                 │ debg TestSubjects.click(lnsIndexPatternFieldSearch)
[00:48:47]                 │ debg Find.clickByCssSelector('[data-test-subj="lnsIndexPatternFieldSearch"]') with timeout=10000
[00:48:47]                 │ debg Find.findByCssSelector('[data-test-subj="lnsIndexPatternFieldSearch"]') with timeout=10000
[00:48:49]                 │ debg lensPage.waitForField('runtimefield2')
[00:48:49]                 │ debg TestSubjects.exists(lnsFieldListPanelField-runtimefield2)
[00:48:49]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="lnsFieldListPanelField-runtimefield2"]') with timeout=120000
[00:48:52]                 │ debg --- retry.tryForTime error: [data-test-subj="lnsFieldListPanelField-runtimefield2"] is not displayed
[00:48:55]                 │ debg --- retry.tryForTime failed again with the same message...
[00:48:58]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:01]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:04]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:07]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:10]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:13]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:16]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:19]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:22]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:26]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:29]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:32]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:35]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:38]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:41]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:44]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:47]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:50]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:53]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:56]                 │ debg --- retry.tryForTime failed again with the same message...
[00:49:59]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:02]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:05]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:08]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:11]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:14]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:17]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:20]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:23]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:26]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:29]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:32]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:35]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:38]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:41]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:44]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:47]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:50]                 │ debg --- retry.tryForTime failed again with the same message...
[00:50:51]                 │ debg --- retry.try error: expected testSubject(lnsFieldListPanelField-runtimefield2) to exist
[00:50:51]                 │ info Taking screenshot "/dev/shm/workspace/parallel/15/kibana/x-pack/test/functional/screenshots/failure/lens app  lens runtime fields should able to edit field.png"
[00:50:51]                 │ info Current URL is: http://localhost:61151/app/lens#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:%272015-09-19T06:31:44.000Z%27,to:%272015-09-23T18:31:44.000Z%27))
[00:50:51]                 │ info Saving page source to: /dev/shm/workspace/parallel/15/kibana/x-pack/test/functional/failure_debug/html/lens app  lens runtime fields should able to edit field.html
[00:50:51]                 └- ✖ fail: lens app  lens runtime fields should able to edit field
[00:50:51]                 │      retry.try timeout: Error: expected testSubject(lnsFieldListPanelField-runtimefield2) to exist
[00:50:51]                 │     at TestSubjects.existOrFail (/dev/shm/workspace/parallel/15/kibana/test/functional/services/common/test_subjects.ts:45:13)
[00:50:51]                 │     at /dev/shm/workspace/parallel/15/kibana/x-pack/test/functional/page_objects/lens_page.ts:242:9
[00:50:51]                 │     at runAttempt (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry_for_success.ts:27:15)
[00:50:51]                 │     at retryForSuccess (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry_for_success.ts:66:21)
[00:50:51]                 │     at RetryService.try (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry.ts:31:12)
[00:50:51]                 │     at Object.waitForField (/dev/shm/workspace/parallel/15/kibana/x-pack/test/functional/page_objects/lens_page.ts:241:7)
[00:50:51]                 │     at Context.<anonymous> (/dev/shm/workspace/parallel/15/kibana/x-pack/test/functional/apps/lens/runtime_fields.ts:55:7)
[00:50:51]                 │     at Object.apply (/dev/shm/workspace/parallel/15/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)
[00:50:51]                 │   Error: retry.try timeout: Error: expected testSubject(lnsFieldListPanelField-runtimefield2) to exist
[00:50:51]                 │       at TestSubjects.existOrFail (/dev/shm/workspace/parallel/15/kibana/test/functional/services/common/test_subjects.ts:45:13)
[00:50:51]                 │       at /dev/shm/workspace/parallel/15/kibana/x-pack/test/functional/page_objects/lens_page.ts:242:9
[00:50:51]                 │       at runAttempt (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry_for_success.ts:27:15)
[00:50:51]                 │       at retryForSuccess (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry_for_success.ts:66:21)
[00:50:51]                 │       at RetryService.try (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry.ts:31:12)
[00:50:51]                 │       at Object.waitForField (test/functional/page_objects/lens_page.ts:241:7)
[00:50:51]                 │       at Context.<anonymous> (test/functional/apps/lens/runtime_fields.ts:55:7)
[00:50:51]                 │       at Object.apply (/dev/shm/workspace/parallel/15/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)
[00:50:51]                 │       at onFailure (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry_for_success.ts:17:9)
[00:50:51]                 │       at retryForSuccess (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry_for_success.ts:57:13)
[00:50:51]                 │       at RetryService.try (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry.ts:31:12)
[00:50:51]                 │       at Object.waitForField (test/functional/page_objects/lens_page.ts:241:7)
[00:50:51]                 │       at Context.<anonymous> (test/functional/apps/lens/runtime_fields.ts:55:7)
[00:50:51]                 │       at Object.apply (/dev/shm/workspace/parallel/15/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)
[00:50:51]                 │ 
[00:50:51]                 │

Stack Trace

Error: retry.try timeout: Error: expected testSubject(lnsFieldListPanelField-runtimefield2) to exist
    at TestSubjects.existOrFail (/dev/shm/workspace/parallel/15/kibana/test/functional/services/common/test_subjects.ts:45:13)
    at /dev/shm/workspace/parallel/15/kibana/x-pack/test/functional/page_objects/lens_page.ts:242:9
    at runAttempt (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry_for_success.ts:27:15)
    at retryForSuccess (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry_for_success.ts:66:21)
    at RetryService.try (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry.ts:31:12)
    at Object.waitForField (test/functional/page_objects/lens_page.ts:241:7)
    at Context.<anonymous> (test/functional/apps/lens/runtime_fields.ts:55:7)
    at Object.apply (/dev/shm/workspace/parallel/15/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)
    at onFailure (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry_for_success.ts:17:9)
    at retryForSuccess (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry_for_success.ts:57:13)
    at RetryService.try (/dev/shm/workspace/parallel/15/kibana/test/common/services/retry/retry.ts:31:12)
    at Object.waitForField (test/functional/page_objects/lens_page.ts:241:7)
    at Context.<anonymous> (test/functional/apps/lens/runtime_fields.ts:55:7)
    at Object.apply (/dev/shm/workspace/parallel/15/kibana/node_modules/@kbn/test/target_node/functional_test_runner/lib/mocha/wrap_function.js:87:16)

Metrics [docs]

✅ unchanged

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

@rudolf rudolf merged commit d2bc753 into elastic:7.x Aug 16, 2021
@rudolf rudolf deleted the backport/7.x/pr-108213 branch May 16, 2022 20:31
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Reviewers
No reviews
Assignees
No one assigned
Labels
backport
Projects
None yet
Milestone
No milestone
Development

Successfully merging this pull request may close these issues.
2 participants
@rudolf @kibanamachine