Skip to content

Add tests for the concurrent refresh token requests.#62027

Merged
azasypkin merged 4 commits intoelastic:masterfrom
azasypkin:issue-xxx-expired-token
May 6, 2020
Merged

Add tests for the concurrent refresh token requests.#62027
azasypkin merged 4 commits intoelastic:masterfrom
azasypkin:issue-xxx-expired-token

Conversation

@azasypkin
Copy link
Copy Markdown
Contributor

@azasypkin azasypkin commented Mar 31, 2020

Once elastic/elasticsearch#54289 is fixed we'd need to add a couple of tests to make sure it works as expected in Kibana context.

@azasypkin azasypkin added test blocked Team:Security Platform Security: Auth, Users, Roles, Spaces, Audit Logging, etc t// Feature:Security/Authentication Platform Security - Authentication v7.8.0 labels Mar 31, 2020
@elasticmachine
Copy link
Copy Markdown
Contributor

Pinging @elastic/kibana-security (Team:Security)

@azasypkin
Copy link
Copy Markdown
Contributor Author

@elasticmachine merge upstream

@azasypkin azasypkin removed the blocked label May 4, 2020
@azasypkin
Copy link
Copy Markdown
Contributor Author

@elasticmachine merge upstream

@azasypkin azasypkin marked this pull request as ready for review May 4, 2020 13:00
@azasypkin azasypkin added the release_note:skip Skip the PR/issue when compiling release notes label May 4, 2020
@azasypkin azasypkin requested a review from legrego May 4, 2020 13:02
it('should refresh access token even if multiple concurrent requests try to refresh it', async () => {
// Send 5 concurrent requests with a cookie that contains an expired access token.
await Promise.all(
Array.from({ length: 5 }).map((value, index) =>
Copy link
Copy Markdown
Member

Choose a reason for hiding this comment

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

question is there any value to testing that the token we get is the same for all 5 of these requests?

Copy link
Copy Markdown
Contributor Author

Choose a reason for hiding this comment

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

I'm not sure, technically it's internal implementation detail of Elasticsearch/Kibana and for consumer it doesn't really matter whether we get the same or different tokens as long as they are valid. But even if think it's worth it, we'll need to add code that decrypts cookie in tests and extracts token from it relying on internal cookie structure. Or you meant something else?

Copy link
Copy Markdown
Member

Choose a reason for hiding this comment

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

I'm not sure, technically it's internal implementation detail of Elasticsearch/Kibana and for consumer it doesn't really matter whether we get the same or different tokens as long as they are valid.

I guess my concern was making sure they were all valid. If we happen to get different tokens back in different requests, can we be sure that they'll all work correctly? You're right that it's an implementation detail...I was considering taking a defensive approach here, given all of the session timeout issues we've had to triage lately.

At the end of the day, it's not a huge deal to me one way or another. I'm fine to leave these as-is if it's too complex to decrypt and check, or if you don't think it's worthwhile to do.

@azasypkin azasypkin requested a review from legrego May 6, 2020 06:46
Copy link
Copy Markdown
Member

@legrego legrego left a comment

Choose a reason for hiding this comment

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

LGTM, thanks!

@azasypkin
Copy link
Copy Markdown
Contributor Author

@elasticmachine merge upstream

@kibanamachine
Copy link
Copy Markdown
Contributor

💔 Build Failed

Failed CI Steps


Test Failures

Kibana Pipeline / kibana-oss-agent / Chrome UI Functional Tests.test/functional/apps/visualize/_area_chart·js.visualize app area charts axis scaling does not scale top hit agg

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: visualize app
[00:00:00]           └-> "before all" hook
[00:00:00]           └-> "before all" hook
[00:00:00]             │ debg Starting visualize before method
[00:00:00]             │ info [logstash_functional] Loading "mappings.json"
[00:00:00]             │ info [logstash_functional] Loading "data.json.gz"
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.22][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.22][0]]"
[00:00:00]             │ info [logstash_functional] Created index "logstash-2015.09.22"
[00:00:00]             │ debg [logstash_functional] "logstash-2015.09.22" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.20][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.20][0]]"
[00:00:00]             │ info [logstash_functional] Created index "logstash-2015.09.20"
[00:00:00]             │ debg [logstash_functional] "logstash-2015.09.20" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.21][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.21][0]]"
[00:00:00]             │ info [logstash_functional] Created index "logstash-2015.09.21"
[00:00:00]             │ debg [logstash_functional] "logstash-2015.09.21" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:10]             │ info progress: 11128
[00:00:12]             │ info [logstash_functional] Indexed 4633 docs into "logstash-2015.09.22"
[00:00:12]             │ info [logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:00:12]             │ info [logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:00:12]             │ info [long_window_logstash] Loading "mappings.json"
[00:00:12]             │ info [long_window_logstash] Loading "data.json.gz"
[00:00:12]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:12]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [long-window-logstash-0] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:12]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[long-window-logstash-0][0]]])." previous.health="YELLOW" reason="shards started [[long-window-logstash-0][0]]"
[00:00:12]             │ info [long_window_logstash] Created index "long-window-logstash-0"
[00:00:12]             │ 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:00:22]             │ info progress: 12262
[00:00:23]             │ info [long_window_logstash] Indexed 14005 docs into "long-window-logstash-0"
[00:00:24]             │ info [visualize] Loading "mappings.json"
[00:00:24]             │ info [visualize] Loading "data.json"
[00:00:24]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_1/p8VgPqgNSoa1I-fxFNEwkw] deleting index
[00:00:24]             │ info [visualize] Deleted existing index [".kibana_1"]
[00:00:24]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:24]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:24]             │ info [visualize] Created index ".kibana"
[00:00:24]             │ debg [visualize] ".kibana" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:00:24]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana/o4zKIQnGS5ueoNcCxLZOGA] update_mapping [_doc]
[00:00:24]             │ info [visualize] Indexed 12 docs into ".kibana"
[00:00:24]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana/o4zKIQnGS5ueoNcCxLZOGA] update_mapping [_doc]
[00:00:24]             │ debg Migrating saved objects
[00:00:24]             │ proc [kibana]   log   [13:51:06.431] [info][savedobjects-service] Creating index .kibana_2.
[00:00:24]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:24]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_2] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:24]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] updating number_of_replicas to [0] for indices [.kibana_2]
[00:00:24]             │ proc [kibana]   log   [13:51:06.511] [info][savedobjects-service] Reindexing .kibana to .kibana_1
[00:00:24]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:24]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:24]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] updating number_of_replicas to [0] for indices [.kibana_1]
[00:00:24]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:24]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.tasks] creating index, cause [auto(task api)], templates [], shards [1]/[1], mappings [_doc]
[00:00:24]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] updating number_of_replicas to [0] for indices [.tasks]
[00:00:24]             │ info [o.e.t.LoggingTaskListener] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] 1541 finished with response BulkByScrollResponse[took=82.1ms,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:00:25]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana/o4zKIQnGS5ueoNcCxLZOGA] deleting index
[00:00:25]             │ proc [kibana]   log   [13:51:06.916] [info][savedobjects-service] Migrating .kibana_1 saved objects to .kibana_2
[00:00:25]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_2/PIppQz7bT4GwW4XoRYwY5g] update_mapping [_doc]
[00:00:25]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_2/PIppQz7bT4GwW4XoRYwY5g] update_mapping [_doc]
[00:00:25]             │ proc [kibana]   log   [13:51:07.030] [info][savedobjects-service] Pointing alias .kibana to .kibana_2.
[00:00:25]             │ proc [kibana]   log   [13:51:07.069] [info][savedobjects-service] Finished in 640ms.
[00:00:25]             │ debg applying update to kibana config: {"accessibility:disableAnimations":true,"dateFormat:tz":"UTC"}
[00:00:25]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_2/PIppQz7bT4GwW4XoRYwY5g] update_mapping [_doc]
[00:00:26]             │ debg replacing kibana config doc: {"defaultIndex":"logstash-*","format:bytes:defaultPattern":"0,0.[000]b"}
[00:00:26]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_2/PIppQz7bT4GwW4XoRYwY5g] update_mapping [_doc]
[00:00:27]           └-: 
[00:00:27]             └-> "before all" hook
[00:01:43]             └-: area charts
[00:01:43]               └-> "before all" hook
[00:01:43]               └-> "before all" hook
[00:01:43]                 │ debg navigateToApp visualize
[00:01:43]                 │ debg navigating to visualize url: http://localhost:61101/app/kibana#/visualize
[00:01:43]                 │ debg navigate to: http://localhost:61101/app/kibana#/visualize
[00:01:43]                 │ debg browser[INFO] http://localhost:61101/app/kibana?_t=1588773145122#/visualize 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:01:43]                 │
[00:01:43]                 │ debg browser[INFO] http://localhost:61101/bundles/app/kibana/bootstrap.js 10:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:01:43]                 │ debg ... sleep(700) start
[00:01:44]                 │ debg ... sleep(700) end
[00:01:44]                 │ debg returned from get, calling refresh
[00:01:44]                 │ debg browser[INFO] http://localhost:61101/app/kibana?_t=1588773145122#/visualize 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:01:44]                 │
[00:01:44]                 │ debg browser[INFO] http://localhost:61101/bundles/app/kibana/bootstrap.js 10:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:01:44]                 │ debg currentUrl = http://localhost:61101/app/kibana#/visualize
[00:01:44]                 │          appUrl = http://localhost:61101/app/kibana#/visualize
[00:01:44]                 │ debg TestSubjects.find(kibanaChrome)
[00:01:44]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:01:45]                 │ debg TestSubjects.find(kibanaChrome)
[00:01:45]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=10000
[00:01:45]                 │ debg browser[INFO] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 380:106115 "INFO: 2020-05-06T13:52:26Z
[00:01:45]                 │        Adding connection to http://localhost:61101/elasticsearch
[00:01:45]                 │
[00:01:45]                 │      "
[00:01:45]                 │ debg ... sleep(501) start
[00:01:46]                 │ debg ... sleep(501) end
[00:01:46]                 │ debg in navigateTo url = http://localhost:61101/app/kibana#/visualize?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:01:46]                 │ debg --- retry.try error: URL changed, waiting for it to settle
[00:01:46]                 │ debg ... sleep(501) start
[00:01:47]                 │ debg ... sleep(501) end
[00:01:47]                 │ debg in navigateTo url = http://localhost:61101/app/kibana#/visualize?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:01:47]                 │ debg TestSubjects.exists(statusPageContainer)
[00:01:47]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:01:49]                 │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:01:50]                 │ debg TestSubjects.exists(newItemButton)
[00:01:50]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="newItemButton"]') with timeout=2500
[00:01:50]                 │ debg TestSubjects.click(newItemButton)
[00:01:50]                 │ debg Find.clickByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:01:50]                 │ debg Find.findByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:01:50]                 │ debg TestSubjects.find(visNewDialogTypes)
[00:01:50]                 │ debg Find.findByCssSelector('[data-test-subj="visNewDialogTypes"]') with timeout=10000
[00:01:50]                 │ debg clickAreaChart
[00:01:50]                 │ debg TestSubjects.click(visType-area)
[00:01:50]                 │ debg Find.clickByCssSelector('[data-test-subj="visType-area"]') with timeout=10000
[00:01:50]                 │ debg Find.findByCssSelector('[data-test-subj="visType-area"]') with timeout=10000
[00:01:50]                 │ debg isGlobalLoadingIndicatorVisible
[00:01:50]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:01:50]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:01:50]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:01:52]                 │ debg --- retry.tryForTime failed again with the same message...
[00:01:53]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:01:53]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:01:53]                 │ debg clickNewSearch
[00:01:53]                 │ debg TestSubjects.click(savedObjectTitlelogstash-*)
[00:01:53]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitlelogstash-*"]') with timeout=10000
[00:01:53]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitlelogstash-*"]') with timeout=10000
[00:01:53]                 │ debg isGlobalLoadingIndicatorVisible
[00:01:53]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:01:53]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:01:53]                 │ERROR browser[SEVERE] http://localhost:61101/app/theme-github.js 0:0 Uncaught SyntaxError: Unexpected token '<'
[00:01:53]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 287:519565 Uncaught Error: couldn't load module ace/theme/github or it didn't call define
[00:01:53]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:01:56]                 │ debg --- retry.tryForTime failed again with the same message...
[00:01:56]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:01:56]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:01:56]                 │ debg Setting absolute range to Sep 19, 2015 @ 06:31:44.000 to Sep 23, 2015 @ 18:31:44.000
[00:01:56]                 │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:01:56]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:01:56]                 │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:01:56]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:01:56]                 │ debg TestSubjects.click(superDatePickerShowDatesButton)
[00:01:56]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:01:56]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:01:56]                 │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:01:56]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:01:56]                 │ debg TestSubjects.click(superDatePickerendDatePopoverButton)
[00:01:56]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:01:56]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:01:56]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:01:56]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:01:56]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:01:56]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:01:56]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:01:56]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:01:56]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:01:56]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 23, 2015 @ 18:31:44.000)
[00:01:56]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:01:56]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:01:56]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:01:57]                 │ debg ... sleep(500) start
[00:01:58]                 │ debg ... sleep(500) end
[00:01:58]                 │ debg TestSubjects.click(superDatePickerstartDatePopoverButton)
[00:01:58]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:01:58]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:01:58]                 │ debg Find.waitForElementStale with timeout=10000
[00:01:58]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:01:58]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:01:58]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:01:58]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:01:58]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:01:58]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:01:58]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:01:58]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 19, 2015 @ 06:31:44.000)
[00:01:58]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:01:58]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:01:58]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:01:59]                 │ debg TestSubjects.exists(superDatePickerApplyTimeButton)
[00:01:59]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerApplyTimeButton"]') with timeout=2500
[00:02:01]                 │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerApplyTimeButton"] is not displayed
[00:02:02]                 │ debg TestSubjects.click(querySubmitButton)
[00:02:02]                 │ debg Find.clickByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:02:02]                 │ debg Find.findByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:02:02]                 │ debg Find.waitForElementStale with timeout=10000
[00:02:02]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:02]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:02]                 │ debg Click X-axis
[00:02:02]                 │ debg TestSubjects.click(visEditorAdd_buckets)
[00:02:02]                 │ debg Find.clickByCssSelector('[data-test-subj="visEditorAdd_buckets"]') with timeout=10000
[00:02:02]                 │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_buckets"]') with timeout=10000
[00:02:02]                 │ debg TestSubjects.click(visEditorAdd_buckets_X-axis)
[00:02:02]                 │ debg Find.clickByCssSelector('[data-test-subj="visEditorAdd_buckets_X-axis"]') with timeout=10000
[00:02:02]                 │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_buckets_X-axis"]') with timeout=10000
[00:02:02]                 │ debg Click Date Histogram
[00:02:02]                 │ debg Find.findByCssSelector('
[00:02:02]                 │                [data-test-subj="bucketsAggGroup"]
[00:02:02]                 │                [data-test-subj^="visEditorAggAccordion"].euiAccordion-isOpen
[00:02:02]                 │                
[00:02:02]                 │                [data-test-subj="defaultEditorAggSelect"]
[00:02:02]                 │              ') with timeout=10000
[00:02:02]                 │ debg comboBox.setElement, value: Date Histogram
[00:02:02]                 │ debg comboBox.isOptionSelected, value: Date Histogram
[00:02:05]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:02:05]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:02:05]                 │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="Date Histogram"]') with timeout=2500
[00:02:05]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:02:05]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:02:08]                 │ERROR browser[SEVERE] http://localhost:61101/app/theme-github.js 0:0 Uncaught SyntaxError: Unexpected token '<'
[00:02:08]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 287:519565 Uncaught Error: couldn't load module ace/theme/github or it didn't call define
[00:02:08]                 │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:02:08]                 │ debg ... sleep(500) start
[00:02:09]                 │ debg ... sleep(500) end
[00:02:09]                 │ debg Check field value
[00:02:09]                 │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: visDefaultEditorField
[00:02:09]                 │ debg TestSubjects.find(visDefaultEditorField)
[00:02:09]                 │ debg Find.findByCssSelector('[data-test-subj="visDefaultEditorField"]') with timeout=10000
[00:02:09]                 │ debg fieldValue = @timestamp
[00:02:09]                 │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: visEditorInterval
[00:02:09]                 │ debg TestSubjects.find(visEditorInterval)
[00:02:09]                 │ debg Find.findByCssSelector('[data-test-subj="visEditorInterval"]') with timeout=10000
[00:02:09]                 │ debg intervalValue = Auto
[00:02:09]                 │ debg TestSubjects.find(visualizationLoader)
[00:02:09]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:02:09]                 │ debg Before Rendering count 2
[00:02:09]                 │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:02:09]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:02:09]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:02:09]                 │ debg Waiting up to 20000ms for rendering count to be greater than or equal to [3]...
[00:02:09]                 │ debg TestSubjects.find(visualizationLoader)
[00:02:09]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:02:09]                 │ debg -- currentRenderingCount=2
[00:02:10]                 │ debg TestSubjects.find(visualizationLoader)
[00:02:10]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:02:10]                 │ debg -- currentRenderingCount=3
[00:02:10]               └-> should save and load with special characters
[00:02:10]                 └-> "before each" hook: global before each
[00:02:10]                 │ debg ensureSavePanelOpen
[00:02:10]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:10]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:10]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:11]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:12]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:12]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:12]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:12]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=5000
[00:02:14]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:17]                 │ debg --- retry.tryForTime failed again with the same message...
[00:02:18]                 │ debg TestSubjects.click(visualizeSaveButton)
[00:02:18]                 │ debg Find.clickByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:18]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:18]                 │ debg TestSubjects.setValue(savedObjectTitle, Visualization AreaChart Name Test/?&=%)
[00:02:18]                 │ debg TestSubjects.click(savedObjectTitle)
[00:02:18]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:18]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:18]                 │ debg Click Save Visualization button
[00:02:18]                 │ debg TestSubjects.click(confirmSaveSavedObjectButton)
[00:02:18]                 │ debg Find.clickByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:18]                 │ debg Find.findByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:19]                 │ debg TestSubjects.exists(saveVisualizationSuccess)
[00:02:19]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="saveVisualizationSuccess"]') with timeout=120000
[00:02:20]                 │ debg Find.findByCssSelector('.euiToast') with timeout=20000
[00:02:20]                 │ debg Find.findByCssSelector('.euiToastHeader__title') with timeout=10000
[00:02:20]                 │ERROR browser[SEVERE] http://localhost:61101/app/theme-github.js 0:0 Uncaught SyntaxError: Unexpected token '<'
[00:02:20]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 287:519565 Uncaught Error: couldn't load module ace/theme/github or it didn't call define
[00:02:20]                 │ debg Toast title: Saved 'Visualization AreaChart Name Test/?&=%'
[00:02:20]                 │ debg Find.clickByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:20]                 │ debg Find.findByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:20]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:20]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:20]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:22]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:23]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:23]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:23]                 │ debg Waiting for save modal to close
[00:02:23]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:23]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=2500
[00:02:25]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:26]                 │ debg Waiting up to 20000ms for last breadcrumb to have new vis name...
[00:02:26]                 │ debg TestSubjects.getVisibleText(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:02:26]                 │ debg TestSubjects.find(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:02:26]                 │ debg Find.findByCssSelector('[data-test-subj="headerGlobalNav"] [data-test-subj="breadcrumbs"] [data-test-subj~="breadcrumb"][data-test-subj~="last"]') with timeout=10000
[00:02:26]                 └- ✓ pass  (15.8s) "visualize app  area charts should save and load with special characters"
[00:02:26]               └-> should save and load with non-ascii characters
[00:02:26]                 └-> "before each" hook: global before each
[00:02:26]                 │ debg ensureSavePanelOpen
[00:02:26]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:26]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:26]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:27]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:28]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:28]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:28]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:28]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=5000
[00:02:30]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:33]                 │ debg --- retry.tryForTime failed again with the same message...
[00:02:34]                 │ debg TestSubjects.click(visualizeSaveButton)
[00:02:34]                 │ debg Find.clickByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:34]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:34]                 │ debg TestSubjects.setValue(savedObjectTitle, Visualization AreaChart Name Test with Umlaut ä)
[00:02:34]                 │ debg TestSubjects.click(savedObjectTitle)
[00:02:34]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:34]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:34]                 │ debg Click Save Visualization button
[00:02:34]                 │ debg TestSubjects.click(confirmSaveSavedObjectButton)
[00:02:34]                 │ debg Find.clickByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:34]                 │ debg Find.findByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:34]                 │ debg TestSubjects.exists(saveVisualizationSuccess)
[00:02:34]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="saveVisualizationSuccess"]') with timeout=120000
[00:02:36]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 333:78758 TypeError: Cannot set property 'title' of undefined
[00:02:36]                 │          at http://localhost:61101/32781/bundles/plugin/visualize/2.plugin.js:1:449429
[00:02:36]                 │          at c.$digest (http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:100155)
[00:02:36]                 │          at http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:102127
[00:02:36]                 │          at Yr.completeTask (http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:122692)
[00:02:36]                 │          at http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:34257
[00:02:36]                 │ debg Find.findByCssSelector('.euiToast') with timeout=20000
[00:02:36]                 │ debg Find.findByCssSelector('.euiToastHeader__title') with timeout=10000
[00:02:36]                 │ debg Toast title: Saved 'Visualization AreaChart Name Test with Umlaut ä'
[00:02:36]                 │ debg Find.clickByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:36]                 │ debg Find.findByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:36]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:36]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:36]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:37]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:38]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:38]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:38]                 │ debg Waiting for save modal to close
[00:02:38]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:38]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=2500
[00:02:41]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:41]                 │ debg Waiting up to 20000ms for last breadcrumb to have new vis name...
[00:02:41]                 │ debg TestSubjects.getVisibleText(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:02:41]                 │ debg TestSubjects.find(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:02:41]                 │ debg Find.findByCssSelector('[data-test-subj="headerGlobalNav"] [data-test-subj="breadcrumbs"] [data-test-subj~="breadcrumb"][data-test-subj~="last"]') with timeout=10000
[00:02:41]                 └- ✓ pass  (15.5s) "visualize app  area charts should save and load with non-ascii characters"
[00:02:41]               └-> should save and load
[00:02:41]                 └-> "before each" hook: global before each
[00:02:41]                 │ debg ensureSavePanelOpen
[00:02:41]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:41]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:41]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:43]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[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 TestSubjects.exists(savedObjectSaveModal)
[00:02:43]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=5000
[00:02:46]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:49]                 │ debg --- retry.tryForTime failed again with the same message...
[00:02:49]                 │ debg TestSubjects.click(visualizeSaveButton)
[00:02:49]                 │ debg Find.clickByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:49]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:49]                 │ debg TestSubjects.setValue(savedObjectTitle, Visualization AreaChart Name Test)
[00:02:49]                 │ debg TestSubjects.click(savedObjectTitle)
[00:02:49]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:49]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:50]                 │ debg Click Save Visualization button
[00:02:50]                 │ debg TestSubjects.click(confirmSaveSavedObjectButton)
[00:02:50]                 │ debg Find.clickByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:50]                 │ debg Find.findByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:50]                 │ debg TestSubjects.exists(saveVisualizationSuccess)
[00:02:50]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="saveVisualizationSuccess"]') with timeout=120000
[00:02:51]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 333:78758 TypeError: Cannot set property 'title' of undefined
[00:02:51]                 │          at http://localhost:61101/32781/bundles/plugin/visualize/2.plugin.js:1:449429
[00:02:51]                 │          at c.$digest (http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:100155)
[00:02:51]                 │          at http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:102127
[00:02:51]                 │          at Yr.completeTask (http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:122692)
[00:02:51]                 │          at http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:34257
[00:02:51]                 │ debg Find.findByCssSelector('.euiToast') with timeout=20000
[00:02:51]                 │ debg Find.findByCssSelector('.euiToastHeader__title') with timeout=10000
[00:02:51]                 │ debg Toast title: Saved 'Visualization AreaChart Name Test'
[00:02:51]                 │ debg Find.clickByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:51]                 │ debg Find.findByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:51]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:51]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:51]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:53]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:53]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:53]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:53]                 │ debg Waiting for save modal to close
[00:02:53]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:53]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=2500
[00:02:56]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:56]                 │ debg Waiting up to 20000ms for last breadcrumb to have new vis name...
[00:02:56]                 │ debg TestSubjects.getVisibleText(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:02:56]                 │ debg TestSubjects.find(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:02:56]                 │ debg Find.findByCssSelector('[data-test-subj="headerGlobalNav"] [data-test-subj="breadcrumbs"] [data-test-subj~="breadcrumb"][data-test-subj~="last"]') with timeout=10000
[00:02:56]                 │ debg Find.clickByCssSelector('[href="#/visualize"]') with timeout=10000
[00:02:56]                 │ debg Find.findByCssSelector('[href="#/visualize"]') with timeout=10000
[00:02:56]                 │ debg clickVisualizationByLinkText(Visualization AreaChart Name Test)
[00:02:56]                 │ debg Find.clickByPartialLinkText('Visualization AreaChart Name Test') with timeout=10000
[00:02:56]                 │ debg Find.byPartialLinkText('Visualization AreaChart Name Test')  with timeout=10000
[00:02:57]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:57]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:57]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:58]                 │ERROR browser[SEVERE] http://localhost:61101/app/theme-github.js 0:0 Uncaught SyntaxError: Unexpected token '<'
[00:02:58]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 287:519565 Uncaught Error: couldn't load module ace/theme/github or it didn't call define
[00:02:58]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:03:00]                 │ debg --- retry.tryForTime failed again with the same message...
[00:03:00]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:03:00]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:03:00]                 │ debg Waiting up to 20000ms for rendering count to stabilize...
[00:03:00]                 │ debg TestSubjects.find(visualizationLoader)
[00:03:00]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:00]                 │ debg -- firstCount=1
[00:03:00]                 │ debg ... sleep(1000) start
[00:03:01]                 │ debg ... sleep(1000) end
[00:03:01]                 │ debg TestSubjects.find(visualizationLoader)
[00:03:01]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:01]                 │ debg -- secondCount=1
[00:03:01]                 │ debg Find.findByCssSelector('.visualization') with timeout=10000
[00:03:01]                 └- ✓ pass  (20.2s) "visualize app  area charts should save and load"
[00:03:01]               └-> should have inspector enabled
[00:03:01]                 └-> "before each" hook: global before each
[00:03:01]                 │ debg TestSubjects.getAttribute(openInspectorButton, disabled)
[00:03:01]                 │ debg TestSubjects.find(openInspectorButton)
[00:03:01]                 │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:01]                 └- ✓ pass  (26ms) "visualize app  area charts should have inspector enabled"
[00:03:01]               └-> should show correct chart
[00:03:01]                 └-> "before each" hook: global before each
[00:03:01]                 │ debg Find.findByCssSelector('.visAxis--x.visAxis__column--bottom') with timeout=10000
[00:03:01]                 │ debg X-Axis labels = 2015-09-20 00:00,2015-09-21 00:00,2015-09-22 00:00,2015-09-23 00:00
[00:03:01]                 │ debg Find.findByCssSelector('.visAxis__column--y.visAxis__column--left') with timeout=10000
[00:03:01]                 │ debg Y-Axis labels = 0,200,400,600,800,1,000,1,200,1,400,1,600
[00:03:01]                 │ debg Find.findByCssSelector('div.visAxis__splitAxes--y > div > svg > g.ValueAxis-1 > g:last-of-type.tick') with timeout=10000
[00:03:01]                 │ debg maxYLabel = 1600, maxYLabelYPosition = 184.40625
[00:03:01]                 │ debg Find.findByCssSelector('div.visAxis__column--y.visAxis__column--left  > div > div > svg:nth-child(2) > g > g:nth-child(1).tick') with timeout=10000
[00:03:01]                 │ debg Find.findByCssSelector('rect.background') with timeout=10000
[00:03:01]                 │ debg height --------- 551
[00:03:01]                 │ debg Find.findByCssSelector('path[data-label="Count"]') with timeout=20000
[00:03:01]                 │ debg M101.17164551440328,538.2581250000001L118.54403440329217,481.43625000000003L135.91642329218107,296.16249999999997L153.28881218106994,56.13312500000003L170.66120106995885,78.86187499999997L188.0335899588477,292.374375L205.4059788477366,486.25750000000005L222.77836773662548,540.324375L240.1507566255144,536.53625L257.52314551440327,481.43625000000003L274.8955344032921,315.791875L292.267923292181,82.30562500000002L309.64031218106993,63.70937499999999L327.01270106995884,307.526875L344.38508995884774,490.04562500000003L361.75747884773665,541.701875L379.1298677366255,539.98L396.50225662551435,490.734375L413.87464551440326,307.526875L431.2470344032921,66.12L448.619423292181,84.37187499999997L465.9918121810699,300.98375L483.3642010699588,481.780625L500.73658995884773,541.0131250000001L500.73658995884773,551L483.3642010699588,551L465.9918121810699,551L448.619423292181,551L431.2470344032921,551L413.87464551440326,551L396.50225662551435,551L379.1298677366255,551L361.75747884773665,551L344.38508995884774,551L327.01270106995884,551L309.64031218106993,551L292.267923292181,551L274.8955344032921,551L257.52314551440327,551L240.1507566255144,551L222.77836773662548,551L205.4059788477366,551L188.0335899588477,551L170.66120106995885,551L153.28881218106994,551L135.91642329218107,551L118.54403440329217,551L101.17164551440328,551Z
[00:03:01]                 │ debg chartData[i] =37
[00:03:01]                 │ debg chartData[i] =202
[00:03:01]                 │ debg chartData[i] =740
[00:03:01]                 │ debg chartData[i] =1437
[00:03:01]                 │ debg chartData[i] =1371
[00:03:01]                 │ debg chartData[i] =751
[00:03:01]                 │ debg chartData[i] =188
[00:03:01]                 │ debg chartData[i] =31
[00:03:01]                 │ debg chartData[i] =42
[00:03:01]                 │ debg chartData[i] =202
[00:03:01]                 │ debg chartData[i] =683
[00:03:01]                 │ debg chartData[i] =1361
[00:03:01]                 │ debg chartData[i] =1415
[00:03:01]                 │ debg chartData[i] =707
[00:03:01]                 │ debg chartData[i] =177
[00:03:01]                 │ debg chartData[i] =27
[00:03:01]                 │ debg chartData[i] =32
[00:03:01]                 │ debg chartData[i] =175
[00:03:01]                 │ debg chartData[i] =707
[00:03:01]                 │ debg chartData[i] =1408
[00:03:01]                 │ debg chartData[i] =1355
[00:03:01]                 │ debg chartData[i] =726
[00:03:01]                 │ debg chartData[i] =201
[00:03:01]                 │ debg chartData[i] =29
[00:03:01]                 │ debg expectedAreaChartData = 37,202,740,1437,1371,751,188,31,42,202,683,1361,1415,707,177,27,32,175,707,1408,1355,726,201,29
[00:03:01]                 │ debg actual chart data =     37,202,740,1437,1371,751,188,31,42,202,683,1361,1415,707,177,27,32,175,707,1408,1355,726,201,29
[00:03:01]                 └- ✓ pass  (205ms) "visualize app  area charts should show correct chart"
[00:03:01]               └-> should show correct data
[00:03:01]                 └-> "before each" hook: global before each
[00:03:01]                 │ debg Inspector.open
[00:03:01]                 │ debg TestSubjects.exists(inspectorPanel)
[00:03:01]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:04]                 │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:04]                 │ debg TestSubjects.click(openInspectorButton)
[00:03:04]                 │ debg Find.clickByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:04]                 │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:05]                 │ debg TestSubjects.exists(inspectorPanel)
[00:03:05]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:05]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 341:2042 Uncaught TypeError: Cannot read property 'onClose' of undefined
[00:03:05]                 │ debg TestSubjects.find(inspectorPanel)
[00:03:05]                 │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:05]                 │ debg Find.clickByButtonText('Rows per page: 20') with timeout=10000
[00:03:05]                 │ debg Find.byButtonText('Rows per page: 20') with timeout=10000
[00:03:06]                 │ debg Find.findByCssSelector('.euiPanel .euiContextMenuPanel') with timeout=10000
[00:03:06]                 │ debg Find.clickByButtonText('50 rows') with timeout=10000
[00:03:06]                 │ debg Find.byButtonText('50 rows') with timeout=10000
[00:03:06]                 │ debg Inspector.expectTableData(2015-09-20 00:00,37,2015-09-20 03:00,202,2015-09-20 06:00,740,2015-09-20 09:00,1,437,2015-09-20 12:00,1,371,2015-09-20 15:00,751,2015-09-20 18:00,188,2015-09-20 21:00,31,2015-09-21 00:00,42,2015-09-21 03:00,202,2015-09-21 06:00,683,2015-09-21 09:00,1,361,2015-09-21 12:00,1,415,2015-09-21 15:00,707,2015-09-21 18:00,177,2015-09-21 21:00,27,2015-09-22 00:00,32,2015-09-22 03:00,175,2015-09-22 06:00,707,2015-09-22 09:00,1,408,2015-09-22 12:00,1,355,2015-09-22 15:00,726,2015-09-22 18:00,201,2015-09-22 21:00,29)
[00:03:06]                 │ debg TestSubjects.find(inspectorPanel)
[00:03:06]                 │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:06]                 │ debg Close Inspector
[00:03:06]                 │ debg TestSubjects.exists(inspectorPanel)
[00:03:06]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:06]                 │ debg Closing flyout inspectorPanel
[00:03:06]                 │ debg TestSubjects.find(inspectorPanel)
[00:03:06]                 │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:06]                 │ debg Waiting up to 20000ms for flyout closed...
[00:03:06]                 │ debg TestSubjects.exists(inspectorPanel)
[00:03:06]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=1000
[00:03:07]                 │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:08]                 │ debg TestSubjects.exists(inspectorPanel)
[00:03:08]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:10]                 │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:11]                 └- ✓ pass  (9.2s) "visualize app  area charts should show correct data"
[00:03:11]               └-: axis scaling
[00:03:11]                 └-> "before all" hook
[00:03:11]                 └-> does not scale by default
[00:03:11]                   └-> "before each" hook: global before each
[00:03:11]                   │ debg Find.findByCssSelector('button[aria-controls="visEditorAggAccordion2"]') with timeout=10000
[00:03:11]                   │ debg toggle visEditorAggAccordion2 expand = false
[00:03:11]                   │ debg toggle visEditorAggAccordion2 click()
[00:03:11]                   │ debg visEditor.setInterval(Second, {default, 2, false})
[00:03:11]                   │ debg comboBox.set, comboBoxSelector: visEditorInterval
[00:03:11]                   │ debg TestSubjects.find(visEditorInterval)
[00:03:11]                   │ debg Find.findByCssSelector('[data-test-subj="visEditorInterval"]') with timeout=10000
[00:03:11]                   │ debg comboBox.setElement, value: Second
[00:03:11]                   │ debg comboBox.isOptionSelected, value: Second
[00:03:13]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:13]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:13]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="Second"]') with timeout=2500
[00:03:13]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:13]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:16]                   │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:03:16]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:16]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:16]                   │ debg Before Rendering count 1
[00:03:16]                   │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:03:16]                   │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:16]                   │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:17]                   │ debg Waiting up to 20000ms for rendering count to be greater than or equal to [2]...
[00:03:17]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:17]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:17]                   │ debg -- currentRenderingCount=1
[00:03:17]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:17]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:17]                   │ debg -- currentRenderingCount=2
[00:03:17]                   │ debg Inspector.open
[00:03:17]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:17]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:20]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:20]                   │ debg TestSubjects.click(openInspectorButton)
[00:03:20]                   │ debg Find.clickByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:20]                   │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:21]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:21]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:21]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 341:2042 Uncaught TypeError: Cannot read property 'onClose' of undefined
[00:03:21]                   │ debg Inspector.expectTableData(2015-09-20 00:00,6,2015-09-20 01:00,9,2015-09-20 02:00,22,2015-09-20 03:00,31,2015-09-20 04:00,52,2015-09-20 05:00,119,2015-09-20 06:00,181,2015-09-20 07:00,218,2015-09-20 08:00,341,2015-09-20 09:00,440,2015-09-20 10:00,480,2015-09-20 11:00,517,2015-09-20 12:00,522,2015-09-20 13:00,446,2015-09-20 14:00,403,2015-09-20 15:00,321,2015-09-20 16:00,258,2015-09-20 17:00,172,2015-09-20 18:00,95,2015-09-20 19:00,55)
[00:03:21]                   │ debg TestSubjects.find(inspectorPanel)
[00:03:21]                   │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:21]                   │ debg Close Inspector
[00:03:21]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:21]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:21]                   │ debg Closing flyout inspectorPanel
[00:03:21]                   │ debg TestSubjects.find(inspectorPanel)
[00:03:21]                   │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:21]                   │ debg Waiting up to 20000ms for flyout closed...
[00:03:21]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:21]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=1000
[00:03:22]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:22]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:22]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:25]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:25]                   └- ✓ pass  (14.7s) "visualize app  area charts axis scaling does not scale by default"
[00:03:25]                 └-> scales when enabled count agg
[00:03:25]                   └-> "before each" hook: global before each
[00:03:25]                   │ debg TestSubjects.find(advancedParams-2)
[00:03:25]                   │ debg Find.findByCssSelector('[data-test-subj="advancedParams-2"]') with timeout=10000
[00:03:25]                   │ debg Find.descendantDisplayedByCssSelector('button')
[00:03:25]                   │ debg TestSubjects.click(scaleMetricsSwitch)
[00:03:25]                   │ debg Find.clickByCssSelector('[data-test-subj="scaleMetricsSwitch"]') with timeout=10000
[00:03:25]                   │ debg Find.findByCssSelector('[data-test-subj="scaleMetricsSwitch"]') with timeout=10000
[00:03:26]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:26]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:26]                   │ debg Before Rendering count 2
[00:03:26]                   │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:03:26]                   │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:26]                   │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:26]                   │ debg Waiting up to 20000ms for rendering count to be greater than or equal to [3]...
[00:03:26]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:26]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:26]                   │ debg -- currentRenderingCount=2
[00:03:27]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:27]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:27]                   │ debg -- currentRenderingCount=3
[00:03:27]                   │ debg Inspector.open
[00:03:27]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:27]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:29]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:30]                   │ debg TestSubjects.click(openInspectorButton)
[00:03:30]                   │ debg Find.clickByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:30]                   │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:30]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:30]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:30]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 341:2042 Uncaught TypeError: Cannot read property 'onClose' of undefined
[00:03:30]                   │ debg Inspector.expectTableData(2015-09-20 00:00,0.002,2015-09-20 01:00,0.003,2015-09-20 02:00,0.006,2015-09-20 03:00,0.009,2015-09-20 04:00,0.014,2015-09-20 05:00,0.033,2015-09-20 06:00,0.05,2015-09-20 07:00,0.061,2015-09-20 08:00,0.095,2015-09-20 09:00,0.122,2015-09-20 10:00,0.133,2015-09-20 11:00,0.144,2015-09-20 12:00,0.145,2015-09-20 13:00,0.124,2015-09-20 14:00,0.112,2015-09-20 15:00,0.089,2015-09-20 16:00,0.072,2015-09-20 17:00,0.048,2015-09-20 18:00,0.026,2015-09-20 19:00,0.015)
[00:03:30]                   │ debg TestSubjects.find(inspectorPanel)
[00:03:30]                   │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:30]                   │ debg Close Inspector
[00:03:30]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:30]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:30]                   │ debg Closing flyout inspectorPanel
[00:03:30]                   │ debg TestSubjects.find(inspectorPanel)
[00:03:30]                   │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:30]                   │ debg Waiting up to 20000ms for flyout closed...
[00:03:30]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:30]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=1000
[00:03:31]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:32]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:32]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:34]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:35]                   └- ✓ pass  (9.3s) "visualize app  area charts axis scaling scales when enabled count agg"
[00:03:35]                 └-> does not scale top hit agg
[00:03:35]                   └-> "before each" hook: global before each
[00:03:35]                   │ debg TestSubjects.click(visEditorAdd_metrics)
[00:03:35]                   │ debg Find.clickByCssSelector('[data-test-subj="visEditorAdd_metrics"]') with timeout=10000
[00:03:35]                   │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_metrics"]') with timeout=10000
[00:03:35]                   │ debg TestSubjects.click(visEditorAdd_metrics_Y-axis)
[00:03:35]                   │ debg Find.clickByCssSelector('[data-test-subj="visEditorAdd_metrics_Y-axis"]') with timeout=10000
[00:03:35]                   │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_metrics_Y-axis"]') with timeout=10000
[00:03:35]                   │ debg Find.findByCssSelector('
[00:03:35]                   │                [data-test-subj="metricsAggGroup"]
[00:03:35]                   │                [data-test-subj^="visEditorAggAccordion"].euiAccordion-isOpen
[00:03:35]                   │                
[00:03:35]                   │                [data-test-subj="defaultEditorAggSelect"]
[00:03:35]                   │              ') with timeout=10000
[00:03:35]                   │ debg comboBox.setElement, value: Top Hit
[00:03:35]                   │ debg comboBox.isOptionSelected, value: Top Hit
[00:03:37]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:37]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:37]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="Top Hit"]') with timeout=2500
[00:03:38]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:38]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:40]                   │ERROR browser[SEVERE] http://localhost:61101/app/theme-github.js 0:0 Uncaught SyntaxError: Unexpected token '<'
[00:03:40]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 287:519565 Uncaught Error: couldn't load module ace/theme/github or it didn't call define
[00:03:40]                   │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:03:41]                   │ debg ... sleep(500) start
[00:03:41]                   │ debg ... sleep(500) end
[00:03:41]                   │ debg selectField bytes
[00:03:41]                   │ debg Find.findByCssSelector('
[00:03:41]                   │                [data-test-subj="metricsAggGroup"]
[00:03:41]                   │                [data-test-subj^="visEditorAggAccordion"].euiAccordion-isOpen
[00:03:41]                   │                [data-test-subj="visAggEditorParams"]
[00:03:41]                   │                
[00:03:41]                   │                [data-test-subj="visDefaultEditorField"]
[00:03:41]                   │              ') with timeout=10000
[00:03:41]                   │ debg comboBox.setElement, value: bytes
[00:03:41]                   │ debg comboBox.isOptionSelected, value: bytes
[00:03:44]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:44]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:44]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="bytes"]') with timeout=2500
[00:03:44]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:44]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:47]                   │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:03:47]                   │ debg Find.selectValue('[data-test-subj="visDefaultEditorAggregateWith"]', option[value="average"]')
[00:03:47]                   │ debg Find.findByCssSelector('[data-test-subj="visDefaultEditorAggregateWith"]') with timeout=10000
[00:03:47]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:47]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:47]                   │ debg Before Rendering count 3
[00:03:47]                   │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:03:47]                   │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:47]                   │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:48]                   │ debg Waiting up to 20000ms for rendering count to be greater than or equal to [4]...
[00:03:48]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:48]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:48]                   │ debg -- currentRenderingCount=3
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <path> attribute d: Expected number, "…02.304032345679,NaNL108.15964345…".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:48]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:48]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:48]                   │ debg -- currentRenderingCount=4
[00:03:48]                   │ debg Inspector.open
[00:03:48]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:48]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:51]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:52]                   │ debg TestSubjects.click(openInspectorButton)
[00:03:52]                   │ debg Find.clickByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:52]                   │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:52]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:52]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:52]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 341:2042 Uncaught TypeError: Cannot read property 'onClose' of undefined
[00:03:52]                   │ debg Inspector.expectTableData(2015-09-20 00:00,6,9.035KB,2015-09-20 01:00,9,5.854KB,2015-09-20 02:00,22,4.588KB,2015-09-20 03:00,31,8.349KB,2015-09-20 04:00,52,2.637KB,2015-09-20 05:00,119,1.712KB,2015-09-20 06:00,181,9.157KB,2015-09-20 07:00,218,8.192KB,2015-09-20 08:00,341,12.384KB,2015-09-20 09:00,440,4.482KB,2015-09-20 10:00,480,9.449KB,2015-09-20 11:00,517,213B,2015-09-20 12:00,522,638B,2015-09-20 13:00,446,7.421KB,2015-09-20 14:00,403,4.854KB,2015-09-20 15:00,321,4.132KB,2015-09-20 16:00,258,601B,2015-09-20 17:00,172,4.239KB,2015-09-20 18:00,95,6.272KB,2015-09-20 19:00,55,2.053KB)
[00:03:52]                   │ debg TestSubjects.find(inspectorPanel)
[00:03:52]                   │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:52]                   │ info Taking screenshot "/dev/shm/workspace/kibana/test/functional/screenshots/failure/visualize app  area charts axis scaling does not scale top hit agg.png"
[00:03:52]                   │ info Current URL is: http://localhost:61101/app/kibana#/visualize/edit/e64a9bd0-8fa0-11ea-9ffd-671fdad151a3?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:%272015-09-19T06:31:44.000Z%27,to:%272015-09-23T18:31:44.000Z%27))&_a=(filters:!(),linked:!f,query:(language:kuery,query:%27%27),uiState:(),vis:(aggs:!((enabled:!t,id:%271%27,params:(),schema:metric,type:count),(enabled:!t,id:%272%27,params:(drop_partials:!f,extended_bounds:(),field:%27@timestamp%27,interval:s,min_doc_count:1,scaleMetricValues:!t,timeRange:(from:%272015-09-19T06:31:44.000Z%27,to:%272015-09-23T18:31:44.000Z%27),useNormalizedEsInterval:!t),schema:segment,type:date_histogram),(enabled:!t,id:%273%27,params:(aggregate:average,field:bytes,size:1,sortField:%27@timestamp%27,sortOrder:desc),schema:metric,type:top_hits)),params:(addLegend:!t,addTimeMarker:!f,addTooltip:!t,categoryAxes:!((id:CategoryAxis-1,labels:(filter:!t,show:!t,truncate:100),position:bottom,scale:(type:linear),show:!t,style:(),title:(),type:category)),grid:(categoryLines:!f),labels:(),legendPosition:right,seriesParams:!((data:(id:%271%27,label:Count),drawLinesBetweenPoints:!t,interpolate:linear,lineWidth:2,mode:stacked,show:!t,showCircles:!t,type:area,valueAxis:ValueAxis-1),(data:(id:%273%27,label:%27Last%20bytes%27),drawLinesBetweenPoints:!t,interpolate:linear,lineWidth:2,mode:stacked,show:!t,showCircles:!t,type:area,valueAxis:ValueAxis-1)),thresholdLine:(color:%23E7664C,show:!f,style:full,value:10,width:1),times:!(),type:area,valueAxes:!((id:ValueAxis-1,labels:(filter:!f,rotate:0,show:!t,truncate:100),name:LeftAxis-1,position:left,scale:(mode:normal,type:linear),show:!t,style:(),title:(text:Count),type:value))),title:%27Visualization%20AreaChart%20Name%20Test%27,type:area))
[00:03:52]                   │ info Saving page source to: /dev/shm/workspace/kibana/test/functional/failure_debug/html/visualize app  area charts axis scaling does not scale top hit agg.html
[00:03:52]                   └- ✖ fail: "visualize app  area charts axis scaling does not scale top hit agg"
[00:03:52]                   │

