[Search Source] Specify strict_date_optional_time_nanos as a format #90415

majagrubic · 2021-02-05T10:47:05Z

Summary

I am opening this PR to move the discussion from various Slack messages I've had over the past few days to a central place.
The problem is related to:
elastic/elasticsearch#67063

This breaks Discover: When ES returns a timestamp without proper precision, we cannot sort documents properly, so they appear out of order.

@nik9000 suggested the following workaround: we could specify strict_date_optional_time_nanos as the format for the timestamp field if it is date_nanos and this will return the timestamp in the proper format.
This works well and makes sense and should be a given if the request is something like:

GET maja-index/_search
{
  "fields": [{"field": "@timestamp", "format": "strict_date_optional_time_nanos"}]
}

But the problem is that Discover in most cases requests '*' as a list of fields. In that case, we'd need to check if at least one of the fields in the index is date_nanos and append that formatter, so the request would look something like:

GET maja-index/_search
{
  "fields": [{"field": "*", "format": "strict_date_optional_time_nanos"}]
}

The problem with this is:

It works sometimes and will indeed return both nanos and "regular" date fields properly formatted.

But for another index I'm getting "request doesn't support [format]" exception. I guess this depends on the mapping itself?

Should we even be doing this? Looks like a lot of boilerplate in search_source

Checklist

Delete any items that are not applicable to this PR.

Any text added follows EUI's writing guidelines, uses sentence case text and includes i18n support
Documentation was added for features that require explanation or tutorials
Unit or functional tests were updated or added to match the most common scenarios
Any UI touched in this PR is usable by keyboard only (learn more about keyboard accessibility)
Any UI touched in this PR does not create any new axe failures (run axe in browser: FF, Chrome)
If a plugin configuration key changed, check if it needs to be allowlisted in the cloud and added to the docker list
This renders correctly on smaller devices using a responsive layout. (You can test this in your browser)
This was checked for cross-browser compatibility

For maintainers

This was checked for breaking API changes and was labeled appropriately

…r for date_nanos field

kibanamachine · 2021-02-05T11:21:30Z

💔 Build Failed

continuous-integration/kibana-ci/pull-request
Commit: 8e9911e
Pipeline Steps (look for red circles / failed steps)
Interpreting CI Failures

Failed CI Steps

Test Failures

