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

[7.x] [ML] Adds a11y tests for ML plugin (#88323) #88490

Closed

Conversation

peteharverson
Copy link
Contributor

Backports the following commits to 7.x:

* [ML] Adds a11y tests for ML plugin

* [ML] Edits to a11y tests following review
@elasticmachine
Copy link
Contributor

Pinging @elastic/ml-ui (:ml)

@peteharverson peteharverson self-assigned this Jan 15, 2021
@peteharverson
Copy link
Contributor Author

@elasticmachine merge upstream

@kibanamachine
Copy link
Contributor

kibanamachine commented Jan 18, 2021

💔 Build Failed

Failed CI Steps


Test Failures

X-Pack Accessibility Tests.x-pack/test/accessibility/apps/ml·ts.ml for user with full ML access with data loaded anomaly detection create single metric job pick fields step

Link to Jenkins

Standard Out

Failed Tests Reporter:
  - Test has failed 1 times on tracked branches: https://dryrun

[00:00:00]       │
[00:04:15]         └-: ml
[00:04:15]           └-> "before all" hook
[00:04:15]           └-> "before all" hook
[00:04:15]             │ debg creating role ft_ml_source
[00:04:15]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_ml_source]
[00:04:15]             │ debg creating role ft_ml_source_readonly
[00:04:15]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_ml_source_readonly]
[00:04:15]             │ debg creating role ft_ml_dest
[00:04:15]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_ml_dest]
[00:04:15]             │ debg creating role ft_ml_dest_readonly
[00:04:15]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_ml_dest_readonly]
[00:04:15]             │ debg creating role ft_ml_ui_extras
[00:04:15]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_ml_ui_extras]
[00:04:15]             │ debg creating role ft_default_space_ml_all
[00:04:15]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_default_space_ml_all]
[00:04:15]             │ debg creating role ft_default_space1_ml_all
[00:04:15]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_default_space1_ml_all]
[00:04:15]             │ debg creating role ft_all_spaces_ml_all
[00:04:15]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_all_spaces_ml_all]
[00:04:15]             │ debg creating role ft_default_space_ml_read
[00:04:16]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_default_space_ml_read]
[00:04:16]             │ debg creating role ft_default_space1_ml_read
[00:04:16]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_default_space1_ml_read]
[00:04:16]             │ debg creating role ft_all_spaces_ml_read
[00:04:16]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_all_spaces_ml_read]
[00:04:16]             │ debg creating role ft_default_space_ml_none
[00:04:16]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_default_space_ml_none]
[00:04:16]             │ debg creating user ft_ml_poweruser
[00:04:16]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_poweruser]
[00:04:16]             │ debg created user ft_ml_poweruser
[00:04:16]             │ debg creating user ft_ml_poweruser_spaces
[00:04:16]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_poweruser_spaces]
[00:04:16]             │ debg created user ft_ml_poweruser_spaces
[00:04:16]             │ debg creating user ft_ml_poweruser_space1
[00:04:16]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_poweruser_space1]
[00:04:16]             │ debg created user ft_ml_poweruser_space1
[00:04:16]             │ debg creating user ft_ml_poweruser_all_spaces
[00:04:16]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_poweruser_all_spaces]
[00:04:16]             │ debg created user ft_ml_poweruser_all_spaces
[00:04:16]             │ debg creating user ft_ml_viewer
[00:04:16]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_viewer]
[00:04:16]             │ debg created user ft_ml_viewer
[00:04:16]             │ debg creating user ft_ml_viewer_spaces
[00:04:16]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_viewer_spaces]
[00:04:16]             │ debg created user ft_ml_viewer_spaces
[00:04:16]             │ debg creating user ft_ml_viewer_space1
[00:04:16]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_viewer_space1]
[00:04:16]             │ debg created user ft_ml_viewer_space1
[00:04:16]             │ debg creating user ft_ml_viewer_all_spaces
[00:04:17]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_viewer_all_spaces]
[00:04:17]             │ debg created user ft_ml_viewer_all_spaces
[00:04:17]             │ debg creating user ft_ml_unauthorized
[00:04:17]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_unauthorized]
[00:04:17]             │ debg created user ft_ml_unauthorized
[00:04:17]             │ debg creating user ft_ml_unauthorized_spaces
[00:04:17]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_unauthorized_spaces]
[00:04:17]             │ debg created user ft_ml_unauthorized_spaces
[00:04:17]           └-: for user with full ML access
[00:04:17]             └-> "before all" hook
[00:04:17]             └-> "before all" hook
[00:04:17]               │ debg SecurityPage.forceLogout
[00:04:17]               │ debg Find.existsByDisplayedByCssSelector('.login-form') with timeout=100
[00:04:17]               │ debg --- retry.tryForTime error: .login-form is not displayed
[00:04:17]               │ debg Redirecting to /logout to force the logout
[00:04:18]               │ debg Waiting on the login form to appear
[00:04:18]               │ debg Waiting for Login Page to appear.
[00:04:18]               │ debg Waiting up to 100000ms for login page...
[00:04:18]               │ debg browser[INFO] http://localhost:6121/logout?_t=1610973138262 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:04:18]               │
[00:04:18]               │ debg browser[INFO] http://localhost:6121/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:04:18]               │ debg Find.existsByDisplayedByCssSelector('.login-form') with timeout=2500
[00:04:20]               │ debg browser[INFO] http://localhost:6121/login?msg=LOGGED_OUT 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:04:20]               │
[00:04:20]               │ debg browser[INFO] http://localhost:6121/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:04:20]               │ERROR browser[SEVERE] http://localhost:6121/internal/spaces/_active_space - Failed to load resource: the server responded with a status of 401 (Unauthorized)
[00:04:20]               │ debg --- retry.tryForTime error: .login-form is not displayed
[00:04:20]               │ debg browser[INFO] http://localhost:6121/38009/bundles/core/core.entry.js 12:196200 "Detected an unhandled Promise rejection.
[00:04:20]               │      Error: Unauthorized"
[00:04:20]               │ERROR browser[SEVERE] http://localhost:6121/38009/bundles/core/core.entry.js 5:3002 
[00:04:21]               │ debg Find.existsByDisplayedByCssSelector('.login-form') with timeout=2500
[00:04:21]               │ debg TestSubjects.exists(loginForm)
[00:04:21]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="loginForm"]') with timeout=2500
[00:04:21]               │ debg Waiting for Login Form to appear.
[00:04:21]               │ debg Waiting up to 100000ms for login form...
[00:04:21]               │ debg TestSubjects.exists(loginForm)
[00:04:21]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="loginForm"]') with timeout=2500
[00:04:21]               │ debg TestSubjects.setValue(loginUsername, ft_ml_poweruser)
[00:04:21]               │ debg TestSubjects.click(loginUsername)
[00:04:21]               │ debg Find.clickByCssSelector('[data-test-subj="loginUsername"]') with timeout=10000
[00:04:21]               │ debg Find.findByCssSelector('[data-test-subj="loginUsername"]') with timeout=10000
[00:04:22]               │ debg TestSubjects.setValue(loginPassword, mlp001)
[00:04:22]               │ debg TestSubjects.click(loginPassword)
[00:04:22]               │ debg Find.clickByCssSelector('[data-test-subj="loginPassword"]') with timeout=10000
[00:04:22]               │ debg Find.findByCssSelector('[data-test-subj="loginPassword"]') with timeout=10000
[00:04:22]               │ debg TestSubjects.click(loginSubmit)
[00:04:22]               │ debg Find.clickByCssSelector('[data-test-subj="loginSubmit"]') with timeout=10000
[00:04:22]               │ debg Find.findByCssSelector('[data-test-subj="loginSubmit"]') with timeout=10000
[00:04:22]               │ debg Waiting for login result, expected: chrome.
[00:04:22]               │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"] .app-wrapper:not(.hidden-chrome)') with timeout=20000
[00:04:22]               │ proc [kibana]   log   [12:32:22.622] [info][plugins][routes][security] Logging in with provider "basic" (basic)
[00:04:26]               │ debg browser[INFO] http://localhost:6121/app/home 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:04:26]               │
[00:04:26]               │ debg browser[INFO] http://localhost:6121/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:04:26]               │ debg Finished login process currentUrl = http://localhost:6121/app/home#/
[00:04:26]               │ debg Waiting up to 20000ms for logout button visible...
[00:04:26]               │ debg TestSubjects.exists(userMenuButton)
[00:04:26]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenuButton"]') with timeout=2500
[00:04:26]               │ debg TestSubjects.exists(userMenu)
[00:04:26]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenu"]') with timeout=2500
[00:04:29]               │ debg --- retry.tryForTime error: [data-test-subj="userMenu"] is not displayed
[00:04:29]               │ debg TestSubjects.click(userMenuButton)
[00:04:29]               │ debg Find.clickByCssSelector('[data-test-subj="userMenuButton"]') with timeout=10000
[00:04:29]               │ debg Find.findByCssSelector('[data-test-subj="userMenuButton"]') with timeout=10000
[00:04:30]               │ debg TestSubjects.exists(userMenu)
[00:04:30]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenu"]') with timeout=120000
[00:04:30]               │ debg TestSubjects.exists(userMenu > logoutLink)
[00:04:30]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenu"] [data-test-subj="logoutLink"]') with timeout=2500
[00:04:30]               │ debg Deleting indices: '.ml-*'...
[00:04:30]               │ debg Indices '.ml-*' don't exist. Nothing to delete.
[00:04:39]             └-: with data loaded
[00:04:39]               └-> "before all" hook
[00:04:39]               └-> "before all" hook
[00:04:39]                 │ info [ml/farequote] Loading "mappings.json"
[00:04:39]                 │ info [ml/farequote] Loading "data.json.gz"
[00:04:39]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [ft_farequote] creating index, cause [api], templates [], shards [1]/[1]
[00:04:39]                 │ info [ml/farequote] Created index "ft_farequote"
[00:04:39]                 │ debg [ml/farequote] "ft_farequote" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:04:47]                 │ info [ml/farequote] Indexed 86274 docs into "ft_farequote"
[00:04:47]                 │ info [ml/ihp_outlier] Loading "mappings.json"
[00:04:47]                 │ info [ml/ihp_outlier] Loading "data.json.gz"
[00:04:47]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [ft_ihp_outlier] creating index, cause [api], templates [], shards [1]/[1]
[00:04:47]                 │ info [ml/ihp_outlier] Created index "ft_ihp_outlier"
[00:04:47]                 │ debg [ml/ihp_outlier] "ft_ihp_outlier" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:04:47]                 │ info [ml/ihp_outlier] Indexed 1460 docs into "ft_ihp_outlier"
[00:04:47]                 │ info [ml/module_sample_ecommerce] Loading "mappings.json"
[00:04:47]                 │ info [ml/module_sample_ecommerce] Loading "data.json.gz"
[00:04:47]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [ft_module_sample_ecommerce] creating index, cause [api], templates [], shards [1]/[1]
[00:04:47]                 │ info [ml/module_sample_ecommerce] Created index "ft_module_sample_ecommerce"
[00:04:47]                 │ debg [ml/module_sample_ecommerce] "ft_module_sample_ecommerce" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:04:47]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [ft_module_sample_ecommerce/pmkO54CwR1O0qm77OZf9eQ] update_mapping [_doc]
[00:04:47]                 │ info [ml/module_sample_ecommerce] Indexed 287 docs into "ft_module_sample_ecommerce"
[00:04:47]                 │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:04:47]                 │ debg  > Not found
[00:04:47]                 │ debg Creating index pattern with title 'ft_farequote' and time field '@timestamp'
[00:04:47]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.kibana_2/qqw-iO8iSxS6u4fXNMNwrQ] update_mapping [_doc]
[00:04:47]                 │ debg Waiting up to 5000ms for index-pattern with title 'ft_farequote' to exist...
[00:04:47]                 │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:04:47]                 │ debg  > Found '45c2fb90-5989-11eb-85ae-3d0279aa4f9d'
[00:04:47]                 │ debg  > Created with id '45c2fb90-5989-11eb-85ae-3d0279aa4f9d'
[00:04:47]                 │ debg Searching for 'index-pattern' with title 'ft_ihp_outlier'...
[00:04:47]                 │ debg  > Not found
[00:04:47]                 │ debg Creating index pattern with title 'ft_ihp_outlier' and time field '@timestamp'
[00:04:48]                 │ debg Waiting up to 5000ms for index-pattern with title 'ft_ihp_outlier' to exist...
[00:04:48]                 │ debg Searching for 'index-pattern' with title 'ft_ihp_outlier'...
[00:04:48]                 │ debg  > Found '45dff970-5989-11eb-85ae-3d0279aa4f9d'
[00:04:48]                 │ debg  > Created with id '45dff970-5989-11eb-85ae-3d0279aa4f9d'
[00:04:48]                 │ debg Searching for 'index-pattern' with title 'ft_module_sample_ecommerce'...
[00:04:48]                 │ debg  > Not found
[00:04:48]                 │ debg Creating index pattern with title 'ft_module_sample_ecommerce' and time field 'order_date'
[00:04:49]                 │ debg Waiting up to 5000ms for index-pattern with title 'ft_module_sample_ecommerce' to exist...
[00:04:49]                 │ debg Searching for 'index-pattern' with title 'ft_module_sample_ecommerce'...
[00:04:49]                 │ debg  > Found '46781ac0-5989-11eb-85ae-3d0279aa4f9d'
[00:04:49]                 │ debg  > Created with id '46781ac0-5989-11eb-85ae-3d0279aa4f9d'
[00:04:49]                 │ debg applying update to kibana config: {"dateFormat:tz":"UTC"}
[00:04:50]                 │ debg Creating anomaly detection job with id 'fq_single_a11y' ...
[00:04:50]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-anomalies-shared] creating index, cause [api], templates [.ml-anomalies-], shards [1]/[1]
[00:04:50]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-anomalies-shared]
[00:04:51]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-annotations-6] creating index, cause [api], templates [], shards [1]/[1]
[00:04:51]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-annotations-6]
[00:04:51]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-anomalies-shared/Q_Yu1HbvQWKF0GgfuO_IBQ] update_mapping [_doc]
[00:04:51]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-config] creating index, cause [auto(bulk api)], templates [.ml-config], shards [1]/[1]
[00:04:51]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-config]
[00:04:51]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-notifications-000001] creating index, cause [auto(bulk api)], templates [.ml-notifications-000001], shards [1]/[1]
[00:04:51]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-notifications-000001]
[00:04:51]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.kibana_2/qqw-iO8iSxS6u4fXNMNwrQ] update_mapping [_doc]
[00:04:51]                 │ debg Waiting up to 5000ms for 'fq_single_a11y' to exist...
[00:04:52]                 │ debg > AD job created.
[00:04:52]                 │ debg Creating datafeed with id 'datafeed-fq_single_a11y' ...
[00:04:52]                 │ debg Waiting up to 5000ms for 'datafeed-fq_single_a11y' to exist...
[00:04:52]                 │ debg > Datafeed created.
[00:04:52]                 │ debg Opening anomaly detection job 'fq_single_a11y'...
[00:04:53]                 │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] Opening job [fq_single_a11y]
[00:04:53]                 │ info [o.e.x.c.m.u.MlIndexAndAlias] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] About to create first concrete index [.ml-state-000001] with alias [.ml-state-write]
[00:04:53]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-state-000001] creating index, cause [api], templates [.ml-state], shards [1]/[1]
[00:04:53]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-state-000001]
[00:04:53]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-state-000001] from [null] to [{"phase":"new","action":"complete","name":"complete"}] in policy [ml-size-based-ilm-policy]
[00:04:53]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-state-000001] from [{"phase":"new","action":"complete","name":"complete"}] to [{"phase":"hot","action":"unfollow","name":"wait-for-indexing-complete"}] in policy [ml-size-based-ilm-policy]
[00:04:53]                 │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] Loading model snapshot [N/A], job latest_record_timestamp [N/A]
[00:04:53]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-state-000001] from [{"phase":"hot","action":"unfollow","name":"wait-for-indexing-complete"}] to [{"phase":"hot","action":"unfollow","name":"wait-for-follow-shard-tasks"}] in policy [ml-size-based-ilm-policy]
[00:04:53]                 │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] [autodetect/163283] [CResourceMonitor.cc@77] Setting model memory limit to 20 MB
[00:04:53]                 │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] Successfully set job state to [opened] for job [fq_single_a11y]
[00:04:53]                 │ debg > AD job opened.
[00:04:53]                 │ debg Starting datafeed 'datafeed-fq_single_a11y' with start: '0', end: '1610973173888'...
[00:04:53]                 │ debg > Datafeed started.
[00:04:53]                 │ debg Waiting up to 120000ms for datafeed state to be stopped...
[00:04:53]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:53]                 │ info [o.e.x.m.d.DatafeedJob] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] Datafeed started (from: 1970-01-01T00:00:00.000Z to: 2021-01-18T12:32:53.888Z) with frequency [600000ms]
[00:04:53]                 │ debg --- retry.waitForWithTimeout error: expected job state to be stopped but got started
[00:04:53]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-anomalies-shared/Q_Yu1HbvQWKF0GgfuO_IBQ] update_mapping [_doc]
[00:04:54]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 10000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:04:54]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:54]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:54]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:54]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:54]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 20000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:04:55]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:55]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:55]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 30000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:04:55]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:55]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 40000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:04:55]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:56]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 50000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:04:56]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:56]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:56]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:56]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:57]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 60000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:04:57]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:57]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:57]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 70000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:04:57]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:57]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:57]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 80000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:04:58]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:58]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:58]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:58]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:59]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:59]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:04:59]                 │ info [o.e.x.m.d.DatafeedJob] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] Lookback has finished
[00:04:59]                 │ info [o.e.x.m.d.DatafeedManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [no_realtime] attempt to stop datafeed [datafeed-fq_single_a11y] for job [fq_single_a11y]
[00:04:59]                 │ info [o.e.x.m.d.DatafeedManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [no_realtime] try lock [20s] to stop datafeed [datafeed-fq_single_a11y] for job [fq_single_a11y]...
[00:04:59]                 │ info [o.e.x.m.d.DatafeedManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [no_realtime] stopping datafeed [datafeed-fq_single_a11y] for job [fq_single_a11y], acquired [true]...
[00:04:59]                 │ info [o.e.x.m.d.DatafeedManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [no_realtime] datafeed [datafeed-fq_single_a11y] for job [fq_single_a11y] has been stopped
[00:04:59]                 │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] Closing job [fq_single_a11y], because [close job (api)]
[00:04:59]                 │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] [autodetect/163283] [CCmdSkeleton.cc@61] Handled 86274 records
[00:04:59]                 │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] [autodetect/163283] [CAnomalyJob.cc@1569] Pruning all models
[00:04:59]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-anomalies-shared/Q_Yu1HbvQWKF0GgfuO_IBQ] update_mapping [_doc]
[00:04:59]                 │ info [o.e.x.m.p.AbstractNativeProcess] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] State output finished
[00:04:59]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:04:59]                 │ debg Waiting up to 120000ms for job state to be closed...
[00:04:59]                 │ debg Fetching anomaly detection job stats for job fq_single_a11y...
[00:04:59]                 │ debg > AD job stats fetched.
[00:04:59]                 │ debg --- retry.waitForWithTimeout error: expected job state to be closed but got closing
[00:04:59]                 │ info [o.e.x.m.j.p.a.o.AutodetectResultProcessor] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 120 buckets parsed from autodetect output
[00:05:00]                 │ debg Fetching anomaly detection job stats for job fq_single_a11y...
[00:05:00]                 │ debg > AD job stats fetched.
[00:05:00]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:00]                 │ debg Fetching anomaly detection job stats for job fq_single_a11y...
[00:05:01]                 │ debg > AD job stats fetched.
[00:05:01]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:01]                 │ info [o.e.x.m.j.p.a.AutodetectCommunicator] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] job closed
[00:05:01]                 │ debg Fetching anomaly detection job stats for job fq_single_a11y...
[00:05:01]                 │ debg > AD job stats fetched.
[00:05:01]                 │ debg Creating data frame analytic job with id 'iph_outlier_a11y' ...
[00:05:01]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-config/5Xc9Wu5ST0iV1hBBEGAchA] update_mapping [_doc]
[00:05:01]                 │ debg Waiting up to 5000ms for 'iph_outlier_a11y' to exist...
[00:05:01]                 │ debg Fetching data frame analytics job 'iph_outlier_a11y'...
[00:05:01]                 │ debg > DFA job fetched.
[00:05:01]                 │ debg > DFA job created.
[00:05:01]                 │ debg Starting data frame analytics job 'iph_outlier_a11y'...
[00:05:02]                 │ info [o.e.x.m.a.TransportStartDataFrameAnalyticsAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Starting data frame analytics from state [stopped]
[00:05:02]                 │ debg > DFA job started.
[00:05:02]                 │ debg Waiting up to 60000ms for 'iph_outlier_a11y' to have training_docs_count > 0...
[00:05:02]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:02]                 │ info [o.e.x.c.m.u.MlIndexAndAlias] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] About to create first concrete index [.ml-stats-000001] with alias [.ml-stats-write]
[00:05:02]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-stats-000001] creating index, cause [api], templates [.ml-stats], shards [1]/[1]
[00:05:02]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-stats-000001]
[00:05:02]                 │ debg > DFA job stats fetched.
[00:05:02]                 │ debg --- retry.waitForWithTimeout error: expected data frame analytics job 'iph_outlier_a11y' to have training_docs_count > 0 (got 0)
[00:05:02]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-stats-000001] from [null] to [{"phase":"new","action":"complete","name":"complete"}] in policy [ml-size-based-ilm-policy]
[00:05:02]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-stats-000001] from [{"phase":"new","action":"complete","name":"complete"}] to [{"phase":"hot","action":"unfollow","name":"wait-for-indexing-complete"}] in policy [ml-size-based-ilm-policy]
[00:05:02]                 │ info [o.e.x.m.d.s.ReindexingStep] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Creating destination index [user-iph_outlier_a11y]
[00:05:02]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [user-iph_outlier_a11y] creating index, cause [api], templates [], shards [1]/[1]
[00:05:02]                 │ info [o.e.x.m.d.s.ReindexingStep] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Started reindexing
[00:05:02]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-stats-000001] from [{"phase":"hot","action":"unfollow","name":"wait-for-indexing-complete"}] to [{"phase":"hot","action":"unfollow","name":"wait-for-follow-shard-tasks"}] in policy [ml-size-based-ilm-policy]
[00:05:03]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:03]                 │ debg > DFA job stats fetched.
[00:05:03]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:03]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Started loading data
[00:05:03]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Started analyzing
[00:05:03]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Waiting for result processor to complete
[00:05:03]                 │ info [o.e.x.m.d.p.AnalyticsResultProcessor] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Started writing results
[00:05:03]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:03]                 │ debg > DFA job stats fetched.
[00:05:03]                 │ debg Waiting up to 120000ms for analytics state to be stopped...
[00:05:03]                 │ debg Fetching analytics state for job iph_outlier_a11y
[00:05:03]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:03]                 │ debg > DFA job stats fetched.
[00:05:03]                 │ debg --- retry.waitForWithTimeout error: expected analytics state to be stopped but got started
[00:05:03]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Result processor has completed
[00:05:03]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Closing process
[00:05:03]                 │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] [data_frame_analyzer/163764] [Main.cc@241] [{"name":"E_DFOEstimatedPeakMemoryUsage","description":"The upfront estimate of the peak memory outlier detection would use","value":962304}
[00:05:03]                 │      ,{"name":"E_DFOPeakMemoryUsage","description":"The peak memory outlier detection used","value":639352}
[00:05:03]                 │      ,{"name":"E_DFOTimeToCreateEnsemble","description":"The time it took to create the ensemble used for outlier detection","value":28}
[00:05:03]                 │      ,{"name":"E_DFOTimeToComputeScores","description":"The time it took to compute outlier scores","value":84}
[00:05:03]                 │      ,{"name":"E_DFONumberPartitions","description":"The number of partitions outlier detection used","value":1}
[00:05:03]                 │      ]
[00:05:03]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Closed process
[00:05:03]                 │ info [o.e.x.m.d.DataFrameAnalyticsManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Marking task completed
[00:05:04]                 │ debg Fetching analytics state for job iph_outlier_a11y
[00:05:04]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:04]                 │ debg > DFA job stats fetched.
[00:05:04]                 │ debg Creating calendar with id 'calendar_a11y'...
[00:05:04]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-meta] creating index, cause [auto(bulk api)], templates [.ml-meta], shards [1]/[1]
[00:05:04]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-meta]
[00:05:04]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-meta/zCjGF58ASr--uNeGheFkIg] update_mapping [_doc]
[00:05:04]                 │ debg Waiting up to 5000ms for 'calendar_a11y' to exist...
[00:05:04]                 │ debg > Calendar created.
[00:05:04]                 │ debg Creating events for calendar with id 'calendar_a11y'...
[00:05:04]                 │ debg Waiting up to 5000ms for 'calendar_a11y' events to exist...
[00:05:04]                 │ debg > Calendar events created.
[00:05:04]                 │ debg Creating filter with id 'filter_a11y'...
[00:05:04]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-meta/zCjGF58ASr--uNeGheFkIg] update_mapping [_doc]
[00:05:04]                 │ debg Waiting up to 5000ms for 'filter_a11y' to exist...
[00:05:04]                 │ debg > Filter created.
[00:05:04]               └-> overview page
[00:05:04]                 └-> "before each" hook: global before each
[00:05:04]                 │ debg navigating to ml url: http://localhost:6121/app/ml
[00:05:04]                 │ debg navigate to: http://localhost:6121/app/ml
[00:05:04]                 │ debg browser[INFO] http://localhost:6121/app/ml?_t=1610973184657 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:05:04]                 │
[00:05:04]                 │ debg browser[INFO] http://localhost:6121/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:05:04]                 │ debg ... sleep(700) start
[00:05:05]                 │ debg ... sleep(700) end
[00:05:05]                 │ debg returned from get, calling refresh
[00:05:05]                 │ debg browser[INFO] http://localhost:6121/app/ml?_t=1610973184657 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:05:05]                 │
[00:05:05]                 │ debg browser[INFO] http://localhost:6121/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:05:05]                 │ debg currentUrl = http://localhost:6121/app/ml
[00:05:05]                 │          appUrl = http://localhost:6121/app/ml
[00:05:05]                 │ debg TestSubjects.find(kibanaChrome)
[00:05:05]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:05:07]                 │ debg ... sleep(501) start
[00:05:08]                 │ debg ... sleep(501) end
[00:05:08]                 │ debg in navigateTo url = http://localhost:6121/app/ml/overview
[00:05:08]                 │ debg --- retry.try error: URL changed, waiting for it to settle
[00:05:08]                 │ debg ... sleep(501) start
[00:05:09]                 │ debg ... sleep(501) end
[00:05:09]                 │ debg in navigateTo url = http://localhost:6121/app/ml/overview
[00:05:09]                 │ debg TestSubjects.exists(statusPageContainer)
[00:05:09]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:05:11]                 │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:05:12]                 │ debg TestSubjects.exists(mlApp)
[00:05:12]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlApp"]') with timeout=2000
[00:05:12]                 │ debg TestSubjects.click(~mlMainTab & ~overview)
[00:05:12]                 │ debg Find.clickByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="overview"]') with timeout=10000
[00:05:12]                 │ debg Find.findByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="overview"]') with timeout=10000
[00:05:12]                 │ debg TestSubjects.exists(~mlMainTab & ~overview & ~selected)
[00:05:12]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="overview"][data-test-subj~="selected"]') with timeout=120000
[00:05:12]                 │ debg TestSubjects.exists(mlPageOverview)
[00:05:12]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageOverview"]') with timeout=120000
[00:05:13]                 └- ✓ pass  (9.1s) "ml for user with full ML access with data loaded overview page"
[00:05:13]               └-> anomaly detection jobs list page
[00:05:13]                 └-> "before each" hook: global before each
[00:05:13]                 │ debg TestSubjects.click(~mlMainTab & ~anomalyDetection)
[00:05:13]                 │ debg Find.clickByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"]') with timeout=10000
[00:05:13]                 │ debg Find.findByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"]') with timeout=10000
[00:05:13]                 │ debg TestSubjects.exists(~mlMainTab & ~anomalyDetection & ~selected)
[00:05:13]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"][data-test-subj~="selected"]') with timeout=120000
[00:05:13]                 │ debg TestSubjects.exists(mlPageJobManagement)
[00:05:13]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageJobManagement"]') with timeout=120000
[00:05:14]                 └- ✓ pass  (835ms) "ml for user with full ML access with data loaded anomaly detection jobs list page"
[00:05:14]               └-> anomaly detection create job select index pattern page
[00:05:14]                 └-> "before each" hook: global before each
[00:05:14]                 │ debg TestSubjects.clickWhenNotDisabled(mlCreateNewJobButton)
[00:05:14]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlCreateNewJobButton"]') with timeout=10000
[00:05:14]                 │ debg Find.findByCssSelector('[data-test-subj="mlCreateNewJobButton"]') with timeout=10000
[00:05:14]                 │ debg TestSubjects.exists(mlPageSourceSelection)
[00:05:14]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageSourceSelection"]') with timeout=120000
[00:05:15]                 └- ✓ pass  (685ms) "ml for user with full ML access with data loaded anomaly detection create job select index pattern page"
[00:05:15]               └-> anomaly detection create job select type page
[00:05:15]                 └-> "before each" hook: global before each
[00:05:15]                 │ debg TestSubjects.setValue(savedObjectFinderSearchInput, ft_farequote)
[00:05:15]                 │ debg TestSubjects.click(savedObjectFinderSearchInput)
[00:05:15]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:05:15]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:05:15]                 │ debg TestSubjects.exists(savedObjectTitleft_farequote)
[00:05:15]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectTitleft_farequote"]') with timeout=120000
[00:05:15]                 │ debg TestSubjects.clickWhenNotDisabled(savedObjectTitleft_farequote)
[00:05:15]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="savedObjectTitleft_farequote"]') with timeout=10000
[00:05:15]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitleft_farequote"]') with timeout=10000
[00:05:15]                 │ debg TestSubjects.exists(mlPageJobTypeSelection)
[00:05:15]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageJobTypeSelection"]') with timeout=10000
[00:05:16]                 └- ✓ pass  (1.6s) "ml for user with full ML access with data loaded anomaly detection create job select type page"
[00:05:16]               └-> anomaly detection create single metric job time range step
[00:05:16]                 └-> "before each" hook: global before each
[00:05:16]                 │ debg TestSubjects.clickWhenNotDisabled(mlJobTypeLinkSingleMetricJob)
[00:05:16]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobTypeLinkSingleMetricJob"]') with timeout=10000
[00:05:16]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobTypeLinkSingleMetricJob"]') with timeout=10000
[00:05:16]                 │ debg TestSubjects.exists(mlPageJobWizard single_metric)
[00:05:16]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageJobWizard single_metric"]') with timeout=120000
[00:05:17]                 │ debg === TEST STEP === job creation set the time range
[00:05:17]                 │ debg TestSubjects.clickWhenNotDisabled(mlButtonUseFullData)
[00:05:17]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlButtonUseFullData"]') with timeout=10000
[00:05:17]                 │ debg Find.findByCssSelector('[data-test-subj="mlButtonUseFullData"]') with timeout=10000
[00:05:17]                 │ debg TestSubjects.find(mlJobWizardDateRange)
[00:05:17]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardDateRange"]') with timeout=10000
[00:05:18]                 └- ✓ pass  (1.6s) "ml for user with full ML access with data loaded anomaly detection create single metric job time range step"
[00:05:18]               └-> anomaly detection create single metric job pick fields step
[00:05:18]                 └-> "before each" hook: global before each
[00:05:18]                 │ debg TestSubjects.exists(mlJobWizardNavButtonNext)
[00:05:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=120000
[00:05:18]                 │ debg TestSubjects.clickWhenNotDisabled(mlJobWizardNavButtonNext)
[00:05:18]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:05:18]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:05:18]                 │ debg TestSubjects.exists(mlJobWizardStepTitlePickFields)
[00:05:18]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardStepTitlePickFields"]') with timeout=5000
[00:05:18]                 │ debg === TEST STEP === job creation selects field and aggregation
[00:05:18]                 │ debg comboBox.set, comboBoxSelector: mlJobWizardAggSelection > comboBoxInput
[00:05:18]                 │ debg TestSubjects.find(mlJobWizardAggSelection > comboBoxInput)
[00:05:18]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardAggSelection"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:05:18]                 │ debg comboBox.setElement, value: Mean(responsetime)
[00:05:18]                 │ debg comboBox.isOptionSelected, value: Mean(responsetime)
[00:05:21]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:05:21]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:05:21]                 │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="Mean(responsetime)"]') with timeout=2500
[00:05:21]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:05:21]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:05:23]                 │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:05:24]                 │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlJobWizardAggSelection > comboBoxInput
[00:05:24]                 │ debg TestSubjects.find(mlJobWizardAggSelection > comboBoxInput)
[00:05:24]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardAggSelection"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:05:24]                 │ debg === TEST STEP === job creation inputs the bucket span
[00:05:24]                 │ debg TestSubjects.setValueWithChecks(mlJobWizardInputBucketSpan, 30m)
[00:05:24]                 │ debg TestSubjects.click(mlJobWizardInputBucketSpan)
[00:05:24]                 │ debg Find.clickByCssSelector('[data-test-subj="mlJobWizardInputBucketSpan"]') with timeout=10000
[00:05:24]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardInputBucketSpan"]') with timeout=10000
[00:05:25]                 │ debg TestSubjects.getAttribute(mlJobWizardInputBucketSpan, value, tryTimeout=120000, findTimeout=10000)
[00:05:25]                 │ debg TestSubjects.find(mlJobWizardInputBucketSpan)
[00:05:25]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardInputBucketSpan"]') with timeout=10000
[00:05:25]                 │ info Taking screenshot "/dev/shm/workspace/parallel/2/kibana/x-pack/test/functional/screenshots/failure/ml for user with full ML access with data loaded anomaly detection create single metric job pick fields step.png"
[00:05:25]                 │ info Current URL is: http://localhost:6121/app/ml/jobs/new_job/single_metric?index=45c2fb90-5989-11eb-85ae-3d0279aa4f9d
[00:05:25]                 │ info Saving page source to: /dev/shm/workspace/parallel/2/kibana/x-pack/test/functional/failure_debug/html/ml for user with full ML access with data loaded anomaly detection create single metric job pick fields step.html
[00:05:25]                 └- ✖ fail: ml for user with full ML access with data loaded anomaly detection create single metric job pick fields step
[00:05:25]                 │      Error: a11y report:
[00:05:25]                 │ 
[00:05:25]                 │ VIOLATION
[00:05:25]                 │   [label]: Ensures every form element has a label
[00:05:25]                 │     Help: https://dequeuniversity.com/rules/axe/4.0/label?application=axeAPI
[00:05:25]                 │     Elements:
[00:05:25]                 │       - #i581b7c41-5989-11eb-88ca-394aec5ba071
[00:05:25]                 │       - .euiFieldText
[00:05:25]                 │       at Accessibility.testAxeReport (/dev/shm/workspace/parallel/2/kibana/test/accessibility/services/a11y/a11y.ts:91:15)
[00:05:25]                 │       at Accessibility.testAppSnapshot (/dev/shm/workspace/parallel/2/kibana/test/accessibility/services/a11y/a11y.ts:60:18)
[00:05:25]                 │       at runMicrotasks (<anonymous>)
[00:05:25]                 │       at processTicksAndRejections (internal/process/task_queues.js:93:5)
[00:05:25]                 │       at Context.<anonymous> (test/accessibility/apps/ml.ts:181:11)
[00:05:25]                 │       at Object.apply (/dev/shm/workspace/parallel/2/kibana/packages/kbn-test/src/functional_test_runner/lib/mocha/wrap_function.js:84:16)
[00:05:25]                 │ 
[00:05:25]                 │ 