Stack Trace

{ Error: expected [ [ '2015-09-20 00:00', '6', '' ],
  [ '2015-09-20 01:00', '9', '' ],
  [ '2015-09-20 02:00', '22', '' ],
  [ '2015-09-20 03:00', '31', '' ],
  [ '2015-09-20 04:00', '52', '' ],
  [ '2015-09-20 05:00', '119', '' ],
  [ '2015-09-20 06:00', '181', '' ],
  [ '2015-09-20 07:00', '218', '' ],
  [ '2015-09-20 08:00', '341', '' ],
  [ '2015-09-20 09:00', '440', '' ],
  [ '2015-09-20 10:00', '480', '' ],
  [ '2015-09-20 11:00', '517', '' ],
  [ '2015-09-20 12:00', '522', '' ],
  [ '2015-09-20 13:00', '446', '' ],
  [ '2015-09-20 14:00', '403', '' ],
  [ '2015-09-20 15:00', '321', '' ],
  [ '2015-09-20 16:00', '258', '' ],
  [ '2015-09-20 17:00', '172', '' ],
  [ '2015-09-20 18:00', '95', '' ],
  [ '2015-09-20 19:00', '55', '' ] ] to sort of equal [ [ '2015-09-20 00:00', '6', '9.035KB' ],
  [ '2015-09-20 01:00', '9', '5.854KB' ],
  [ '2015-09-20 02:00', '22', '4.588KB' ],
  [ '2015-09-20 03:00', '31', '8.349KB' ],
  [ '2015-09-20 04:00', '52', '2.637KB' ],
  [ '2015-09-20 05:00', '119', '1.712KB' ],
  [ '2015-09-20 06:00', '181', '9.157KB' ],
  [ '2015-09-20 07:00', '218', '8.192KB' ],
  [ '2015-09-20 08:00', '341', '12.384KB' ],
  [ '2015-09-20 09:00', '440', '4.482KB' ],
  [ '2015-09-20 10:00', '480', '9.449KB' ],
  [ '2015-09-20 11:00', '517', '213B' ],
  [ '2015-09-20 12:00', '522', '638B' ],
  [ '2015-09-20 13:00', '446', '7.421KB' ],
  [ '2015-09-20 14:00', '403', '4.854KB' ],
  [ '2015-09-20 15:00', '321', '4.132KB' ],
  [ '2015-09-20 16:00', '258', '601B' ],
  [ '2015-09-20 17:00', '172', '4.239KB' ],
  [ '2015-09-20 18:00', '95', '6.272KB' ],
  [ '2015-09-20 19:00', '55', '2.053KB' ] ]
    at Assertion.assert (packages/kbn-expect/expect.js:100:11)
    at Assertion.eql (packages/kbn-expect/expect.js:244:8)
    at Inspector.expectTableData (test/functional/services/inspector.ts:95:23)
    at process._tickCallback (internal/process/next_tick.js:68:7)
  actual:
   '[\n  [\n    "2015-09-20 00:00"\n    "6"\n    ""\n  ]\n  [\n    "2015-09-20 01:00"\n    "9"\n    ""\n  ]\n  [\n    "2015-09-20 02:00"\n    "22"\n    ""\n  ]\n  [\n    "2015-09-20 03:00"\n    "31"\n    ""\n  ]\n  [\n    "2015-09-20 04:00"\n    "52"\n    ""\n  ]\n  [\n    "2015-09-20 05:00"\n    "119"\n    ""\n  ]\n  [\n    "2015-09-20 06:00"\n    "181"\n    ""\n  ]\n  [\n    "2015-09-20 07:00"\n    "218"\n    ""\n  ]\n  [\n    "2015-09-20 08:00"\n    "341"\n    ""\n  ]\n  [\n    "2015-09-20 09:00"\n    "440"\n    ""\n  ]\n  [\n    "2015-09-20 10:00"\n    "480"\n    ""\n  ]\n  [\n    "2015-09-20 11:00"\n    "517"\n    ""\n  ]\n  [\n    "2015-09-20 12:00"\n    "522"\n    ""\n  ]\n  [\n    "2015-09-20 13:00"\n    "446"\n    ""\n  ]\n  [\n    "2015-09-20 14:00"\n    "403"\n    ""\n  ]\n  [\n    "2015-09-20 15:00"\n    "321"\n    ""\n  ]\n  [\n    "2015-09-20 16:00"\n    "258"\n    ""\n  ]\n  [\n    "2015-09-20 17:00"\n    "172"\n    ""\n  ]\n  [\n    "2015-09-20 18:00"\n    "95"\n    ""\n  ]\n  [\n    "2015-09-20 19:00"\n    "55"\n    ""\n  ]\n]',
  expected:
   '[\n  [\n    "2015-09-20 00:00"\n    "6"\n    "9.035KB"\n  ]\n  [\n    "2015-09-20 01:00"\n    "9"\n    "5.854KB"\n  ]\n  [\n    "2015-09-20 02:00"\n    "22"\n    "4.588KB"\n  ]\n  [\n    "2015-09-20 03:00"\n    "31"\n    "8.349KB"\n  ]\n  [\n    "2015-09-20 04:00"\n    "52"\n    "2.637KB"\n  ]\n  [\n    "2015-09-20 05:00"\n    "119"\n    "1.712KB"\n  ]\n  [\n    "2015-09-20 06:00"\n    "181"\n    "9.157KB"\n  ]\n  [\n    "2015-09-20 07:00"\n    "218"\n    "8.192KB"\n  ]\n  [\n    "2015-09-20 08:00"\n    "341"\n    "12.384KB"\n  ]\n  [\n    "2015-09-20 09:00"\n    "440"\n    "4.482KB"\n  ]\n  [\n    "2015-09-20 10:00"\n    "480"\n    "9.449KB"\n  ]\n  [\n    "2015-09-20 11:00"\n    "517"\n    "213B"\n  ]\n  [\n    "2015-09-20 12:00"\n    "522"\n    "638B"\n  ]\n  [\n    "2015-09-20 13:00"\n    "446"\n    "7.421KB"\n  ]\n  [\n    "2015-09-20 14:00"\n    "403"\n    "4.854KB"\n  ]\n  [\n    "2015-09-20 15:00"\n    "321"\n    "4.132KB"\n  ]\n  [\n    "2015-09-20 16:00"\n    "258"\n    "601B"\n  ]\n  [\n    "2015-09-20 17:00"\n    "172"\n    "4.239KB"\n  ]\n  [\n    "2015-09-20 18:00"\n    "95"\n    "6.272KB"\n  ]\n  [\n    "2015-09-20 19:00"\n    "55"\n    "2.053KB"\n  ]\n]',
  showDiff: true }