Kibana Pipeline / general / Chrome UI Functional Tests.test/functional/apps/discover/_date_nanos·ts.discover app date_nanos should show a timestamp with nanoseconds in the first result row

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:07:08]         └-: discover app
[00:07:08]           └-> "before all" hook in "discover app"
[00:07:08]           └-> "before all" hook in "discover app"
[00:19:39]           └-: date_nanos
[00:19:39]             └-> "before all" hook for "should show a timestamp with nanoseconds in the first result row"
[00:19:39]             └-> "before all" hook for "should show a timestamp with nanoseconds in the first result row"
[00:19:39]               │ info [date_nanos] Loading "mappings.json"
[00:19:39]               │ info [date_nanos] Loading "data.json"
[00:19:39]               │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [date-nanos] creating index, cause [api], templates [], shards [1]/[0]
[00:19:39]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[date-nanos][0]]])." previous.health="YELLOW" reason="shards started [[date-nanos][0]]"
[00:19:39]               │ info [date_nanos] Created index "date-nanos"
[00:19:39]               │ debg [date_nanos] "date-nanos" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:19:39]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [date-nanos/zwoJGKfhTSmUKAKEGSP22g] update_mapping [_doc]
[00:19:39]               │ info [date_nanos] Indexed 9 docs into "date-nanos"
[00:19:39]               │ info [date_nanos] Indexed 2 docs into ".kibana"
[00:19:39]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2/lxbHPSyOQhOFNVYlLSOCaw] update_mapping [_doc]
[00:19:39]               │ debg Migrating saved objects
[00:19:39]               │ proc [kibana]   log   [11:32:53.383] [info][savedobjects-service] Creating index .kibana_3.
[00:19:39]               │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3] creating index, cause [api], templates [], shards [1]/[1]
[00:19:39]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] updating number_of_replicas to [0] for indices [.kibana_3]
[00:19:39]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_3][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_3][0]]"
[00:19:39]               │ proc [kibana]   log   [11:32:53.425] [info][savedobjects-service] Migrating .kibana_2 saved objects to .kibana_3
[00:19:39]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/i20IBEZUShuwuLaeZRgExA] update_mapping [_doc]
[00:19:39]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/i20IBEZUShuwuLaeZRgExA] update_mapping [_doc]
[00:19:40]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/i20IBEZUShuwuLaeZRgExA] update_mapping [_doc]
[00:19:40]               │ proc [kibana]   log   [11:32:53.487] [info][savedobjects-service] Pointing alias .kibana to .kibana_3.
[00:19:40]               │ proc [kibana]   log   [11:32:53.508] [info][savedobjects-service] Finished in 127ms.
[00:19:40]               │ debg [date_nanos] Migrated Kibana index after loading Kibana data
[00:19:40]               │ debg replacing kibana config doc: {"defaultIndex":"date-nanos"}
[00:19:40]               │ debg navigating to discover url: http://localhost:61101/app/discover#/
[00:19:40]               │ debg navigate to: http://localhost:61101/app/discover#/
[00:19:41]               │ debg browser[INFO] http://localhost:61101/app/discover?_t=1612524774413#/ 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:19:41]               │
[00:19:41]               │ debg browser[INFO] http://localhost:61101/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:19:41]               │ debg ... sleep(700) start
[00:19:41]               │ debg ... sleep(700) end
[00:19:41]               │ debg returned from get, calling refresh
[00:19:42]               │ debg browser[INFO] http://localhost:61101/app/discover?_t=1612524774413#/ 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:19:42]               │
[00:19:42]               │ debg browser[INFO] http://localhost:61101/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:19:42]               │ debg currentUrl = http://localhost:61101/app/discover#/
[00:19:42]               │          appUrl = http://localhost:61101/app/discover#/
[00:19:42]               │ debg TestSubjects.find(kibanaChrome)
[00:19:42]               │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:19:42]               │ debg ... sleep(501) start
[00:19:42]               │ debg ... sleep(501) end
[00:19:42]               │ debg in navigateTo url = http://localhost:61101/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(columns:!(_source),filters:!(),index:date-nanos,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:19:42]               │ debg --- retry.try error: URL changed, waiting for it to settle
[00:19:43]               │ debg ... sleep(501) start
[00:19:43]               │ debg ... sleep(501) end
[00:19:43]               │ debg in navigateTo url = http://localhost:61101/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(columns:!(_source),filters:!(),index:date-nanos,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:19:43]               │ debg TestSubjects.exists(statusPageContainer)
[00:19:43]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:19:46]               │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:19:46]               │ debg Setting absolute range to Sep 22, 2019 @ 20:31:44.000 to Sep 23, 2019 @ 03:31:44.000
[00:19:46]               │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:19:46]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:19:46]               │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:19:46]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:19:46]               │ debg TestSubjects.click(superDatePickerShowDatesButton)
[00:19:46]               │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:19:46]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:19:47]               │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:19:47]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:19:47]               │ debg TestSubjects.click(superDatePickerendDatePopoverButton)
[00:19:47]               │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:19:47]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:19:47]               │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:19:47]               │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:19:47]               │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:19:47]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:19:47]               │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:19:47]               │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:47]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:47]               │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 23, 2019 @ 03:31:44.000)
[00:19:47]               │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:19:47]               │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:47]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:47]               │ debg TestSubjects.click(superDatePickerstartDatePopoverButton)
[00:19:47]               │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:19:47]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:19:47]               │ debg Find.waitForElementStale with timeout=10000
[00:19:48]               │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:19:48]               │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:19:48]               │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:19:48]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:19:48]               │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:19:48]               │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:48]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:48]               │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 22, 2019 @ 20:31:44.000)
[00:19:48]               │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:19:48]               │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:48]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:19:48]               │ debg TestSubjects.exists(superDatePickerApplyTimeButton)
[00:19:48]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerApplyTimeButton"]') with timeout=2500
[00:19:51]               │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerApplyTimeButton"] is not displayed
[00:19:51]               │ debg TestSubjects.click(querySubmitButton)
[00:19:51]               │ debg Find.clickByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:19:51]               │ debg Find.findByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:19:51]               │ debg Find.waitForElementStale with timeout=10000
[00:19:52]               │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:19:52]               │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:19:52]             └-> should show a timestamp with nanoseconds in the first result row
[00:19:52]               └-> "before each" hook: global before each for "should show a timestamp with nanoseconds in the first result row"
[00:19:52]               │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:19:52]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:19:52]               │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:19:52]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:19:54]               │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerShowDatesButton"] is not displayed
[00:19:55]               │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:19:55]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:19:55]               │ debg TestSubjects.getVisibleText(superDatePickerstartDatePopoverButton)
[00:19:55]               │ debg TestSubjects.find(superDatePickerstartDatePopoverButton)
[00:19:55]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:19:55]               │ debg TestSubjects.getVisibleText(superDatePickerendDatePopoverButton)
[00:19:55]               │ debg TestSubjects.find(superDatePickerendDatePopoverButton)
[00:19:55]               │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:19:55]               │ debg Find.findByCssSelector('tr.kbnDocTable__row:nth-child(1)') with timeout=10000
[00:20:05]               │ info Taking screenshot "/dev/shm/workspace/parallel/10/kibana/test/functional/screenshots/failure/discover app date_nanos should show a timestamp with nanoseconds in the first result row.png"
[00:20:05]               │ info Current URL is: http://localhost:61101/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:%272019-09-22T20:31:44.000Z%27,to:%272019-09-23T03:31:44.000Z%27))&_a=(columns:!(_source),filters:!(),index:date-nanos,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:20:05]               │ info Saving page source to: /dev/shm/workspace/parallel/10/kibana/test/functional/failure_debug/html/discover app date_nanos should show a timestamp with nanoseconds in the first result row.html
[00:20:05]               └- ✖ fail: discover app date_nanos should show a timestamp with nanoseconds in the first result row
[00:20:05]               │      TimeoutError: Waiting for element to be located By(css selector, tr.kbnDocTable__row:nth-child(1))
[00:20:05]               │ Wait timed out after 10042ms
[00:20:05]               │       at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
[00:20:05]               │       at runMicrotasks (<anonymous>)
[00:20:05]               │       at processTicksAndRejections (internal/process/task_queues.js:93:5)
[00:20:05]               │ 
[00:20:05]               │

Stack Trace

TimeoutError: Waiting for element to be located By(css selector, tr.kbnDocTable__row:nth-child(1))
Wait timed out after 10042ms
    at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:93:5) {
  remoteStacktrace: ''
}

