Skip to content
New issue

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

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

Already on GitHub? Sign in to your account

Revert "Revert "[Reporting/New Platform Migration] Use a new config s… #61873

Closed

Conversation

tsullivan
Copy link
Member

…ervice on server-side (#55882)" (#61075)"

This reverts commit 427848c.

Summary

Summarize your PR. If it involves visual changes include a screenshot or gif.

Checklist

Delete any items that are not applicable to this PR.

For maintainers

@tsullivan tsullivan closed this Mar 30, 2020
@tsullivan tsullivan deleted the reporting/np-server-config-2 branch March 30, 2020 18:07
@kibanamachine
Copy link
Contributor

💔 Build Failed

Failed CI Steps


Test Failures

Kibana Pipeline / kibana-xpack-agent / Chrome X-Pack UI Functional Tests.x-pack/test/functional/apps/lens/lens_reporting·ts.lens app lens reporting should not cause PDF reports to fail

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:26:16]         └-: lens app
[00:26:16]           └-> "before all" hook
[00:26:16]           └-> "before all" hook
[00:26:16]             │ debg Starting lens before method
[00:26:16]             │ info [logstash_functional] Loading "mappings.json"
[00:26:16]             │ info [logstash_functional] Loading "data.json.gz"
[00:26:16]             │ info [logstash_functional] Skipped restore for existing index "logstash-2015.09.22"
[00:26:16]             │ info [logstash_functional] Skipped restore for existing index "logstash-2015.09.20"
[00:26:16]             │ info [logstash_functional] Skipped restore for existing index "logstash-2015.09.21"
[00:26:18]             │ info [lens/basic] Loading "mappings.json"
[00:26:18]             │ info [lens/basic] Loading "data.json.gz"
[00:26:18]             │ info [o.e.c.m.MetaDataDeleteIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/VCWErOZwTCGm8Uz2mnKLBA] deleting index
[00:26:18]             │ info [o.e.c.m.MetaDataDeleteIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_1/EDuN2oBCQci1DFsk7Z0Zmw] deleting index
[00:26:18]             │ info [lens/basic] Deleted existing index [".kibana_2",".kibana_1"]
[00:26:18]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_1] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:26:18]             │ info [lens/basic] Created index ".kibana_1"
[00:26:18]             │ debg [lens/basic] ".kibana_1" settings {"index":{"auto_expand_replicas":"0-1","number_of_replicas":"0","number_of_shards":"1"}}
[00:26:18]             │ info [lens/basic] Indexed 10 docs into ".kibana_1"
[00:26:18]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_1/sK5h8OrbRhWTV0pFA8YYOg] update_mapping [_doc]
[00:26:18]             │ debg Migrating saved objects
[00:26:19]             │ proc [kibana]   log   [19:07:04.998] [info][savedobjects-service] Creating index .kibana_2.
[00:26:19]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:26:19]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.kibana_2]
[00:26:19]             │ proc [kibana]   log   [19:07:05.080] [info][savedobjects-service] Migrating .kibana_1 saved objects to .kibana_2
[00:26:19]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/PRqzf9suToG373dPneWy9w] update_mapping [_doc]
[00:26:19]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/PRqzf9suToG373dPneWy9w] update_mapping [_doc]
[00:26:19]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/PRqzf9suToG373dPneWy9w] update_mapping [_doc]
[00:26:19]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/PRqzf9suToG373dPneWy9w] update_mapping [_doc]
[00:26:19]             │ proc [kibana]   log   [19:07:05.292] [info][savedobjects-service] Pointing alias .kibana to .kibana_2.
[00:26:19]             │ proc [kibana]   log   [19:07:05.350] [info][savedobjects-service] Finished in 353ms.
[00:26:19]           └-: 
[00:26:19]             └-> "before all" hook
[00:29:05]             └-: lens reporting
[00:29:05]               └-> "before all" hook
[00:29:05]               └-> "before all" hook
[00:29:05]                 │ info [lens/reporting] Loading "mappings.json"
[00:29:05]                 │ info [lens/reporting] Loading "data.json.gz"
[00:29:05]                 │ info [o.e.c.m.MetaDataDeleteIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_1/sK5h8OrbRhWTV0pFA8YYOg] deleting index
[00:29:05]                 │ info [o.e.c.m.MetaDataDeleteIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/PRqzf9suToG373dPneWy9w] deleting index
[00:29:05]                 │ info [lens/reporting] Deleted existing index [".kibana_2",".kibana_1"]
[00:29:05]                 │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_1] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:29:06]                 │ info [lens/reporting] Created index ".kibana_1"
[00:29:06]                 │ debg [lens/reporting] ".kibana_1" settings {"index":{"auto_expand_replicas":"0-1","number_of_replicas":"0","number_of_shards":"1"}}
[00:29:06]                 │ info [lens/reporting] Indexed 13 docs into ".kibana_1"
[00:29:06]                 │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_1/HBQUEgNATPqvDSLXE9EkwQ] update_mapping [_doc]
[00:29:06]                 │ debg Migrating saved objects
[00:29:07]                 │ proc [kibana]   log   [19:09:52.557] [info][savedobjects-service] Creating index .kibana_2.
[00:29:07]                 │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:29:07]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.kibana_2]
[00:29:07]                 │ proc [kibana]   log   [19:09:52.649] [info][savedobjects-service] Migrating .kibana_1 saved objects to .kibana_2
[00:29:07]                 │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/rE0_L6RFQKOMW1K5RmSh2g] update_mapping [_doc]
[00:29:07]                 │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/rE0_L6RFQKOMW1K5RmSh2g] update_mapping [_doc]
[00:29:07]                 │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/rE0_L6RFQKOMW1K5RmSh2g] update_mapping [_doc]
[00:29:07]                 │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/rE0_L6RFQKOMW1K5RmSh2g] update_mapping [_doc]
[00:29:07]                 │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/rE0_L6RFQKOMW1K5RmSh2g] update_mapping [_doc]
[00:29:07]                 │ proc [kibana]   log   [19:09:52.931] [info][savedobjects-service] Pointing alias .kibana to .kibana_2.
[00:29:07]                 │ proc [kibana]   log   [19:09:52.989] [info][savedobjects-service] Finished in 435ms.
[00:29:07]               └-> should not cause PDF reports to fail
[00:29:07]                 └-> "before each" hook: global before each
[00:29:07]                 │ debg navigating to dashboard url: http://localhost:6141/app/kibana#/dashboards
[00:29:07]                 │ debg Navigate to: http://localhost:6141/app/kibana#/dashboards
[00:29:07]                 │ debg ... sleep(700) start
[00:29:07]                 │ debg browser[INFO] http://localhost:6141/app/kibana?_t=1585595392996#/dashboards 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:29:07]                 │
[00:29:07]                 │ debg browser[INFO] http://localhost:6141/bundles/app/kibana/bootstrap.js 9:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:29:08]                 │ debg ... sleep(700) end
[00:29:08]                 │ debg returned from get, calling refresh
[00:29:08]                 │ debg browser[INFO] http://localhost:6141/app/kibana?_t=1585595392996#/dashboards 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:29:08]                 │
[00:29:08]                 │ debg browser[INFO] http://localhost:6141/bundles/app/kibana/bootstrap.js 9:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:29:08]                 │ debg currentUrl = http://localhost:6141/app/kibana#/dashboards
[00:29:08]                 │          appUrl = http://localhost:6141/app/kibana#/dashboards
[00:29:08]                 │ debg TestSubjects.find(kibanaChrome)
[00:29:08]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:29:13]                 │ debg TestSubjects.find(kibanaChrome)
[00:29:13]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=10000
[00:29:13]                 │ debg browser[INFO] http://localhost:6141/bundles/plugin/data/data.plugin.js 96:139630 "INFO: 2020-03-30T19:09:58Z
[00:29:13]                 │        Adding connection to http://localhost:6141/elasticsearch
[00:29:13]                 │
[00:29:13]                 │      "
[00:29:14]                 │ debg ... sleep(501) start
[00:29:15]                 │ debg ... sleep(501) end
[00:29:15]                 │ debg in navigateTo url = http://localhost:6141/app/kibana#/dashboards?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:29:15]                 │ debg TestSubjects.exists(statusPageContainer)
[00:29:15]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:29:17]                 │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:29:18]                 │ debg TestSubjects.click(dashboardListingTitleLink-Lens-reportz)
[00:29:18]                 │ debg Find.clickByCssSelector('[data-test-subj="dashboardListingTitleLink-Lens-reportz"]') with timeout=10000
[00:29:18]                 │ debg Find.findByCssSelector('[data-test-subj="dashboardListingTitleLink-Lens-reportz"]') with timeout=10000
[00:29:18]                 │ debg openPdfReportingPanel
[00:29:18]                 │ debg openShareMenuItem title:PDF Reports
[00:29:18]                 │ debg TestSubjects.exists(shareContextMenu)
[00:29:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="shareContextMenu"]') with timeout=2500
[00:29:21]                 │ debg --- retry.tryForTime error: [data-test-subj="shareContextMenu"] is not displayed
[00:29:21]                 │ debg TestSubjects.click(shareTopNavButton)
[00:29:21]                 │ debg Find.clickByCssSelector('[data-test-subj="shareTopNavButton"]') with timeout=10000
[00:29:21]                 │ debg Find.findByCssSelector('[data-test-subj="shareTopNavButton"]') with timeout=10000
[00:29:21]                 │ debg Find.findByCssSelector('div.euiContextMenuPanel') with timeout=10000
[00:29:21]                 │ debg TestSubjects.click(sharePanel-PDFReports)
[00:29:21]                 │ debg Find.clickByCssSelector('[data-test-subj="sharePanel-PDFReports"]') with timeout=10000
[00:29:21]                 │ debg Find.findByCssSelector('[data-test-subj="sharePanel-PDFReports"]') with timeout=10000
[00:29:21]                 │ debg Find.waitForElementStale with timeout=10000
[00:29:22]                 │ debg TestSubjects.click(generateReportButton)
[00:29:22]                 │ debg Find.clickByCssSelector('[data-test-subj="generateReportButton"]') with timeout=10000
[00:29:22]                 │ debg Find.findByCssSelector('[data-test-subj="generateReportButton"]') with timeout=10000
[00:29:22]                 │ debg getReportURL
[00:29:22]                 │ debg TestSubjects.getAttribute(downloadCompletedReportButton, href)
[00:29:22]                 │ debg TestSubjects.find(downloadCompletedReportButton)
[00:29:22]                 │ debg Find.findByCssSelector('[data-test-subj="downloadCompletedReportButton"]') with timeout=60000
[00:29:22]                 │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.reporting-2020.03.29] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:29:22]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.reporting-2020.03.29]
[00:29:22]                 │ proc [kibana]   log   [19:10:07.800] [info][queue-job][reporting] Successfully queued job: k8eukeom0b02cf8f9172uccl
[00:29:24]                 │ proc [kibana]   log   [19:10:09.936] [info][esqueue][queue-worker][reporting] k8etiiuq0b02cf8f91ey59op - Job marked as claimed: /.reporting-2020.03.29/k8eukeom0b02cf8f9172uccl
[00:29:24]                 │ proc [kibana]   log   [19:10:09.969] [info][esqueue][queue-worker][reporting] k8etiiuq0b02cf8f91ey59op - Starting job
[00:29:24]                 │ proc [kibana]   log   [19:10:10.003] [info][browser-driver][execute][k8eukeom0b02cf8f9172uccl][printable_pdf][reporting] Creating browser page driver
[00:29:24]                 │ proc [kibana]   log   [19:10:10.053] [error][execute][k8eukeom0b02cf8f9172uccl][printable_pdf][reporting] Error: Error spawning Chromium browser: [Error: Protocol error (Target.setDiscoverTargets): Target closed.]
[00:29:24]                 │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-4/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:124:24)
[00:29:24]                 │ proc [kibana]   log   [19:10:10.071] [error][error][esqueue][queue-worker][reporting] k8etiiuq0b02cf8f91ey59op - Failure occurred on job k8eukeom0b02cf8f9172uccl: Error: Error spawning Chromium browser: [Error: Protocol error (Target.setDiscoverTargets): Target closed.]
[00:29:24]                 │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-4/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:124:24)
[00:29:24]                 │ proc [kibana]   log   [19:10:10.071] [warning][esqueue][queue-worker][reporting] k8etiiuq0b02cf8f91ey59op - Failing job k8eukeom0b02cf8f9172uccl
[00:29:24]                 │ proc [kibana]  error  [19:10:10.076] [warning][process] UnhandledPromiseRejectionWarning: Error: Protocol error (Target.setDiscoverTargets): Target closed.
[00:29:24]                 │ proc [kibana]     at Promise (/dev/shm/workspace/install/kibana-4/node_modules/puppeteer-core/lib/Connection.js:74:56)
[00:29:24]                 │ proc [kibana]     at new Promise (<anonymous>)
[00:29:24]                 │ proc [kibana]     at Connection.send (/dev/shm/workspace/install/kibana-4/node_modules/puppeteer-core/lib/Connection.js:73:12)
[00:29:24]                 │ proc [kibana]     at Function.create (/dev/shm/workspace/install/kibana-4/node_modules/puppeteer-core/lib/Browser.js:34:22)
[00:29:24]                 │ proc [kibana]     at Launcher.launch (/dev/shm/workspace/install/kibana-4/node_modules/puppeteer-core/lib/Launcher.js:184:37)
[00:29:24]                 │ proc [kibana]     at module.exports.launch (/dev/shm/workspace/install/kibana-4/node_modules/puppeteer-core/lib/Puppeteer.js:37:27)
[00:29:24]                 │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-4/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:108:25)
[00:29:24]                 │ proc [kibana]     at Observable._trySubscribe (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/Observable.js:44:25)
[00:29:24]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/Observable.js:30:22)
[00:29:24]                 │ proc [kibana]     at MergeMapOperator.call (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/operators/mergeMap.js:39:23)
[00:29:24]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/Observable.js:25:31)
[00:29:24]                 │ proc [kibana]     at TakeOperator.call (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/operators/take.js:38:23)
[00:29:24]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/Observable.js:25:31)
[00:29:24]                 │ proc [kibana]     at ThrowIfEmptyOperator.call (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/operators/throwIfEmpty.js:30:23)
[00:29:24]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/Observable.js:25:31)
[00:29:24]                 │ proc [kibana]     at Object.subscribeToResult (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/util/subscribeToResult.js:12:23)
[00:29:24]                 │ proc [kibana]     at emitWarning (internal/process/promises.js:97:15)
[00:29:24]                 │ proc [kibana]     at emitPromiseRejectionWarnings (internal/process/promises.js:143:7)
[00:29:24]                 │ proc [kibana]     at process._tickCallback (internal/process/next_tick.js:69:34)
[00:29:24]                 │ proc [kibana]  error  [19:10:10.077] [warning][process] Error: Protocol error (Target.setDiscoverTargets): Target closed.
[00:29:24]                 │ proc [kibana]     at Promise (/dev/shm/workspace/install/kibana-4/node_modules/puppeteer-core/lib/Connection.js:74:56)
[00:29:24]                 │ proc [kibana]     at new Promise (<anonymous>)
[00:29:24]                 │ proc [kibana]     at Connection.send (/dev/shm/workspace/install/kibana-4/node_modules/puppeteer-core/lib/Connection.js:73:12)
[00:29:24]                 │ proc [kibana]     at Function.create (/dev/shm/workspace/install/kibana-4/node_modules/puppeteer-core/lib/Browser.js:34:22)
[00:29:24]                 │ proc [kibana]     at Launcher.launch (/dev/shm/workspace/install/kibana-4/node_modules/puppeteer-core/lib/Launcher.js:184:37)
[00:29:24]                 │ proc [kibana]     at module.exports.launch (/dev/shm/workspace/install/kibana-4/node_modules/puppeteer-core/lib/Puppeteer.js:37:27)
[00:29:24]                 │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-4/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:108:25)
[00:29:24]                 │ proc [kibana]     at Observable._trySubscribe (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/Observable.js:44:25)
[00:29:24]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/Observable.js:30:22)
[00:29:24]                 │ proc [kibana]     at MergeMapOperator.call (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/operators/mergeMap.js:39:23)
[00:29:24]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/Observable.js:25:31)
[00:29:24]                 │ proc [kibana]     at TakeOperator.call (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/operators/take.js:38:23)
[00:29:24]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/Observable.js:25:31)
[00:29:24]                 │ proc [kibana]     at ThrowIfEmptyOperator.call (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/operators/throwIfEmpty.js:30:23)
[00:29:24]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/Observable.js:25:31)
[00:29:24]                 │ proc [kibana]     at Object.subscribeToResult (/dev/shm/workspace/install/kibana-4/node_modules/rxjs/internal/util/subscribeToResult.js:12:23)
[00:29:24]                 │ proc [kibana]   log   [19:10:10.100] [info][esqueue][queue-worker][reporting] k8etiiuq0b02cf8f91ey59op - Job marked as failed: /.reporting-2020.03.29/k8eukeom0b02cf8f9172uccl
[00:30:23]                 │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="downloadCompletedReportButton"])
[00:30:23]                 │      Wait timed out after 61190ms
[00:30:23]                 │ debg TestSubjects.getAttribute(downloadCompletedReportButton, href)
[00:30:23]                 │ debg TestSubjects.find(downloadCompletedReportButton)
[00:30:23]                 │ debg Find.findByCssSelector('[data-test-subj="downloadCompletedReportButton"]') with timeout=60000
[00:31:25]                 │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="downloadCompletedReportButton"])
[00:31:25]                 │      Wait timed out after 61198ms
[00:31:25]                 │ info Taking screenshot "/dev/shm/workspace/kibana/x-pack/test/functional/screenshots/failure/lens app  lens reporting should not cause PDF reports to fail.png"
[00:31:25]                 │ info Current URL is: http://localhost:6141/app/kibana#/dashboard/c27849f0-e523-11e9-9af5-2b261e1eb063?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))&_a=(description:%27%27,filters:!(),fullScreenMode:!f,options:(hidePanelTitles:!f,useMargins:!t),panels:!((embeddableConfig:(),gridData:(h:15,i:e5966143-f050-40eb-b4e7-f94469c1426c,w:24,x:0,y:0),id:%2774b0f140-e523-11e9-9af5-2b261e1eb063%27,panelIndex:e5966143-f050-40eb-b4e7-f94469c1426c,type:lens,version:%278.0.0-SNAPSHOT%27),(embeddableConfig:(),gridData:(h:15,i:f9ff2513-2de9-4944-a8d1-a9fead4b020d,w:24,x:24,y:0),id:%2784320f00-e523-11e9-9af5-2b261e1eb063%27,panelIndex:f9ff2513-2de9-4944-a8d1-a9fead4b020d,type:lens,version:%278.0.0-SNAPSHOT%27),(embeddableConfig:(),gridData:(h:15,i:b62d7d36-83ca-415b-8971-34d891b09a1a,w:24,x:0,y:15),id:%279325b9d0-e523-11e9-9af5-2b261e1eb063%27,panelIndex:b62d7d36-83ca-415b-8971-34d891b09a1a,type:lens,version:%278.0.0-SNAPSHOT%27)),query:(language:kuery,query:%27%27),timeRestore:!f,title:%27Lens%20reportz%27,viewMode:view)
[00:31:25]                 │ info Saving page source to: /dev/shm/workspace/kibana/x-pack/test/functional/failure_debug/html/lens app  lens reporting should not cause PDF reports to fail.html
[00:31:25]                 └- ✖ fail: "lens app  lens reporting should not cause PDF reports to fail"
[00:31:25]                 │