Kibana Pipeline / kibana-oss-agent / Chrome UI Functional Tests.test/functional/apps/visualize/_area_chart·js.visualize app area charts axis scaling does not scale top hit agg

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: visualize app
[00:00:00]           └-> "before all" hook
[00:00:00]           └-> "before all" hook
[00:00:00]             │ debg Starting visualize before method
[00:00:00]             │ info [logstash_functional] Loading "mappings.json"
[00:00:00]             │ info [logstash_functional] Loading "data.json.gz"
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.22][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.22][0]]"
[00:00:00]             │ info [logstash_functional] Created index "logstash-2015.09.22"
[00:00:00]             │ debg [logstash_functional] "logstash-2015.09.22" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.20][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.20][0]]"
[00:00:00]             │ info [logstash_functional] Created index "logstash-2015.09.20"
[00:00:00]             │ debg [logstash_functional] "logstash-2015.09.20" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:00]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.21][0]]])." previous.health="YELLOW" reason="shards started [[logstash-2015.09.21][0]]"
[00:00:00]             │ info [logstash_functional] Created index "logstash-2015.09.21"
[00:00:00]             │ debg [logstash_functional] "logstash-2015.09.21" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:10]             │ info progress: 10532
[00:00:12]             │ info [logstash_functional] Indexed 4633 docs into "logstash-2015.09.22"
[00:00:12]             │ info [logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:00:12]             │ info [logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:00:12]             │ info [long_window_logstash] Loading "mappings.json"
[00:00:12]             │ info [long_window_logstash] Loading "data.json.gz"
[00:00:12]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:12]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [long-window-logstash-0] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:12]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] current.health="GREEN" message="Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[long-window-logstash-0][0]]])." previous.health="YELLOW" reason="shards started [[long-window-logstash-0][0]]"
[00:00:12]             │ info [long_window_logstash] Created index "long-window-logstash-0"
[00:00:12]             │ 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:00:22]             │ info progress: 11959
[00:00:24]             │ info [long_window_logstash] Indexed 14005 docs into "long-window-logstash-0"
[00:00:25]             │ info [visualize] Loading "mappings.json"
[00:00:25]             │ info [visualize] Loading "data.json"
[00:00:25]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_1/XWCNj7eeTma4f8a-3oTBSg] deleting index
[00:00:25]             │ info [visualize] Deleted existing index [".kibana_1"]
[00:00:25]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:25]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:25]             │ info [visualize] Created index ".kibana"
[00:00:25]             │ debg [visualize] ".kibana" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:00:25]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana/tcxtTwEmQLyZzvmUNZeXwg] update_mapping [_doc]
[00:00:25]             │ info [visualize] Indexed 12 docs into ".kibana"
[00:00:25]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana/tcxtTwEmQLyZzvmUNZeXwg] update_mapping [_doc]
[00:00:25]             │ debg Migrating saved objects
[00:00:25]             │ proc [kibana]   log   [13:45:30.616] [info][savedobjects-service] Creating index .kibana_2.
[00:00:25]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:25]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_2] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:25]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] updating number_of_replicas to [0] for indices [.kibana_2]
[00:00:25]             │ proc [kibana]   log   [13:45:30.715] [info][savedobjects-service] Reindexing .kibana to .kibana_1
[00:00:25]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:25]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:25]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] updating number_of_replicas to [0] for indices [.kibana_1]
[00:00:25]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] applying create index request using v1 templates []
[00:00:25]             │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.tasks] creating index, cause [auto(task api)], templates [], shards [1]/[1], mappings [_doc]
[00:00:25]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] updating number_of_replicas to [0] for indices [.tasks]
[00:00:26]             │ info [o.e.t.LoggingTaskListener] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] 1568 finished with response BulkByScrollResponse[took=99.7ms,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:00:26]             │ info [o.e.c.m.MetadataDeleteIndexService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana/tcxtTwEmQLyZzvmUNZeXwg] deleting index
[00:00:26]             │ proc [kibana]   log   [13:45:31.146] [info][savedobjects-service] Migrating .kibana_1 saved objects to .kibana_2
[00:00:26]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_2/etmX3A8nSjSrdSohiZprpg] update_mapping [_doc]
[00:00:26]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_2/etmX3A8nSjSrdSohiZprpg] update_mapping [_doc]
[00:00:26]             │ proc [kibana]   log   [13:45:31.277] [info][savedobjects-service] Pointing alias .kibana to .kibana_2.
[00:00:26]             │ proc [kibana]   log   [13:45:31.322] [info][savedobjects-service] Finished in 709ms.
[00:00:26]             │ debg applying update to kibana config: {"accessibility:disableAnimations":true,"dateFormat:tz":"UTC"}
[00:00:26]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_2/etmX3A8nSjSrdSohiZprpg] update_mapping [_doc]
[00:00:27]             │ debg replacing kibana config doc: {"defaultIndex":"logstash-*","format:bytes:defaultPattern":"0,0.[000]b"}
[00:00:27]             │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-debian-tests-xl-1588771428785100227] [.kibana_2/etmX3A8nSjSrdSohiZprpg] update_mapping [_doc]
[00:00:28]           └-: 
[00:00:28]             └-> "before all" hook
[00:01:51]             └-: area charts
[00:01:51]               └-> "before all" hook
[00:01:51]               └-> "before all" hook
[00:01:51]                 │ debg navigateToApp visualize
[00:01:51]                 │ debg navigating to visualize url: http://localhost:61101/app/kibana#/visualize
[00:01:51]                 │ debg navigate to: http://localhost:61101/app/kibana#/visualize
[00:01:51]                 │ debg ... sleep(700) start
[00:01:51]                 │ debg browser[INFO] http://localhost:61101/app/kibana?_t=1588772816862#/visualize 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:01:51]                 │
[00:01:51]                 │ debg browser[INFO] http://localhost:61101/bundles/app/kibana/bootstrap.js 10:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:01:52]                 │ debg ... sleep(700) end
[00:01:52]                 │ debg returned from get, calling refresh
[00:01:53]                 │ debg browser[INFO] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 380:106115 "INFO: 2020-05-06T13:46:57Z
[00:01:53]                 │        Adding connection to http://localhost:61101/elasticsearch
[00:01:53]                 │
[00:01:53]                 │      "
[00:01:53]                 │ debg browser[INFO] http://localhost:61101/app/kibana?_t=1588772816862#/visualize 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:01:53]                 │
[00:01:53]                 │ debg browser[INFO] http://localhost:61101/bundles/app/kibana/bootstrap.js 10:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:01:53]                 │ debg currentUrl = http://localhost:61101/app/kibana#/visualize
[00:01:53]                 │          appUrl = http://localhost:61101/app/kibana#/visualize
[00:01:53]                 │ debg TestSubjects.find(kibanaChrome)
[00:01:53]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:01:53]                 │ debg TestSubjects.find(kibanaChrome)
[00:01:53]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=10000
[00:01:53]                 │ debg browser[INFO] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 380:106115 "INFO: 2020-05-06T13:46:58Z
[00:01:53]                 │        Adding connection to http://localhost:61101/elasticsearch
[00:01:53]                 │
[00:01:53]                 │      "
[00:01:53]                 │ debg ... sleep(501) start
[00:01:54]                 │ debg ... sleep(501) end
[00:01:54]                 │ debg in navigateTo url = http://localhost:61101/app/kibana#/visualize?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:01:54]                 │ debg --- retry.try error: URL changed, waiting for it to settle
[00:01:54]                 │ debg ... sleep(501) start
[00:01:55]                 │ debg ... sleep(501) end
[00:01:55]                 │ debg in navigateTo url = http://localhost:61101/app/kibana#/visualize?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:01:55]                 │ debg TestSubjects.exists(statusPageContainer)
[00:01:55]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:01:57]                 │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:01:58]                 │ debg TestSubjects.exists(newItemButton)
[00:01:58]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="newItemButton"]') with timeout=2500
[00:01:58]                 │ debg TestSubjects.click(newItemButton)
[00:01:58]                 │ debg Find.clickByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:01:58]                 │ debg Find.findByCssSelector('[data-test-subj="newItemButton"]') with timeout=10000
[00:01:58]                 │ debg TestSubjects.find(visNewDialogTypes)
[00:01:58]                 │ debg Find.findByCssSelector('[data-test-subj="visNewDialogTypes"]') with timeout=10000
[00:01:58]                 │ debg clickAreaChart
[00:01:58]                 │ debg TestSubjects.click(visType-area)
[00:01:58]                 │ debg Find.clickByCssSelector('[data-test-subj="visType-area"]') with timeout=10000
[00:01:58]                 │ debg Find.findByCssSelector('[data-test-subj="visType-area"]') with timeout=10000
[00:01:58]                 │ debg isGlobalLoadingIndicatorVisible
[00:01:58]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:01:58]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:01:59]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:01:59]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:01:59]                 │ debg clickNewSearch
[00:01:59]                 │ debg TestSubjects.click(savedObjectTitlelogstash-*)
[00:01:59]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitlelogstash-*"]') with timeout=10000
[00:01:59]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitlelogstash-*"]') with timeout=10000
[00:01:59]                 │ debg isGlobalLoadingIndicatorVisible
[00:01:59]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:01:59]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:01:59]                 │ERROR browser[SEVERE] http://localhost:61101/app/theme-github.js 0:0 Uncaught SyntaxError: Unexpected token '<'
[00:01:59]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 287:519565 Uncaught Error: couldn't load module ace/theme/github or it didn't call define
[00:01:59]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:01]                 │ debg --- retry.tryForTime failed again with the same message...
[00:02:02]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:02]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:02]                 │ debg Setting absolute range to Sep 19, 2015 @ 06:31:44.000 to Sep 23, 2015 @ 18:31:44.000
[00:02:02]                 │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:02:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:02:02]                 │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:02:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:02:02]                 │ debg TestSubjects.click(superDatePickerShowDatesButton)
[00:02:02]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:02:02]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:02:02]                 │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:02:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:02:02]                 │ debg TestSubjects.click(superDatePickerendDatePopoverButton)
[00:02:02]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:02:02]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:02:02]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:02:02]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:02:02]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:02:02]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:02:02]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:02:02]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:02]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:02]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 23, 2015 @ 18:31:44.000)
[00:02:02]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:02:02]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:02]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:03]                 │ debg ... sleep(500) start
[00:02:03]                 │ debg ... sleep(500) end
[00:02:03]                 │ debg TestSubjects.click(superDatePickerstartDatePopoverButton)
[00:02:03]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:02:03]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:02:03]                 │ debg Find.waitForElementStale with timeout=10000
[00:02:04]                 │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:02:04]                 │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:02:04]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:02:04]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:02:04]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:02:04]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:04]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:04]                 │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 19, 2015 @ 06:31:44.000)
[00:02:04]                 │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:02:04]                 │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:04]                 │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:04]                 │ debg TestSubjects.exists(superDatePickerApplyTimeButton)
[00:02:04]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerApplyTimeButton"]') with timeout=2500
[00:02:07]                 │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerApplyTimeButton"] is not displayed
[00:02:07]                 │ debg TestSubjects.click(querySubmitButton)
[00:02:07]                 │ debg Find.clickByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:02:07]                 │ debg Find.findByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:02:08]                 │ debg Find.waitForElementStale with timeout=10000
[00:02:08]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:08]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:08]                 │ debg Click X-axis
[00:02:08]                 │ debg TestSubjects.click(visEditorAdd_buckets)
[00:02:08]                 │ debg Find.clickByCssSelector('[data-test-subj="visEditorAdd_buckets"]') with timeout=10000
[00:02:08]                 │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_buckets"]') with timeout=10000
[00:02:08]                 │ debg TestSubjects.click(visEditorAdd_buckets_X-axis)
[00:02:08]                 │ debg Find.clickByCssSelector('[data-test-subj="visEditorAdd_buckets_X-axis"]') with timeout=10000
[00:02:08]                 │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_buckets_X-axis"]') with timeout=10000
[00:02:08]                 │ debg Click Date Histogram
[00:02:08]                 │ debg Find.findByCssSelector('
[00:02:08]                 │                [data-test-subj="bucketsAggGroup"]
[00:02:08]                 │                [data-test-subj^="visEditorAggAccordion"].euiAccordion-isOpen
[00:02:08]                 │                
[00:02:08]                 │                [data-test-subj="defaultEditorAggSelect"]
[00:02:08]                 │              ') with timeout=10000
[00:02:08]                 │ debg comboBox.setElement, value: Date Histogram
[00:02:08]                 │ debg comboBox.isOptionSelected, value: Date Histogram
[00:02:11]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:02:11]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:02:11]                 │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="Date Histogram"]') with timeout=2500
[00:02:11]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:02:11]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:02:14]                 │ERROR browser[SEVERE] http://localhost:61101/app/theme-github.js 0:0 Uncaught SyntaxError: Unexpected token '<'
[00:02:14]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 287:519565 Uncaught Error: couldn't load module ace/theme/github or it didn't call define
[00:02:14]                 │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:02:14]                 │ debg ... sleep(500) start
[00:02:15]                 │ debg ... sleep(500) end
[00:02:15]                 │ debg Check field value
[00:02:15]                 │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: visDefaultEditorField
[00:02:15]                 │ debg TestSubjects.find(visDefaultEditorField)
[00:02:15]                 │ debg Find.findByCssSelector('[data-test-subj="visDefaultEditorField"]') with timeout=10000
[00:02:15]                 │ debg fieldValue = @timestamp
[00:02:15]                 │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: visEditorInterval
[00:02:15]                 │ debg TestSubjects.find(visEditorInterval)
[00:02:15]                 │ debg Find.findByCssSelector('[data-test-subj="visEditorInterval"]') with timeout=10000
[00:02:15]                 │ debg intervalValue = Auto
[00:02:15]                 │ debg TestSubjects.find(visualizationLoader)
[00:02:15]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:02:15]                 │ debg Before Rendering count 2
[00:02:15]                 │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:02:15]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:02:15]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:02:15]                 │ debg Waiting up to 20000ms for rendering count to be greater than or equal to [3]...
[00:02:15]                 │ debg TestSubjects.find(visualizationLoader)
[00:02:15]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:02:15]                 │ debg -- currentRenderingCount=2
[00:02:16]                 │ debg TestSubjects.find(visualizationLoader)
[00:02:16]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:02:16]                 │ debg -- currentRenderingCount=3
[00:02:16]               └-> should save and load with special characters
[00:02:16]                 └-> "before each" hook: global before each
[00:02:16]                 │ debg ensureSavePanelOpen
[00:02:16]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:16]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:16]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:17]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:18]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:18]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:18]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=5000
[00:02:20]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:23]                 │ debg --- retry.tryForTime failed again with the same message...
[00:02:24]                 │ debg TestSubjects.click(visualizeSaveButton)
[00:02:24]                 │ debg Find.clickByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:24]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:24]                 │ debg TestSubjects.setValue(savedObjectTitle, Visualization AreaChart Name Test/?&=%)
[00:02:24]                 │ debg TestSubjects.click(savedObjectTitle)
[00:02:24]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:24]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:24]                 │ debg Click Save Visualization button
[00:02:24]                 │ debg TestSubjects.click(confirmSaveSavedObjectButton)
[00:02:24]                 │ debg Find.clickByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:24]                 │ debg Find.findByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:25]                 │ debg TestSubjects.exists(saveVisualizationSuccess)
[00:02:25]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="saveVisualizationSuccess"]') with timeout=120000
[00:02:26]                 │ debg Find.findByCssSelector('.euiToast') with timeout=20000
[00:02:26]                 │ debg Find.findByCssSelector('.euiToastHeader__title') with timeout=10000
[00:02:26]                 │ERROR browser[SEVERE] http://localhost:61101/app/theme-github.js 0:0 Uncaught SyntaxError: Unexpected token '<'
[00:02:26]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 287:519565 Uncaught Error: couldn't load module ace/theme/github or it didn't call define
[00:02:26]                 │ debg Toast title: Saved 'Visualization AreaChart Name Test/?&=%'
[00:02:26]                 │ debg Find.clickByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:26]                 │ debg Find.findByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:26]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:26]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:26]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:28]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:28]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:28]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:28]                 │ debg Waiting for save modal to close
[00:02:28]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:28]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=2500
[00:02:31]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:32]                 │ debg Waiting up to 20000ms for last breadcrumb to have new vis name...
[00:02:32]                 │ debg TestSubjects.getVisibleText(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:02:32]                 │ debg TestSubjects.find(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:02:32]                 │ debg Find.findByCssSelector('[data-test-subj="headerGlobalNav"] [data-test-subj="breadcrumbs"] [data-test-subj~="breadcrumb"][data-test-subj~="last"]') with timeout=10000
[00:02:32]                 └- ✓ pass  (15.9s) "visualize app  area charts should save and load with special characters"
[00:02:32]               └-> should save and load with non-ascii characters
[00:02:32]                 └-> "before each" hook: global before each
[00:02:32]                 │ debg ensureSavePanelOpen
[00:02:32]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:32]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:32]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:33]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:34]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:34]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:34]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:34]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=5000
[00:02:36]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:39]                 │ debg --- retry.tryForTime failed again with the same message...
[00:02:40]                 │ debg TestSubjects.click(visualizeSaveButton)
[00:02:40]                 │ debg Find.clickByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:40]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:40]                 │ debg TestSubjects.setValue(savedObjectTitle, Visualization AreaChart Name Test with Umlaut ä)
[00:02:40]                 │ debg TestSubjects.click(savedObjectTitle)
[00:02:40]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:40]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:40]                 │ debg Click Save Visualization button
[00:02:40]                 │ debg TestSubjects.click(confirmSaveSavedObjectButton)
[00:02:40]                 │ debg Find.clickByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:40]                 │ debg Find.findByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:40]                 │ debg TestSubjects.exists(saveVisualizationSuccess)
[00:02:40]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="saveVisualizationSuccess"]') with timeout=120000
[00:02:41]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 333:78758 TypeError: Cannot set property 'title' of undefined
[00:02:41]                 │          at http://localhost:61101/32781/bundles/plugin/visualize/2.plugin.js:1:449429
[00:02:41]                 │          at c.$digest (http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:100155)
[00:02:41]                 │          at http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:102127
[00:02:41]                 │          at Yr.completeTask (http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:122692)
[00:02:41]                 │          at http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:34257
[00:02:41]                 │ debg Find.findByCssSelector('.euiToast') with timeout=20000
[00:02:41]                 │ debg Find.findByCssSelector('.euiToastHeader__title') with timeout=10000
[00:02:41]                 │ debg Toast title: Saved 'Visualization AreaChart Name Test with Umlaut ä'
[00:02:41]                 │ debg Find.clickByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:41]                 │ debg Find.findByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:41]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:41]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:41]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:43]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[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 Waiting for save modal to close
[00:02:43]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:43]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=2500
[00:02:46]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:46]                 │ debg Waiting up to 20000ms for last breadcrumb to have new vis name...
[00:02:46]                 │ debg TestSubjects.getVisibleText(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:02:46]                 │ debg TestSubjects.find(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:02:46]                 │ debg Find.findByCssSelector('[data-test-subj="headerGlobalNav"] [data-test-subj="breadcrumbs"] [data-test-subj~="breadcrumb"][data-test-subj~="last"]') with timeout=10000
[00:02:46]                 └- ✓ pass  (14.6s) "visualize app  area charts should save and load with non-ascii characters"
[00:02:46]               └-> should save and load
[00:02:46]                 └-> "before each" hook: global before each
[00:02:46]                 │ debg ensureSavePanelOpen
[00:02:46]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:46]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:46]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:48]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:48]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:48]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:48]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:48]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=5000
[00:02:51]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:02:54]                 │ debg --- retry.tryForTime failed again with the same message...
[00:02:54]                 │ debg TestSubjects.click(visualizeSaveButton)
[00:02:54]                 │ debg Find.clickByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:54]                 │ debg Find.findByCssSelector('[data-test-subj="visualizeSaveButton"]') with timeout=10000
[00:02:54]                 │ debg TestSubjects.setValue(savedObjectTitle, Visualization AreaChart Name Test)
[00:02:54]                 │ debg TestSubjects.click(savedObjectTitle)
[00:02:54]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:54]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:02:55]                 │ debg Click Save Visualization button
[00:02:55]                 │ debg TestSubjects.click(confirmSaveSavedObjectButton)
[00:02:55]                 │ debg Find.clickByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:55]                 │ debg Find.findByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:02:55]                 │ debg TestSubjects.exists(saveVisualizationSuccess)
[00:02:55]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="saveVisualizationSuccess"]') with timeout=120000
[00:02:56]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 333:78758 TypeError: Cannot set property 'title' of undefined
[00:02:56]                 │          at http://localhost:61101/32781/bundles/plugin/visualize/2.plugin.js:1:449429
[00:02:56]                 │          at c.$digest (http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:100155)
[00:02:56]                 │          at http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:102127
[00:02:56]                 │          at Yr.completeTask (http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:122692)
[00:02:56]                 │          at http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js:334:34257
[00:02:56]                 │ debg Find.findByCssSelector('.euiToast') with timeout=20000
[00:02:56]                 │ debg Find.findByCssSelector('.euiToastHeader__title') with timeout=10000
[00:02:56]                 │ debg Toast title: Saved 'Visualization AreaChart Name Test'
[00:02:56]                 │ debg Find.clickByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:56]                 │ debg Find.findByCssSelector('.euiToast__closeButton') with timeout=10000
[00:02:56]                 │ debg isGlobalLoadingIndicatorVisible
[00:02:56]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:02:56]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:02:57]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:02:58]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:58]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:58]                 │ debg Waiting for save modal to close
[00:02:58]                 │ debg TestSubjects.exists(savedObjectSaveModal)
[00:02:58]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=2500
[00:03:00]                 │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:03:01]                 │ debg Waiting up to 20000ms for last breadcrumb to have new vis name...
[00:03:01]                 │ debg TestSubjects.getVisibleText(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:03:01]                 │ debg TestSubjects.find(headerGlobalNav > breadcrumbs > ~breadcrumb & ~last)
[00:03:01]                 │ debg Find.findByCssSelector('[data-test-subj="headerGlobalNav"] [data-test-subj="breadcrumbs"] [data-test-subj~="breadcrumb"][data-test-subj~="last"]') with timeout=10000
[00:03:01]                 │ debg Find.clickByCssSelector('[href="#/visualize"]') with timeout=10000
[00:03:01]                 │ debg Find.findByCssSelector('[href="#/visualize"]') with timeout=10000
[00:03:01]                 │ debg clickVisualizationByLinkText(Visualization AreaChart Name Test)
[00:03:01]                 │ debg Find.clickByPartialLinkText('Visualization AreaChart Name Test') with timeout=10000
[00:03:01]                 │ debg Find.byPartialLinkText('Visualization AreaChart Name Test')  with timeout=10000
[00:03:02]                 │ debg isGlobalLoadingIndicatorVisible
[00:03:02]                 │ debg TestSubjects.exists(globalLoadingIndicator)
[00:03:02]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:03:02]                 │ERROR browser[SEVERE] http://localhost:61101/app/theme-github.js 0:0 Uncaught SyntaxError: Unexpected token '<'
[00:03:02]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 287:519565 Uncaught Error: couldn't load module ace/theme/github or it didn't call define
[00:03:02]                 │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:03:04]                 │ debg --- retry.tryForTime failed again with the same message...
[00:03:05]                 │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:03:05]                 │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:03:05]                 │ debg Waiting up to 20000ms for rendering count to stabilize...
[00:03:05]                 │ debg TestSubjects.find(visualizationLoader)
[00:03:05]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:05]                 │ debg -- firstCount=1
[00:03:05]                 │ debg ... sleep(1000) start
[00:03:06]                 │ debg ... sleep(1000) end
[00:03:06]                 │ debg TestSubjects.find(visualizationLoader)
[00:03:06]                 │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:06]                 │ debg -- secondCount=1
[00:03:06]                 │ debg Find.findByCssSelector('.visualization') with timeout=10000
[00:03:06]                 └- ✓ pass  (19.7s) "visualize app  area charts should save and load"
[00:03:06]               └-> should have inspector enabled
[00:03:06]                 └-> "before each" hook: global before each
[00:03:06]                 │ debg TestSubjects.getAttribute(openInspectorButton, disabled)
[00:03:06]                 │ debg TestSubjects.find(openInspectorButton)
[00:03:06]                 │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:06]                 └- ✓ pass  (31ms) "visualize app  area charts should have inspector enabled"
[00:03:06]               └-> should show correct chart
[00:03:06]                 └-> "before each" hook: global before each
[00:03:06]                 │ debg Find.findByCssSelector('.visAxis--x.visAxis__column--bottom') with timeout=10000
[00:03:06]                 │ debg X-Axis labels = 2015-09-20 00:00,2015-09-21 00:00,2015-09-22 00:00,2015-09-23 00:00
[00:03:06]                 │ debg Find.findByCssSelector('.visAxis__column--y.visAxis__column--left') with timeout=10000
[00:03:06]                 │ debg Y-Axis labels = 0,200,400,600,800,1,000,1,200,1,400,1,600
[00:03:06]                 │ debg Find.findByCssSelector('div.visAxis__splitAxes--y > div > svg > g.ValueAxis-1 > g:last-of-type.tick') with timeout=10000
[00:03:06]                 │ debg maxYLabel = 1600, maxYLabelYPosition = 184.40625
[00:03:06]                 │ debg Find.findByCssSelector('div.visAxis__column--y.visAxis__column--left  > div > div > svg:nth-child(2) > g > g:nth-child(1).tick') with timeout=10000
[00:03:06]                 │ debg Find.findByCssSelector('rect.background') with timeout=10000
[00:03:06]                 │ debg height --------- 551
[00:03:06]                 │ debg Find.findByCssSelector('path[data-label="Count"]') with timeout=20000
[00:03:06]                 │ debg M101.17164551440328,538.2581250000001L118.54403440329217,481.43625000000003L135.91642329218107,296.16249999999997L153.28881218106994,56.13312500000003L170.66120106995885,78.86187499999997L188.0335899588477,292.374375L205.4059788477366,486.25750000000005L222.77836773662548,540.324375L240.1507566255144,536.53625L257.52314551440327,481.43625000000003L274.8955344032921,315.791875L292.267923292181,82.30562500000002L309.64031218106993,63.70937499999999L327.01270106995884,307.526875L344.38508995884774,490.04562500000003L361.75747884773665,541.701875L379.1298677366255,539.98L396.50225662551435,490.734375L413.87464551440326,307.526875L431.2470344032921,66.12L448.619423292181,84.37187499999997L465.9918121810699,300.98375L483.3642010699588,481.780625L500.73658995884773,541.0131250000001L500.73658995884773,551L483.3642010699588,551L465.9918121810699,551L448.619423292181,551L431.2470344032921,551L413.87464551440326,551L396.50225662551435,551L379.1298677366255,551L361.75747884773665,551L344.38508995884774,551L327.01270106995884,551L309.64031218106993,551L292.267923292181,551L274.8955344032921,551L257.52314551440327,551L240.1507566255144,551L222.77836773662548,551L205.4059788477366,551L188.0335899588477,551L170.66120106995885,551L153.28881218106994,551L135.91642329218107,551L118.54403440329217,551L101.17164551440328,551Z
[00:03:06]                 │ debg chartData[i] =37
[00:03:06]                 │ debg chartData[i] =202
[00:03:06]                 │ debg chartData[i] =740
[00:03:06]                 │ debg chartData[i] =1437
[00:03:06]                 │ debg chartData[i] =1371
[00:03:06]                 │ debg chartData[i] =751
[00:03:06]                 │ debg chartData[i] =188
[00:03:06]                 │ debg chartData[i] =31
[00:03:06]                 │ debg chartData[i] =42
[00:03:06]                 │ debg chartData[i] =202
[00:03:06]                 │ debg chartData[i] =683
[00:03:06]                 │ debg chartData[i] =1361
[00:03:06]                 │ debg chartData[i] =1415
[00:03:06]                 │ debg chartData[i] =707
[00:03:06]                 │ debg chartData[i] =177
[00:03:06]                 │ debg chartData[i] =27
[00:03:06]                 │ debg chartData[i] =32
[00:03:06]                 │ debg chartData[i] =175
[00:03:06]                 │ debg chartData[i] =707
[00:03:06]                 │ debg chartData[i] =1408
[00:03:06]                 │ debg chartData[i] =1355
[00:03:06]                 │ debg chartData[i] =726
[00:03:06]                 │ debg chartData[i] =201
[00:03:06]                 │ debg chartData[i] =29
[00:03:06]                 │ debg expectedAreaChartData = 37,202,740,1437,1371,751,188,31,42,202,683,1361,1415,707,177,27,32,175,707,1408,1355,726,201,29
[00:03:06]                 │ debg actual chart data =     37,202,740,1437,1371,751,188,31,42,202,683,1361,1415,707,177,27,32,175,707,1408,1355,726,201,29
[00:03:06]                 └- ✓ pass  (185ms) "visualize app  area charts should show correct chart"
[00:03:06]               └-> should show correct data
[00:03:06]                 └-> "before each" hook: global before each
[00:03:06]                 │ debg Inspector.open
[00:03:06]                 │ debg TestSubjects.exists(inspectorPanel)
[00:03:06]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:09]                 │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:09]                 │ debg TestSubjects.click(openInspectorButton)
[00:03:09]                 │ debg Find.clickByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:09]                 │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:09]                 │ debg TestSubjects.exists(inspectorPanel)
[00:03:09]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:09]                 │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 341:2042 Uncaught TypeError: Cannot read property 'onClose' of undefined
[00:03:09]                 │ debg TestSubjects.find(inspectorPanel)
[00:03:09]                 │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:09]                 │ debg Find.clickByButtonText('Rows per page: 20') with timeout=10000
[00:03:09]                 │ debg Find.byButtonText('Rows per page: 20') with timeout=10000
[00:03:10]                 │ debg Find.findByCssSelector('.euiPanel .euiContextMenuPanel') with timeout=10000
[00:03:10]                 │ debg Find.clickByButtonText('50 rows') with timeout=10000
[00:03:10]                 │ debg Find.byButtonText('50 rows') with timeout=10000
[00:03:10]                 │ debg Inspector.expectTableData(2015-09-20 00:00,37,2015-09-20 03:00,202,2015-09-20 06:00,740,2015-09-20 09:00,1,437,2015-09-20 12:00,1,371,2015-09-20 15:00,751,2015-09-20 18:00,188,2015-09-20 21:00,31,2015-09-21 00:00,42,2015-09-21 03:00,202,2015-09-21 06:00,683,2015-09-21 09:00,1,361,2015-09-21 12:00,1,415,2015-09-21 15:00,707,2015-09-21 18:00,177,2015-09-21 21:00,27,2015-09-22 00:00,32,2015-09-22 03:00,175,2015-09-22 06:00,707,2015-09-22 09:00,1,408,2015-09-22 12:00,1,355,2015-09-22 15:00,726,2015-09-22 18:00,201,2015-09-22 21:00,29)
[00:03:10]                 │ debg TestSubjects.find(inspectorPanel)
[00:03:10]                 │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:11]                 │ debg Close Inspector
[00:03:11]                 │ debg TestSubjects.exists(inspectorPanel)
[00:03:11]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:11]                 │ debg Closing flyout inspectorPanel
[00:03:11]                 │ debg TestSubjects.find(inspectorPanel)
[00:03:11]                 │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:11]                 │ debg Waiting up to 20000ms for flyout closed...
[00:03:11]                 │ debg TestSubjects.exists(inspectorPanel)
[00:03:11]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=1000
[00:03:12]                 │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:12]                 │ debg TestSubjects.exists(inspectorPanel)
[00:03:12]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:15]                 │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:15]                 └- ✓ pass  (9.1s) "visualize app  area charts should show correct data"
[00:03:15]               └-: axis scaling
[00:03:15]                 └-> "before all" hook
[00:03:15]                 └-> does not scale by default
[00:03:15]                   └-> "before each" hook: global before each
[00:03:15]                   │ debg Find.findByCssSelector('button[aria-controls="visEditorAggAccordion2"]') with timeout=10000
[00:03:15]                   │ debg toggle visEditorAggAccordion2 expand = false
[00:03:15]                   │ debg toggle visEditorAggAccordion2 click()
[00:03:15]                   │ debg visEditor.setInterval(Second, {default, 2, false})
[00:03:15]                   │ debg comboBox.set, comboBoxSelector: visEditorInterval
[00:03:15]                   │ debg TestSubjects.find(visEditorInterval)
[00:03:15]                   │ debg Find.findByCssSelector('[data-test-subj="visEditorInterval"]') with timeout=10000
[00:03:15]                   │ debg comboBox.setElement, value: Second
[00:03:15]                   │ debg comboBox.isOptionSelected, value: Second
[00:03:18]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:18]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:18]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="Second"]') with timeout=2500
[00:03:18]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:18]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:21]                   │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:03:21]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:21]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:21]                   │ debg Before Rendering count 1
[00:03:21]                   │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:03:21]                   │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:21]                   │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:21]                   │ debg Waiting up to 20000ms for rendering count to be greater than or equal to [2]...
[00:03:21]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:21]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:22]                   │ debg -- currentRenderingCount=1
[00:03:22]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:22]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:22]                   │ debg -- currentRenderingCount=2
[00:03:22]                   │ debg Inspector.open
[00:03:22]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:22]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:25]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:25]                   │ debg TestSubjects.click(openInspectorButton)
[00:03:25]                   │ debg Find.clickByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:25]                   │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:25]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:25]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:25]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 341:2042 Uncaught TypeError: Cannot read property 'onClose' of undefined
[00:03:25]                   │ debg Inspector.expectTableData(2015-09-20 00:00,6,2015-09-20 01:00,9,2015-09-20 02:00,22,2015-09-20 03:00,31,2015-09-20 04:00,52,2015-09-20 05:00,119,2015-09-20 06:00,181,2015-09-20 07:00,218,2015-09-20 08:00,341,2015-09-20 09:00,440,2015-09-20 10:00,480,2015-09-20 11:00,517,2015-09-20 12:00,522,2015-09-20 13:00,446,2015-09-20 14:00,403,2015-09-20 15:00,321,2015-09-20 16:00,258,2015-09-20 17:00,172,2015-09-20 18:00,95,2015-09-20 19:00,55)
[00:03:25]                   │ debg TestSubjects.find(inspectorPanel)
[00:03:25]                   │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:25]                   │ debg Close Inspector
[00:03:25]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:25]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:25]                   │ debg Closing flyout inspectorPanel
[00:03:25]                   │ debg TestSubjects.find(inspectorPanel)
[00:03:25]                   │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:26]                   │ debg Waiting up to 20000ms for flyout closed...
[00:03:26]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:26]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=1000
[00:03:27]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:27]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:27]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:30]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:30]                   └- ✓ pass  (14.9s) "visualize app  area charts axis scaling does not scale by default"
[00:03:30]                 └-> scales when enabled count agg
[00:03:30]                   └-> "before each" hook: global before each
[00:03:30]                   │ debg TestSubjects.find(advancedParams-2)
[00:03:30]                   │ debg Find.findByCssSelector('[data-test-subj="advancedParams-2"]') with timeout=10000
[00:03:30]                   │ debg Find.descendantDisplayedByCssSelector('button')
[00:03:30]                   │ debg TestSubjects.click(scaleMetricsSwitch)
[00:03:30]                   │ debg Find.clickByCssSelector('[data-test-subj="scaleMetricsSwitch"]') with timeout=10000
[00:03:30]                   │ debg Find.findByCssSelector('[data-test-subj="scaleMetricsSwitch"]') with timeout=10000
[00:03:30]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:30]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:30]                   │ debg Before Rendering count 2
[00:03:30]                   │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:03:30]                   │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:30]                   │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:31]                   │ debg Waiting up to 20000ms for rendering count to be greater than or equal to [3]...
[00:03:31]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:31]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:31]                   │ debg -- currentRenderingCount=2
[00:03:31]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:31]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:31]                   │ debg -- currentRenderingCount=3
[00:03:31]                   │ debg Inspector.open
[00:03:31]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:31]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:34]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:34]                   │ debg TestSubjects.click(openInspectorButton)
[00:03:34]                   │ debg Find.clickByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:34]                   │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:35]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:35]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:35]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 341:2042 Uncaught TypeError: Cannot read property 'onClose' of undefined
[00:03:35]                   │ debg Inspector.expectTableData(2015-09-20 00:00,0.002,2015-09-20 01:00,0.003,2015-09-20 02:00,0.006,2015-09-20 03:00,0.009,2015-09-20 04:00,0.014,2015-09-20 05:00,0.033,2015-09-20 06:00,0.05,2015-09-20 07:00,0.061,2015-09-20 08:00,0.095,2015-09-20 09:00,0.122,2015-09-20 10:00,0.133,2015-09-20 11:00,0.144,2015-09-20 12:00,0.145,2015-09-20 13:00,0.124,2015-09-20 14:00,0.112,2015-09-20 15:00,0.089,2015-09-20 16:00,0.072,2015-09-20 17:00,0.048,2015-09-20 18:00,0.026,2015-09-20 19:00,0.015)
[00:03:35]                   │ debg TestSubjects.find(inspectorPanel)
[00:03:35]                   │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:35]                   │ debg Close Inspector
[00:03:35]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:35]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:35]                   │ debg Closing flyout inspectorPanel
[00:03:35]                   │ debg TestSubjects.find(inspectorPanel)
[00:03:35]                   │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:35]                   │ debg Waiting up to 20000ms for flyout closed...
[00:03:35]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:35]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=1000
[00:03:36]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:36]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:36]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:39]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:39]                   └- ✓ pass  (9.3s) "visualize app  area charts axis scaling scales when enabled count agg"
[00:03:39]                 └-> does not scale top hit agg
[00:03:39]                   └-> "before each" hook: global before each
[00:03:39]                   │ debg TestSubjects.click(visEditorAdd_metrics)
[00:03:39]                   │ debg Find.clickByCssSelector('[data-test-subj="visEditorAdd_metrics"]') with timeout=10000
[00:03:39]                   │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_metrics"]') with timeout=10000
[00:03:40]                   │ debg TestSubjects.click(visEditorAdd_metrics_Y-axis)
[00:03:40]                   │ debg Find.clickByCssSelector('[data-test-subj="visEditorAdd_metrics_Y-axis"]') with timeout=10000
[00:03:40]                   │ debg Find.findByCssSelector('[data-test-subj="visEditorAdd_metrics_Y-axis"]') with timeout=10000
[00:03:40]                   │ debg Find.findByCssSelector('
[00:03:40]                   │                [data-test-subj="metricsAggGroup"]
[00:03:40]                   │                [data-test-subj^="visEditorAggAccordion"].euiAccordion-isOpen
[00:03:40]                   │                
[00:03:40]                   │                [data-test-subj="defaultEditorAggSelect"]
[00:03:40]                   │              ') with timeout=10000
[00:03:40]                   │ debg comboBox.setElement, value: Top Hit
[00:03:40]                   │ debg comboBox.isOptionSelected, value: Top Hit
[00:03:42]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:42]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:42]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="Top Hit"]') with timeout=2500
[00:03:42]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:42]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:45]                   │ERROR browser[SEVERE] http://localhost:61101/app/theme-github.js 0:0 Uncaught SyntaxError: Unexpected token '<'
[00:03:45]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 287:519565 Uncaught Error: couldn't load module ace/theme/github or it didn't call define
[00:03:45]                   │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:03:46]                   │ debg ... sleep(500) start
[00:03:46]                   │ debg ... sleep(500) end
[00:03:46]                   │ debg selectField bytes
[00:03:46]                   │ debg Find.findByCssSelector('
[00:03:46]                   │                [data-test-subj="metricsAggGroup"]
[00:03:46]                   │                [data-test-subj^="visEditorAggAccordion"].euiAccordion-isOpen
[00:03:46]                   │                [data-test-subj="visAggEditorParams"]
[00:03:46]                   │                
[00:03:46]                   │                [data-test-subj="visDefaultEditorField"]
[00:03:46]                   │              ') with timeout=10000
[00:03:46]                   │ debg comboBox.setElement, value: bytes
[00:03:46]                   │ debg comboBox.isOptionSelected, value: bytes
[00:03:48]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:48]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:48]                   │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="bytes"]') with timeout=2500
[00:03:49]                   │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:03:49]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:03:51]                   │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:03:52]                   │ debg Find.selectValue('[data-test-subj="visDefaultEditorAggregateWith"]', option[value="average"]')
[00:03:52]                   │ debg Find.findByCssSelector('[data-test-subj="visDefaultEditorAggregateWith"]') with timeout=10000
[00:03:52]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:52]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:52]                   │ debg Before Rendering count 3
[00:03:52]                   │ debg TestSubjects.clickWhenNotDisabled(visualizeEditorRenderButton)
[00:03:52]                   │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:52]                   │ debg Find.findByCssSelector('[data-test-subj="visualizeEditorRenderButton"]') with timeout=10000
[00:03:52]                   │ debg Waiting up to 20000ms for rendering count to be greater than or equal to [4]...
[00:03:52]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:52]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:53]                   │ debg -- currentRenderingCount=3
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <path> attribute d: Expected number, "…02.304032345679,NaNL108.15964345…".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/plugin/visTypeVislib/visTypeVislib.plugin.js 14 Error: <circle> attribute cy: Expected length, "NaN".
[00:03:53]                   │ debg TestSubjects.find(visualizationLoader)
[00:03:53]                   │ debg Find.findByCssSelector('[data-test-subj="visualizationLoader"]') with timeout=10000
[00:03:53]                   │ debg -- currentRenderingCount=4
[00:03:53]                   │ debg Inspector.open
[00:03:53]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:53]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:56]                   │ debg --- retry.tryForTime error: [data-test-subj="inspectorPanel"] is not displayed
[00:03:56]                   │ debg TestSubjects.click(openInspectorButton)
[00:03:56]                   │ debg Find.clickByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:56]                   │ debg Find.findByCssSelector('[data-test-subj="openInspectorButton"]') with timeout=10000
[00:03:56]                   │ debg TestSubjects.exists(inspectorPanel)
[00:03:56]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=2500
[00:03:56]                   │ERROR browser[SEVERE] http://localhost:61101/32781/bundles/kbn-ui-shared-deps/kbn-ui-shared-deps.js 341:2042 Uncaught TypeError: Cannot read property 'onClose' of undefined
[00:03:56]                   │ debg Inspector.expectTableData(2015-09-20 00:00,6,9.035KB,2015-09-20 01:00,9,5.854KB,2015-09-20 02:00,22,4.588KB,2015-09-20 03:00,31,8.349KB,2015-09-20 04:00,52,2.637KB,2015-09-20 05:00,119,1.712KB,2015-09-20 06:00,181,9.157KB,2015-09-20 07:00,218,8.192KB,2015-09-20 08:00,341,12.384KB,2015-09-20 09:00,440,4.482KB,2015-09-20 10:00,480,9.449KB,2015-09-20 11:00,517,213B,2015-09-20 12:00,522,638B,2015-09-20 13:00,446,7.421KB,2015-09-20 14:00,403,4.854KB,2015-09-20 15:00,321,4.132KB,2015-09-20 16:00,258,601B,2015-09-20 17:00,172,4.239KB,2015-09-20 18:00,95,6.272KB,2015-09-20 19:00,55,2.053KB)
[00:03:56]                   │ debg TestSubjects.find(inspectorPanel)
[00:03:56]                   │ debg Find.findByCssSelector('[data-test-subj="inspectorPanel"]') with timeout=10000
[00:03:56]                   │ info Taking screenshot "/dev/shm/workspace/kibana/test/functional/screenshots/failure/visualize app  area charts axis scaling does not scale top hit agg.png"
[00:03:57]                   │ info Current URL is: http://localhost:61101/app/kibana#/visualize/edit/211adf50-8fa0-11ea-8b3d-535cdcaa6ebb?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:%272015-09-19T06:31:44.000Z%27,to:%272015-09-23T18:31:44.000Z%27))&_a=(filters:!(),linked:!f,query:(language:kuery,query:%27%27),uiState:(),vis:(aggs:!((enabled:!t,id:%271%27,params:(),schema:metric,type:count),(enabled:!t,id:%272%27,params:(drop_partials:!f,extended_bounds:(),field:%27@timestamp%27,interval:s,min_doc_count:1,scaleMetricValues:!t,timeRange:(from:%272015-09-19T06:31:44.000Z%27,to:%272015-09-23T18:31:44.000Z%27),useNormalizedEsInterval:!t),schema:segment,type:date_histogram),(enabled:!t,id:%273%27,params:(aggregate:average,field:bytes,size:1,sortField:%27@timestamp%27,sortOrder:desc),schema:metric,type:top_hits)),params:(addLegend:!t,addTimeMarker:!f,addTooltip:!t,categoryAxes:!((id:CategoryAxis-1,labels:(filter:!t,show:!t,truncate:100),position:bottom,scale:(type:linear),show:!t,style:(),title:(),type:category)),grid:(categoryLines:!f),labels:(),legendPosition:right,seriesParams:!((data:(id:%271%27,label:Count),drawLinesBetweenPoints:!t,interpolate:linear,lineWidth:2,mode:stacked,show:!t,showCircles:!t,type:area,valueAxis:ValueAxis-1),(data:(id:%273%27,label:%27Last%20bytes%27),drawLinesBetweenPoints:!t,interpolate:linear,lineWidth:2,mode:stacked,show:!t,showCircles:!t,type:area,valueAxis:ValueAxis-1)),thresholdLine:(color:%23E7664C,show:!f,style:full,value:10,width:1),times:!(),type:area,valueAxes:!((id:ValueAxis-1,labels:(filter:!f,rotate:0,show:!t,truncate:100),name:LeftAxis-1,position:left,scale:(mode:normal,type:linear),show:!t,style:(),title:(text:Count),type:value))),title:%27Visualization%20AreaChart%20Name%20Test%27,type:area))
[00:03:57]                   │ info Saving page source to: /dev/shm/workspace/kibana/test/functional/failure_debug/html/visualize app  area charts axis scaling does not scale top hit agg.html
[00:03:57]                   └- ✖ fail: "visualize app  area charts axis scaling does not scale top hit agg"
[00:03:57]                   │