Kibana Pipeline / general / Chrome UI Functional Tests.test/functional/apps/context/_date_nanos·js.context app context view for date_nanos displays predessors - anchor - successors in right order

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:30]         └-: context app
[00:00:30]           └-> "before all" hook in "context app"
[00:00:30]           └-> "before all" hook in "context app"
[00:00:30]             │ info [logstash_functional] Loading "mappings.json"
[00:00:30]             │ info [logstash_functional] Loading "data.json.gz"
[00:00:30]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0]
[00:00:30]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.22][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.22][0]]"
[00:00:30]             │ info [logstash_functional] Created index "logstash-2015.09.22"
[00:00:30]             │ debg [logstash_functional] "logstash-2015.09.22" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:30]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0]
[00:00:30]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.20][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.20][0]]"
[00:00:30]             │ info [logstash_functional] Created index "logstash-2015.09.20"
[00:00:30]             │ debg [logstash_functional] "logstash-2015.09.20" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:30]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0]
[00:00:30]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.21][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.21][0]]"
[00:00:30]             │ info [logstash_functional] Created index "logstash-2015.09.21"
[00:00:30]             │ debg [logstash_functional] "logstash-2015.09.21" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:40]             │ info progress: 12020
[00:00:41]             │ info [logstash_functional] Indexed 4633 docs into "logstash-2015.09.22"
[00:00:41]             │ info [logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:00:41]             │ info [logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:00:42]             │ info [visualize] Loading "mappings.json"
[00:00:42]             │ info [visualize] Loading "data.json"
[00:00:42]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_1/qGMMYSz-TTaFuucxjl7q2w] deleting index
[00:00:42]             │ info [visualize] Deleted existing index ".kibana_1"
[00:00:42]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana] creating index, cause [api], templates [], shards [1]/[1]
[00:00:42]             │ info [visualize] Created index ".kibana"
[00:00:42]             │ debg [visualize] ".kibana" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:00:42]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana/fc4W5oi2Q4yjBSheMLttcw] update_mapping [_doc]
[00:00:42]             │ info [visualize] Indexed 13 docs into ".kibana"
[00:00:42]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana/fc4W5oi2Q4yjBSheMLttcw] update_mapping [_doc]
[00:00:42]             │ debg Migrating saved objects
[00:00:42]             │ proc [kibana]   log   [11:17:27.372] [info][savedobjects-service] Creating index .kibana_2.
[00:00:42]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2] creating index, cause [api], templates [], shards [1]/[1]
[00:00:42]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] updating number_of_replicas to [0] for indices [.kibana_2]
[00:00:42]             │ proc [kibana]   log   [11:17:27.443] [info][savedobjects-service] Reindexing .kibana to .kibana_1
[00:00:42]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1]
[00:00:42]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] updating number_of_replicas to [0] for indices [.kibana_1]
[00:00:42]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.tasks] creating index, cause [auto(task api)], templates [], shards [1]/[1]
[00:00:42]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] updating number_of_replicas to [0] for indices [.tasks]
[00:00:42]             │ info [o.e.t.LoggingTaskListener] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] 799 finished with response BulkByScrollResponse[took=69ms,timed_out=false,sliceId=null,updated=0,created=13,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:00:42]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana/fc4W5oi2Q4yjBSheMLttcw] deleting index
[00:00:42]             │ proc [kibana]   log   [11:17:27.826] [info][savedobjects-service] Migrating .kibana_1 saved objects to .kibana_2
[00:00:42]             │ proc [kibana]   log   [11:17:27.843] [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:00:42]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2/V5wxSoCnTPOC6uJvebb-uQ] update_mapping [_doc]
[00:00:42]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2/V5wxSoCnTPOC6uJvebb-uQ] update_mapping [_doc]
[00:00:42]             │ proc [kibana]   log   [11:17:27.933] [info][savedobjects-service] Pointing alias .kibana to .kibana_2.
[00:00:42]             │ proc [kibana]   log   [11:17:27.968] [info][savedobjects-service] Finished in 598ms.
[00:00:42]             │ debg [visualize] Migrated Kibana index after loading Kibana data
[00:00:42]             │ debg applying update to kibana config: {"accessibility:disableAnimations":true,"dateFormat:tz":"UTC","visualization:visualize:legacyChartsLibrary":true}
[00:00:42]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2/V5wxSoCnTPOC6uJvebb-uQ] update_mapping [_doc]
[00:00:44]             │ debg replacing kibana config doc: {"defaultIndex":"logstash-*"}
[00:00:45]             │ debg navigating to discover url: http://localhost:61111/app/discover#/
[00:00:45]             │ debg navigate to: http://localhost:61111/app/discover#/
[00:00:45]             │ debg browser[INFO] http://localhost:61111/app/discover?_t=1612523850461#/ 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:00:45]             │
[00:00:45]             │ debg browser[INFO] http://localhost:61111/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:00:45]             │ debg ... sleep(700) start
[00:00:46]             │ debg ... sleep(700) end
[00:00:46]             │ debg returned from get, calling refresh
[00:00:46]             │ debg browser[INFO] http://localhost:61111/app/discover?_t=1612523850461#/ 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:00:46]             │
[00:00:46]             │ debg browser[INFO] http://localhost:61111/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:00:46]             │ debg currentUrl = http://localhost:61111/app/discover#/
[00:00:46]             │          appUrl = http://localhost:61111/app/discover#/
[00:00:46]             │ debg TestSubjects.find(kibanaChrome)
[00:00:46]             │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:00:46]             │ debg ... sleep(501) start
[00:00:47]             │ debg ... sleep(501) end
[00:00:47]             │ debg in navigateTo url = http://localhost:61111/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(columns:!(_source),filters:!(),index:%27logstash-*%27,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:00:47]             │ debg --- retry.try error: URL changed, waiting for it to settle
[00:00:47]             │ debg ... sleep(501) start
[00:00:48]             │ debg ... sleep(501) end
[00:00:48]             │ debg in navigateTo url = http://localhost:61111/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(columns:!(_source),filters:!(),index:%27logstash-*%27,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:00:48]             │ debg TestSubjects.exists(statusPageContainer)
[00:00:48]             │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:00:51]             │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:02:23]           └-: context view for date_nanos
[00:02:23]             └-> "before all" hook for "displays predessors - anchor - successors in right order "
[00:02:23]             └-> "before all" hook for "displays predessors - anchor - successors in right order "
[00:02:23]               │ info [date_nanos] Loading "mappings.json"
[00:02:23]               │ info [date_nanos] Loading "data.json"
[00:02:23]               │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [date-nanos] creating index, cause [api], templates [], shards [1]/[0]
[00:02:23]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[date-nanos][0]]])." previous.health="YELLOW" reason="shards started [[date-nanos][0]]"
[00:02:23]               │ info [date_nanos] Created index "date-nanos"
[00:02:23]               │ debg [date_nanos] "date-nanos" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:02:23]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [date-nanos/sSdEyiN2T6a1yhFCruk25g] update_mapping [_doc]
[00:02:23]               │ info [date_nanos] Indexed 9 docs into "date-nanos"
[00:02:23]               │ info [date_nanos] Indexed 2 docs into ".kibana"
[00:02:23]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2/V5wxSoCnTPOC6uJvebb-uQ] update_mapping [_doc]
[00:02:23]               │ debg Migrating saved objects
[00:02:23]               │ proc [kibana]   log   [11:19:08.805] [info][savedobjects-service] Creating index .kibana_3.
[00:02:23]               │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3] creating index, cause [api], templates [], shards [1]/[1]
[00:02:23]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] updating number_of_replicas to [0] for indices [.kibana_3]
[00:02:23]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_3][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_3][0]]"
[00:02:23]               │ proc [kibana]   log   [11:19:08.861] [info][savedobjects-service] Migrating .kibana_2 saved objects to .kibana_3
[00:02:23]               │ proc [kibana]   log   [11:19:08.870] [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:02:23]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/OiJhJSDBTjybw5pUUxpIIQ] update_mapping [_doc]
[00:02:23]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/OiJhJSDBTjybw5pUUxpIIQ] update_mapping [_doc]
[00:02:23]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/OiJhJSDBTjybw5pUUxpIIQ] update_mapping [_doc]
[00:02:23]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/OiJhJSDBTjybw5pUUxpIIQ] update_mapping [_doc]
[00:02:23]               │ proc [kibana]   log   [11:19:09.003] [info][savedobjects-service] Pointing alias .kibana to .kibana_3.
[00:02:24]               │ proc [kibana]   log   [11:19:09.035] [info][savedobjects-service] Finished in 233ms.
[00:02:24]               │ debg [date_nanos] Migrated Kibana index after loading Kibana data
[00:02:24]               │ debg replacing kibana config doc: {"defaultIndex":"date-nanos"}
[00:02:24]               │ debg applying update to kibana config: {"context:defaultSize":"1","context:step":"3"}
[00:02:25]             └-> displays predessors - anchor - successors in right order 
[00:02:25]               └-> "before each" hook: global before each for "displays predessors - anchor - successors in right order "
[00:02:25]               │ debg browser.get(http://localhost:61111/app/discover#/context/date-nanos/AU_x3-TaGFA8no6Qj999Z?_a=(columns:!('@message')))
[00:02:25]               │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:25]               │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:25]               │ debg browser[INFO] http://localhost:61111/app/discover?_t=1612523950864#/context/date-nanos/AU_x3-TaGFA8no6Qj999Z?_a=(columns:!(%27@message%27)) 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:02:25]               │
[00:02:25]               │ debg browser[INFO] http://localhost:61111/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:02:26]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:02:26]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:02:36]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:02:36]               │      Wait timed out after 10031ms
[00:02:36]               │ERROR browser[SEVERE] http://localhost:61111/40180/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 366:78979 TypeError: Cannot create property 'format' on string '*'
[00:02:36]               │          at http://localhost:61111/40180/bundles/plugin/data/data.plugin.js:1:556816
[00:02:36]               │          at Array.map (<anonymous>)
[00:02:36]               │          at search_source_SearchSource.flatten (http://localhost:61111/40180/bundles/plugin/data/data.plugin.js:1:556547)
[00:02:36]               │          at t.project (http://localhost:61111/40180/bundles/plugin/data/data.plugin.js:1:550684)
[00:02:36]               │          at t._next (http://localhost:61111/40180/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:429:131209)
[00:02:36]               │          at t.next (http://localhost:61111/40180/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:21:40224)
[00:02:36]               │          at http://localhost:61111/40180/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:298:107517 "Possibly unhandled rejection: {}"
[00:02:37]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:02:37]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:02:47]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:02:47]               │      Wait timed out after 10053ms
[00:02:47]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:02:47]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:02:57]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:02:57]               │      Wait timed out after 10042ms
[00:02:58]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:02:58]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:08]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:08]               │      Wait timed out after 10018ms
[00:03:08]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:08]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:18]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:18]               │      Wait timed out after 10025ms
[00:03:19]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:19]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:29]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:29]               │      Wait timed out after 10006ms
[00:03:29]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:29]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:39]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:39]               │      Wait timed out after 10048ms
[00:03:40]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:40]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:50]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:50]               │      Wait timed out after 10018ms
[00:03:50]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:50]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:04:00]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:00]               │      Wait timed out after 10060ms
[00:04:01]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:04:01]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:04:11]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:11]               │      Wait timed out after 10021ms
[00:04:11]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:04:11]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:04:22]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:22]               │      Wait timed out after 10046ms
[00:04:22]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:04:22]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:04:32]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:32]               │      Wait timed out after 10055ms
[00:04:33]               │ info Taking screenshot "/dev/shm/workspace/parallel/11/kibana/test/functional/screenshots/failure/context app context view for date_nanos displays predessors - anchor - successors in right order .png"
[00:04:33]               │ info Current URL is: http://localhost:61111/app/discover#/context/date-nanos/AU_x3-TaGFA8no6Qj999Z?_a=(columns:!(%27@message%27))
[00:04:33]               │ info Saving page source to: /dev/shm/workspace/parallel/11/kibana/test/functional/failure_debug/html/context app context view for date_nanos displays predessors - anchor - successors in right order .html
[00:04:33]               └- ✖ fail: context app context view for date_nanos displays predessors - anchor - successors in right order 
[00:04:33]               │      Error: retry.try timeout: TimeoutError: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:33]               │ Wait timed out after 10055ms
[00:04:33]               │     at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
[00:04:33]               │     at processTicksAndRejections (internal/process/task_queues.js:93:5)
[00:04:33]               │       at onFailure (test/common/services/retry/retry_for_success.ts:17:9)
[00:04:33]               │       at retryForSuccess (test/common/services/retry/retry_for_success.ts:57:13)
[00:04:33]               │       at Retry.try (test/common/services/retry/retry.ts:32:14)
[00:04:33]               │       at ContextPage.waitUntilContextLoadingHasFinished (test/functional/page_objects/context_page.ts:82:14)
[00:04:33]               │       at ContextPage.navigateTo (test/functional/page_objects/context_page.ts:40:7)
[00:04:33]               │       at Context.<anonymous> (test/functional/apps/context/_date_nanos.js:39:7)
[00:04:33]               │       at Object.apply (packages/kbn-test/src/functional_test_runner/lib/mocha/wrap_function.js:73:16)
[00:04:33]               │ 
[00:04:33]               │