Stack Trace

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

Kibana Pipeline / kibana-xpack-agent / X-Pack Chromium API Reporting Tests.x-pack/test/reporting/api/usage·js.chromium reporting usage from new jobs posted preserve_layout pdf

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: chromium
[00:00:00]           └-> "before all" hook
[00:00:00]           └-> "before all" hook
[00:00:00]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/kibana] Loading "mappings.json"
[00:00:00]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/kibana] Loading "data.json.gz"
[00:00:00]             │ info [o.e.c.m.MetaDataDeleteIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_1/pnKw6ORQSX-fc8anRJkb1Q] deleting index
[00:00:00]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/kibana] Deleted existing index [".kibana_1"]
[00:00:00]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:00]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana][0]]]).
[00:00:00]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/kibana] Created index ".kibana"
[00:00:00]             │ debg [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/kibana] ".kibana" settings {"index":{"auto_expand_replicas":"0-1","number_of_replicas":"0","number_of_shards":"1"}}
[00:00:00]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana/pLcsKBvBQBeWF9IjAlrIqw] update_mapping [_doc]
[00:00:00]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/kibana] Indexed 84 docs into ".kibana"
[00:00:00]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana/pLcsKBvBQBeWF9IjAlrIqw] update_mapping [_doc]
[00:00:00]             │ debg Migrating saved objects
[00:00:01]             │ proc [kibana]   log   [19:05:21.880] [warning][actions][plugins][telemetry] Error executing actions telemetry task: [query_shard_exception] failed to create query: [nested] failed to find nested object under path [references], with { index_uuid="pLcsKBvBQBeWF9IjAlrIqw" & index=".kibana" } :: {"path":"/.kibana/_search","query":{"rest_total_hits_as_int":true},"body":"{\"query\":{\"bool\":{\"filter\":{\"bool\":{\"must\":{\"nested\":{\"path\":\"references\",\"query\":{\"bool\":{\"filter\":{\"bool\":{\"must\":[{\"term\":{\"references.type\":\"action\"}}]}}}}}}}}}},\"aggs\":{\"refs\":{\"nested\":{\"path\":\"references\"},\"aggs\":{\"actionRefIds\":{\"scripted_metric\":{\"init_script\":\"state.connectorIds = new HashMap(); state.total = 0;\",\"map_script\":\"\\n        String connectorId = doc['references.id'].value;\\n        String actionRef = doc['references.name'].value;\\n        if (state.connectorIds[connectorId] === null) {\\n          state.connectorIds[connectorId] = actionRef;\\n          state.total++;\\n        }\\n      \",\"combine_script\":\"return state\",\"reduce_script\":\"\\n          Map connectorIds = [:];\\n          long total = 0;\\n          for (state in states) {\\n            if (state !== null) {\\n              total += state.total;\\n              for (String k : state.connectorIds.keySet()) {\\n                connectorIds.put(k, connectorIds.containsKey(k) ? connectorIds.get(k) + state.connectorIds.get(k) : state.connectorIds.get(k));\\n              }\\n            }\\n          }\\n          Map result = new HashMap();\\n          result.total = total;\\n          result.connectorIds = connectorIds;\\n          return result;\\n      \"}}}}}}","statusCode":400,"response":"{\"error\":{\"root_cause\":[{\"type\":\"query_shard_exception\",\"reason\":\"failed to create query: [nested] failed to find nested object under path [references]\",\"index_uuid\":\"pLcsKBvBQBeWF9IjAlrIqw\",\"index\":\".kibana\"}],\"type\":\"search_phase_execution_exception\",\"reason\":\"all shards failed\",\"phase\":\"query\",\"grouped\":true,\"failed_shards\":[{\"shard\":0,\"index\":\".kibana\",\"node\":\"3LeKSQ6UQECgBdjm5_jO1g\",\"reason\":{\"type\":\"query_shard_exception\",\"reason\":\"failed to create query: [nested] failed to find nested object under path [references]\",\"index_uuid\":\"pLcsKBvBQBeWF9IjAlrIqw\",\"index\":\".kibana\",\"caused_by\":{\"type\":\"illegal_state_exception\",\"reason\":\"[nested] failed to find nested object under path [references]\"}}}]},\"status\":400}"}
[00:00:01]             │ proc [kibana]   log   [19:05:22.162] [info][savedobjects-service] Creating index .kibana_2.
[00:00:01]             │ proc [kibana]   log   [19:05:22.188] [warning][alerting][plugins][telemetry] Error executing alerting telemetry task: TypeError: Cannot read property 'value' of undefined
[00:00:01]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:01]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.kibana_2]
[00:00:01]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_2][0]]]).
[00:00:01]             │ proc [kibana]   log   [19:05:22.326] [info][savedobjects-service] Reindexing .kibana to .kibana_1
[00:00:01]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:01]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.kibana_1]
[00:00:01]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.kibana_1][0]]]).
[00:00:01]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.tasks] creating index, cause [auto(task api)], templates [], shards [1]/[1], mappings [_doc]
[00:00:01]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.tasks]
[00:00:01]             │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.tasks][0]]]).
[00:00:01]             │ info [o.e.t.LoggingTaskListener] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] 1048 finished with response BulkByScrollResponse[took=146.6ms,timed_out=false,sliceId=null,updated=0,created=85,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:00:02]             │ info [o.e.c.m.MetaDataDeleteIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana/pLcsKBvBQBeWF9IjAlrIqw] deleting index
[00:00:02]             │ proc [kibana]   log   [19:05:23.043] [info][savedobjects-service] Migrating .kibana_1 saved objects to .kibana_2
[00:00:02]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/VgcOe6-HTQibQI-sKKdhDg] update_mapping [_doc]
[00:00:02]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/VgcOe6-HTQibQI-sKKdhDg] update_mapping [_doc]
[00:00:02]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/VgcOe6-HTQibQI-sKKdhDg] update_mapping [_doc]
[00:00:02]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/VgcOe6-HTQibQI-sKKdhDg] update_mapping [_doc]
[00:00:02]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/VgcOe6-HTQibQI-sKKdhDg] update_mapping [_doc]
[00:00:02]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/VgcOe6-HTQibQI-sKKdhDg] update_mapping [_doc]
[00:00:02]             │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/VgcOe6-HTQibQI-sKKdhDg] update_mapping [_doc]
[00:00:03]             │ proc [kibana]   log   [19:05:23.827] [info][savedobjects-service] Pointing alias .kibana to .kibana_2.
[00:00:03]             │ proc [kibana]   log   [19:05:23.919] [info][savedobjects-service] Finished in 1760ms.
[00:00:03]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Loading "mappings.json"
[00:00:03]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Loading "data.json.gz"
[00:00:03]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [animals-dogs-2018-01-01] creating index, cause [api], templates [], shards [5]/[1], mappings [_doc]
[00:00:03]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Created index "animals-dogs-2018-01-01"
[00:00:03]             │ debg [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] "animals-dogs-2018-01-01" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:00:03]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [animals-dogs-2018-04-10] creating index, cause [api], templates [], shards [5]/[1], mappings [_doc]
[00:00:03]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Created index "animals-dogs-2018-04-10"
[00:00:03]             │ debg [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] "animals-dogs-2018-04-10" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:00:03]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [animals-cats-2018-01-01] creating index, cause [api], templates [], shards [5]/[1], mappings [_doc]
[00:00:03]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Created index "animals-cats-2018-01-01"
[00:00:03]             │ debg [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] "animals-cats-2018-01-01" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:00:03]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [animals-cats-2018-04-10] creating index, cause [api], templates [], shards [5]/[1], mappings [_doc]
[00:00:03]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Created index "animals-cats-2018-04-10"
[00:00:03]             │ debg [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] "animals-cats-2018-04-10" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:00:04]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [dogbreeds] creating index, cause [api], templates [], shards [5]/[1], mappings [_doc]
[00:00:04]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Created index "dogbreeds"
[00:00:04]             │ debg [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] "dogbreeds" settings {"index":{"number_of_replicas":"1","number_of_shards":"5"}}
[00:00:04]             │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [logstash-0] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:04]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Created index "logstash-0"
[00:00:04]             │ debg [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] "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:13]             │ info progress: 9626
[00:00:16]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 6 docs into "animals-dogs-2018-01-01"
[00:00:16]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 6 docs into "animals-dogs-2018-04-10"
[00:00:16]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 6 docs into "animals-cats-2018-01-01"
[00:00:16]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 5 docs into "animals-cats-2018-04-10"
[00:00:16]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 6 docs into "dogbreeds"
[00:00:16]             │ info [/dev/shm/workspace/kibana/test/functional/fixtures/es_archiver/dashboard/current/data] Indexed 14005 docs into "logstash-0"
[00:00:16]             │ debg applying update to kibana config: {"defaultIndex":"0bf35f60-3dc9-11e8-8660-4d65aa086b3c"}
[00:00:17]           └-: reporting usage
[00:00:17]             └-> "before all" hook
[00:00:17]             └-> "before all" hook
[00:00:17]               │ debg ReportingAPI.deleteAllReportingIndexes
[00:00:39]             └-: from new jobs posted
[00:00:39]               └-> "before all" hook
[00:00:39]               └-> csv
[00:00:39]                 └-> "before each" hook: global before each
[00:00:39]                 │ debg ReportingAPI.postJob(/api/reporting/generate/csv?jobParams=(conflictedTypesFields:!(),fields:!(%27@timestamp%27,agent,bytes,clientip),indexPatternId:%270bf35f60-3dc9-11e8-8660-4d65aa086b3c%27,metaFields:!(_source,_id,_type,_index,_score),searchRequest:(body:(_source:(excludes:!(),includes:!(%27@timestamp%27,agent,bytes,clientip)),docvalue_fields:!(%27@timestamp%27),query:(bool:(filter:!((bool:(minimum_should_match:1,should:!((match:(clientip:%2773.14.212.83%27)))))),must:!((range:(bytes:(gte:100,lt:1000))),(range:(%27@timestamp%27:(format:epoch_millis,gte:1369165215770,lte:1526931615770)))),must_not:!(),should:!())),script_fields:(),sort:!((%27@timestamp%27:(order:desc,unmapped_type:boolean))),stored_fields:!(%27@timestamp%27,agent,bytes,clientip),version:!t),index:%27logstash-*%27),title:%27Bytes%20and%20kuery%20in%20saved%20search%20with%20filter%27,type:search))
[00:00:39]                 │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.reporting-2020.03.29] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:39]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.reporting-2020.03.29]
[00:00:39]                 │ proc [kibana]   log   [19:06:00.246] [info][queue-job][reporting] Successfully queued job: k8euf3ne10zjcf8f91e0sva1
[00:00:39]                 │ debg Waiting for job to finish: /api/reporting/jobs/download/k8euf3ne10zjcf8f91e0sva1
[00:00:39]                 │ proc [kibana]   log   [19:06:00.352] [info][esqueue][queue-worker][reporting] k8eue7c710zjcf8f91g2w2me - Job marked as claimed: /.reporting-2020.03.29/k8euf3ne10zjcf8f91e0sva1
[00:00:39]                 │ proc [kibana]   log   [19:06:00.354] [info][esqueue][queue-worker][reporting] k8eue7c710zjcf8f91g2w2me - Starting job
[00:00:39]                 │ proc [kibana]   log   [19:06:00.431] [warning][csv][execute-job][reporting] Kibana Advanced Setting "dateFormat:tz" is set to "Browser". Dates will be formatted as UTC to avoid ambiguity.
[00:00:39]                 │ proc [kibana]   log   [19:06:00.494] [info][esqueue][queue-worker][reporting] k8eue7c710zjcf8f91g2w2me - Job execution completed successfully
[00:00:39]                 │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.reporting-2020.03.29/pwXdDggoSAyAHzjEwFgdmA] update_mapping [_doc]
[00:00:39]                 │ proc [kibana]   log   [19:06:00.574] [info][esqueue][queue-worker][reporting] k8eue7c710zjcf8f91g2w2me - Job data saved successfully: /.reporting-2020.03.29/k8euf3ne10zjcf8f91e0sva1
[00:00:41]                 │ debg Report at path /api/reporting/jobs/download/k8euf3ne10zjcf8f91e0sva1 returned code 200
[00:00:41]                 └- ✓ pass  (2.5s) "chromium reporting usage from new jobs posted csv"
[00:00:41]               └-> "after each" hook
[00:00:41]                 │ debg ReportingAPI.deleteAllReportingIndexes
[00:00:41]                 │ info [o.e.c.m.MetaDataDeleteIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.reporting-2020.03.29/pwXdDggoSAyAHzjEwFgdmA] deleting index
[00:00:41]               └-> preserve_layout pdf
[00:00:41]                 └-> "before each" hook: global before each
[00:00:41]                 │ debg ReportingAPI.postJob(/api/reporting/generate/printablePdf?jobParams=(browserTimezone:America%2FNew_York,layout:(dimensions:(height:439,width:1362),id:preserve_layout),objectType:dashboard,relativeUrls:!(%27%2Fapp%2Fkibana%23%2Fdashboard%2F61c58ad0-3dd3-11e8-b2b9-5d5dc1715159%3F_g%3D(refreshInterval:(display:Off,pause:!!f,value:0),time:(from:!%27Mon%2BApr%2B09%2B2018%2B17:56:08%2BGMT-0400!%27,mode:absolute,to:!%27Wed%2BApr%2B11%2B2018%2B17:56:08%2BGMT-0400!%27))%26_a%3D(description:!%27!%27,filters:!!((!%27$state!%27:(store:appState),meta:(alias:!!n,disabled:!!f,index:a0f483a0-3dc9-11e8-8660-4d65aa086b3c,key:animal,negate:!!f,params:(query:dog,type:phrase),type:phrase,value:dog),query:(match:(animal:(query:dog,type:phrase))))),fullScreenMode:!!f,options:(hidePanelTitles:!!f,useMargins:!!t),panels:!!((embeddableConfig:(),gridData:(h:15,i:!%271!%27,w:24,x:0,y:0),id:!%2750643b60-3dd3-11e8-b2b9-5d5dc1715159!%27,panelIndex:!%271!%27,type:visualization,version:!%276.3.0!%27),(embeddableConfig:(),gridData:(h:15,i:!%272!%27,w:24,x:24,y:0),id:a16d1990-3dca-11e8-8660-4d65aa086b3c,panelIndex:!%272!%27,type:search,version:!%276.3.0!%27)),query:(language:lucene,query:!%27!%27),timeRestore:!!t,title:!%27dashboard%2Bwith%2Bfilter!%27,viewMode:view)%27),title:%27dashboard%20with%20filter%27))
[00:00:41]                 │ debg ReportingAPI.postJob(/api/reporting/generate/printablePdf?jobParams=(browserTimezone:America%2FNew_York,layout:(dimensions:(height:441,width:1002),id:preserve_layout),objectType:visualization,relativeUrls:!(%27%2Fapp%2Fkibana%23%2Fvisualize%2Fedit%2F3fe22200-3dcb-11e8-8660-4d65aa086b3c%3F_g%3D(refreshInterval:(display:Off,pause:!!f,value:0),time:(from:!%27Mon%2BApr%2B09%2B2018%2B17:56:08%2BGMT-0400!%27,mode:absolute,to:!%27Wed%2BApr%2B11%2B2018%2B17:56:08%2BGMT-0400!%27))%26_a%3D(filters:!!(),linked:!!f,query:(language:lucene,query:!%27!%27),uiState:(),vis:(aggs:!!((enabled:!!t,id:!%271!%27,params:(),schema:metric,type:count),(enabled:!!t,id:!%272!%27,params:(field:bytes,missingBucket:!!f,missingBucketLabel:Missing,order:desc,orderBy:!%271!%27,otherBucket:!!f,otherBucketLabel:Other,size:5),schema:segment,type:terms)),params:(addLegend:!!t,addTooltip:!!t,isDonut:!!t,labels:(last_level:!!t,show:!!f,truncate:100,values:!!t),legendPosition:right,type:pie),title:!%27Rendering%2BTest:%2Bpie!%27,type:pie))%27),title:%27Rendering%20Test:%20pie%27))
[00:00:41]                 │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.reporting-2020.03.29] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:41]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.reporting-2020.03.29]
[00:00:41]                 │ proc [kibana]   log   [19:06:02.708] [info][queue-job][reporting] Successfully queued job: k8euf5ka10zjcf8f915f0y7b
[00:00:41]                 │ proc [kibana]   log   [19:06:02.710] [info][queue-job][reporting] Successfully queued job: k8euf5k210zjcf8f915ac4wb
[00:00:41]                 │ debg Waiting for job to finish: /api/reporting/jobs/download/k8euf5k210zjcf8f915ac4wb
[00:00:41]                 │ debg Waiting for job to finish: /api/reporting/jobs/download/k8euf5ka10zjcf8f915f0y7b
[00:00:42]                 │ proc [kibana]   log   [19:06:03.592] [info][esqueue][queue-worker][reporting] k8eue7c710zjcf8f91g2w2me - Job marked as claimed: /.reporting-2020.03.29/k8euf5k210zjcf8f915ac4wb
[00:00:42]                 │ proc [kibana]   log   [19:06:03.592] [info][esqueue][queue-worker][reporting] k8eue7c710zjcf8f91g2w2me - Starting job
[00:00:42]                 │ proc [kibana]   log   [19:06:03.633] [info][browser-driver][execute][k8euf5k210zjcf8f915ac4wb][printable_pdf][reporting] Creating browser page driver
[00:00:43]                 │ proc [kibana]   log   [19:06:03.759] [error][execute][k8euf5k210zjcf8f915ac4wb][printable_pdf][reporting] Error: Error spawning Chromium browser: [Error: Protocol error (Target.setDiscoverTargets): Target closed.]
[00:00:43]                 │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-2/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:124:24)
[00:00:43]                 │ proc [kibana]   log   [19:06:03.786] [error][error][esqueue][queue-worker][reporting] k8eue7c710zjcf8f91g2w2me - Failure occurred on job k8euf5k210zjcf8f915ac4wb: Error: Error spawning Chromium browser: [Error: Protocol error (Target.setDiscoverTargets): Target closed.]
[00:00:43]                 │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-2/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:124:24)
[00:00:43]                 │ proc [kibana]   log   [19:06:03.787] [warning][esqueue][queue-worker][reporting] k8eue7c710zjcf8f91g2w2me - Failing job k8euf5k210zjcf8f915ac4wb
[00:00:43]                 │ proc [kibana]  error  [19:06:03.795] [warning][process] UnhandledPromiseRejectionWarning: Error: Protocol error (Target.setDiscoverTargets): Target closed.
[00:00:43]                 │ proc [kibana]     at Promise (/dev/shm/workspace/install/kibana-2/node_modules/puppeteer-core/lib/Connection.js:74:56)
[00:00:43]                 │ proc [kibana]     at new Promise (<anonymous>)
[00:00:43]                 │ proc [kibana]     at Connection.send (/dev/shm/workspace/install/kibana-2/node_modules/puppeteer-core/lib/Connection.js:73:12)
[00:00:43]                 │ proc [kibana]     at Function.create (/dev/shm/workspace/install/kibana-2/node_modules/puppeteer-core/lib/Browser.js:34:22)
[00:00:43]                 │ proc [kibana]     at Launcher.launch (/dev/shm/workspace/install/kibana-2/node_modules/puppeteer-core/lib/Launcher.js:184:37)
[00:00:43]                 │ proc [kibana]     at module.exports.launch (/dev/shm/workspace/install/kibana-2/node_modules/puppeteer-core/lib/Puppeteer.js:37:27)
[00:00:43]                 │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-2/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:108:25)
[00:00:43]                 │ proc [kibana]     at Observable._trySubscribe (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/Observable.js:44:25)
[00:00:43]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/Observable.js:30:22)
[00:00:43]                 │ proc [kibana]     at MergeMapOperator.call (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/operators/mergeMap.js:39:23)
[00:00:43]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/Observable.js:25:31)
[00:00:43]                 │ proc [kibana]     at TakeOperator.call (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/operators/take.js:38:23)
[00:00:43]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/Observable.js:25:31)
[00:00:43]                 │ proc [kibana]     at ThrowIfEmptyOperator.call (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/operators/throwIfEmpty.js:30:23)
[00:00:43]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/Observable.js:25:31)
[00:00:43]                 │ proc [kibana]     at Object.subscribeToResult (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/util/subscribeToResult.js:12:23)
[00:00:43]                 │ proc [kibana]     at emitWarning (internal/process/promises.js:97:15)
[00:00:43]                 │ proc [kibana]     at emitPromiseRejectionWarnings (internal/process/promises.js:143:7)
[00:00:43]                 │ proc [kibana]     at process._tickCallback (internal/process/next_tick.js:69:34)
[00:00:43]                 │ proc [kibana]  error  [19:06:03.797] [warning][process] Error: Protocol error (Target.setDiscoverTargets): Target closed.
[00:00:43]                 │ proc [kibana]     at Promise (/dev/shm/workspace/install/kibana-2/node_modules/puppeteer-core/lib/Connection.js:74:56)
[00:00:43]                 │ proc [kibana]     at new Promise (<anonymous>)
[00:00:43]                 │ proc [kibana]     at Connection.send (/dev/shm/workspace/install/kibana-2/node_modules/puppeteer-core/lib/Connection.js:73:12)
[00:00:43]                 │ proc [kibana]     at Function.create (/dev/shm/workspace/install/kibana-2/node_modules/puppeteer-core/lib/Browser.js:34:22)
[00:00:43]                 │ proc [kibana]     at Launcher.launch (/dev/shm/workspace/install/kibana-2/node_modules/puppeteer-core/lib/Launcher.js:184:37)
[00:00:43]                 │ proc [kibana]     at module.exports.launch (/dev/shm/workspace/install/kibana-2/node_modules/puppeteer-core/lib/Puppeteer.js:37:27)
[00:00:43]                 │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-2/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:108:25)
[00:00:43]                 │ proc [kibana]     at Observable._trySubscribe (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/Observable.js:44:25)
[00:00:43]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/Observable.js:30:22)
[00:00:43]                 │ proc [kibana]     at MergeMapOperator.call (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/operators/mergeMap.js:39:23)
[00:00:43]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/Observable.js:25:31)
[00:00:43]                 │ proc [kibana]     at TakeOperator.call (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/operators/take.js:38:23)
[00:00:43]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/Observable.js:25:31)
[00:00:43]                 │ proc [kibana]     at ThrowIfEmptyOperator.call (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/operators/throwIfEmpty.js:30:23)
[00:00:43]                 │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/Observable.js:25:31)
[00:00:43]                 │ proc [kibana]     at Object.subscribeToResult (/dev/shm/workspace/install/kibana-2/node_modules/rxjs/internal/util/subscribeToResult.js:12:23)
[00:00:43]                 │ proc [kibana]   log   [19:06:03.798] [info][esqueue][queue-worker][reporting] k8eue7c710zjcf8f91g2w2me - Job marked as failed: /.reporting-2020.03.29/k8euf5k210zjcf8f915ac4wb
[00:00:43]                 │ debg Report at path /api/reporting/jobs/download/k8euf5ka10zjcf8f915f0y7b returned code 503
[00:00:43]                 │ debg Report at path /api/reporting/jobs/download/k8euf5k210zjcf8f915ac4wb returned code 503
[00:00:45]                 │ proc [kibana]   log   [19:06:05.768] [error][reporting] Report k8euf5k210zjcf8f915ac4wb has failed: {"message":"Reporting generation failed","reason":"Error: Error spawning Chromium browser: [Error: Protocol error (Target.setDiscoverTargets): Target closed.]"}
[00:00:45]                 │ debg Report at path /api/reporting/jobs/download/k8euf5k210zjcf8f915ac4wb returned code 500
[00:00:45]                 └- ✖ fail: "chromium reporting usage from new jobs posted preserve_layout pdf"
[00:00:45]                 │