Stack Trace

Error: a11y report:

VIOLATION
  [label]: Ensures every form element has a label
    Help: https://dequeuniversity.com/rules/axe/4.0/label?application=axeAPI
    Elements:
      - #i581b7c41-5989-11eb-88ca-394aec5ba071
      - .euiFieldText
    at Accessibility.testAxeReport (/dev/shm/workspace/parallel/2/kibana/test/accessibility/services/a11y/a11y.ts:91:15)
    at Accessibility.testAppSnapshot (/dev/shm/workspace/parallel/2/kibana/test/accessibility/services/a11y/a11y.ts:60:18)
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:93:5)
    at Context.<anonymous> (test/accessibility/apps/ml.ts:181:11)
    at Object.apply (/dev/shm/workspace/parallel/2/kibana/packages/kbn-test/src/functional_test_runner/lib/mocha/wrap_function.js:84:16)

X-Pack Accessibility Tests.x-pack/test/accessibility/apps/ml·ts.ml for user with full ML access with data loaded anomaly detection create single metric job pick fields step

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:04:42]         └-: ml
[00:04:42]           └-> "before all" hook
[00:04:42]           └-> "before all" hook
[00:04:42]             │ debg creating role ft_ml_source
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_ml_source]
[00:04:42]             │ debg creating role ft_ml_source_readonly
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_ml_source_readonly]
[00:04:42]             │ debg creating role ft_ml_dest
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_ml_dest]
[00:04:42]             │ debg creating role ft_ml_dest_readonly
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_ml_dest_readonly]
[00:04:42]             │ debg creating role ft_ml_ui_extras
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_ml_ui_extras]
[00:04:42]             │ debg creating role ft_default_space_ml_all
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_default_space_ml_all]
[00:04:42]             │ debg creating role ft_default_space1_ml_all
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_default_space1_ml_all]
[00:04:42]             │ debg creating role ft_all_spaces_ml_all
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_all_spaces_ml_all]
[00:04:42]             │ debg creating role ft_default_space_ml_read
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_default_space_ml_read]
[00:04:42]             │ debg creating role ft_default_space1_ml_read
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_default_space1_ml_read]
[00:04:42]             │ debg creating role ft_all_spaces_ml_read
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_all_spaces_ml_read]
[00:04:42]             │ debg creating role ft_default_space_ml_none
[00:04:42]             │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added role [ft_default_space_ml_none]
[00:04:42]             │ debg creating user ft_ml_poweruser
[00:04:42]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_poweruser]
[00:04:42]             │ debg created user ft_ml_poweruser
[00:04:42]             │ debg creating user ft_ml_poweruser_spaces
[00:04:42]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_poweruser_spaces]
[00:04:42]             │ debg created user ft_ml_poweruser_spaces
[00:04:42]             │ debg creating user ft_ml_poweruser_space1
[00:04:43]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_poweruser_space1]
[00:04:43]             │ debg created user ft_ml_poweruser_space1
[00:04:43]             │ debg creating user ft_ml_poweruser_all_spaces
[00:04:43]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_poweruser_all_spaces]
[00:04:43]             │ debg created user ft_ml_poweruser_all_spaces
[00:04:43]             │ debg creating user ft_ml_viewer
[00:04:43]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_viewer]
[00:04:43]             │ debg created user ft_ml_viewer
[00:04:43]             │ debg creating user ft_ml_viewer_spaces
[00:04:43]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_viewer_spaces]
[00:04:43]             │ debg created user ft_ml_viewer_spaces
[00:04:43]             │ debg creating user ft_ml_viewer_space1
[00:04:43]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_viewer_space1]
[00:04:43]             │ debg created user ft_ml_viewer_space1
[00:04:43]             │ debg creating user ft_ml_viewer_all_spaces
[00:04:43]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_viewer_all_spaces]
[00:04:43]             │ debg created user ft_ml_viewer_all_spaces
[00:04:43]             │ debg creating user ft_ml_unauthorized
[00:04:43]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_unauthorized]
[00:04:43]             │ debg created user ft_ml_unauthorized
[00:04:43]             │ debg creating user ft_ml_unauthorized_spaces
[00:04:43]             │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] added user [ft_ml_unauthorized_spaces]
[00:04:43]             │ debg created user ft_ml_unauthorized_spaces
[00:04:43]           └-: for user with full ML access
[00:04:43]             └-> "before all" hook
[00:04:43]             └-> "before all" hook
[00:04:43]               │ debg SecurityPage.forceLogout
[00:04:43]               │ debg Find.existsByDisplayedByCssSelector('.login-form') with timeout=100
[00:04:44]               │ debg --- retry.tryForTime error: .login-form is not displayed
[00:04:44]               │ debg Redirecting to /logout to force the logout
[00:04:44]               │ debg Waiting on the login form to appear
[00:04:44]               │ debg Waiting for Login Page to appear.
[00:04:44]               │ debg Waiting up to 100000ms for login page...
[00:04:44]               │ debg browser[INFO] http://localhost:6121/logout?_t=1610972720057 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:04:44]               │
[00:04:44]               │ debg browser[INFO] http://localhost:6121/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:04:44]               │ debg Find.existsByDisplayedByCssSelector('.login-form') with timeout=2500
[00:04:47]               │ debg browser[INFO] http://localhost:6121/login?msg=LOGGED_OUT 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:04:47]               │
[00:04:47]               │ debg browser[INFO] http://localhost:6121/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:04:47]               │ERROR browser[SEVERE] http://localhost:6121/internal/spaces/_active_space - Failed to load resource: the server responded with a status of 401 (Unauthorized)
[00:04:47]               │ debg --- retry.tryForTime error: .login-form is not displayed
[00:04:47]               │ debg browser[INFO] http://localhost:6121/38009/bundles/core/core.entry.js 12:196200 "Detected an unhandled Promise rejection.
[00:04:47]               │      Error: Unauthorized"
[00:04:47]               │ERROR browser[SEVERE] http://localhost:6121/38009/bundles/core/core.entry.js 5:3002 
[00:04:48]               │ debg Find.existsByDisplayedByCssSelector('.login-form') with timeout=2500
[00:04:48]               │ debg TestSubjects.exists(loginForm)
[00:04:48]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="loginForm"]') with timeout=2500
[00:04:48]               │ debg Waiting for Login Form to appear.
[00:04:48]               │ debg Waiting up to 100000ms for login form...
[00:04:48]               │ debg TestSubjects.exists(loginForm)
[00:04:48]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="loginForm"]') with timeout=2500
[00:04:48]               │ debg TestSubjects.setValue(loginUsername, ft_ml_poweruser)
[00:04:48]               │ debg TestSubjects.click(loginUsername)
[00:04:48]               │ debg Find.clickByCssSelector('[data-test-subj="loginUsername"]') with timeout=10000
[00:04:48]               │ debg Find.findByCssSelector('[data-test-subj="loginUsername"]') with timeout=10000
[00:04:48]               │ debg TestSubjects.setValue(loginPassword, mlp001)
[00:04:48]               │ debg TestSubjects.click(loginPassword)
[00:04:48]               │ debg Find.clickByCssSelector('[data-test-subj="loginPassword"]') with timeout=10000
[00:04:48]               │ debg Find.findByCssSelector('[data-test-subj="loginPassword"]') with timeout=10000
[00:04:48]               │ debg TestSubjects.click(loginSubmit)
[00:04:48]               │ debg Find.clickByCssSelector('[data-test-subj="loginSubmit"]') with timeout=10000
[00:04:48]               │ debg Find.findByCssSelector('[data-test-subj="loginSubmit"]') with timeout=10000
[00:04:48]               │ debg Waiting for login result, expected: chrome.
[00:04:48]               │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"] .app-wrapper:not(.hidden-chrome)') with timeout=20000
[00:04:48]               │ proc [kibana]   log   [12:25:24.390] [info][plugins][routes][security] Logging in with provider "basic" (basic)
[00:04:53]               │ debg browser[INFO] http://localhost:6121/app/home 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:04:53]               │
[00:04:53]               │ debg browser[INFO] http://localhost:6121/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:04:53]               │ debg Finished login process currentUrl = http://localhost:6121/app/home#/
[00:04:53]               │ debg Waiting up to 20000ms for logout button visible...
[00:04:53]               │ debg TestSubjects.exists(userMenuButton)
[00:04:53]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenuButton"]') with timeout=2500
[00:04:53]               │ debg TestSubjects.exists(userMenu)
[00:04:53]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenu"]') with timeout=2500
[00:04:55]               │ debg --- retry.tryForTime error: [data-test-subj="userMenu"] is not displayed
[00:04:56]               │ debg TestSubjects.click(userMenuButton)
[00:04:56]               │ debg Find.clickByCssSelector('[data-test-subj="userMenuButton"]') with timeout=10000
[00:04:56]               │ debg Find.findByCssSelector('[data-test-subj="userMenuButton"]') with timeout=10000
[00:04:56]               │ debg TestSubjects.exists(userMenu)
[00:04:56]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenu"]') with timeout=120000
[00:04:56]               │ debg TestSubjects.exists(userMenu > logoutLink)
[00:04:56]               │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="userMenu"] [data-test-subj="logoutLink"]') with timeout=2500
[00:04:56]               │ debg Deleting indices: '.ml-*'...
[00:04:56]               │ debg Indices '.ml-*' don't exist. Nothing to delete.
[00:05:07]             └-: with data loaded
[00:05:07]               └-> "before all" hook
[00:05:07]               └-> "before all" hook
[00:05:07]                 │ info [ml/farequote] Loading "mappings.json"
[00:05:07]                 │ info [ml/farequote] Loading "data.json.gz"
[00:05:07]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [ft_farequote] creating index, cause [api], templates [], shards [1]/[1]
[00:05:07]                 │ info [ml/farequote] Created index "ft_farequote"
[00:05:07]                 │ debg [ml/farequote] "ft_farequote" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:05:15]                 │ info [ml/farequote] Indexed 86274 docs into "ft_farequote"
[00:05:15]                 │ info [ml/ihp_outlier] Loading "mappings.json"
[00:05:15]                 │ info [ml/ihp_outlier] Loading "data.json.gz"
[00:05:15]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [ft_ihp_outlier] creating index, cause [api], templates [], shards [1]/[1]
[00:05:16]                 │ info [ml/ihp_outlier] Created index "ft_ihp_outlier"
[00:05:16]                 │ debg [ml/ihp_outlier] "ft_ihp_outlier" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:05:16]                 │ info [ml/ihp_outlier] Indexed 1460 docs into "ft_ihp_outlier"
[00:05:16]                 │ info [ml/module_sample_ecommerce] Loading "mappings.json"
[00:05:16]                 │ info [ml/module_sample_ecommerce] Loading "data.json.gz"
[00:05:16]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [ft_module_sample_ecommerce] creating index, cause [api], templates [], shards [1]/[1]
[00:05:16]                 │ info [ml/module_sample_ecommerce] Created index "ft_module_sample_ecommerce"
[00:05:16]                 │ debg [ml/module_sample_ecommerce] "ft_module_sample_ecommerce" settings {"index":{"number_of_replicas":"1","number_of_shards":"1"}}
[00:05:16]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [ft_module_sample_ecommerce/w5yYsAtuSwqGNnOxMSpBSw] update_mapping [_doc]
[00:05:16]                 │ info [ml/module_sample_ecommerce] Indexed 287 docs into "ft_module_sample_ecommerce"
[00:05:16]                 │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:05:16]                 │ debg  > Not found
[00:05:16]                 │ debg Creating index pattern with title 'ft_farequote' and time field '@timestamp'
[00:05:16]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.kibana_2/jO1IgrneTE2mHcxaCJJmQQ] update_mapping [_doc]
[00:05:16]                 │ debg Waiting up to 5000ms for index-pattern with title 'ft_farequote' to exist...
[00:05:16]                 │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:05:16]                 │ debg  > Found '4ddc2730-5988-11eb-9ef1-dd98c0a14a59'
[00:05:16]                 │ debg  > Created with id '4ddc2730-5988-11eb-9ef1-dd98c0a14a59'
[00:05:16]                 │ debg Searching for 'index-pattern' with title 'ft_ihp_outlier'...
[00:05:17]                 │ debg  > Not found
[00:05:17]                 │ debg Creating index pattern with title 'ft_ihp_outlier' and time field '@timestamp'
[00:05:17]                 │ debg Waiting up to 5000ms for index-pattern with title 'ft_ihp_outlier' to exist...
[00:05:17]                 │ debg Searching for 'index-pattern' with title 'ft_ihp_outlier'...
[00:05:17]                 │ debg  > Found '4e10cbc0-5988-11eb-9ef1-dd98c0a14a59'
[00:05:17]                 │ debg  > Created with id '4e10cbc0-5988-11eb-9ef1-dd98c0a14a59'
[00:05:17]                 │ debg Searching for 'index-pattern' with title 'ft_module_sample_ecommerce'...
[00:05:18]                 │ debg  > Not found
[00:05:18]                 │ debg Creating index pattern with title 'ft_module_sample_ecommerce' and time field 'order_date'
[00:05:18]                 │ debg Waiting up to 5000ms for index-pattern with title 'ft_module_sample_ecommerce' to exist...
[00:05:18]                 │ debg Searching for 'index-pattern' with title 'ft_module_sample_ecommerce'...
[00:05:18]                 │ debg  > Found '4ea802b0-5988-11eb-9ef1-dd98c0a14a59'
[00:05:18]                 │ debg  > Created with id '4ea802b0-5988-11eb-9ef1-dd98c0a14a59'
[00:05:18]                 │ debg applying update to kibana config: {"dateFormat:tz":"UTC"}
[00:05:19]                 │ debg Creating anomaly detection job with id 'fq_single_a11y' ...
[00:05:20]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-anomalies-shared] creating index, cause [api], templates [.ml-anomalies-], shards [1]/[1]
[00:05:20]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-anomalies-shared]
[00:05:20]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-annotations-6] creating index, cause [api], templates [], shards [1]/[1]
[00:05:20]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-annotations-6]
[00:05:20]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-anomalies-shared/fZ9PTiTOQcapSBawZHfh7g] update_mapping [_doc]
[00:05:20]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-config] creating index, cause [auto(bulk api)], templates [.ml-config], shards [1]/[1]
[00:05:20]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-config]
[00:05:20]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-notifications-000001] creating index, cause [auto(bulk api)], templates [.ml-notifications-000001], shards [1]/[1]
[00:05:20]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-notifications-000001]
[00:05:20]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.kibana_2/jO1IgrneTE2mHcxaCJJmQQ] update_mapping [_doc]
[00:05:20]                 │ debg Waiting up to 5000ms for 'fq_single_a11y' to exist...
[00:05:21]                 │ debg > AD job created.
[00:05:21]                 │ debg Creating datafeed with id 'datafeed-fq_single_a11y' ...
[00:05:22]                 │ debg Waiting up to 5000ms for 'datafeed-fq_single_a11y' to exist...
[00:05:22]                 │ debg > Datafeed created.
[00:05:22]                 │ debg Opening anomaly detection job 'fq_single_a11y'...
[00:05:22]                 │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] Opening job [fq_single_a11y]
[00:05:22]                 │ info [o.e.x.c.m.u.MlIndexAndAlias] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] About to create first concrete index [.ml-state-000001] with alias [.ml-state-write]
[00:05:22]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-state-000001] creating index, cause [api], templates [.ml-state], shards [1]/[1]
[00:05:22]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-state-000001]
[00:05:22]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-state-000001] from [null] to [{"phase":"new","action":"complete","name":"complete"}] in policy [ml-size-based-ilm-policy]
[00:05:22]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-state-000001] from [{"phase":"new","action":"complete","name":"complete"}] to [{"phase":"hot","action":"unfollow","name":"wait-for-indexing-complete"}] in policy [ml-size-based-ilm-policy]
[00:05:22]                 │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] Loading model snapshot [N/A], job latest_record_timestamp [N/A]
[00:05:22]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-state-000001] from [{"phase":"hot","action":"unfollow","name":"wait-for-indexing-complete"}] to [{"phase":"hot","action":"unfollow","name":"wait-for-follow-shard-tasks"}] in policy [ml-size-based-ilm-policy]
[00:05:22]                 │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] [autodetect/145205] [CResourceMonitor.cc@77] Setting model memory limit to 20 MB
[00:05:22]                 │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] Successfully set job state to [opened] for job [fq_single_a11y]
[00:05:22]                 │ debg > AD job opened.
[00:05:22]                 │ debg Starting datafeed 'datafeed-fq_single_a11y' with start: '0', end: '1610972758211'...
[00:05:22]                 │ debg > Datafeed started.
[00:05:22]                 │ debg Waiting up to 120000ms for datafeed state to be stopped...
[00:05:22]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:22]                 │ info [o.e.x.m.d.DatafeedJob] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] Datafeed started (from: 1970-01-01T00:00:00.000Z to: 2021-01-18T12:25:58.211Z) with frequency [600000ms]
[00:05:22]                 │ debg --- retry.waitForWithTimeout error: expected job state to be stopped but got started
[00:05:23]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-anomalies-shared/fZ9PTiTOQcapSBawZHfh7g] update_mapping [_doc]
[00:05:23]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:23]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 10000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:05:23]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:23]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:23]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:24]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 20000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:05:24]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:24]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:24]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 30000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:05:24]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:24]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:25]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 40000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:05:25]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:25]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:25]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 50000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:05:25]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:26]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:26]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 60000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:05:26]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:26]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:26]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 70000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:05:27]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:27]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:27]                 │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 80000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:05:27]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:27]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:28]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:28]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:28]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:28]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:29]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:29]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:29]                 │ info [o.e.x.m.d.DatafeedJob] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] Lookback has finished
[00:05:29]                 │ info [o.e.x.m.d.DatafeedManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [no_realtime] attempt to stop datafeed [datafeed-fq_single_a11y] for job [fq_single_a11y]
[00:05:29]                 │ info [o.e.x.m.d.DatafeedManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [no_realtime] try lock [20s] to stop datafeed [datafeed-fq_single_a11y] for job [fq_single_a11y]...
[00:05:29]                 │ info [o.e.x.m.d.DatafeedManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [no_realtime] stopping datafeed [datafeed-fq_single_a11y] for job [fq_single_a11y], acquired [true]...
[00:05:29]                 │ info [o.e.x.m.d.DatafeedManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [no_realtime] datafeed [datafeed-fq_single_a11y] for job [fq_single_a11y] has been stopped
[00:05:29]                 │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] Closing job [fq_single_a11y], because [close job (api)]
[00:05:29]                 │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] [autodetect/145205] [CCmdSkeleton.cc@61] Handled 86274 records
[00:05:29]                 │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] [autodetect/145205] [CAnomalyJob.cc@1569] Pruning all models
[00:05:29]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-anomalies-shared/fZ9PTiTOQcapSBawZHfh7g] update_mapping [_doc]
[00:05:29]                 │ info [o.e.x.m.p.AbstractNativeProcess] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] State output finished
[00:05:29]                 │ info [o.e.x.m.j.p.a.o.AutodetectResultProcessor] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] 120 buckets parsed from autodetect output
[00:05:29]                 │ debg Fetching datafeed state for datafeed datafeed-fq_single_a11y
[00:05:29]                 │ debg Waiting up to 120000ms for job state to be closed...
[00:05:29]                 │ debg Fetching anomaly detection job stats for job fq_single_a11y...
[00:05:29]                 │ debg > AD job stats fetched.
[00:05:29]                 │ debg --- retry.waitForWithTimeout error: expected job state to be closed but got closing
[00:05:30]                 │ debg Fetching anomaly detection job stats for job fq_single_a11y...
[00:05:30]                 │ debg > AD job stats fetched.
[00:05:30]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:30]                 │ debg Fetching anomaly detection job stats for job fq_single_a11y...
[00:05:30]                 │ debg > AD job stats fetched.
[00:05:30]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:30]                 │ info [o.e.x.m.j.p.a.AutodetectCommunicator] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [fq_single_a11y] job closed
[00:05:31]                 │ debg Fetching anomaly detection job stats for job fq_single_a11y...
[00:05:31]                 │ debg > AD job stats fetched.
[00:05:31]                 │ debg Creating data frame analytic job with id 'iph_outlier_a11y' ...
[00:05:31]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-config/xwHX9wk6TY6miiFccS31Sw] update_mapping [_doc]
[00:05:32]                 │ debg Waiting up to 5000ms for 'iph_outlier_a11y' to exist...
[00:05:32]                 │ debg Fetching data frame analytics job 'iph_outlier_a11y'...
[00:05:32]                 │ debg > DFA job fetched.
[00:05:32]                 │ debg > DFA job created.
[00:05:32]                 │ debg Starting data frame analytics job 'iph_outlier_a11y'...
[00:05:32]                 │ info [o.e.x.m.a.TransportStartDataFrameAnalyticsAction] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Starting data frame analytics from state [stopped]
[00:05:32]                 │ debg > DFA job started.
[00:05:32]                 │ debg Waiting up to 60000ms for 'iph_outlier_a11y' to have training_docs_count > 0...
[00:05:32]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:32]                 │ info [o.e.x.c.m.u.MlIndexAndAlias] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] About to create first concrete index [.ml-stats-000001] with alias [.ml-stats-write]
[00:05:32]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-stats-000001] creating index, cause [api], templates [.ml-stats], shards [1]/[1]
[00:05:32]                 │ debg > DFA job stats fetched.
[00:05:32]                 │ debg --- retry.waitForWithTimeout error: expected data frame analytics job 'iph_outlier_a11y' to have training_docs_count > 0 (got 0)
[00:05:32]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-stats-000001]
[00:05:32]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-stats-000001] from [null] to [{"phase":"new","action":"complete","name":"complete"}] in policy [ml-size-based-ilm-policy]
[00:05:32]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-stats-000001] from [{"phase":"new","action":"complete","name":"complete"}] to [{"phase":"hot","action":"unfollow","name":"wait-for-indexing-complete"}] in policy [ml-size-based-ilm-policy]
[00:05:32]                 │ info [o.e.x.m.d.s.ReindexingStep] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Creating destination index [user-iph_outlier_a11y]
[00:05:32]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [user-iph_outlier_a11y] creating index, cause [api], templates [], shards [1]/[1]
[00:05:32]                 │ info [o.e.x.m.d.s.ReindexingStep] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Started reindexing
[00:05:32]                 │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] moving index [.ml-stats-000001] from [{"phase":"hot","action":"unfollow","name":"wait-for-indexing-complete"}] to [{"phase":"hot","action":"unfollow","name":"wait-for-follow-shard-tasks"}] in policy [ml-size-based-ilm-policy]
[00:05:33]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:33]                 │ debg > DFA job stats fetched.
[00:05:33]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:33]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Started loading data
[00:05:33]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Started analyzing
[00:05:33]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Waiting for result processor to complete
[00:05:33]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:33]                 │ debg > DFA job stats fetched.
[00:05:33]                 │ debg Waiting up to 120000ms for analytics state to be stopped...
[00:05:33]                 │ debg Fetching analytics state for job iph_outlier_a11y
[00:05:33]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:33]                 │ debg > DFA job stats fetched.
[00:05:33]                 │ debg --- retry.waitForWithTimeout error: expected analytics state to be stopped but got started
[00:05:33]                 │ info [o.e.x.m.d.p.AnalyticsResultProcessor] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Started writing results
[00:05:34]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Result processor has completed
[00:05:34]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Closing process
[00:05:34]                 │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] [data_frame_analyzer/145751] [Main.cc@241] [{"name":"E_DFOEstimatedPeakMemoryUsage","description":"The upfront estimate of the peak memory outlier detection would use","value":962304}
[00:05:34]                 │      ,{"name":"E_DFOPeakMemoryUsage","description":"The peak memory outlier detection used","value":639352}
[00:05:34]                 │      ,{"name":"E_DFOTimeToCreateEnsemble","description":"The time it took to create the ensemble used for outlier detection","value":44}
[00:05:34]                 │      ,{"name":"E_DFOTimeToComputeScores","description":"The time it took to compute outlier scores","value":144}
[00:05:34]                 │      ,{"name":"E_DFONumberPartitions","description":"The number of partitions outlier detection used","value":1}
[00:05:34]                 │      ]
[00:05:34]                 │ info [o.e.x.m.d.p.AnalyticsProcessManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Closed process
[00:05:34]                 │ info [o.e.x.m.d.DataFrameAnalyticsManager] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [iph_outlier_a11y] Marking task completed
[00:05:34]                 │ debg Fetching analytics state for job iph_outlier_a11y
[00:05:34]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:34]                 │ debg > DFA job stats fetched.
[00:05:34]                 │ debg --- retry.waitForWithTimeout failed again with the same message...
[00:05:34]                 │ debg Fetching analytics state for job iph_outlier_a11y
[00:05:34]                 │ debg Fetching data frame analytics job stats for job iph_outlier_a11y...
[00:05:34]                 │ debg > DFA job stats fetched.
[00:05:34]                 │ debg Creating calendar with id 'calendar_a11y'...
[00:05:34]                 │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-meta] creating index, cause [auto(bulk api)], templates [.ml-meta], shards [1]/[1]
[00:05:34]                 │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] updating number_of_replicas to [0] for indices [.ml-meta]
[00:05:34]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-meta/gdcxEFh8Ruqe3wZHV_7OUA] update_mapping [_doc]
[00:05:34]                 │ debg Waiting up to 5000ms for 'calendar_a11y' to exist...
[00:05:34]                 │ debg > Calendar created.
[00:05:34]                 │ debg Creating events for calendar with id 'calendar_a11y'...
[00:05:34]                 │ debg Waiting up to 5000ms for 'calendar_a11y' events to exist...
[00:05:34]                 │ debg > Calendar events created.
[00:05:34]                 │ debg Creating filter with id 'filter_a11y'...
[00:05:34]                 │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-ubuntu-16-tests-xxl-1610969982000961712] [.ml-meta/gdcxEFh8Ruqe3wZHV_7OUA] update_mapping [_doc]
[00:05:34]                 │ debg Waiting up to 5000ms for 'filter_a11y' to exist...
[00:05:34]                 │ debg > Filter created.
[00:05:34]               └-> overview page
[00:05:34]                 └-> "before each" hook: global before each
[00:05:34]                 │ debg navigating to ml url: http://localhost:6121/app/ml
[00:05:34]                 │ debg navigate to: http://localhost:6121/app/ml
[00:05:35]                 │ debg browser[INFO] http://localhost:6121/app/ml?_t=1610972770392 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:05:35]                 │
[00:05:35]                 │ debg browser[INFO] http://localhost:6121/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:05:35]                 │ debg ... sleep(700) start
[00:05:35]                 │ debg ... sleep(700) end
[00:05:35]                 │ debg returned from get, calling refresh
[00:05:36]                 │ debg browser[INFO] http://localhost:6121/app/ml?_t=1610972770392 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:05:36]                 │
[00:05:36]                 │ debg browser[INFO] http://localhost:6121/bootstrap.js 42:19 "^ A single error about an inline script not firing due to content security policy is expected!"
[00:05:36]                 │ debg currentUrl = http://localhost:6121/app/ml
[00:05:36]                 │          appUrl = http://localhost:6121/app/ml
[00:05:36]                 │ debg TestSubjects.find(kibanaChrome)
[00:05:36]                 │ debg Find.findByCssSelector('[data-test-subj="kibanaChrome"]') with timeout=60000
[00:05:37]                 │ debg ... sleep(501) start
[00:05:38]                 │ debg ... sleep(501) end
[00:05:38]                 │ debg in navigateTo url = http://localhost:6121/app/ml/overview
[00:05:38]                 │ debg --- retry.try error: URL changed, waiting for it to settle
[00:05:38]                 │ debg ... sleep(501) start
[00:05:39]                 │ debg ... sleep(501) end
[00:05:39]                 │ debg in navigateTo url = http://localhost:6121/app/ml/overview
[00:05:39]                 │ debg TestSubjects.exists(statusPageContainer)
[00:05:39]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="statusPageContainer"]') with timeout=2500
[00:05:41]                 │ debg --- retry.tryForTime error: [data-test-subj="statusPageContainer"] is not displayed
[00:05:42]                 │ debg TestSubjects.exists(mlApp)
[00:05:42]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlApp"]') with timeout=2000
[00:05:42]                 │ debg TestSubjects.click(~mlMainTab & ~overview)
[00:05:42]                 │ debg Find.clickByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="overview"]') with timeout=10000
[00:05:42]                 │ debg Find.findByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="overview"]') with timeout=10000
[00:05:42]                 │ debg TestSubjects.exists(~mlMainTab & ~overview & ~selected)
[00:05:42]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="overview"][data-test-subj~="selected"]') with timeout=120000
[00:05:42]                 │ debg TestSubjects.exists(mlPageOverview)
[00:05:42]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageOverview"]') with timeout=120000
[00:05:43]                 └- ✓ pass  (8.7s) "ml for user with full ML access with data loaded overview page"
[00:05:43]               └-> anomaly detection jobs list page
[00:05:43]                 └-> "before each" hook: global before each
[00:05:43]                 │ debg TestSubjects.click(~mlMainTab & ~anomalyDetection)
[00:05:43]                 │ debg Find.clickByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"]') with timeout=10000
[00:05:43]                 │ debg Find.findByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"]') with timeout=10000
[00:05:43]                 │ debg TestSubjects.exists(~mlMainTab & ~anomalyDetection & ~selected)
[00:05:43]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="mlMainTab"][data-test-subj~="anomalyDetection"][data-test-subj~="selected"]') with timeout=120000
[00:05:43]                 │ debg TestSubjects.exists(mlPageJobManagement)
[00:05:43]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageJobManagement"]') with timeout=120000
[00:05:44]                 └- ✓ pass  (919ms) "ml for user with full ML access with data loaded anomaly detection jobs list page"
[00:05:44]               └-> anomaly detection create job select index pattern page
[00:05:44]                 └-> "before each" hook: global before each
[00:05:44]                 │ debg TestSubjects.clickWhenNotDisabled(mlCreateNewJobButton)
[00:05:44]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlCreateNewJobButton"]') with timeout=10000
[00:05:44]                 │ debg Find.findByCssSelector('[data-test-subj="mlCreateNewJobButton"]') with timeout=10000
[00:05:44]                 │ debg TestSubjects.exists(mlPageSourceSelection)
[00:05:44]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageSourceSelection"]') with timeout=120000
[00:05:45]                 └- ✓ pass  (739ms) "ml for user with full ML access with data loaded anomaly detection create job select index pattern page"
[00:05:45]               └-> anomaly detection create job select type page
[00:05:45]                 └-> "before each" hook: global before each
[00:05:45]                 │ debg TestSubjects.setValue(savedObjectFinderSearchInput, ft_farequote)
[00:05:45]                 │ debg TestSubjects.click(savedObjectFinderSearchInput)
[00:05:45]                 │ debg Find.clickByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:05:45]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectFinderSearchInput"]') with timeout=10000
[00:05:45]                 │ debg TestSubjects.exists(savedObjectTitleft_farequote)
[00:05:45]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="savedObjectTitleft_farequote"]') with timeout=120000
[00:05:45]                 │ debg TestSubjects.clickWhenNotDisabled(savedObjectTitleft_farequote)
[00:05:45]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="savedObjectTitleft_farequote"]') with timeout=10000
[00:05:45]                 │ debg Find.findByCssSelector('[data-test-subj="savedObjectTitleft_farequote"]') with timeout=10000
[00:05:45]                 │ debg TestSubjects.exists(mlPageJobTypeSelection)
[00:05:45]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageJobTypeSelection"]') with timeout=10000
[00:05:46]                 └- ✓ pass  (1.4s) "ml for user with full ML access with data loaded anomaly detection create job select type page"
[00:05:46]               └-> anomaly detection create single metric job time range step
[00:05:46]                 └-> "before each" hook: global before each
[00:05:46]                 │ debg TestSubjects.clickWhenNotDisabled(mlJobTypeLinkSingleMetricJob)
[00:05:46]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobTypeLinkSingleMetricJob"]') with timeout=10000
[00:05:46]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobTypeLinkSingleMetricJob"]') with timeout=10000
[00:05:46]                 │ debg TestSubjects.exists(mlPageJobWizard single_metric)
[00:05:46]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlPageJobWizard single_metric"]') with timeout=120000
[00:05:47]                 │ debg === TEST STEP === job creation set the time range
[00:05:47]                 │ debg TestSubjects.clickWhenNotDisabled(mlButtonUseFullData)
[00:05:47]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlButtonUseFullData"]') with timeout=10000
[00:05:47]                 │ debg Find.findByCssSelector('[data-test-subj="mlButtonUseFullData"]') with timeout=10000
[00:05:47]                 │ debg TestSubjects.find(mlJobWizardDateRange)
[00:05:47]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardDateRange"]') with timeout=10000
[00:05:48]                 └- ✓ pass  (1.7s) "ml for user with full ML access with data loaded anomaly detection create single metric job time range step"
[00:05:48]               └-> anomaly detection create single metric job pick fields step
[00:05:48]                 └-> "before each" hook: global before each
[00:05:48]                 │ debg TestSubjects.exists(mlJobWizardNavButtonNext)
[00:05:48]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=120000
[00:05:48]                 │ debg TestSubjects.clickWhenNotDisabled(mlJobWizardNavButtonNext)
[00:05:48]                 │ debg Find.clickByCssSelectorWhenNotDisabled('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:05:48]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardNavButtonNext"]') with timeout=10000
[00:05:48]                 │ debg TestSubjects.exists(mlJobWizardStepTitlePickFields)
[00:05:48]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj="mlJobWizardStepTitlePickFields"]') with timeout=5000
[00:05:48]                 │ debg === TEST STEP === job creation selects field and aggregation
[00:05:48]                 │ debg comboBox.set, comboBoxSelector: mlJobWizardAggSelection > comboBoxInput
[00:05:48]                 │ debg TestSubjects.find(mlJobWizardAggSelection > comboBoxInput)
[00:05:48]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardAggSelection"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:05:48]                 │ debg comboBox.setElement, value: Mean(responsetime)
[00:05:48]                 │ debg comboBox.isOptionSelected, value: Mean(responsetime)
[00:05:51]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:05:51]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:05:51]                 │ debg Find.allByCssSelector('.euiFilterSelectItem[title^="Mean(responsetime)"]') with timeout=2500
[00:05:51]                 │ debg TestSubjects.exists(~comboBoxOptionsList)
[00:05:51]                 │ debg Find.existsByDisplayedByCssSelector('[data-test-subj~="comboBoxOptionsList"]') with timeout=2500
[00:05:54]                 │ debg --- retry.tryForTime error: [data-test-subj~="comboBoxOptionsList"] is not displayed
[00:05:54]                 │ debg comboBox.getComboBoxSelectedOptions, comboBoxSelector: mlJobWizardAggSelection > comboBoxInput
[00:05:54]                 │ debg TestSubjects.find(mlJobWizardAggSelection > comboBoxInput)
[00:05:54]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardAggSelection"] [data-test-subj="comboBoxInput"]') with timeout=10000
[00:05:54]                 │ debg === TEST STEP === job creation inputs the bucket span
[00:05:54]                 │ debg TestSubjects.setValueWithChecks(mlJobWizardInputBucketSpan, 30m)
[00:05:54]                 │ debg TestSubjects.click(mlJobWizardInputBucketSpan)
[00:05:54]                 │ debg Find.clickByCssSelector('[data-test-subj="mlJobWizardInputBucketSpan"]') with timeout=10000
[00:05:54]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardInputBucketSpan"]') with timeout=10000
[00:05:55]                 │ debg TestSubjects.getAttribute(mlJobWizardInputBucketSpan, value, tryTimeout=120000, findTimeout=10000)
[00:05:55]                 │ debg TestSubjects.find(mlJobWizardInputBucketSpan)
[00:05:55]                 │ debg Find.findByCssSelector('[data-test-subj="mlJobWizardInputBucketSpan"]') with timeout=10000
[00:05:55]                 │ info Taking screenshot "/dev/shm/workspace/parallel/2/kibana/x-pack/test/functional/screenshots/failure/ml for user with full ML access with data loaded anomaly detection create single metric job pick fields step.png"
[00:05:56]                 │ info Current URL is: http://localhost:6121/app/ml/jobs/new_job/single_metric?index=4ddc2730-5988-11eb-9ef1-dd98c0a14a59
[00:05:56]                 │ info Saving page source to: /dev/shm/workspace/parallel/2/kibana/x-pack/test/functional/failure_debug/html/ml for user with full ML access with data loaded anomaly detection create single metric job pick fields step.html
[00:05:56]                 └- ✖ fail: ml for user with full ML access with data loaded anomaly detection create single metric job pick fields step
[00:05:56]                 │      Error: a11y report:
[00:05:56]                 │ 
[00:05:56]                 │ VIOLATION
[00:05:56]                 │   [label]: Ensures every form element has a label
[00:05:56]                 │     Help: https://dequeuniversity.com/rules/axe/4.0/label?application=axeAPI
[00:05:56]                 │     Elements:
[00:05:56]                 │       - #i60f3b221-5988-11eb-baaa-b16d0c1e6143
[00:05:56]                 │       - .euiFieldText
[00:05:56]                 │       at Accessibility.testAxeReport (/dev/shm/workspace/parallel/2/kibana/test/accessibility/services/a11y/a11y.ts:91:15)
[00:05:56]                 │       at Accessibility.testAppSnapshot (/dev/shm/workspace/parallel/2/kibana/test/accessibility/services/a11y/a11y.ts:60:18)
[00:05:56]                 │       at runMicrotasks (<anonymous>)
[00:05:56]                 │       at processTicksAndRejections (internal/process/task_queues.js:93:5)
[00:05:56]                 │       at Context.<anonymous> (test/accessibility/apps/ml.ts:181:11)
[00:05:56]                 │       at Object.apply (/dev/shm/workspace/parallel/2/kibana/packages/kbn-test/src/functional_test_runner/lib/mocha/wrap_function.js:84:16)
[00:05:56]                 │ 
[00:05:56]                 │ 

Stack Trace

Error: a11y report:

VIOLATION
  [label]: Ensures every form element has a label
    Help: https://dequeuniversity.com/rules/axe/4.0/label?application=axeAPI
    Elements:
      - #i60f3b221-5988-11eb-baaa-b16d0c1e6143
      - .euiFieldText
    at Accessibility.testAxeReport (/dev/shm/workspace/parallel/2/kibana/test/accessibility/services/a11y/a11y.ts:91:15)
    at Accessibility.testAppSnapshot (/dev/shm/workspace/parallel/2/kibana/test/accessibility/services/a11y/a11y.ts:60:18)
    at runMicrotasks (<anonymous>)
    at processTicksAndRejections (internal/process/task_queues.js:93:5)
    at Context.<anonymous> (test/accessibility/apps/ml.ts:181:11)
    at Object.apply (/dev/shm/workspace/parallel/2/kibana/packages/kbn-test/src/functional_test_runner/lib/mocha/wrap_function.js:84:16)

Metrics [docs]

Async chunks

Total size of all lazy-loaded chunks that will be downloaded as the user navigates the app

id before after diff
ml 7.1MB 7.1MB -15.0B

History

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
Projects
None yet
Development

Successfully merging this pull request may close these issues.

3 participants