Stack Trace

Error: retry.try timeout: TimeoutError: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
Wait timed out after 10055ms
    at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
    at processTicksAndRejections (internal/process/task_queues.js:93:5)
    at onFailure (test/common/services/retry/retry_for_success.ts:17:9)
    at retryForSuccess (test/common/services/retry/retry_for_success.ts:57:13)
    at Retry.try (test/common/services/retry/retry.ts:32:14)
    at ContextPage.waitUntilContextLoadingHasFinished (test/functional/page_objects/context_page.ts:82:14)
    at ContextPage.navigateTo (test/functional/page_objects/context_page.ts:40:7)
    at Context.<anonymous> (test/functional/apps/context/_date_nanos.js:39:7)
    at Object.apply (packages/kbn-test/src/functional_test_runner/lib/mocha/wrap_function.js:73:16)

Kibana Pipeline / general / Chrome UI Functional Tests.test/functional/apps/context/_date_nanos·js.context app context view for date_nanos displays predessors - anchor - successors in right order

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:32]         └-: context app
[00:00:32]           └-> "before all" hook in "context app"
[00:00:32]           └-> "before all" hook in "context app"
[00:00:32]             │ info [logstash_functional] Loading "mappings.json"
[00:00:32]             │ info [logstash_functional] Loading "data.json.gz"
[00:00:32]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0]
[00:00:32]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.22][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.22][0]]"
[00:00:32]             │ info [logstash_functional] Created index "logstash-2015.09.22"
[00:00:32]             │ debg [logstash_functional] "logstash-2015.09.22" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:32]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0]
[00:00:32]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.20][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.20][0]]"
[00:00:32]             │ info [logstash_functional] Created index "logstash-2015.09.20"
[00:00:32]             │ debg [logstash_functional] "logstash-2015.09.20" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:32]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0]
[00:00:33]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.21][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.21][0]]"
[00:00:33]             │ info [logstash_functional] Created index "logstash-2015.09.21"
[00:00:33]             │ debg [logstash_functional] "logstash-2015.09.21" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:42]             │ info progress: 8415
[00:00:47]             │ info [logstash_functional] Indexed 4633 docs into "logstash-2015.09.22"
[00:00:47]             │ info [logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:00:47]             │ info [logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:00:48]             │ info [visualize] Loading "mappings.json"
[00:00:48]             │ info [visualize] Loading "data.json"
[00:00:48]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_1/Kgk86yURS2CCWjNFtJn-6w] deleting index
[00:00:48]             │ info [visualize] Deleted existing index ".kibana_1"
[00:00:48]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana] creating index, cause [api], templates [], shards [1]/[1]
[00:00:48]             │ info [visualize] Created index ".kibana"
[00:00:48]             │ debg [visualize] ".kibana" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:00:48]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana/QGaozGzPQTaECJ7IbpAPiA] update_mapping [_doc]
[00:00:48]             │ info [visualize] Indexed 13 docs into ".kibana"
[00:00:48]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana/QGaozGzPQTaECJ7IbpAPiA] update_mapping [_doc]
[00:00:48]             │ debg Migrating saved objects
[00:00:48]             │ proc [kibana]   log   [11:11:43.256] [info][savedobjects-service] Creating index .kibana_2.
[00:00:48]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2] creating index, cause [api], templates [], shards [1]/[1]
[00:00:48]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] updating number_of_replicas to [0] for indices [.kibana_2]
[00:00:48]             │ proc [kibana]   log   [11:11:43.340] [info][savedobjects-service] Reindexing .kibana to .kibana_1
[00:00:48]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1]
[00:00:48]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] updating number_of_replicas to [0] for indices [.kibana_1]
[00:00:49]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.tasks] creating index, cause [auto(task api)], templates [], shards [1]/[1]
[00:00:49]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] updating number_of_replicas to [0] for indices [.tasks]
[00:00:49]             │ info [o.e.t.LoggingTaskListener] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] 830 finished with response BulkByScrollResponse[took=68.4ms,timed_out=false,sliceId=null,updated=0,created=13,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:00:49]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana/QGaozGzPQTaECJ7IbpAPiA] deleting index
[00:00:49]             │ proc [kibana]   log   [11:11:43.757] [info][savedobjects-service] Migrating .kibana_1 saved objects to .kibana_2
[00:00:49]             │ proc [kibana]   log   [11:11:43.778] [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:00:49]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2/igaMsm5BS1KLKUua9dI26g] update_mapping [_doc]
[00:00:49]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2/igaMsm5BS1KLKUua9dI26g] update_mapping [_doc]
[00:00:49]             │ proc [kibana]   log   [11:11:43.918] [info][savedobjects-service] Pointing alias .kibana to .kibana_2.
[00:00:49]             │ proc [kibana]   log   [11:11:43.968] [info][savedobjects-service] Finished in 715ms.
[00:00:49]             │ debg [visualize] Migrated Kibana index after loading Kibana data
[00:00:49]             │ debg applying update to kibana config: {"accessibility:disableAnimations":true,"dateFormat:tz":"UTC","visualization:visualize:legacyChartsLibrary":true}
[00:00:49]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2/igaMsm5BS1KLKUua9dI26g] update_mapping [_doc]
[00:00:50]             │ debg replacing kibana config doc: {"defaultIndex":"logstash-*"}
[00:00:51]             │ debg navigating to discover url: http://localhost:61111/app/discover#/
[00:00:51]             │ debg navigate to: http://localhost:61111/app/discover#/
[00:00:52]             │ debg browser[INFO] http://localhost:61111/app/discover?_t=1612523506361#/ 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:00:52]             │
[00:00:52]             │ debg browser[INFO] http://localhost:61111/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:00:52]             │ debg ... sleep(700) start
[00:00:52]             │ debg ... sleep(700) end
[00:00:52]             │ debg returned from get, calling refresh
[00:00:53]             │ debg browser[INFO] http://localhost:61111/app/discover?_t=1612523506361#/ 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:00:53]             │
[00:00:53]             │ debg browser[INFO] http://localhost:61111/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:00:53]             │ debg currentUrl = http://localhost:61111/app/discover#/
[00:00:53]             │          appUrl = http://localhost:61111/app/discover#/
[00:00:53]             │ debg TestSubjects.find(kibanaChrome)
[00:00:53]             │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:00:53]             │ debg ... sleep(501) start
[00:00:54]             │ debg ... sleep(501) end
[00:00:54]             │ debg in navigateTo url = http://localhost:61111/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(columns:!(_source),filters:!(),index:%27logstash-*%27,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:00:54]             │ debg --- retry.try error: URL changed, waiting for it to settle
[00:00:54]             │ debg ... sleep(501) start
[00:00:55]             │ debg ... sleep(501) end
[00:00:55]             │ debg in navigateTo url = http://localhost:61111/app/discover#/?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(columns:!(_source),filters:!(),index:%27logstash-*%27,interval:auto,query:(language:kuery,query:%27%27),sort:!(!(%27@timestamp%27,desc)))
[00:00:55]             │ debg TestSubjects.exists(statusPageContainer)
[00:00:55]             │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:00:57]             │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:02:41]           └-: context view for date_nanos
[00:02:41]             └-> "before all" hook for "displays predessors - anchor - successors in right order "
[00:02:41]             └-> "before all" hook for "displays predessors - anchor - successors in right order "
[00:02:41]               │ info [date_nanos] Loading "mappings.json"
[00:02:41]               │ info [date_nanos] Loading "data.json"
[00:02:41]               │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [date-nanos] creating index, cause [api], templates [], shards [1]/[0]
[00:02:41]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[date-nanos][0]]])." previous.health="YELLOW" reason="shards started [[date-nanos][0]]"
[00:02:41]               │ info [date_nanos] Created index "date-nanos"
[00:02:41]               │ debg [date_nanos] "date-nanos" settings {"index":{"number_of_replicas":"0","number_of_shards":"1"}}
[00:02:41]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [date-nanos/yEv5Ocu1Q2q0X_63B4IIng] update_mapping [_doc]
[00:02:41]               │ info [date_nanos] Indexed 9 docs into "date-nanos"
[00:02:41]               │ info [date_nanos] Indexed 2 docs into ".kibana"
[00:02:41]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_2/igaMsm5BS1KLKUua9dI26g] update_mapping [_doc]
[00:02:41]               │ debg Migrating saved objects
[00:02:41]               │ proc [kibana]   log   [11:13:35.984] [info][savedobjects-service] Creating index .kibana_3.
[00:02:41]               │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3] creating index, cause [api], templates [], shards [1]/[1]
[00:02:41]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] updating number_of_replicas to [0] for indices [.kibana_3]
[00:02:41]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_3][0]]])." previous.health="YELLOW" reason="shards started [[.kibana_3][0]]"
[00:02:41]               │ proc [kibana]   log   [11:13:36.048] [info][savedobjects-service] Migrating .kibana_2 saved objects to .kibana_3
[00:02:41]               │ proc [kibana]   log   [11:13:36.058] [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:02:41]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/88TMeiyETOSASUzA1Im4Lw] update_mapping [_doc]
[00:02:41]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/88TMeiyETOSASUzA1Im4Lw] update_mapping [_doc]
[00:02:41]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/88TMeiyETOSASUzA1Im4Lw] update_mapping [_doc]
[00:02:41]               │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-18-tests-xxl-1612522040171281679] [.kibana_3/88TMeiyETOSASUzA1Im4Lw] update_mapping [_doc]
[00:02:41]               │ proc [kibana]   log   [11:13:36.198] [info][savedobjects-service] Pointing alias .kibana to .kibana_3.
[00:02:41]               │ proc [kibana]   log   [11:13:36.233] [info][savedobjects-service] Finished in 251ms.
[00:02:41]               │ debg [date_nanos] Migrated Kibana index after loading Kibana data
[00:02:41]               │ debg replacing kibana config doc: {"defaultIndex":"date-nanos"}
[00:02:42]               │ debg applying update to kibana config: {"context:defaultSize":"1","context:step":"3"}
[00:02:43]             └-> displays predessors - anchor - successors in right order 
[00:02:43]               └-> "before each" hook: global before each for "displays predessors - anchor - successors in right order "
[00:02:43]               │ debg browser.get(http://localhost:61111/app/discover#/context/date-nanos/AU_x3-TaGFA8no6Qj999Z?_a=(columns:!('@message')))
[00:02:43]               │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:43]               │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:43]               │ debg browser[INFO] http://localhost:61111/app/discover?_t=1612523618055#/context/date-nanos/AU_x3-TaGFA8no6Qj999Z?_a=(columns:!(%27@message%27)) 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:02:43]               │
[00:02:43]               │ debg browser[INFO] http://localhost:61111/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:02:44]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:02:44]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:02:44]               │ debg TestSubjects.find(predecessorsLoadMoreButton)
[00:02:44]               │ debg Find.findByCssSelector('[data-test-subj="predecessorsLoadMoreButton"]') with timeout=10000
[00:02:44]               │ERROR browser[SEVERE] http://localhost:61111/40180/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 366:78979 TypeError: Cannot create property 'format' on string '*'
[00:02:44]               │          at http://localhost:61111/40180/bundles/plugin/data/data.plugin.js:1:556816
[00:02:44]               │          at Array.map (<anonymous>)
[00:02:44]               │          at search_source_SearchSource.flatten (http://localhost:61111/40180/bundles/plugin/data/data.plugin.js:1:556547)
[00:02:44]               │          at t.project (http://localhost:61111/40180/bundles/plugin/data/data.plugin.js:1:550684)
[00:02:44]               │          at t._next (http://localhost:61111/40180/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:429:131209)
[00:02:44]               │          at t.next (http://localhost:61111/40180/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:21:40224)
[00:02:44]               │          at http://localhost:61111/40180/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:298:107517 "Possibly unhandled rejection: {}"
[00:02:54]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="predecessorsLoadMoreButton"])
[00:02:54]               │      Wait timed out after 10032ms
[00:02:55]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:02:55]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:05]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:05]               │      Wait timed out after 10015ms
[00:03:05]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:05]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:15]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:15]               │      Wait timed out after 10059ms
[00:03:16]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:16]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:26]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:26]               │      Wait timed out after 10011ms
[00:03:26]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:26]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:36]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:36]               │      Wait timed out after 10043ms
[00:03:37]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:37]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:47]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:47]               │      Wait timed out after 10016ms
[00:03:47]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:47]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:03:57]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:03:57]               │      Wait timed out after 10035ms
[00:03:58]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:03:58]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:04:08]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:08]               │      Wait timed out after 10045ms
[00:04:08]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:04:08]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:04:19]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:19]               │      Wait timed out after 10033ms
[00:04:19]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:04:19]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:04:29]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:29]               │      Wait timed out after 10040ms
[00:04:30]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:04:30]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:04:40]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:40]               │      Wait timed out after 10056ms
[00:04:40]               │ debg TestSubjects.find(successorsLoadMoreButton)
[00:04:40]               │ debg Find.findByCssSelector('[data-test-subj="successorsLoadMoreButton"]') with timeout=10000
[00:04:50]               │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:50]               │      Wait timed out after 10023ms
[00:04:51]               │ info Taking screenshot "/dev/shm/workspace/parallel/11/kibana/test/functional/screenshots/failure/context app context view for date_nanos displays predessors - anchor - successors in right order .png"
[00:04:51]               │ info Current URL is: http://localhost:61111/app/discover#/context/date-nanos/AU_x3-TaGFA8no6Qj999Z?_a=(columns:!(%27@message%27))
[00:04:51]               │ info Saving page source to: /dev/shm/workspace/parallel/11/kibana/test/functional/failure_debug/html/context app context view for date_nanos displays predessors - anchor - successors in right order .html
[00:04:51]               └- ✖ fail: context app context view for date_nanos displays predessors - anchor - successors in right order 
[00:04:51]               │      Error: retry.try timeout: TimeoutError: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
[00:04:51]               │ Wait timed out after 10023ms
[00:04:51]               │     at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
[00:04:51]               │     at runMicrotasks (<anonymous>)
[00:04:51]               │     at processTicksAndRejections (internal/process/task_queues.js:93:5)
[00:04:51]               │       at onFailure (test/common/services/retry/retry_for_success.ts:17:9)
[00:04:51]               │       at retryForSuccess (test/common/services/retry/retry_for_success.ts:57:13)
[00:04:51]               │       at Retry.try (test/common/services/retry/retry.ts:32:14)
[00:04:51]               │       at ContextPage.waitUntilContextLoadingHasFinished (test/functional/page_objects/context_page.ts:82:14)
[00:04:51]               │       at ContextPage.navigateTo (test/functional/page_objects/context_page.ts:40:7)
[00:04:51]               │       at Context.<anonymous> (test/functional/apps/context/_date_nanos.js:39:7)
[00:04:51]               │       at Object.apply (packages/kbn-test/src/functional_test_runner/lib/mocha/wrap_function.js:73:16)
[00:04:51]               │ 
[00:04:51]               │