Stack Trace

{ Error: expected [ [ '2015-09-20 00:00', '6', '' ],
  [ '2015-09-20 01:00', '9', '' ],
  [ '2015-09-20 02:00', '22', '' ],
  [ '2015-09-20 03:00', '31', '' ],
  [ '2015-09-20 04:00', '52', '' ],
  [ '2015-09-20 05:00', '119', '' ],
  [ '2015-09-20 06:00', '181', '' ],
  [ '2015-09-20 07:00', '218', '' ],
  [ '2015-09-20 08:00', '341', '' ],
  [ '2015-09-20 09:00', '440', '' ],
  [ '2015-09-20 10:00', '480', '' ],
  [ '2015-09-20 11:00', '517', '' ],
  [ '2015-09-20 12:00', '522', '' ],
  [ '2015-09-20 13:00', '446', '' ],
  [ '2015-09-20 14:00', '403', '' ],
  [ '2015-09-20 15:00', '321', '' ],
  [ '2015-09-20 16:00', '258', '' ],
  [ '2015-09-20 17:00', '172', '' ],
  [ '2015-09-20 18:00', '95', '' ],
  [ '2015-09-20 19:00', '55', '' ] ] to sort of equal [ [ '2015-09-20 00:00', '6', '9.035KB' ],
  [ '2015-09-20 01:00', '9', '5.854KB' ],
  [ '2015-09-20 02:00', '22', '4.588KB' ],
  [ '2015-09-20 03:00', '31', '8.349KB' ],
  [ '2015-09-20 04:00', '52', '2.637KB' ],
  [ '2015-09-20 05:00', '119', '1.712KB' ],
  [ '2015-09-20 06:00', '181', '9.157KB' ],
  [ '2015-09-20 07:00', '218', '8.192KB' ],
  [ '2015-09-20 08:00', '341', '12.384KB' ],
  [ '2015-09-20 09:00', '440', '4.482KB' ],
  [ '2015-09-20 10:00', '480', '9.449KB' ],
  [ '2015-09-20 11:00', '517', '213B' ],
  [ '2015-09-20 12:00', '522', '638B' ],
  [ '2015-09-20 13:00', '446', '7.421KB' ],
  [ '2015-09-20 14:00', '403', '4.854KB' ],
  [ '2015-09-20 15:00', '321', '4.132KB' ],
  [ '2015-09-20 16:00', '258', '601B' ],
  [ '2015-09-20 17:00', '172', '4.239KB' ],
  [ '2015-09-20 18:00', '95', '6.272KB' ],
  [ '2015-09-20 19:00', '55', '2.053KB' ] ]
    at Assertion.assert (packages/kbn-expect/expect.js:100:11)
    at Assertion.eql (packages/kbn-expect/expect.js:244:8)
    at Inspector.expectTableData (test/functional/services/inspector.ts:95:23)
    at process._tickCallback (internal/process/next_tick.js:68:7)
  actual:
   '[\n  [\n    "2015-09-20 00:00"\n    "6"\n    ""\n  ]\n  [\n    "2015-09-20 01:00"\n    "9"\n    ""\n  ]\n  [\n    "2015-09-20 02:00"\n    "22"\n    ""\n  ]\n  [\n    "2015-09-20 03:00"\n    "31"\n    ""\n  ]\n  [\n    "2015-09-20 04:00"\n    "52"\n    ""\n  ]\n  [\n    "2015-09-20 05:00"\n    "119"\n    ""\n  ]\n  [\n    "2015-09-20 06:00"\n    "181"\n    ""\n  ]\n  [\n    "2015-09-20 07:00"\n    "218"\n    ""\n  ]\n  [\n    "2015-09-20 08:00"\n    "341"\n    ""\n  ]\n  [\n    "2015-09-20 09:00"\n    "440"\n    ""\n  ]\n  [\n    "2015-09-20 10:00"\n    "480"\n    ""\n  ]\n  [\n    "2015-09-20 11:00"\n    "517"\n    ""\n  ]\n  [\n    "2015-09-20 12:00"\n    "522"\n    ""\n  ]\n  [\n    "2015-09-20 13:00"\n    "446"\n    ""\n  ]\n  [\n    "2015-09-20 14:00"\n    "403"\n    ""\n  ]\n  [\n    "2015-09-20 15:00"\n    "321"\n    ""\n  ]\n  [\n    "2015-09-20 16:00"\n    "258"\n    ""\n  ]\n  [\n    "2015-09-20 17:00"\n    "172"\n    ""\n  ]\n  [\n    "2015-09-20 18:00"\n    "95"\n    ""\n  ]\n  [\n    "2015-09-20 19:00"\n    "55"\n    ""\n  ]\n]',
  expected:
   '[\n  [\n    "2015-09-20 00:00"\n    "6"\n    "9.035KB"\n  ]\n  [\n    "2015-09-20 01:00"\n    "9"\n    "5.854KB"\n  ]\n  [\n    "2015-09-20 02:00"\n    "22"\n    "4.588KB"\n  ]\n  [\n    "2015-09-20 03:00"\n    "31"\n    "8.349KB"\n  ]\n  [\n    "2015-09-20 04:00"\n    "52"\n    "2.637KB"\n  ]\n  [\n    "2015-09-20 05:00"\n    "119"\n    "1.712KB"\n  ]\n  [\n    "2015-09-20 06:00"\n    "181"\n    "9.157KB"\n  ]\n  [\n    "2015-09-20 07:00"\n    "218"\n    "8.192KB"\n  ]\n  [\n    "2015-09-20 08:00"\n    "341"\n    "12.384KB"\n  ]\n  [\n    "2015-09-20 09:00"\n    "440"\n    "4.482KB"\n  ]\n  [\n    "2015-09-20 10:00"\n    "480"\n    "9.449KB"\n  ]\n  [\n    "2015-09-20 11:00"\n    "517"\n    "213B"\n  ]\n  [\n    "2015-09-20 12:00"\n    "522"\n    "638B"\n  ]\n  [\n    "2015-09-20 13:00"\n    "446"\n    "7.421KB"\n  ]\n  [\n    "2015-09-20 14:00"\n    "403"\n    "4.854KB"\n  ]\n  [\n    "2015-09-20 15:00"\n    "321"\n    "4.132KB"\n  ]\n  [\n    "2015-09-20 16:00"\n    "258"\n    "601B"\n  ]\n  [\n    "2015-09-20 17:00"\n    "172"\n    "4.239KB"\n  ]\n  [\n    "2015-09-20 18:00"\n    "95"\n    "6.272KB"\n  ]\n  [\n    "2015-09-20 19:00"\n    "55"\n    "2.053KB"\n  ]\n]',
  showDiff: true }