Stack Trace

Error: expected 500 to equal 200
    at Assertion.assert (/dev/shm/workspace/kibana/packages/kbn-expect/expect.js:100:11)
    at Assertion.be.Assertion.equal (/dev/shm/workspace/kibana/packages/kbn-expect/expect.js:227:8)
    at Assertion.be (/dev/shm/workspace/kibana/packages/kbn-expect/expect.js:69:22)
    at Object.waitForJobToFinish (test/reporting/services/reporting_api.js:38:29)

Kibana Pipeline / kibana-xpack-agent / X-Pack Chromium Functional Reporting Tests.x-pack/test/reporting/functional/reporting·js.reporting app Reporting Dashboard Print Layout downloads a PDF file

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: reporting app
[00:00:00]           └-> "before all" hook
[00:00:00]           └-: Reporting
[00:00:00]             └-> "before all" hook
[00:00:00]             └-> "before all" hook: initialize tests
[00:00:00]               │ debg ReportingPage:initTests
[00:00:00]               │ debg navigating to settings url: http://localhost:6161/app/kibana#/management
[00:00:00]               │ debg Navigate to: http://localhost:6161/app/kibana#/management
[00:00:00]               │ debg ... sleep(700) start
[00:00:00]               │ debg browser[INFO] http://localhost:6161/login?next=%2Fapp%2Fkibana%3F_t%3D1585595898859#/management 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:00:00]               │
[00:00:00]               │ debg browser[INFO] http://localhost:6161/bundles/app/core/bootstrap.js 9:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:00:01]               │ debg ... sleep(700) end
[00:00:01]               │ debg returned from get, calling refresh
[00:00:01]               │ debg browser[INFO] http://localhost:6161/login?next=%2Fapp%2Fkibana%3F_t%3D1585595898859#/management 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:00:01]               │
[00:00:01]               │ debg browser[INFO] http://localhost:6161/bundles/app/core/bootstrap.js 9:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:00:01]               │ debg currentUrl = http://localhost:6161/login?next=%2Fapp%2Fkibana%3F_t%3D1585595898859#/management
[00:00:01]               │          appUrl = http://localhost:6161/app/kibana#/management
[00:00:01]               │ debg TestSubjects.find(kibanaChrome)
[00:00:01]               │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:00:08]               │ debg browser[INFO] http://localhost:6161/bundles/plugin/data/data.plugin.js 96:139630 "INFO: 2020-03-30T19:18:27Z
[00:00:08]               │        Adding connection to http://localhost:6161/elasticsearch
[00:00:08]               │
[00:00:08]               │      "
[00:00:08]               │ debg Found login page
[00:00:08]               │ debg TestSubjects.setValue(loginUsername, elastic)
[00:00:08]               │ debg TestSubjects.click(loginUsername)
[00:00:08]               │ debg Find.clickByCssSelector('[data-test-subj="loginUsername"]') with timeout=10000
[00:00:08]               │ debg Find.findByCssSelector('[data-test-subj="loginUsername"]') with timeout=10000
[00:00:08]               │ debg TestSubjects.setValue(loginPassword, changeme)
[00:00:08]               │ debg TestSubjects.click(loginPassword)
[00:00:08]               │ debg Find.clickByCssSelector('[data-test-subj="loginPassword"]') with timeout=10000
[00:00:08]               │ debg Find.findByCssSelector('[data-test-subj="loginPassword"]') with timeout=10000
[00:00:08]               │ debg TestSubjects.click(loginSubmit)
[00:00:08]               │ debg Find.clickByCssSelector('[data-test-subj="loginSubmit"]') with timeout=10000
[00:00:08]               │ debg Find.findByCssSelector('[data-test-subj="loginSubmit"]') with timeout=10000
[00:00:09]               │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"] nav:not(.ng-hide)') with timeout=60000
[00:00:09]               │ debg browser[INFO] http://localhost:6161/app/kibana?_t=1585595898859#/management 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:00:09]               │
[00:00:09]               │ debg browser[INFO] http://localhost:6161/bundles/app/kibana/bootstrap.js 9:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:00:13]               │ debg browser[INFO] http://localhost:6161/bundles/plugin/data/data.plugin.js 96:139630 "INFO: 2020-03-30T19:18:32Z
[00:00:13]               │        Adding connection to http://localhost:6161/elasticsearch
[00:00:13]               │
[00:00:13]               │      "
[00:00:16]               │ debg browser[INFO] http://localhost:6161/app/kibana?_t=1585595914601#/management 341 Refused to execute inline script because it violates the following Content Security Policy directive: "script-src 'unsafe-eval' 'self'". Either the 'unsafe-inline' keyword, a hash ('sha256-P5polb1UreUSOe5V/Pv7tc+yeZuJXiOi/3fqhGsU7BE='), or a nonce ('nonce-...') is required to enable inline execution.
[00:00:16]               │
[00:00:16]               │ debg browser[INFO] http://localhost:6161/bundles/app/kibana/bootstrap.js 9:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:00:16]               │ debg Finished login process currentUrl = http://localhost:6161/app/kibana#/management
[00:00:16]               │ debg TestSubjects.find(kibanaChrome)
[00:00:16]               │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=10000
[00:00:19]               │ debg browser[INFO] http://localhost:6161/bundles/plugin/data/data.plugin.js 96:139630 "INFO: 2020-03-30T19:18:38Z
[00:00:19]               │        Adding connection to http://localhost:6161/elasticsearch
[00:00:19]               │
[00:00:19]               │      "
[00:00:20]               │ debg ... sleep(501) start
[00:00:21]               │ debg ... sleep(501) end
[00:00:21]               │ debg in navigateTo url = http://localhost:6161/app/kibana#/management?_g=()
[00:00:21]               │ debg TestSubjects.exists(statusPageContainer)
[00:00:21]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:00:23]               │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:00:24]               │ info [../../functional/es_archives/logstash_functional] Loading "mappings.json"
[00:00:24]               │ info [../../functional/es_archives/logstash_functional] Loading "data.json.gz"
[00:00:24]               │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [logstash-2015.09.22] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:24]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.22][0]]]).
[00:00:24]               │ info [../../functional/es_archives/logstash_functional] Created index "logstash-2015.09.22"
[00:00:24]               │ debg [../../functional/es_archives/logstash_functional] "logstash-2015.09.22" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:24]               │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [logstash-2015.09.20] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:24]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.20][0]]]).
[00:00:24]               │ info [../../functional/es_archives/logstash_functional] Created index "logstash-2015.09.20"
[00:00:24]               │ debg [../../functional/es_archives/logstash_functional] "logstash-2015.09.20" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:24]               │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [logstash-2015.09.21] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:24]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[logstash-2015.09.21][0]]]).
[00:00:24]               │ info [../../functional/es_archives/logstash_functional] Created index "logstash-2015.09.21"
[00:00:24]               │ debg [../../functional/es_archives/logstash_functional] "logstash-2015.09.21" settings {"index":{"analysis":{"analyzer":{"url":{"max_token_length":"1000","tokenizer":"uax_url_email","type":"standard"}}},"number_of_replicas":"0","number_of_shards":"1"}}
[00:00:34]               │ info progress: 11640
[00:00:35]               │ info [../../functional/es_archives/logstash_functional] Indexed 4634 docs into "logstash-2015.09.22"
[00:00:35]               │ info [../../functional/es_archives/logstash_functional] Indexed 4757 docs into "logstash-2015.09.20"
[00:00:35]               │ info [../../functional/es_archives/logstash_functional] Indexed 4614 docs into "logstash-2015.09.21"
[00:00:36]               │ info [reporting/historic] Loading "mappings.json"
[00:00:36]               │ info [reporting/historic] Loading "data.json.gz"
[00:00:36]               │ info [o.e.c.m.MetaDataDeleteIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_1/wrWv9zGqSNS5NQw9M9Srlw] deleting index
[00:00:36]               │ info [reporting/historic] Deleted existing index [".kibana_1"]
[00:00:36]               │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:36]               │ info [reporting/historic] Created index ".kibana"
[00:00:36]               │ debg [reporting/historic] ".kibana" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:00:36]               │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.reporting-2018.03.11] creating index, cause [api], templates [], shards [1]/[0], mappings [_doc]
[00:00:36]               │ info [reporting/historic] Created index ".reporting-2018.03.11"
[00:00:36]               │ debg [reporting/historic] ".reporting-2018.03.11" settings {"index":{"auto_expand_replicas":"0-1","number_of_replicas":"0","number_of_shards":"1"}}
[00:00:36]               │ info [reporting/historic] Indexed 10 docs into ".kibana"
[00:00:36]               │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana/WahVqOXfRbGygut5fUpO6A] update_mapping [_doc]
[00:00:36]               │ debg Migrating saved objects
[00:00:37]               │ proc [kibana]   log   [19:18:56.524] [info][savedobjects-service] Creating index .kibana_2.
[00:00:37]               │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:37]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.kibana_2]
[00:00:37]               │ proc [kibana]   log   [19:18:56.620] [info][savedobjects-service] Reindexing .kibana to .kibana_1
[00:00:37]               │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_1] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:00:37]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.kibana_1]
[00:00:37]               │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.tasks] creating index, cause [auto(task api)], templates [], shards [1]/[1], mappings [_doc]
[00:00:37]               │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.tasks]
[00:00:38]               │ info [o.e.t.LoggingTaskListener] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] 2131 finished with response BulkByScrollResponse[took=46.6ms,timed_out=false,sliceId=null,updated=0,created=11,deleted=0,batches=1,versionConflicts=0,noops=0,retries=0,throttledUntil=0s,bulk_failures=[],search_failures=[]]
[00:00:38]               │ info [o.e.c.m.MetaDataDeleteIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana/WahVqOXfRbGygut5fUpO6A] deleting index
[00:00:38]               │ proc [kibana]   log   [19:18:57.069] [info][savedobjects-service] Migrating .kibana_1 saved objects to .kibana_2
[00:00:38]               │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/jDDpa_LdSe-7ax-SXi_afg] update_mapping [_doc]
[00:00:38]               │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/jDDpa_LdSe-7ax-SXi_afg] update_mapping [_doc]
[00:00:38]               │ info [o.e.c.m.MetaDataMappingService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.kibana_2/jDDpa_LdSe-7ax-SXi_afg] update_mapping [_doc]
[00:00:38]               │ proc [kibana]   log   [19:18:57.321] [info][savedobjects-service] Pointing alias .kibana to .kibana_2.
[00:00:38]               │ proc [kibana]   log   [19:18:57.395] [info][savedobjects-service] Finished in 874ms.
[00:00:38]               │ debg replacing kibana config doc: {"defaultIndex":"logstash-*"}
[00:00:38]             └-: Dashboard
[00:00:38]               └-> "before all" hook
[00:01:30]               └-: Print Layout
[00:01:30]                 └-> "before all" hook
[00:01:30]                 └-> downloads a PDF file
[00:01:30]                   └-> "before each" hook: global before each
[00:01:30]                   └-> "before each" hook
[00:01:30]                     │ debg TestSubjects.findAll(toastCloseButton)
[00:01:30]                     │ debg Find.allByCssSelector('[data-test-subj="toastCloseButton"]') with timeout=10000
[00:01:40]                   │ debg navigating to dashboard url: http://localhost:6161/app/kibana#/dashboards
[00:01:40]                   │ debg Navigate to: http://localhost:6161/app/kibana#/dashboards
[00:01:40]                   │ debg ... sleep(700) start
[00:01:40]                   │ debg browser[INFO] http://localhost:6161/app/kibana?_t=1585595998934#/dashboards 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:40]                   │
[00:01:40]                   │ debg browser[INFO] http://localhost:6161/bundles/app/kibana/bootstrap.js 9:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:01:41]                   │ debg ... sleep(700) end
[00:01:41]                   │ debg returned from get, calling refresh
[00:01:41]                   │ debg browser[INFO] http://localhost:6161/app/kibana?_t=1585595998934#/dashboards 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:41]                   │
[00:01:41]                   │ debg browser[INFO] http://localhost:6161/bundles/app/kibana/bootstrap.js 9:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:01:41]                   │ debg currentUrl = http://localhost:6161/app/kibana#/dashboards
[00:01:41]                   │          appUrl = http://localhost:6161/app/kibana#/dashboards
[00:01:41]                   │ debg TestSubjects.find(kibanaChrome)
[00:01:41]                   │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:01:48]                   │ debg TestSubjects.find(kibanaChrome)
[00:01:48]                   │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=10000
[00:01:48]                   │ debg browser[INFO] http://localhost:6161/bundles/plugin/data/data.plugin.js 96:139630 "INFO: 2020-03-30T19:20:05Z
[00:01:48]                   │        Adding connection to http://localhost:6161/elasticsearch
[00:01:48]                   │
[00:01:48]                   │      "
[00:01:48]                   │ debg ... sleep(501) start
[00:01:49]                   │ debg ... sleep(501) end
[00:01:49]                   │ debg in navigateTo url = http://localhost:6161/app/kibana#/dashboards?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:01:49]                   │ debg --- retry.try error: URL changed, waiting for it to settle
[00:01:49]                   │ debg ... sleep(501) start
[00:01:50]                   │ debg ... sleep(501) end
[00:01:50]                   │ debg in navigateTo url = http://localhost:6161/app/kibana#/dashboards?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:now-15m,to:now))
[00:01:50]                   │ debg TestSubjects.exists(statusPageContainer)
[00:01:50]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:01:52]                   │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:01:53]                   │ debg Load Saved Dashboard My PDF Dashboard
[00:01:53]                   │ debg gotoDashboardLandingPage
[00:01:53]                   │ debg onDashboardLandingPage
[00:01:53]                   │ debg TestSubjects.exists(dashboardLandingPage)
[00:01:53]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardLandingPage"]') with timeout=5000
[00:01:53]                   │ debg searchForItemWithName: My PDF Dashboard
[00:01:53]                   │ debg Find.allByCssSelector('.euiFieldSearch') 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:54]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:01:54]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:01:54]                   │ debg TestSubjects.click(dashboardListingTitleLink-My-PDF-Dashboard)
[00:01:54]                   │ debg Find.clickByCssSelector('[data-test-subj="dashboardListingTitleLink-My-PDF-Dashboard"]') with timeout=10000
[00:01:54]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardListingTitleLink-My-PDF-Dashboard"]') with timeout=10000
[00:01:54]                   │ debg isGlobalLoadingIndicatorVisible
[00:01:54]                   │ debg TestSubjects.exists(globalLoadingIndicator)
[00:01:54]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:01:54]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:01:54]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:01:54]                   │ debg TestSubjects.missingOrFail(dashboardLandingPage)
[00:01:54]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="dashboardLandingPage"]') with timeout=10000
[00:01:55]                   │ debg Switching to edit mode
[00:01:55]                   │ debg TestSubjects.click(dashboardEditMode)
[00:01:55]                   │ debg Find.clickByCssSelector('[data-test-subj="dashboardEditMode"]') with timeout=10000
[00:01:55]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardEditMode"]') with timeout=10000
[00:01:55]                   │ debg Waiting up to 20000ms for in edit mode...
[00:01:55]                   │ debg TestSubjects.findAll(embeddablePanel)
[00:01:55]                   │ debg Find.allByCssSelector('[data-test-subj="embeddablePanel"]') with timeout=2500
[00:01:58]                   │ debg TestSubjects.findAll(embeddablePanelToggleMenuIcon)
[00:01:58]                   │ debg Find.allByCssSelector('[data-test-subj="embeddablePanelToggleMenuIcon"]') with timeout=2500
[00:02:00]                   │ debg Reporting:setTimepickerInDataRange
[00:02:00]                   │ debg Setting absolute range to Sep 19, 2015 @ 06:31:44.000 to Sep 19, 2015 @ 18:01:44.000
[00:02:00]                   │ debg TestSubjects.exists(superDatePickerToggleQuickMenuButton)
[00:02:00]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerToggleQuickMenuButton"]') with timeout=20000
[00:02:00]                   │ debg TestSubjects.exists(superDatePickerShowDatesButton)
[00:02:00]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=2500
[00:02:00]                   │ debg TestSubjects.click(superDatePickerShowDatesButton)
[00:02:00]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:02:00]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerShowDatesButton"]') with timeout=10000
[00:02:00]                   │ debg TestSubjects.exists(superDatePickerstartDatePopoverButton)
[00:02:00]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=2500
[00:02:00]                   │ debg TestSubjects.click(superDatePickerendDatePopoverButton)
[00:02:00]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:02:00]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerendDatePopoverButton"]') with timeout=10000
[00:02:00]                   │ debg Find.findByCssSelector('div.euiPopover__panel-isOpen') with timeout=10000
[00:02:00]                   │ debg TestSubjects.click(superDatePickerAbsoluteTab)
[00:02:00]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:02:00]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteTab"]') with timeout=10000
[00:02:00]                   │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:02:00]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:00]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:01]                   │ debg TestSubjects.setValue(superDatePickerAbsoluteDateInput, Sep 19, 2015 @ 18:01:44.000)
[00:02:01]                   │ debg TestSubjects.click(superDatePickerAbsoluteDateInput)
[00:02:01]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:01]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerAbsoluteDateInput"]') with timeout=10000
[00:02:01]                   │ debg ... sleep(500) start
[00:02:02]                   │ debg ... sleep(500) end
[00:02:02]                   │ debg TestSubjects.click(superDatePickerstartDatePopoverButton)
[00:02:02]                   │ debg Find.clickByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:02:02]                   │ debg Find.findByCssSelector('[data-test-subj="superDatePickerstartDatePopoverButton"]') with timeout=10000
[00:02:02]                   │ debg Find.waitForElementStale 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 19, 2015 @ 06: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 TestSubjects.exists(superDatePickerApplyTimeButton)
[00:02:03]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="superDatePickerApplyTimeButton"]') with timeout=2500
[00:02:05]                   │ debg --- retry.tryForTime error: [data-test-subj="superDatePickerApplyTimeButton"] is not displayed
[00:02:06]                   │ debg TestSubjects.click(querySubmitButton)
[00:02:06]                   │ debg Find.clickByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:02:06]                   │ debg Find.findByCssSelector('[data-test-subj="querySubmitButton"]') with timeout=10000
[00:02:06]                   │ debg Find.waitForElementStale with timeout=10000
[00:02:06]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:02:06]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:02:06]                   │ debg DashboardAddPanel.addVisualizations
[00:02:06]                   │ debg DashboardAddPanel.addEmbeddable, name: Visualization PieChart, type: visualization
[00:02:06]                   │ debg DashboardAddPanel.ensureAddPanelIsShowing
[00:02:06]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:02:06]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:06]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:02:09]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:02:09]                   │ debg DashboardAddPanel.clickOpenAddPanel
[00:02:09]                   │ debg TestSubjects.click(dashboardAddPanelButton)
[00:02:09]                   │ debg Find.clickByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:02:09]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:02:09]                   │ debg ... sleep(500) start
[00:02:10]                   │ debg ... sleep(500) end
[00:02:10]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:02:10]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:10]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:02:10]                   │ debg DashboardAddPanel.addToFilter(visualization)
[00:02:10]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:11]                   │ debg DashboardAddPanel.toggleFilter
[00:02:11]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:02:11]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:11]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:11]                   │ debg TestSubjects.click(savedObjectFinderFilter-visualization)
[00:02:11]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:02:11]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:02:11]                   │ debg DashboardAddPanel.toggleFilter
[00:02:11]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:02:11]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:11]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:11]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:11]                   │ debg TestSubjects.setValue(savedObjectFinderSearchInput, "Visualization PieChart")
[00:02:11]                   │ debg TestSubjects.click(savedObjectFinderSearchInput)
[00:02:11]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:02:11]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:02:12]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:12]                   │ debg TestSubjects.click(savedObjectTitleVisualization-PieChart)
[00:02:12]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitleVisualization-PieChart"]') with timeout=10000
[00:02:12]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitleVisualization-PieChart"]') with timeout=10000
[00:02:12]                   │ debg TestSubjects.exists(addObjectToDashboardSuccess)
[00:02:12]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="addObjectToDashboardSuccess"]') with timeout=2500
[00:02:15]                   │ debg --- retry.tryForTime error: [data-test-subj="addObjectToDashboardSuccess"] is not displayed
[00:02:15]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:15]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:02:15]                   │ debg Closing flyout dashboardAddPanel
[00:02:15]                   │ debg TestSubjects.find(dashboardAddPanel)
[00:02:15]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=10000
[00:02:15]                   │ debg Waiting up to 20000ms for flyout closed...
[00:02:15]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:15]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:02:17]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:02:17]                   │ debg DashboardAddPanel.addEmbeddable, name: Visualization☺ VerticalBarChart, type: visualization
[00:02:17]                   │ debg DashboardAddPanel.ensureAddPanelIsShowing
[00:02:17]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:02:17]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:17]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:02:20]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:02:20]                   │ debg DashboardAddPanel.clickOpenAddPanel
[00:02:20]                   │ debg TestSubjects.click(dashboardAddPanelButton)
[00:02:20]                   │ debg Find.clickByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:02:20]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:02:20]                   │ debg ... sleep(500) start
[00:02:21]                   │ debg ... sleep(500) end
[00:02:21]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:02:21]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:21]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:02:21]                   │ debg DashboardAddPanel.addToFilter(visualization)
[00:02:21]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:21]                   │ debg DashboardAddPanel.toggleFilter
[00:02:21]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:02:21]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:21]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:21]                   │ debg TestSubjects.click(savedObjectFinderFilter-visualization)
[00:02:21]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:02:21]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:02:21]                   │ debg DashboardAddPanel.toggleFilter
[00:02:21]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:02:21]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:21]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:21]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:22]                   │ debg TestSubjects.setValue(savedObjectFinderSearchInput, "Visualization☺ VerticalBarChart")
[00:02:22]                   │ debg TestSubjects.click(savedObjectFinderSearchInput)
[00:02:22]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:02:22]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:02:22]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:23]                   │ debg TestSubjects.click(savedObjectTitleVisualization☺-VerticalBarChart)
[00:02:23]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitleVisualization☺-VerticalBarChart"]') with timeout=10000
[00:02:23]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitleVisualization☺-VerticalBarChart"]') with timeout=10000
[00:02:23]                   │ debg TestSubjects.exists(addObjectToDashboardSuccess)
[00:02:23]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="addObjectToDashboardSuccess"]') with timeout=2500
[00:02:26]                   │ debg --- retry.tryForTime error: [data-test-subj="addObjectToDashboardSuccess"] is not displayed
[00:02:26]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:26]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:02:26]                   │ debg Closing flyout dashboardAddPanel
[00:02:26]                   │ debg TestSubjects.find(dashboardAddPanel)
[00:02:26]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=10000
[00:02:26]                   │ debg Waiting up to 20000ms for flyout closed...
[00:02:26]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:26]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:02:27]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:02:28]                   │ debg DashboardAddPanel.addEmbeddable, name: Visualization漢字 AreaChart, type: visualization
[00:02:28]                   │ debg DashboardAddPanel.ensureAddPanelIsShowing
[00:02:28]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:02:28]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:28]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:02:30]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:02:31]                   │ debg DashboardAddPanel.clickOpenAddPanel
[00:02:31]                   │ debg TestSubjects.click(dashboardAddPanelButton)
[00:02:31]                   │ debg Find.clickByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:02:31]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:02:31]                   │ debg ... sleep(500) start
[00:02:32]                   │ debg ... sleep(500) end
[00:02:32]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:02:32]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:32]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:02:32]                   │ debg DashboardAddPanel.addToFilter(visualization)
[00:02:32]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:32]                   │ debg DashboardAddPanel.toggleFilter
[00:02:32]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:02:32]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:32]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:32]                   │ debg TestSubjects.click(savedObjectFinderFilter-visualization)
[00:02:32]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:02:32]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:02:32]                   │ debg DashboardAddPanel.toggleFilter
[00:02:32]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:02:32]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:32]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:32]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:33]                   │ debg TestSubjects.setValue(savedObjectFinderSearchInput, "Visualization漢字 AreaChart")
[00:02:33]                   │ debg TestSubjects.click(savedObjectFinderSearchInput)
[00:02:33]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:02:33]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:02:33]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:34]                   │ debg TestSubjects.click(savedObjectTitleVisualization漢字-AreaChart)
[00:02:34]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitleVisualization漢字-AreaChart"]') with timeout=10000
[00:02:34]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitleVisualization漢字-AreaChart"]') with timeout=10000
[00:02:34]                   │ debg TestSubjects.exists(addObjectToDashboardSuccess)
[00:02:34]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="addObjectToDashboardSuccess"]') with timeout=2500
[00:02:37]                   │ debg --- retry.tryForTime error: [data-test-subj="addObjectToDashboardSuccess"] is not displayed
[00:02:37]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:37]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:02:37]                   │ debg Closing flyout dashboardAddPanel
[00:02:37]                   │ debg TestSubjects.find(dashboardAddPanel)
[00:02:37]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=10000
[00:02:37]                   │ debg Waiting up to 20000ms for flyout closed...
[00:02:37]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:37]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:02:38]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:02:39]                   │ debg DashboardAddPanel.addEmbeddable, name: Visualization☺漢字 DataTable, type: visualization
[00:02:39]                   │ debg DashboardAddPanel.ensureAddPanelIsShowing
[00:02:39]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:02:39]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:39]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:02:41]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:02:42]                   │ debg DashboardAddPanel.clickOpenAddPanel
[00:02:42]                   │ debg TestSubjects.click(dashboardAddPanelButton)
[00:02:42]                   │ debg Find.clickByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:02:42]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:02:42]                   │ debg ... sleep(500) start
[00:02:42]                   │ debg ... sleep(500) end
[00:02:42]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:02:42]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:42]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:02:42]                   │ debg DashboardAddPanel.addToFilter(visualization)
[00:02:42]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:43]                   │ debg DashboardAddPanel.toggleFilter
[00:02:43]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:02:43]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:43]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:43]                   │ debg TestSubjects.click(savedObjectFinderFilter-visualization)
[00:02:43]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:02:43]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:02:43]                   │ debg DashboardAddPanel.toggleFilter
[00:02:43]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:02:43]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:43]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:43]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:44]                   │ debg TestSubjects.setValue(savedObjectFinderSearchInput, "Visualization☺漢字 DataTable")
[00:02:44]                   │ debg TestSubjects.click(savedObjectFinderSearchInput)
[00:02:44]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:02:44]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:02:44]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:44]                   │ debg TestSubjects.click(savedObjectTitleVisualization☺漢字-DataTable)
[00:02:44]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitleVisualization☺漢字-DataTable"]') with timeout=10000
[00:02:44]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitleVisualization☺漢字-DataTable"]') with timeout=10000
[00:02:45]                   │ debg TestSubjects.exists(addObjectToDashboardSuccess)
[00:02:45]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="addObjectToDashboardSuccess"]') with timeout=2500
[00:02:47]                   │ debg --- retry.tryForTime error: [data-test-subj="addObjectToDashboardSuccess"] is not displayed
[00:02:48]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:48]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:02:48]                   │ debg Closing flyout dashboardAddPanel
[00:02:48]                   │ debg TestSubjects.find(dashboardAddPanel)
[00:02:48]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=10000
[00:02:48]                   │ debg Waiting up to 20000ms for flyout closed...
[00:02:48]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:48]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:02:49]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:02:49]                   │ debg DashboardAddPanel.addEmbeddable, name: Visualization漢字 LineChart, type: visualization
[00:02:49]                   │ debg DashboardAddPanel.ensureAddPanelIsShowing
[00:02:49]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:02:49]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:49]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:02:52]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:02:52]                   │ debg DashboardAddPanel.clickOpenAddPanel
[00:02:52]                   │ debg TestSubjects.click(dashboardAddPanelButton)
[00:02:52]                   │ debg Find.clickByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:02:52]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:02:52]                   │ debg ... sleep(500) start
[00:02:53]                   │ debg ... sleep(500) end
[00:02:53]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:02:53]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:53]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:02:53]                   │ debg DashboardAddPanel.addToFilter(visualization)
[00:02:53]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:53]                   │ debg DashboardAddPanel.toggleFilter
[00:02:53]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:02:53]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:53]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:54]                   │ debg TestSubjects.click(savedObjectFinderFilter-visualization)
[00:02:54]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:02:54]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:02:54]                   │ debg DashboardAddPanel.toggleFilter
[00:02:54]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:02:54]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:54]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:02:54]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:54]                   │ debg TestSubjects.setValue(savedObjectFinderSearchInput, "Visualization漢字 LineChart")
[00:02:54]                   │ debg TestSubjects.click(savedObjectFinderSearchInput)
[00:02:54]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:02:54]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:02:55]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:02:55]                   │ debg TestSubjects.click(savedObjectTitleVisualization漢字-LineChart)
[00:02:55]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitleVisualization漢字-LineChart"]') with timeout=10000
[00:02:55]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitleVisualization漢字-LineChart"]') with timeout=10000
[00:02:55]                   │ debg TestSubjects.exists(addObjectToDashboardSuccess)
[00:02:55]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="addObjectToDashboardSuccess"]') with timeout=2500
[00:02:58]                   │ debg --- retry.tryForTime error: [data-test-subj="addObjectToDashboardSuccess"] is not displayed
[00:02:58]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:58]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:02:58]                   │ debg Closing flyout dashboardAddPanel
[00:02:58]                   │ debg TestSubjects.find(dashboardAddPanel)
[00:02:58]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=10000
[00:02:58]                   │ debg Waiting up to 20000ms for flyout closed...
[00:02:58]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:02:58]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:02:59]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:03:00]                   │ debg DashboardAddPanel.addEmbeddable, name: Visualization MetricChart, type: visualization
[00:03:00]                   │ debg DashboardAddPanel.ensureAddPanelIsShowing
[00:03:00]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:03:00]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:03:00]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:03:02]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:03:03]                   │ debg DashboardAddPanel.clickOpenAddPanel
[00:03:03]                   │ debg TestSubjects.click(dashboardAddPanelButton)
[00:03:03]                   │ debg Find.clickByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:03:03]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanelButton"]') with timeout=10000
[00:03:03]                   │ debg ... sleep(500) start
[00:03:04]                   │ debg ... sleep(500) end
[00:03:04]                   │ debg DashboardAddPanel.isAddPanelOpen
[00:03:04]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:03:04]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=2500
[00:03:04]                   │ debg DashboardAddPanel.addToFilter(visualization)
[00:03:04]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:03:04]                   │ debg DashboardAddPanel.toggleFilter
[00:03:04]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:03:04]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:03:04]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:03:04]                   │ debg TestSubjects.click(savedObjectFinderFilter-visualization)
[00:03:04]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:03:04]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilter-visualization"]') with timeout=10000
[00:03:04]                   │ debg DashboardAddPanel.toggleFilter
[00:03:04]                   │ debg TestSubjects.click(savedObjectFinderFilterButton)
[00:03:04]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:03:04]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderFilterButton"]') with timeout=10000
[00:03:04]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:03:05]                   │ debg TestSubjects.setValue(savedObjectFinderSearchInput, "Visualization MetricChart")
[00:03:05]                   │ debg TestSubjects.click(savedObjectFinderSearchInput)
[00:03:05]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:03:05]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:03:05]                   │ debg Find.waitForDeletedByCssSelector('[data-test-subj="savedObjectFinderLoadingIndicator"]') with timeout=10000
[00:03:06]                   │ debg TestSubjects.click(savedObjectTitleVisualization-MetricChart)
[00:03:06]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitleVisualization-MetricChart"]') with timeout=10000
[00:03:06]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitleVisualization-MetricChart"]') with timeout=10000
[00:03:06]                   │ debg TestSubjects.exists(addObjectToDashboardSuccess)
[00:03:06]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="addObjectToDashboardSuccess"]') with timeout=2500
[00:03:08]                   │ debg --- retry.tryForTime error: [data-test-subj="addObjectToDashboardSuccess"] is not displayed
[00:03:09]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:03:09]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:03:09]                   │ debg Closing flyout dashboardAddPanel
[00:03:09]                   │ debg TestSubjects.find(dashboardAddPanel)
[00:03:09]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=10000
[00:03:09]                   │ debg Waiting up to 20000ms for flyout closed...
[00:03:09]                   │ debg TestSubjects.exists(dashboardAddPanel)
[00:03:09]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="dashboardAddPanel"]') with timeout=1000
[00:03:10]                   │ debg --- retry.tryForTime error: [data-test-subj="dashboardAddPanel"] is not displayed
[00:03:11]                   │ debg TestSubjects.click(dashboardSaveMenuItem)
[00:03:11]                   │ debg Find.clickByCssSelector('[data-test-subj="dashboardSaveMenuItem"]') with timeout=10000
[00:03:11]                   │ debg Find.findByCssSelector('[data-test-subj="dashboardSaveMenuItem"]') with timeout=10000
[00:03:11]                   │ debg TestSubjects.find(savedObjectSaveModal)
[00:03:11]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=10000
[00:03:11]                   │ debg entering new title
[00:03:11]                   │ debg TestSubjects.setValue(savedObjectTitle, report test)
[00:03:11]                   │ debg TestSubjects.click(savedObjectTitle)
[00:03:11]                   │ debg Find.clickByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:03:11]                   │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitle"]') with timeout=10000
[00:03:11]                   │ debg DashboardPage.clickSave
[00:03:11]                   │ debg TestSubjects.click(confirmSaveSavedObjectButton)
[00:03:11]                   │ debg Find.clickByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:03:11]                   │ debg Find.findByCssSelector('[data-test-subj="confirmSaveSavedObjectButton"]') with timeout=10000
[00:03:11]                   │ debg Find.waitForElementStale with timeout=10000
[00:03:12]                   │ debg TestSubjects.exists(saveDashboardSuccess)
[00:03:12]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="saveDashboardSuccess"]') with timeout=120000
[00:03:12]                   │ debg Find.findByCssSelector('.euiToast') with timeout=20000
[00:03:12]                   │ debg Find.findByCssSelector('.euiToastHeader__title') with timeout=10000
[00:03:12]                   │ debg Toast title: Dashboard 'report test' was saved
[00:03:12]                   │ debg Find.clickByCssSelector('.euiToast__closeButton') with timeout=10000
[00:03:12]                   │ debg Find.findByCssSelector('.euiToast__closeButton') with timeout=10000
[00:03:12]                   │ debg isGlobalLoadingIndicatorVisible
[00:03:12]                   │ debg TestSubjects.exists(globalLoadingIndicator)
[00:03:12]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="globalLoadingIndicator"]') with timeout=1500
[00:03:13]                   │ debg --- retry.tryForTime error: [data-test-subj="globalLoadingIndicator"] is not displayed
[00:03:14]                   │ debg TestSubjects.exists(globalLoadingIndicator-hidden)
[00:03:14]                   │ debg Find.existsByCssSelector('[data-test-subj="globalLoadingIndicator-hidden"]') with timeout=100000
[00:03:14]                   │ debg Waiting for save modal to close
[00:03:14]                   │ debg TestSubjects.exists(savedObjectSaveModal)
[00:03:14]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectSaveModal"]') with timeout=2500
[00:03:16]                   │ debg --- retry.tryForTime error: [data-test-subj="savedObjectSaveModal"] is not displayed
[00:03:17]                   │ debg openPdfReportingPanel
[00:03:17]                   │ debg openShareMenuItem title:PDF Reports
[00:03:17]                   │ debg TestSubjects.exists(shareContextMenu)
[00:03:17]                   │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="shareContextMenu"]') with timeout=2500
[00:03:19]                   │ debg --- retry.tryForTime error: [data-test-subj="shareContextMenu"] is not displayed
[00:03:20]                   │ debg TestSubjects.click(shareTopNavButton)
[00:03:20]                   │ debg Find.clickByCssSelector('[data-test-subj="shareTopNavButton"]') with timeout=10000
[00:03:20]                   │ debg Find.findByCssSelector('[data-test-subj="shareTopNavButton"]') with timeout=10000
[00:03:20]                   │ debg Find.findByCssSelector('div.euiContextMenuPanel') with timeout=10000
[00:03:20]                   │ debg TestSubjects.click(sharePanel-PDFReports)
[00:03:20]                   │ debg Find.clickByCssSelector('[data-test-subj="sharePanel-PDFReports"]') with timeout=10000
[00:03:20]                   │ debg Find.findByCssSelector('[data-test-subj="sharePanel-PDFReports"]') with timeout=10000
[00:03:20]                   │ debg Find.waitForElementStale with timeout=10000
[00:03:20]                   │ debg TestSubjects.click(usePrintLayout)
[00:03:20]                   │ debg Find.clickByCssSelector('[data-test-subj="usePrintLayout"]') with timeout=10000
[00:03:20]                   │ debg Find.findByCssSelector('[data-test-subj="usePrintLayout"]') with timeout=10000
[00:03:20]                   │ debg TestSubjects.click(generateReportButton)
[00:03:20]                   │ debg Find.clickByCssSelector('[data-test-subj="generateReportButton"]') with timeout=10000
[00:03:20]                   │ debg Find.findByCssSelector('[data-test-subj="generateReportButton"]') with timeout=10000
[00:03:21]                   │ debg getReportURL
[00:03:21]                   │ debg TestSubjects.getAttribute(downloadCompletedReportButton, href)
[00:03:21]                   │ debg TestSubjects.find(downloadCompletedReportButton)
[00:03:21]                   │ debg Find.findByCssSelector('[data-test-subj="downloadCompletedReportButton"]') with timeout=60000
[00:03:21]                   │ info [o.e.c.m.MetaDataCreateIndexService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] [.reporting-2020.03.29] creating index, cause [api], templates [], shards [1]/[1], mappings [_doc]
[00:03:21]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] updating number_of_replicas to [0] for indices [.reporting-2020.03.29]
[00:03:21]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xl-1585591529310224482] Cluster health status changed from [YELLOW] to [GREEN] (reason: [shards started [[.reporting-2020.03.29][0]]]).
[00:03:21]                   │ proc [kibana]   log   [19:21:40.042] [info][queue-job][reporting] Successfully queued job: k8euz8tf1c3ycf8f91b00mbd
[00:03:23]                   │ proc [kibana]   log   [19:21:42.122] [info][esqueue][queue-worker][reporting] k8euuwp61c3ycf8f915jwmn8 - Job marked as claimed: /.reporting-2020.03.29/k8euz8tf1c3ycf8f91b00mbd
[00:03:23]                   │ proc [kibana]   log   [19:21:42.123] [info][esqueue][queue-worker][reporting] k8euuwp61c3ycf8f915jwmn8 - Starting job
[00:03:23]                   │ proc [kibana]   log   [19:21:42.168] [info][browser-driver][execute][k8euz8tf1c3ycf8f91b00mbd][printable_pdf][reporting] Creating browser page driver
[00:03:23]                   │ proc [kibana]   log   [19:21:42.295] [error][execute][k8euz8tf1c3ycf8f91b00mbd][printable_pdf][reporting] Error: Error spawning Chromium browser: [Error: Protocol error (Target.setDiscoverTargets): Target closed.]
[00:03:23]                   │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-6/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:124:24)
[00:03:23]                   │ proc [kibana]   log   [19:21:42.322] [error][error][esqueue][queue-worker][reporting] k8euuwp61c3ycf8f915jwmn8 - Failure occurred on job k8euz8tf1c3ycf8f91b00mbd: Error: Error spawning Chromium browser: [Error: Protocol error (Target.setDiscoverTargets): Target closed.]
[00:03:23]                   │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-6/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:124:24)
[00:03:23]                   │ proc [kibana]   log   [19:21:42.323] [warning][esqueue][queue-worker][reporting] k8euuwp61c3ycf8f915jwmn8 - Failing job k8euz8tf1c3ycf8f91b00mbd
[00:03:23]                   │ proc [kibana]  error  [19:21:42.341] [warning][process] UnhandledPromiseRejectionWarning: Error: Protocol error (Target.setDiscoverTargets): Target closed.
[00:03:23]                   │ proc [kibana]     at Promise (/dev/shm/workspace/install/kibana-6/node_modules/puppeteer-core/lib/Connection.js:74:56)
[00:03:23]                   │ proc [kibana]     at new Promise (<anonymous>)
[00:03:23]                   │ proc [kibana]     at Connection.send (/dev/shm/workspace/install/kibana-6/node_modules/puppeteer-core/lib/Connection.js:73:12)
[00:03:23]                   │ proc [kibana]     at Function.create (/dev/shm/workspace/install/kibana-6/node_modules/puppeteer-core/lib/Browser.js:34:22)
[00:03:23]                   │ proc [kibana]     at Launcher.launch (/dev/shm/workspace/install/kibana-6/node_modules/puppeteer-core/lib/Launcher.js:184:37)
[00:03:23]                   │ proc [kibana]     at module.exports.launch (/dev/shm/workspace/install/kibana-6/node_modules/puppeteer-core/lib/Puppeteer.js:37:27)
[00:03:23]                   │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-6/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:108:25)
[00:03:23]                   │ proc [kibana]     at Observable._trySubscribe (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/Observable.js:44:25)
[00:03:23]                   │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/Observable.js:30:22)
[00:03:23]                   │ proc [kibana]     at MergeMapOperator.call (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/operators/mergeMap.js:39:23)
[00:03:23]                   │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/Observable.js:25:31)
[00:03:23]                   │ proc [kibana]     at TakeOperator.call (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/operators/take.js:38:23)
[00:03:23]                   │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/Observable.js:25:31)
[00:03:23]                   │ proc [kibana]     at ThrowIfEmptyOperator.call (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/operators/throwIfEmpty.js:30:23)
[00:03:23]                   │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/Observable.js:25:31)
[00:03:23]                   │ proc [kibana]     at Object.subscribeToResult (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/util/subscribeToResult.js:12:23)
[00:03:23]                   │ proc [kibana]     at emitWarning (internal/process/promises.js:97:15)
[00:03:23]                   │ proc [kibana]     at emitPromiseRejectionWarnings (internal/process/promises.js:143:7)
[00:03:23]                   │ proc [kibana]     at process._tickCallback (internal/process/next_tick.js:69:34)
[00:03:23]                   │ proc [kibana]  error  [19:21:42.343] [warning][process] Error: Protocol error (Target.setDiscoverTargets): Target closed.
[00:03:23]                   │ proc [kibana]     at Promise (/dev/shm/workspace/install/kibana-6/node_modules/puppeteer-core/lib/Connection.js:74:56)
[00:03:23]                   │ proc [kibana]     at new Promise (<anonymous>)
[00:03:23]                   │ proc [kibana]     at Connection.send (/dev/shm/workspace/install/kibana-6/node_modules/puppeteer-core/lib/Connection.js:73:12)
[00:03:23]                   │ proc [kibana]     at Function.create (/dev/shm/workspace/install/kibana-6/node_modules/puppeteer-core/lib/Browser.js:34:22)
[00:03:23]                   │ proc [kibana]     at Launcher.launch (/dev/shm/workspace/install/kibana-6/node_modules/puppeteer-core/lib/Launcher.js:184:37)
[00:03:23]                   │ proc [kibana]     at module.exports.launch (/dev/shm/workspace/install/kibana-6/node_modules/puppeteer-core/lib/Puppeteer.js:37:27)
[00:03:23]                   │ proc [kibana]     at Observable.Rx.Observable.create [as _subscribe] (/dev/shm/workspace/install/kibana-6/x-pack/legacy/plugins/reporting/server/browsers/chromium/driver_factory/index.js:108:25)
[00:03:23]                   │ proc [kibana]     at Observable._trySubscribe (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/Observable.js:44:25)
[00:03:23]                   │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/Observable.js:30:22)
[00:03:23]                   │ proc [kibana]     at MergeMapOperator.call (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/operators/mergeMap.js:39:23)
[00:03:23]                   │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/Observable.js:25:31)
[00:03:23]                   │ proc [kibana]     at TakeOperator.call (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/operators/take.js:38:23)
[00:03:23]                   │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/Observable.js:25:31)
[00:03:23]                   │ proc [kibana]     at ThrowIfEmptyOperator.call (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/operators/throwIfEmpty.js:30:23)
[00:03:23]                   │ proc [kibana]     at Observable.subscribe (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/Observable.js:25:31)
[00:03:23]                   │ proc [kibana]     at Object.subscribeToResult (/dev/shm/workspace/install/kibana-6/node_modules/rxjs/internal/util/subscribeToResult.js:12:23)
[00:03:23]                   │ proc [kibana]   log   [19:21:42.354] [info][esqueue][queue-worker][reporting] k8euuwp61c3ycf8f915jwmn8 - Job marked as failed: /.reporting-2020.03.29/k8euz8tf1c3ycf8f91b00mbd
[00:04:22]                   │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="downloadCompletedReportButton"])
[00:04:22]                   │      Wait timed out after 61215ms
[00:04:22]                   │ debg TestSubjects.getAttribute(downloadCompletedReportButton, href)
[00:04:22]                   │ debg TestSubjects.find(downloadCompletedReportButton)
[00:04:22]                   │ debg Find.findByCssSelector('[data-test-subj="downloadCompletedReportButton"]') with timeout=60000
[00:05:23]                   │ debg --- retry.try error: Waiting for element to be located By(css selector, [data-test-subj="downloadCompletedReportButton"])
[00:05:23]                   │      Wait timed out after 61137ms
[00:05:24]                   │ info Taking screenshot "/dev/shm/workspace/kibana/x-pack/test/functional/screenshots/failure/reporting app Reporting Dashboard Print Layout downloads a PDF file.png"
[00:05:24]                   │ info Current URL is: http://localhost:6161/app/kibana#/dashboard/667a3fc0-72bb-11ea-8100-2f361124f759?_g=(filters:!(),refreshInterval:(pause:!t,value:0),time:(from:%272015-09-19T06:31:44.000Z%27,to:%272015-09-19T18:01:44.000Z%27))&_a=(description:%27%27,filters:!(),fullScreenMode:!f,options:(hidePanelTitles:!f,useMargins:!t),panels:!((embeddableConfig:(),gridData:(h:15,i:feb538aa-8a49-4067-b93e-5f8033559432,w:24,x:0,y:0),id:Visualization-PieChart,panelIndex:feb538aa-8a49-4067-b93e-5f8033559432,type:visualization,version:%278.0.0-SNAPSHOT%27),(embeddableConfig:(),gridData:(h:15,i:%2742c97cd6-1a60-45f7-b834-a0f7f102d5fb%27,w:24,x:24,y:0),id:Visualization%E2%98%BA-VerticalBarChart,panelIndex:%2742c97cd6-1a60-45f7-b834-a0f7f102d5fb%27,type:visualization,version:%278.0.0-SNAPSHOT%27),(embeddableConfig:(),gridData:(h:15,i:e01a297f-3aac-4e1c-ac41-2c954b6fa04f,w:24,x:0,y:15),id:Visualization%E6%BC%A2%E5%AD%97-AreaChart,panelIndex:e01a297f-3aac-4e1c-ac41-2c954b6fa04f,type:visualization,version:%278.0.0-SNAPSHOT%27),(embeddableConfig:(vis:(params:(sort:(columnIndex:!n,direction:!n)))),gridData:(h:15,i:%27543762fd-3f37-4d46-92c2-c5218b06035b%27,w:24,x:24,y:15),id:Visualization%E2%98%BA%E6%BC%A2%E5%AD%97-DataTable,panelIndex:%27543762fd-3f37-4d46-92c2-c5218b06035b%27,type:visualization,version:%278.0.0-SNAPSHOT%27),(embeddableConfig:(),gridData:(h:15,i:%276f385ac4-0f64-4c87-9b4c-feaed3638c91%27,w:24,x:0,y:30),id:Visualization%E6%BC%A2%E5%AD%97-LineChart,panelIndex:%276f385ac4-0f64-4c87-9b4c-feaed3638c91%27,type:visualization,version:%278.0.0-SNAPSHOT%27),(embeddableConfig:(),gridData:(h:15,i:%2712072e72-11d6-4b6a-a8cd-c51084857d19%27,w:24,x:24,y:30),id:Visualization-MetricChart,panelIndex:%2712072e72-11d6-4b6a-a8cd-c51084857d19%27,type:visualization,version:%278.0.0-SNAPSHOT%27)),query:(language:kuery,query:%27%27),timeRestore:!f,title:%27report%20test%27,viewMode:view)
[00:05:24]                   │ info Saving page source to: /dev/shm/workspace/kibana/x-pack/test/reporting/configs/failure_debug/html/reporting app Reporting Dashboard Print Layout downloads a PDF file.html
[00:05:24]                   └- ✖ fail: "reporting app Reporting Dashboard Print Layout downloads a PDF file"
[00:05:24]                   │

Stack Trace

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

and 1 more failures, only showing the first 3.

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

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

Successfully merging this pull request may close these issues.

2 participants