Stack Trace

Error: retry.try timeout: TimeoutError: Waiting for element to be located By(css selector, [data-test-subj="successorsLoadMoreButton"])
Wait timed out after 10023ms
    at /dev/shm/workspace/kibana/node_modules/selenium-webdriver/lib/webdriver.js:842:17
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:93:5)
    at onFailure (test/common/services/retry/retry_for_success.ts:17:9)
    at retryForSuccess (test/common/services/retry/retry_for_success.ts:57:13)
    at Retry.try (test/common/services/retry/retry.ts:32:14)
    at ContextPage.waitUntilContextLoadingHasFinished (test/functional/page_objects/context_page.ts:82:14)
    at ContextPage.navigateTo (test/functional/page_objects/context_page.ts:40:7)
    at Context.<anonymous> (test/functional/apps/context/_date_nanos.js:39:7)
    at Object.apply (packages/kbn-test/src/functional_test_runner/lib/mocha/wrap_function.js:73:16)

Metrics [docs]

Page load bundle

Size of the bundles that are downloaded on every page load. Target size is below 100kb

id	before	after	diff
`data`	797.7KB	797.9KB	+238.0B

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

mattkime · 2021-02-05T14:44:49Z

Is this needed if elastic/elasticsearch#67893 is merged?

nik9000 · 2021-02-05T15:04:49Z