Kibana Pipeline / kibana-xpack-agent / X-Pack Kerberos API with Anonymous Access Integration Tests.x-pack/test/kerberos_api_integration/apis/security/kerberos_login·ts.apis Kerberos security Kerberos authentication finishing SPNEGO should properly set cookie and authenticate user

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: apis Kerberos
[00:00:00]           └-> "before all" hook
[00:00:00]           └-: security
[00:00:00]             └-> "before all" hook
[00:00:00]             └-: Kerberos authentication
[00:00:00]               └-> "before all" hook
[00:00:00]               └-> "before all" hook
[00:00:00]               └-> should reject API requests if client is not authenticated
[00:00:00]                 └-> "before each" hook: global before each
[00:00:00]                 │ info [o.e.x.s.a.AuthenticationService] [kibana-ci-immutable-ubuntu-18-tests-xl-1588771428785137907] Authentication of [<Kerberos Token>] was terminated by realm [kerb1] - failed to authenticate user, gss context negotiation failure
[00:00:00]                 │      org.elasticsearch.ElasticsearchSecurityException: Defective token detected (Mechanism level: GSSHeader did not find the right tag)
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.kerberos.KerberosAuthenticationToken.unauthorized(KerberosAuthenticationToken.java:123) ~[x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.kerberos.KerberosRealm.handleException(KerberosRealm.java:190) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.kerberos.KerberosRealm.lambda$authenticate$1(KerberosRealm.java:175) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.action.ActionListener$1.onFailure(ActionListener.java:71) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.kerberos.KerberosTicketValidator.validateTicket(KerberosTicketValidator.java:101) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.kerberos.KerberosRealm.authenticate(KerberosRealm.java:156) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$consumeToken$15(AuthenticationService.java:452) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.core.common.IteratingActionListener.onResponse(IteratingActionListener.java:117) [x-pack-core-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$consumeToken$15(AuthenticationService.java:491) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.core.common.IteratingActionListener.run(IteratingActionListener.java:102) [x-pack-core-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.consumeToken(AuthenticationService.java:507) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$extractToken$11(AuthenticationService.java:419) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.extractToken(AuthenticationService.java:429) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$checkForApiKey$3(AuthenticationService.java:370) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:63) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.ApiKeyService.authenticateWithApiKeyIfPresent(ApiKeyService.java:314) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.checkForApiKey(AuthenticationService.java:350) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$authenticateAsync$0(AuthenticationService.java:332) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.action.ActionListener$1.onResponse(ActionListener.java:63) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.TokenService.getAndValidateToken(TokenService.java:387) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$authenticateAsync$2(AuthenticationService.java:328) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lambda$lookForExistingAuthentication$6(AuthenticationService.java:388) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.lookForExistingAuthentication(AuthenticationService.java:399) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService$Authenticator.authenticateAsync(AuthenticationService.java:323) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService.authenticate(AuthenticationService.java:144) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.AuthenticationService.authenticate(AuthenticationService.java:129) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.rest.SecurityRestFilter.handleRequest(SecurityRestFilter.java:61) [x-pack-security-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.rest.RestController.dispatchRequest(RestController.java:234) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.rest.RestController.tryAllHandlers(RestController.java:316) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.rest.RestController.dispatchRequest(RestController.java:174) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.http.AbstractHttpServerTransport.dispatchRequest(AbstractHttpServerTransport.java:329) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.http.AbstractHttpServerTransport.handleIncomingRequest(AbstractHttpServerTransport.java:383) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.http.AbstractHttpServerTransport.incomingRequest(AbstractHttpServerTransport.java:308) [elasticsearch-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.http.netty4.Netty4HttpRequestHandler.channelRead0(Netty4HttpRequestHandler.java:54) [transport-netty4-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at org.elasticsearch.http.netty4.Netty4HttpRequestHandler.channelRead0(Netty4HttpRequestHandler.java:29) [transport-netty4-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:99) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:357) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at org.elasticsearch.http.netty4.Netty4HttpPipeliningHandler.channelRead(Netty4HttpPipeliningHandler.java:58) [transport-netty4-8.0.0-SNAPSHOT.jar:8.0.0-SNAPSHOT]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:357) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) [netty-codec-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.handler.codec.MessageToMessageCodec.channelRead(MessageToMessageCodec.java:111) [netty-codec-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:357) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.handler.codec.MessageToMessageDe
[00:00:00]                 │ info coder.channelRead(MessageToMessageDecoder.java:103) [netty-codec-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:357) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) [netty-codec-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:357) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:324) [netty-codec-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:296) [netty-codec-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:357) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:286) [netty-handler-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:357) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:379) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:365) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:714) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.nio.NioEventLoop.processSelectedKeysPlain(NioEventLoop.java:615) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:578) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:493) [netty-transport-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:989) [netty-common-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) [netty-common-4.1.49.Final.jar:4.1.49.Final]
[00:00:00]                 │      	at java.lang.Thread.run(Thread.java:832) [?:?]
[00:00:00]                 │      Caused by: org.ietf.jgss.GSSException: Defective token detected (Mechanism level: GSSHeader did not find the right tag)
[00:00:00]                 │      	at sun.security.jgss.GSSHeader.<init>(GSSHeader.java:97) ~[?:?]
[00:00:00]                 │      	at sun.security.jgss.GSSContextImpl.acceptSecContext(GSSContextImpl.java:325) ~[?:?]
[00:00:00]                 │      	at sun.security.jgss.GSSContextImpl.acceptSecContext(GSSContextImpl.java:303) ~[?:?]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.kerberos.KerberosTicketValidator.lambda$acceptSecContext$0(KerberosTicketValidator.java:143) ~[?:?]
[00:00:00]                 │      	at java.security.AccessController.doPrivileged(AccessController.java:691) ~[?:?]
[00:00:00]                 │      	at javax.security.auth.Subject.doAs(Subject.java:425) ~[?:?]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.kerberos.KerberosTicketValidator.lambda$doAsWrapper$2(KerberosTicketValidator.java:171) ~[?:?]
[00:00:00]                 │      	at java.security.AccessController.doPrivileged(AccessController.java:554) ~[?:?]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.kerberos.KerberosTicketValidator.doAsWrapper(KerberosTicketValidator.java:171) ~[?:?]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.kerberos.KerberosTicketValidator.acceptSecContext(KerberosTicketValidator.java:142) ~[?:?]
[00:00:00]                 │      	at org.elasticsearch.xpack.security.authc.kerberos.KerberosTicketValidator.validateTicket(KerberosTicketValidator.java:91) ~[?:?]
[00:00:00]                 │      	... 72 more
[00:00:00]                 │ proc [kibana]   log   [14:31:22.001] [info][authentication][plugins][security] Authentication attempt failed: Unauthorized
[00:00:00]                 └- ✓ pass  (114ms) "apis Kerberos security Kerberos authentication should reject API requests if client is not authenticated"
[00:00:00]               └-> does not prevent basic login
[00:00:00]                 └-> "before each" hook: global before each
[00:00:00]                 └- ✓ pass  (115ms) "apis Kerberos security Kerberos authentication does not prevent basic login"
[00:00:00]               └-: initiating SPNEGO
[00:00:00]                 └-> "before all" hook
[00:00:00]               └-: finishing SPNEGO
[00:00:00]                 └-> "before all" hook
[00:00:00]                 └-> should properly set cookie and authenticate user
[00:00:00]                   └-> "before each" hook: global before each
[00:00:00]                   │ info [o.e.x.s.s.SecurityIndexManager] [kibana-ci-immutable-ubuntu-18-tests-xl-1588771428785137907] security index does not exist. Creating [.security-tokens-7] with alias [.security-tokens]
[00:00:00]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xl-1588771428785137907] applying create index request using v1 templates []
[00:00:00]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-18-tests-xl-1588771428785137907] [.security-tokens-7] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:01]                   └- ✖ fail: "apis Kerberos security Kerberos authentication finishing SPNEGO should properly set cookie and authenticate user"
[00:00:01]                   │

