Skip to content

[7.9] Legacy logging: fix remoteAddress being duplicated in userAgent field (#76751)#76846

Merged
pgayvallet merged 1 commit intoelastic:7.9from
pgayvallet:backport/7.9/pr-76751
Sep 7, 2020
Merged

[7.9] Legacy logging: fix remoteAddress being duplicated in userAgent field (#76751)#76846
pgayvallet merged 1 commit intoelastic:7.9from
pgayvallet:backport/7.9/pr-76751

Conversation

@pgayvallet
Copy link
Copy Markdown
Contributor

@pgayvallet pgayvallet commented Sep 7, 2020

Backports the following commits to 7.9:

@pgayvallet pgayvallet added the backport This PR is a backport of another PR label Sep 7, 2020
@kibanamachine
Copy link
Copy Markdown
Contributor

kibanamachine commented Sep 7, 2020

💛 Build succeeded, but was flaky

Test Failures

Firefox UI Functional Tests.test/functional/apps/visualize/_tsvb_chart·ts.visualize app visual builder switch index patterns should be able to switch between index patterns

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:13:05]         └-: visualize app
[00:13:05]           └-> "before all" hook
[00:13:05]           └-> "before all" hook
[00:13:05]             │ debg Starting visualize before method
[00:13:05]             │ info [logstash_functional] Loading "mappings.json"
[00:13:05]             │ info [logstash_functional] Loading "data.json.gz"
[00:13:05]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0]
[00:13:05]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.22][0]]]).
[00:13:05]             │ info [logstash_functional] Created index "logstash-2015.09.22"
[00:13:05]             │ debg [logstash_functional] "logstash-2015.09.22" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:13:05]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0]
[00:13:05]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.20][0]]]).
[00:13:05]             │ info [logstash_functional] Created index "logstash-2015.09.20"
[00:13:05]             │ debg [logstash_functional] "logstash-2015.09.20" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:13:05]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0]
[00:13:05]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.21][0]]]).
[00:13:05]             │ info [logstash_functional] Created index "logstash-2015.09.21"
[00:13:05]             │ debg [logstash_functional] "logstash-2015.09.21" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:13:15]             │ info progress: 12927
[00:13:16]             │ info [logstash_functional] Indexed 4633 docs into "logstash-2015.09.22"
[00:13:16]             │ info [logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:13:16]             │ info [logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:13:16]             │ info [long_window_logstash] Loading "mappings.json"
[00:13:16]             │ info [long_window_logstash] Loading "data.json.gz"
[00:13:16]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [long-window-logstash-0] creating index, cause [api], templates [], shards [1]/[0]
[00:13:16]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[long-window-logstash-0][0]]]).
[00:13:16]             │ info [long_window_logstash] Created index "long-window-logstash-0"
[00:13:16]             │ debg [long_window_logstash] "long-window-logstash-0" settings {"index":{"analysis":{"analyzer":{"makelogs_url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:13:26]             │ info progress: 11439
[00:13:28]             │ info [long_window_logstash] Indexed 14005 docs into "long-window-logstash-0"
[00:13:29]             │ info [visualize] Loading "mappings.json"
[00:13:29]             │ info [visualize] Loading "data.json"
[00:13:29]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana_1/-8LhDDe6RDW3OutHDTiyjA] deleting index
[00:13:29]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana_2/kHyPiAAzSEiiRlHPek5CeQ] deleting index
[00:13:29]             │ info [visualize] Deleted existing index [".kibana_2",".kibana_1"]
[00:13:29]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana] creating index, cause [api], templates [], shards [1]/[1]
[00:13:29]             │ info [visualize] Created index ".kibana"
[00:13:29]             │ debg [visualize] ".kibana" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:13:29]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana/LHixjCDYQyqtJdoOOxa3Ng] update_mapping [_doc]
[00:13:29]             │ info [visualize] Indexed 12 docs into ".kibana"
[00:13:29]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana/LHixjCDYQyqtJdoOOxa3Ng] update_mapping [_doc]
[00:13:29]             │ debg Migrating saved objects
[00:13:29]             │ proc [kibana]   log   [08:35:38.869] [info][savedobjects-service] Creating index .kibana_2.
[00:13:29]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana_2] creating index, cause [api], templates [], shards [1]/[1]
[00:13:29]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] updating number_of_replicas to [0] for indices [.kibana_2]
[00:13:29]             │ proc [kibana]   log   [08:35:38.927] [info][savedobjects-service] Reindexing .kibana to .kibana_1
[00:13:29]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1]
[00:13:29]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] updating number_of_replicas to [0] for indices [.kibana_1]
[00:13:29]             │ info [o.e.t.LoggingTaskListener] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] 10070 finished with response BulkByScrollResponse[took=53.8ms,timed_out=false,sliceId=null,updated=0,created=12,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:13:29]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana/LHixjCDYQyqtJdoOOxa3Ng] deleting index
[00:13:29]             │ proc [kibana]   log   [08:35:39.279] [info][savedobjects-service] Migrating .kibana_1 saved objects to .kibana_2
[00:13:29]             │ proc [kibana]   log   [08:35:39.297] [error][savedobjects-service] Error: Unable to migrate the corrupt Saved Object document index-pattern:test_index*. To prevent Kibana from performing a migration on every restart, please delete or fix this document by ensuring that the namespace and type in the document's id matches the values in the namespace and type fields.
[00:13:29]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana_2/EfRcQLXfSZmvpbQIlQveSA] update_mapping [_doc]
[00:13:29]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana_2/EfRcQLXfSZmvpbQIlQveSA] update_mapping [_doc]
[00:13:29]             │ proc [kibana]   log   [08:35:39.380] [info][savedobjects-service] Pointing alias .kibana to .kibana_2.
[00:13:30]             │ proc [kibana]   log   [08:35:39.429] [info][savedobjects-service] Finished in 562ms.
[00:13:30]             │ debg applying update to kibana config: {"accessibility:disableAnimations":true,"dateFormat:tz":"UTC"}
[00:13:30]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [.kibana_2/EfRcQLXfSZmvpbQIlQveSA] update_mapping [_doc]
[00:13:31]             │ debg replacing kibana config doc: {"defaultIndex":"logstash-*","format:bytes:defaultPattern":"0,0.[000]b"}
[00:14:10]           └-: 
[00:14:10]             └-> "before all" hook
[00:14:10]             └-: visual builder
[00:14:10]               └-> "before all" hook
[00:18:03]               └-: switch index patterns
[00:18:03]                 └-> "before all" hook
[00:18:03]                 └-> should be able to switch between index patterns
[00:18:03]                   └-> "before each" hook: global before each
[00:18:03]                   └-> "before each" hook
[00:18:03]                     │ debg navigating to visualize url: http://localhost:61101/app/visualize#/
[00:18:03]                     │ debg navigate to: http://localhost:61101/app/visualize#/
[00:18:03]                     │ debg browser[log] "^ A single error about an inline script not firing due to content security policy is expected!"
[00:18:03]                     │ debg ... sleep(700) start
[00:18:04]                     │ debg ... sleep(700) end
[00:18:04]                     │ debg returned from get, calling refresh
[00:18:05]                     │ debg browser[info] "INFO: 2020-09-07T08:40:15Z\n  Adding connection to http://localhost:61101/elasticsearch\n\n"
[00:18:06]                     │ERROR browser[error] (new TypeError("NetworkError when attempting to fetch resource.", ""))
[00:18:06]                     │ debg browser[log] "^ A single error about an inline script not firing due to content security policy is expected!"
[00:18:06]                     │ debg currentUrl = http://localhost:61101/app/visualize#/
[00:18:06]                     │          appUrl = http://localhost:61101/app/visualize#/
[00:18:06]                     │ debg TestSubjects.find(kibanaChrome)
[00:18:06]                     │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:18:08]                     │ debg browser[info] "INFO: 2020-09-07T08:40:17Z\n  Adding connection to http://localhost:61101/elasticsearch\n\n"
[00:18:08]                     │ debg ... sleep(501) start
[00:18:09]                     │ debg ... sleep(501) end
[00:18:09]                     │ debg in navigateTo url = http://localhost:61101/app/visualize#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:18:09]                     │ debg --- retry.try error: URL changed, waiting for it to settle
[00:18:09]                     │ debg ... sleep(501) start
[00:18:10]                     │ debg ... sleep(501) end
[00:18:10]                     │ debg in navigateTo url = http://localhost:61101/app/visualize#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:18:10]                     │ debg TestSubjects.exists(statusPageContainer)
[00:18:10]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:18:12]                     │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:18:13]                     │ debg TestSubjects.exists(newItemButton)
[00:18:13]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="newItemButton"]') with timeout=5000
[00:18:13]                     │ debg TestSubjects.click(newItemButton)
[00:18:13]                     │ debg Find.clickByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:18:13]                     │ debg Find.findByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:18:13]                     │ debg TestSubjects.find(visNewDialogTypes)
[00:18:13]                     │ debg Find.findByCssSelector('[data-test-subj="visNewDialogTypes"]') with timeout=10000
[00:18:13]                     │ debg TestSubjects.click(visType-metrics)
[00:18:13]                     │ debg Find.clickByCssSelector('[data-test-subj="visType-metrics"]') with timeout=10000
[00:18:13]                     │ debg Find.findByCssSelector('[data-test-subj="visType-metrics"]') with timeout=10000
[00:18:14]                     │ debg browser[log] "^ A single error about an inline script not firing due to content security policy is expected!"
[00:18:14]                     │ debg isGlobalLoadingIndicatorVisible
[00:18:14]                     │ debg TestSubjects.exists(globalLoadingIndicator)
[00:18:14]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:18:16]                     │ debg browser[info] "INFO: 2020-09-07T08:40:25Z\n  Adding connection to http://localhost:61101/elasticsearch\n\n"
[00:18:16]                     │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:18:17]                     │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:18:17]                     │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:18:17]                     │ debg TestSubjects.exists(tvbVisEditor)
[00:18:17]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="tvbVisEditor"]') with timeout=10000
[00:18:17]                   └-> "before each" hook
[00:18:17]                     │ debg Load kibana_sample_data_flights data
[00:18:17]                     │ info [kibana_sample_data_flights] Loading "mappings.json"
[00:18:17]                     │ info [kibana_sample_data_flights] Loading "data.json.gz"
[00:18:17]                     │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] [kibana_sample_data_flights] creating index, cause [api], templates [], shards [1]/[0]
[00:18:17]                     │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xxl-1599465506535697909] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[kibana_sample_data_flights][0]]]).
[00:18:17]                     │ info [kibana_sample_data_flights] Created index "kibana_sample_data_flights"
[00:18:17]                     │ debg [kibana_sample_data_flights] "kibana_sample_data_flights" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:18:21]                     │ info [kibana_sample_data_flights] Indexed 13059 docs into "kibana_sample_data_flights"
[00:18:21]                     │ debg navigateToActualUrl http://localhost:61101/app/visualize#create?type=metrics
[00:18:21]                     │ debg browser[log] "^ A single error about an inline script not firing due to content security policy is expected!"
[00:18:21]                     │ debg currentUrl = http://localhost:61101/app/visualize#create?type=metrics
[00:18:21]                     │          appUrl = http://localhost:61101/app/visualize#create?type=metrics
[00:18:21]                     │ debg TestSubjects.find(kibanaChrome)
[00:18:21]                     │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:18:23]                     │ debg browser[info] "INFO: 2020-09-07T08:40:32Z\n  Adding connection to http://localhost:61101/elasticsearch\n\n"
[00:18:24]                     │ debg Wait for initializing TSVB editor
[00:18:24]                     │ debg TestSubjects.exists(tvbVisEditor)
[00:18:24]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="tvbVisEditor"]') with timeout=10000
[00:18:27]                     │ debg --- retry.tryForTime error: [data-test-subj="tvbVisEditor"] is not displayed
[00:18:30]                     │ debg --- retry.tryForTime failed again with the same message...
[00:18:38]                     │ debg Set absolute time range from "Sep 19, 2015 @ 06:31:44.000" to "Sep 22, 2015 @ 18:31:44.000"
[00:18:38]                     │ debg Setting absolute range to Sep 19, 2015 @ 06:31:44.000 to Sep 22, 2015 @ 18:31:44.000
[00:18:38]                     │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:18:38]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:18:38]                     │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:18:38]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:18:38]                     │ debg TestSubjects.click(superDatePickerShowDatesButton)
[00:18:38]                     │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:18:38]                     │ debg Find.findByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:18:38]                     │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:18:38]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:18:38]                     │ debg TestSubjects.click(superDatePickerendDatePopoverButton)
[00:18:38]                     │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:18:38]                     │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:18:39]                     │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:18:39]                     │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:18:39]                     │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:18:39]                     │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:18:39]                     │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:18:39]                     │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:18:39]                     │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:18:39]                     │ debg TestSubjects.find(superDatePickerAbsoluteDateInput)
[00:18:39]                     │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:18:40]                     │ debg ... sleep(500) start
[00:18:41]                     │ debg ... sleep(500) end
[00:18:41]                     │ debg TestSubjects.click(superDatePickerstartDatePopoverButton)
[00:18:41]                     │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:18:41]                     │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:18:41]                     │ debg Find.waitForElementStale with timeout=10000
[00:18:41]                     │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:18:41]                     │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:18:41]                     │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:18:41]                     │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:18:42]                     │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:18:42]                     │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:18:42]                     │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:18:42]                     │ debg TestSubjects.find(superDatePickerAbsoluteDateInput)
[00:18:42]                     │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:18:43]                     │ debg TestSubjects.exists(superDatePickerApplyTimeButton)
[00:18:43]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerApplyTimeButton"]') with timeout=2500
[00:18:46]                     │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerApplyTimeButton"] is not displayed
[00:18:46]                     │ debg TestSubjects.click(querySubmitButton)
[00:18:46]                     │ debg Find.clickByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:18:46]                     │ debg Find.findByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:18:46]                     │ debg Find.waitForElementStale with timeout=10000
[00:18:47]                     │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:18:47]                     │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:18:47]                     │ debg ... sleep(2000) start
[00:18:49]                     │ debg ... sleep(2000) end
[00:18:49]                     │ debg TestSubjects.find(metricTsvbTypeBtn)
[00:18:49]                     │ debg Find.findByCssSelector('[data-test-subj="metricTsvbTypeBtn"]') with timeout=10000
[00:18:49]                     │ debg TestSubjects.exists(tsvbMetricValue)
[00:18:49]                     │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="tsvbMetricValue"]') with timeout=10000
[00:18:49]                   │ debg Waiting up to 20000ms for rendering count to stabilize...
[00:18:49]                   │ debg TestSubjects.find(visualizationLoader)
[00:18:49]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:18:49]                   │ debg -- firstCount=3
[00:18:49]                   │ debg ... sleep(1000) start
[00:18:50]                   │ debg ... sleep(1000) end
[00:18:50]                   │ debg TestSubjects.find(visualizationLoader)
[00:18:50]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:18:50]                   │ debg -- secondCount=3
[00:18:50]                   │ debg Find.findByCssSelector('.tvbVisMetric__value--primary') with timeout=10000
[00:18:50]                   │ debg TestSubjects.click(metricEditorPanelOptionsBtn)
[00:18:50]                   │ debg Find.clickByCssSelector('[data-test-subj="metricEditorPanelOptionsBtn"]') with timeout=10000
[00:18:50]                   │ debg Find.findByCssSelector('[data-test-subj="metricEditorPanelOptionsBtn"]') with timeout=10000
[00:18:51]                   │ debg isGlobalLoadingIndicatorVisible
[00:18:51]                   │ debg TestSubjects.exists(globalLoadingIndicator)
[00:18:51]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:18:52]                   │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:18:53]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:18:53]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:18:53]                   │ debg Setting absolute range to Oct 22, 2018 @ 00:00:00.000 to Oct 28, 2018 @ 23:59:59.999
[00:18:53]                   │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:18:53]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:18:53]                   │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:18:53]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:18:55]                   │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerShowDatesButton"] is not displayed
[00:18:56]                   │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:18:56]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:18:56]                   │ debg TestSubjects.click(superDatePickerendDatePopoverButton)
[00:18:56]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:18:56]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:18:57]                   │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:18:57]                   │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:18:57]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:18:57]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:18:57]                   │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:18:57]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:18:57]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:18:57]                   │ debg TestSubjects.find(superDatePickerAbsoluteDateInput)
[00:18:57]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:18:58]                   │ debg ... sleep(500) start
[00:18:59]                   │ debg ... sleep(500) end
[00:18:59]                   │ debg TestSubjects.click(superDatePickerstartDatePopoverButton)
[00:18:59]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:18:59]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:18:59]                   │ debg Find.waitForElementStale with timeout=10000
[00:18:59]                   │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:18:59]                   │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:18:59]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:18:59]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:19:00]                   │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:19:00]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:00]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:00]                   │ debg TestSubjects.find(superDatePickerAbsoluteDateInput)
[00:19:00]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:01]                   │ debg TestSubjects.exists(superDatePickerApplyTimeButton)
[00:19:01]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerApplyTimeButton"]') with timeout=2500
[00:19:03]                   │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerApplyTimeButton"] is not displayed
[00:19:04]                   │ debg TestSubjects.click(querySubmitButton)
[00:19:04]                   │ debg Find.clickByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:19:04]                   │ debg Find.findByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:19:04]                   │ debg Find.waitForElementStale with timeout=10000
[00:19:04]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:19:04]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:19:04]                   │ debg TestSubjects.find(metricsIndexPatternInput)
[00:19:04]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternInput"]') with timeout=10000
[00:19:05]                   │ debg isGlobalLoadingIndicatorVisible
[00:19:05]                   │ debg TestSubjects.exists(globalLoadingIndicator)
[00:19:05]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:19:05]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:19:05]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:19:06]                   │ debg comboBox.clearInputField, comboBoxSelector:metricsIndexPatternFieldsSelect
[00:19:06]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:19:06]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:19:07]                   │ debg comboBox.set, comboBoxSelector: metricsIndexPatternFieldsSelect
[00:19:07]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:19:07]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:19:07]                   │ debg comboBox.setElement, value: timestamp
[00:19:07]                   │ debg comboBox.isOptionSelected, value: timestamp
[00:19:09]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:09]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:09]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="timestamp"]') with timeout=2500
[00:19:11]                   │ debg Find.findByCssSelector('.euiFilterSelectItem') with timeout=5000
[00:19:21]                   │ debg --- retry.try error: Waiting for element to be located By(css selector, .euiFilterSelectItem)
[00:19:21]                   │      Wait timed out after 10009ms
[00:19:22]                   │ debg comboBox.clearInputField, comboBoxSelector:metricsIndexPatternFieldsSelect
[00:19:22]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:19:22]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:19:22]                   │ debg comboBox.set, comboBoxSelector: metricsIndexPatternFieldsSelect
[00:19:22]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:19:22]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:19:22]                   │ debg comboBox.setElement, value: timestamp
[00:19:22]                   │ debg comboBox.isOptionSelected, value: timestamp
[00:19:24]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:24]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:24]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="timestamp"]') with timeout=2500
[00:19:27]                   │ debg Find.findByCssSelector('.euiFilterSelectItem') with timeout=5000
[00:19:37]                   │ debg --- retry.try error: Waiting for element to be located By(css selector, .euiFilterSelectItem)
[00:19:37]                   │      Wait timed out after 10013ms
[00:19:37]                   │ debg comboBox.clearInputField, comboBoxSelector:metricsIndexPatternFieldsSelect
[00:19:37]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:19:37]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:19:38]                   │ debg comboBox.set, comboBoxSelector: metricsIndexPatternFieldsSelect
[00:19:38]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:19:38]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:19:38]                   │ debg comboBox.setElement, value: timestamp
[00:19:38]                   │ debg comboBox.isOptionSelected, value: timestamp
[00:19:40]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:19:40]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:19:40]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="timestamp"]') with timeout=2500
[00:19:42]                   │ debg Find.findByCssSelector('.euiFilterSelectItem') with timeout=5000
[00:20:25]                   │ debg --- retry.try error: Waiting for element to be located By(css selector, .euiFilterSelectItem)
[00:20:25]                   │      Wait timed out after 42855ms
[00:20:26]                   │ debg comboBox.clearInputField, comboBoxSelector:metricsIndexPatternFieldsSelect
[00:20:26]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:20:26]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:20:31]                   │ debg comboBox.set, comboBoxSelector: metricsIndexPatternFieldsSelect
[00:20:31]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:20:31]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:20:31]                   │ debg comboBox.setElement, value: timestamp
[00:20:31]                   │ debg comboBox.isOptionSelected, value: timestamp
[00:20:34]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:20:34]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:20:34]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="timestamp"]') with timeout=2500
[00:20:36]                   │ debg Find.findByCssSelector('.euiFilterSelectItem') with timeout=5000
[00:20:46]                   │ debg --- retry.try error: Waiting for element to be located By(css selector, .euiFilterSelectItem)
[00:20:46]                   │      Wait timed out after 10104ms
[00:20:47]                   │ debg comboBox.clearInputField, comboBoxSelector:metricsIndexPatternFieldsSelect
[00:20:47]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:20:47]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:20:47]                   │ debg comboBox.set, comboBoxSelector: metricsIndexPatternFieldsSelect
[00:20:47]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:20:47]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:20:47]                   │ debg comboBox.setElement, value: timestamp
[00:20:47]                   │ debg comboBox.isOptionSelected, value: timestamp
[00:20:49]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:20:49]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:20:49]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="timestamp"]') with timeout=2500
[00:20:52]                   │ debg Find.findByCssSelector('.euiFilterSelectItem') with timeout=5000
[00:21:02]                   │ debg --- retry.try error: Waiting for element to be located By(css selector, .euiFilterSelectItem)
[00:21:02]                   │      Wait timed out after 10011ms
[00:21:02]                   │ debg comboBox.clearInputField, comboBoxSelector:metricsIndexPatternFieldsSelect
[00:21:02]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:21:02]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:21:02]                   │ debg comboBox.set, comboBoxSelector: metricsIndexPatternFieldsSelect
[00:21:02]                   │ debg TestSubjects.find(metricsIndexPatternFieldsSelect)
[00:21:02]                   │ debg Find.findByCssSelector('[data-test-subj="metricsIndexPatternFieldsSelect"]') with timeout=10000
[00:21:02]                   │ debg comboBox.setElement, value: timestamp
[00:21:02]                   │ debg comboBox.isOptionSelected, value: timestamp
[00:21:05]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:21:05]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:21:05]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="timestamp"]') with timeout=2500
[00:21:07]                   │ debg Find.findByCssSelector('.euiFilterSelectItem') with timeout=5000
[00:21:17]                   │ debg --- retry.try error: Waiting for element to be located By(css selector, .euiFilterSelectItem)
[00:21:17]                   │      Wait timed out after 10012ms
[00:21:18]                   │ debg --- retry.tryForTime error: retry.try timeout: TimeoutError: Waiting for element to be located By(css selector, .euiFilterSelectItem)
[00:21:18]                   │      Wait timed out after 10012ms
[00:21:18]                   │          at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
[00:21:18]                   │          at process._tickCallback (internal/process/next_tick.js:68:7)
[00:21:18]                   │ info Taking screenshot "/dev/shm/workspace/parallel/10/kibana/test/functional/screenshots/failure/visualize app  visual builder switch index patterns should be able to switch between index patterns.png"
[00:21:18]                   │ info Current URL is: http://localhost:61101/app/visualize#/create?type=metrics&_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:%272018-10-22T00:00:00.000Z%27,to:%272018-10-28T23:59:59.999Z%27))&_a=(filters:!(),linked:!f,query:(language:kuery,query:%27%27),uiState:(),vis:(aggs:!(),params:(axis_formatter:number,axis_position:left,axis_scale:normal,background_color_rules:!((id:da25f880-f0e5-11ea-a59b-97604991725f)),default_index_pattern:%27logstash-*%27,default_timefield:%27@timestamp%27,id:%2761ca57f0-469d-11e7-af02-69e470af7417%27,index_pattern:kibana_sample_data_flights,interval:%27%27,isModelInvalid:!f,series:!((axis_position:right,chart_type:line,color:%2368BC00,fill:0.5,formatter:number,id:%2761ca57f1-469d-11e7-af02-69e470af7417%27,line_width:1,metrics:!((id:%2761ca57f2-469d-11e7-af02-69e470af7417%27,type:count)),point_size:1,separate_axis:0,split_color_mode:kibana,split_mode:everything,stacked:none)),show_grid:1,show_legend:1,time_field:%27%27,tooltip_mode:show_all,type:metric),title:%27%27,type:metrics))
[00:21:18]                   │ info Saving page source to: /dev/shm/workspace/parallel/10/kibana/test/functional/failure_debug/html/visualize app  visual builder switch index patterns should be able to switch between index patterns.html
[00:21:18]                   └- ✖ fail: visualize app  visual builder switch index patterns should be able to switch between index patterns
[00:21:18]                   │      retry.tryForTime timeout: Error: retry.try timeout: TimeoutError: Waiting for element to be located By(css selector, .euiFilterSelectItem)
[00:21:18]                   │ Wait timed out after 10012ms
[00:21:18]                   │     at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
[00:21:18]                   │     at process._tickCallback (internal/process/next_tick.js:68:7)
[00:21:18]                   │     at onFailure (/dev/shm/workspace/parallel/10/kibana/test/common/services/retry/retry_for_success.ts:28:9)
[00:21:18]                   │     at retryForSuccess (/dev/shm/workspace/parallel/10/kibana/test/common/services/retry/retry_for_success.ts:68:13)
[00:21:18]                   │   Error: retry.tryForTime timeout: Error: retry.try timeout: TimeoutError: Waiting for element to be located By(css selector, .euiFilterSelectItem)
[00:21:18]                   │   Wait timed out after 10012ms
[00:21:18]                   │       at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
[00:21:18]                   │       at process._tickCallback (internal/process/next_tick.js:68:7)
[00:21:18]                   │       at onFailure (test/common/services/retry/retry_for_success.ts:28:9)
[00:21:18]                   │       at retryForSuccess (test/common/services/retry/retry_for_success.ts:68:13)
[00:21:18]                   │       at onFailure (test/common/services/retry/retry_for_success.ts:28:9)
[00:21:18]                   │       at retryForSuccess (test/common/services/retry/retry_for_success.ts:68:13)
[00:21:18]                   │ 
[00:21:18]                   │

Stack Trace

Error: retry.tryForTime timeout: Error: retry.try timeout: TimeoutError: Waiting for element to be located By(css selector, .euiFilterSelectItem)
Wait timed out after 10012ms
    at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
    at process._tickCallback (internal/process/next_tick.js:68:7)
    at onFailure (test/common/services/retry/retry_for_success.ts:28:9)
    at retryForSuccess (test/common/services/retry/retry_for_success.ts:68:13)
    at onFailure (test/common/services/retry/retry_for_success.ts:28:9)
    at retryForSuccess (test/common/services/retry/retry_for_success.ts:68:13)

Build metrics

✅ unchanged

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

@pgayvallet pgayvallet merged commit f5b4aac into elastic:7.9 Sep 7, 2020
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 This PR is a backport of another PR

Projects

None yet

Milestone

No milestone

Development

Successfully merging this pull request may close these issues.

2 participants

@pgayvallet @kibanamachine