Is this needed if elastic/elasticsearch#67893 is merged?

I don't believe we plan to merge that because its a backwards breaking change. Even if we did, I think you'd be in a fairly brittle state because if folks changed the formatter you might end up with something you couldn't parse.

I kind of thought kibana would always want to stick its own formatter on dates so it could be sure it could parse them back, similar to when it was requesting dates using docvalue_fields.

mattkime · 2021-02-05T15:28:20Z

@nik9000 That PR seems to be correcting unwanted behavior. Might it be merged into 8.0 and not backported? I just want to make sure I'm correctly understanding the problem.

Even if we did, I think you'd be in a fairly brittle state because if folks changed the formatter you might end up with something you couldn't parse.

And that would be true of regular dates as well, correct?

Requested @ppisljar to review this as well. At this point I think the PR makes sense but want to ensure there's no unwanted side effects.

nik9000 · 2021-02-05T15:46:52Z

@nik9000 That PR seems to be correcting unwanted behavior. Might it be merged into 8.0 and not backported? I just want to make sure I'm correctly understanding the problem.

The ES folk think that the unwanted behavior is that you made a date_nanos and its default formatter doesn't include nanos. The least backwards breaking way to fix this is to change its default formatter to one that includes nanos. So I think we'll do that instead of the PR. The PR as it stands now spends some of our backwards compatibility breaking budget on changing the meaning of strict_date_optional_time. It just doesn't seem worth it.