Stack Trace

{ Error: expected { username: 'tester@TEST.ELASTIC.CO',
  roles: [ 'kibana_admin' ],
  full_name: null,
  email: null,
  metadata:
   { kerberos_user_principal_name: 'tester@TEST.ELASTIC.CO',
     kerberos_realm: 'TEST.ELASTIC.CO' },
  enabled: true,
  authentication_realm: { name: 'kerb1', type: 'kerberos' },
  lookup_realm: { name: 'kerb1', type: 'kerberos' },
  authentication_provider: 'kerberos' } response body, got { username: 'tester@TEST.ELASTIC.CO',
  roles: [ 'kibana_admin', 'superuser' ],
  full_name: null,
  email: null,
  metadata:
   { kerberos_user_principal_name: 'tester@TEST.ELASTIC.CO',
     kerberos_realm: 'TEST.ELASTIC.CO' },
  enabled: true,
  authentication_realm: { name: 'kerb1', type: 'kerberos' },
  lookup_realm: { name: 'kerb1', type: 'kerberos' },
  authentication_provider: 'kerberos' }
    at error (/dev/shm/workspace/kibana/node_modules/supertest/lib/test.js:301:13)
    at Test._assertBody (/dev/shm/workspace/kibana/node_modules/supertest/lib/test.js:205:14)
    at Test._assertFunction (/dev/shm/workspace/kibana/node_modules/supertest/lib/test.js:283:11)
    at Test.assert (/dev/shm/workspace/kibana/node_modules/supertest/lib/test.js:173:18)
    at assert (/dev/shm/workspace/kibana/node_modules/supertest/lib/test.js:131:12)
    at /dev/shm/workspace/kibana/node_modules/supertest/lib/test.js:128:5
    at Test.Request.callback (/dev/shm/workspace/kibana/node_modules/superagent/lib/node/index.js:706:12)
    at parser (/dev/shm/workspace/kibana/node_modules/superagent/lib/node/index.js:906:18)
    at IncomingMessage.res.on (/dev/shm/workspace/kibana/node_modules/superagent/lib/node/parsers/json.js:19:7)
    at endReadableNT (_stream_readable.js:1145:12)
    at process._tickCallback (internal/process/next_tick.js:63:19)
  expected:
   '{\n  "authentication_provider": "kerberos"\n  "authentication_realm": {\n    "name": "kerb1"\n    "type": "kerberos"\n  }\n  "email": [null]\n  "enabled": true\n  "full_name": [null]\n  "lookup_realm": {\n    "name": "kerb1"\n    "type": "kerberos"\n  }\n  "metadata": {\n    "kerberos_realm": "TEST.ELASTIC.CO"\n    "kerberos_user_principal_name": "tester@TEST.ELASTIC.CO"\n  }\n  "roles": [\n    "kibana_admin"\n  ]\n  "username": "tester@TEST.ELASTIC.CO"\n}',
  actual:
   '{\n  "authentication_provider": "kerberos"\n  "authentication_realm": {\n    "name": "kerb1"\n    "type": "kerberos"\n  }\n  "email": [null]\n  "enabled": true\n  "full_name": [null]\n  "lookup_realm": {\n    "name": "kerb1"\n    "type": "kerberos"\n  }\n  "metadata": {\n    "kerberos_realm": "TEST.ELASTIC.CO"\n    "kerberos_user_principal_name": "tester@TEST.ELASTIC.CO"\n  }\n  "roles": [\n    "kibana_admin"\n    "superuser"\n  ]\n  "username": "tester@TEST.ELASTIC.CO"\n}',
  showDiff: true }

History

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

@azasypkin
Copy link
Copy Markdown
Contributor Author

azasypkin commented May 6, 2020

7.7/7.7.1: 22dd476
7.8/7.8.0: 582c986
7.x/7.9.0: 547393c

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment

Labels

backported Feature:Security/Authentication Platform Security - Authentication release_note:skip Skip the PR/issue when compiling release notes Team:Security Platform Security: Auth, Users, Roles, Spaces, Audit Logging, etc t// test v7.7.1 v7.8.0 v7.9.0

Projects

None yet

Development

Successfully merging this pull request may close these issues.

4 participants