And that would be true of regular dates as well, correct?

Absolutely. I kind of figured you'd specify a formatter for everything that supports it for the safety. It feels like a good level of paranoia.

majagrubic · 2021-02-05T16:03:55Z

@nik9000 what about fields: ['*'] scenario? could you shed some light into why and when this wouldn't work when specifying a format?

mattkime · 2021-02-05T16:30:11Z

@nik9000

So I think we'll do that instead of the PR.

Can that happen for 7.12?

This PR might still have value but it would be solving a different problem - setting the format when its been changed from default. That would be overriding a user choice so I'd want us to be certain of that its the right choice.

nik9000 · 2021-02-05T16:40:42Z

Can that happen for 7.12?

I don't imagine it will, no.

This PR might still have value but it would be solving a different problem - setting the format when its been changed from default. That would be overriding a user choice so I'd want us to be certain of that its the right choice.

I haven't read the PR but didn't kibana used to ask for dates as docvalue_fields and specify a formatter? I figured this was a similar thing.

majagrubic · 2021-02-05T17:01:32Z

After syncing with @nik9000 and @timroes offline, the consensus was reached that we'd explicitly request a list of date fields (ALL date fields) in the fields request. So the request would look something like:

fields: [ '*', 
   { field: 'my_date_nanos_field', format: '.....' }
 ]

I think this will also alleviate some of the concerns @mattkime had around this.
I'll open a separate PR with this, but leaving this one open just for history.

mattkime · 2021-02-05T17:05:31Z

I haven't read the PR but didn't kibana used to ask for dates as docvalue_fields and specify a formatter? I figured this was a similar thing.

Probably, I'm getting up to speed on this.

ppisljar · 2021-02-09T11:33:41Z

is the plan for this logic to live in searchsource flatten method or in index patterns (part of getComputedFields? ) or being left to actual consumer (as discover) ?

seems that at least in this PR the plan is to do it in searchsource which is the place to do it in my opinion

majagrubic · 2021-02-10T14:55:52Z

@ppisljar I agree that search_source would be the best place for that

majagrubic · 2021-02-17T14:00:27Z

Closing this as it was used mostly as a reference for discussions.

[Search Source] Specify strict_date_optional_time_nanos as a formatte…

8e9911e

…r for date_nanos field

majagrubic requested review from lukeelmers and mattkime February 5, 2021 10:47

mattkime requested a review from ppisljar February 5, 2021 15:28

majagrubic mentioned this pull request Feb 8, 2021

[Search Source] Fix retrieval of unmapped fields; Add field filters #89837

Merged

9 tasks

majagrubic mentioned this pull request Feb 10, 2021

[Discover] Remaining work on Fields API #88426

Closed

31 tasks

majagrubic mentioned this pull request Feb 15, 2021

Date format inconsistent between the Fields API and search_after elastic/elasticsearch#68988

Closed

majagrubic closed this Feb 17, 2021

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

[Search Source] Specify strict_date_optional_time_nanos as a format #90415

[Search Source] Specify strict_date_optional_time_nanos as a format #90415

majagrubic commented Feb 5, 2021 •

edited

Loading

kibanamachine commented Feb 5, 2021 •

edited

Loading

Standard Out

Stack Trace

Standard Out

Stack Trace

Standard Out

Stack Trace

mattkime commented Feb 5, 2021

nik9000 commented Feb 5, 2021

mattkime commented Feb 5, 2021 •

edited

Loading

nik9000 commented Feb 5, 2021

majagrubic commented Feb 5, 2021

mattkime commented Feb 5, 2021

nik9000 commented Feb 5, 2021

majagrubic commented Feb 5, 2021

mattkime commented Feb 5, 2021

ppisljar commented Feb 9, 2021 •

edited

Loading

majagrubic commented Feb 10, 2021

majagrubic commented Feb 17, 2021

[Search Source] Specify strict_date_optional_time_nanos as a format #90415

[Search Source] Specify strict_date_optional_time_nanos as a format #90415

Conversation

majagrubic commented Feb 5, 2021 • edited Loading

Summary

Checklist

For maintainers

kibanamachine commented Feb 5, 2021 • edited Loading

💔 Build Failed

Failed CI Steps

Test Failures

Standard Out

Stack Trace

Standard Out

Stack Trace

Standard Out

Stack Trace

Metrics [docs]

Page load bundle

mattkime commented Feb 5, 2021

nik9000 commented Feb 5, 2021

mattkime commented Feb 5, 2021 • edited Loading

nik9000 commented Feb 5, 2021

majagrubic commented Feb 5, 2021

mattkime commented Feb 5, 2021

nik9000 commented Feb 5, 2021

majagrubic commented Feb 5, 2021

mattkime commented Feb 5, 2021

ppisljar commented Feb 9, 2021 • edited Loading

majagrubic commented Feb 10, 2021

majagrubic commented Feb 17, 2021

majagrubic commented Feb 5, 2021 •

edited

Loading

kibanamachine commented Feb 5, 2021 •

edited

Loading

mattkime commented Feb 5, 2021 •

edited

Loading

ppisljar commented Feb 9, 2021 •

edited

Loading