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

[alerting][actions] add task scheduled date and delay to event log #102252

Merged

Conversation

pmuellr
Copy link
Member

@pmuellr pmuellr commented Jun 15, 2021

NOTE: this PR was reverted after being merged: #102252 (comment)

A new PR #103172 was created to merge this code

resolves #98634

Summary

This adds a new object property to the event log kibana object named task, with two properties to track the time the task was scheduled to run, and the delay between when it was supposed to run and when it actually started. This task property is only added to the provider: alerting; action: execute and provider:actions; action: execute events.

        task: schema.maybe(
          schema.object({
            scheduled: ecsDate(),
            schedule_delay: ecsNumber(),
          })
        ),

I also happened to notice that a namespace property got added to the rule object in the event log documents, coming from this PR: #101132 . Problem is, that field does not exist in ECS! At least in the versions I looked at: https://github.com/elastic/ecs/blob/master/schemas/rule.yml . I'm curious how this happened - but here's a guess: when generating the event log schema, we depend on having the ecs repo checked out next to Kibana, and then we pull data from that. We actually check (somehow) that you have a tagged version checked out, but we assume the code hasn't been changed since the checkout. If it was changed for some reason - to do some testing, experimenting, etc - we'd use that version. We probably need to add an additional safety check, somehow. Like read one of the other "list of fields" files available in the ecs repo, as a double-check that the field is an ECS field.

Here's what the rule looks like in the event log, before this PR:

"rule": {
"properties": {
"author": {
"ignore_above": 1024,
"type": "keyword",
"meta": {
"isArray": "true"
}
},
"category": {
"ignore_above": 1024,
"type": "keyword"
},
"description": {
"ignore_above": 1024,
"type": "keyword"
},
"id": {
"ignore_above": 1024,
"type": "keyword"
},
"license": {
"ignore_above": 1024,
"type": "keyword"
},
"name": {
"ignore_above": 1024,
"type": "keyword"
},
"reference": {
"ignore_above": 1024,
"type": "keyword"
},
"ruleset": {
"ignore_above": 1024,
"type": "keyword"
},
"uuid": {
"ignore_above": 1024,
"type": "keyword"
},
"version": {
"ignore_above": 1024,
"type": "keyword"
},
"namespace": {
"ignore_above": 1024,
"type": "keyword"
}
}

I went ahead and removed all the references to the namespace field.

Checklist

Delete any items that are not applicable to this PR.

For maintainers

@pmuellr pmuellr added Feature:Alerting v8.0.0 release_note:skip Skip the PR/issue when compiling release notes Feature:Task Manager Feature:Actions Team:ResponseOps Label for the ResponseOps team (formerly the Cases and Alerting teams) Feature:EventLog v7.14.0 labels Jun 15, 2021
@pmuellr
Copy link
Member Author

pmuellr commented Jun 16, 2021

Issue #98634 mentions providing current average duration (presumably task duration, by task id (or task type?) to task executors. Did a quick review of how the task manager monitoring stats are currently handled in their HTTP route, seems like we should re-arrange some code there; some notes here: #98634 (comment)

So, going to defer that for now - but I'll see if there's anything else of interest we could pull in - maybe the task id?

@pmuellr pmuellr changed the title [taskManager] add task manager metrics to event log [alerting][actions] add task scheduled date and delay to event log Jun 17, 2021
@pmuellr pmuellr force-pushed the task-manager/expose-metrics-to-executors branch 3 times, most recently from 8585f54 to 1bb43dc Compare June 19, 2021 23:20
resolves elastic#98634

This adds a new object property to the event log kibana object named
task, with two properties to track the time the task was scheduled to
run, and the delay between when it was supposed to run and when it
actually started. This task property is only added to the appropriate
events.

	task: schema.maybe(
	  schema.object({
	    scheduled: ecsDate(),
	    schedule_delay: ecsNumber(),
	  })
	),
@pmuellr pmuellr force-pushed the task-manager/expose-metrics-to-executors branch from 1bb43dc to 6e368f1 Compare June 20, 2021 02:11
@pmuellr pmuellr marked this pull request as ready for review June 20, 2021 18:42
@pmuellr pmuellr requested a review from a team as a code owner June 20, 2021 18:42
@elasticmachine
Copy link
Contributor

Pinging @elastic/kibana-alerting-services (Team:Alerting Services)

Copy link
Contributor

@ymao1 ymao1 left a comment

Choose a reason for hiding this comment

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

Left some minor nits but overall LGTM! Verified that new fields appeared in event log.

@@ -127,6 +127,10 @@ Below is a document in the expected structure, with descriptions of the fields:
// Custom fields that are not part of ECS.
kibana: {
server_uuid: "UUID of kibana server, for diagnosing multi-Kibana scenarios",
task: {
scheduled: "ISO date of when the task for this event was supposed to start",
schedule_delay: "delay in nanoseconds of when this task was supposed to start",
Copy link
Contributor

Choose a reason for hiding this comment

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

Suggested change
schedule_delay: "delay in nanoseconds of when this task was supposed to start",
schedule_delay: "delay in nanoseconds between when this task was supposed to start and when it actually started",

@@ -494,6 +497,7 @@ export class TaskRunner<

const namespace = this.context.spaceIdToNamespace(spaceId);
const eventLogger = this.context.eventLogger;
const scheduleDelay = Date.now() - this.taskInstance.runAt.getTime();
Copy link
Contributor

Choose a reason for hiding this comment

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

nit: Maybe use runDate so that the logged start time will match exactly with the calculated schedule delay?

@pmuellr
Copy link
Member Author

pmuellr commented Jun 22, 2021

@elasticmachine merge upstream

@mikecote mikecote self-requested a review June 22, 2021 16:31
Copy link
Contributor

@mikecote mikecote left a comment

Choose a reason for hiding this comment

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

Changes LGTM! Tested locally and saw the task object populated with proper values 👍

Comment on lines +153 to +156
task: {
scheduled: taskInfo.scheduled.toISOString(),
schedule_delay: Millis2Nanos * (Date.now() - taskInfo.scheduled.getTime()),
},
Copy link
Contributor

Choose a reason for hiding this comment

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

Based on your comment here: #102252 (comment). Task id could be interesting as well, or it could also belong in the event log's kibana saved objects array to complete the dependency train.

Copy link
Member Author

Choose a reason for hiding this comment

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

heh, ya, didn't get around to it, but we do have the top-level task object we can provide more goodies for later.

One of the issues with adding the task manager SO reference, is that we'd also have to indicate the index, since it's not in .kibana - or I guess that's my understanding, that you'd need to know the index name to actually look them up. Or we could special case some of the "types" I suppose, if the existing SO client doesn't magically find them.

Copy link
Contributor

Choose a reason for hiding this comment

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

One of the issues with adding the task manager SO reference, is that we'd also have to indicate the index

I think this would be ok as long as the task SO is accessed via the saved objects client/repository. The SO repository would automatically know it's a type of task and look up the index task belongs into. All good to defer this 👍

@pmuellr pmuellr merged commit bb77fa6 into elastic:master Jun 23, 2021
pmuellr added a commit to pmuellr/kibana that referenced this pull request Jun 23, 2021
…lastic#102252)

resolves elastic#98634

This adds a new object property to the event log kibana object named
task, with two properties to track the time the task was scheduled to
run, and the delay between when it was supposed to run and when it
actually started. This task property is only added to the appropriate
events.

	task: schema.maybe(
	  schema.object({
	    scheduled: ecsDate(),
	    schedule_delay: ecsNumber(),
	  })
	),
jbudz added a commit that referenced this pull request Jun 23, 2021
@jbudz
Copy link
Member

jbudz commented Jun 23, 2021

This was reverted with 3e952fa due to a type failure - https://kibana-ci.elastic.co/job/elastic+kibana+master/15004/execution/node/413/log/

ymao1 pushed a commit to ymao1/kibana that referenced this pull request Jun 23, 2021
…lastic#102252)

resolves elastic#98634

This adds a new object property to the event log kibana object named
task, with two properties to track the time the task was scheduled to
run, and the delay between when it was supposed to run and when it
actually started. This task property is only added to the appropriate
events.

	task: schema.maybe(
	  schema.object({
	    scheduled: ecsDate(),
	    schedule_delay: ecsNumber(),
	  })
	),
@kibanamachine
Copy link
Contributor

kibanamachine commented Jun 23, 2021

💔 Build Failed

Failed CI Steps


Test Failures

Kibana Pipeline / general / X-Pack API Integration Tests.x-pack/test/api_integration/apis/ml/jobs/close_jobs·ts.apis Machine Learning jobs close_jobs close jobs fail because they are running as ML Poweruser

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: apis
[00:00:00]           └-> "before all" hook in "apis"
[00:08:38]           └-: Machine Learning
[00:08:38]             └-> "before all" hook in "Machine Learning"
[00:08:38]             └-> "before all" hook in "Machine Learning"
[00:08:38]               │ debg creating role ft_ml_source
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_ml_source]
[00:08:38]               │ debg creating role ft_ml_source_readonly
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_ml_source_readonly]
[00:08:38]               │ debg creating role ft_ml_dest
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_ml_dest]
[00:08:38]               │ debg creating role ft_ml_dest_readonly
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_ml_dest_readonly]
[00:08:38]               │ debg creating role ft_ml_ui_extras
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_ml_ui_extras]
[00:08:38]               │ debg creating role ft_default_space_ml_all
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_default_space_ml_all]
[00:08:38]               │ debg creating role ft_default_space1_ml_all
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_default_space1_ml_all]
[00:08:38]               │ debg creating role ft_all_spaces_ml_all
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_all_spaces_ml_all]
[00:08:38]               │ debg creating role ft_default_space_ml_read
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_default_space_ml_read]
[00:08:38]               │ debg creating role ft_default_space1_ml_read
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_default_space1_ml_read]
[00:08:38]               │ debg creating role ft_all_spaces_ml_read
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_all_spaces_ml_read]
[00:08:38]               │ debg creating role ft_default_space_ml_none
[00:08:38]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_default_space_ml_none]
[00:08:38]               │ debg creating user ft_ml_poweruser
[00:08:38]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_poweruser]
[00:08:38]               │ debg created user ft_ml_poweruser
[00:08:38]               │ debg creating user ft_ml_poweruser_spaces
[00:08:39]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_poweruser_spaces]
[00:08:39]               │ debg created user ft_ml_poweruser_spaces
[00:08:39]               │ debg creating user ft_ml_poweruser_space1
[00:08:39]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_poweruser_space1]
[00:08:39]               │ debg created user ft_ml_poweruser_space1
[00:08:39]               │ debg creating user ft_ml_poweruser_all_spaces
[00:08:39]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_poweruser_all_spaces]
[00:08:39]               │ debg created user ft_ml_poweruser_all_spaces
[00:08:39]               │ debg creating user ft_ml_viewer
[00:08:39]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_viewer]
[00:08:39]               │ debg created user ft_ml_viewer
[00:08:39]               │ debg creating user ft_ml_viewer_spaces
[00:08:39]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_viewer_spaces]
[00:08:39]               │ debg created user ft_ml_viewer_spaces
[00:08:39]               │ debg creating user ft_ml_viewer_space1
[00:08:39]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_viewer_space1]
[00:08:39]               │ debg created user ft_ml_viewer_space1
[00:08:39]               │ debg creating user ft_ml_viewer_all_spaces
[00:08:39]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_viewer_all_spaces]
[00:08:39]               │ debg created user ft_ml_viewer_all_spaces
[00:08:39]               │ debg creating user ft_ml_unauthorized
[00:08:39]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_unauthorized]
[00:08:39]               │ debg created user ft_ml_unauthorized
[00:08:39]               │ debg creating user ft_ml_unauthorized_spaces
[00:08:39]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_unauthorized_spaces]
[00:08:39]               │ debg created user ft_ml_unauthorized_spaces
[00:17:09]             └-: jobs
[00:17:09]               └-> "before all" hook in "jobs"
[00:17:21]               └-: close_jobs
[00:17:21]                 └-> "before all" hook for "sets up jobs"
[00:17:21]                 └-> "before all" hook for "sets up jobs"
[00:17:21]                   │ info [x-pack/test/functional/es_archives/ml/farequote] Loading "mappings.json"
[00:17:21]                   │ info [x-pack/test/functional/es_archives/ml/farequote] Loading "data.json.gz"
[00:17:21]                   │ info [x-pack/test/functional/es_archives/ml/farequote] Skipped restore for existing index "ft_farequote"
[00:17:22]                   │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:17:22]                   │ debg  > Not found
[00:17:22]                   │ debg Creating index pattern with title 'ft_farequote' and time field '@timestamp'
[00:17:23]                   │ debg Waiting up to 5000ms for index-pattern with title 'ft_farequote' to exist...
[00:17:23]                   │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:17:23]                   │ debg  > Found '8d61bee0-d469-11eb-867b-cfe20e5a7856'
[00:17:23]                   │ debg  > Created with id '8d61bee0-d469-11eb-867b-cfe20e5a7856'
[00:17:23]                   │ debg applying update to kibana config: {"dateFormat:tz":"UTC"}
[00:17:24]                 └-> sets up jobs
[00:17:24]                   └-> "before each" hook: global before each for "sets up jobs"
[00:17:24]                   │ debg Creating anomaly detection job with id 'jobs_summary_fq_single_1624482427907' ...
[00:17:24]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-anomalies-shared] creating index, cause [api], templates [.ml-anomalies-], shards [1]/[1]
[00:17:24]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] updating number_of_replicas to [0] for indices [.ml-anomalies-shared]
[00:17:24]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-annotations-6] creating index, cause [api], templates [], shards [1]/[1]
[00:17:24]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] updating number_of_replicas to [0] for indices [.ml-annotations-6]
[00:17:24]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-config] creating index, cause [auto(bulk api)], templates [], shards [1]/[1]
[00:17:24]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] updating number_of_replicas to [0] for indices [.ml-config]
[00:17:24]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-notifications-000002] creating index, cause [auto(bulk api)], templates [.ml-notifications-000002], shards [1]/[1]
[00:17:24]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] updating number_of_replicas to [0] for indices [.ml-notifications-000002]
[00:17:26]                   │ debg Waiting up to 5000ms for 'jobs_summary_fq_single_1624482427907' to exist...
[00:17:26]                   │ debg > AD job created.
[00:17:26]                   │ debg Opening anomaly detection job 'jobs_summary_fq_single_1624482427907'...
[00:17:26]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Opening job [jobs_summary_fq_single_1624482427907]
[00:17:26]                   │ info [o.e.x.c.m.u.MlIndexAndAlias] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] About to create first concrete index [.ml-state-000001] with alias [.ml-state-write]
[00:17:26]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-state-000001] creating index, cause [api], templates [.ml-state], shards [1]/[1]
[00:17:26]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] updating number_of_replicas to [0] for indices [.ml-state-000001]
[00:17:26]                   │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] moving index [.ml-state-000001] from [null] to [{"phase":"new","action":"complete","name":"complete"}] in policy [ml-size-based-ilm-policy]
[00:17:26]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] Loading model snapshot [N/A], job latest_record_timestamp [N/A]
[00:17:26]                   │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] moving index [.ml-state-000001] from [{"phase":"new","action":"complete","name":"complete"}] to [{"phase":"hot","action":"unfollow","name":"branch-check-unfollow-prerequisites"}] in policy [ml-size-based-ilm-policy]
[00:17:26]                   │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] moving index [.ml-state-000001] from [{"phase":"hot","action":"unfollow","name":"branch-check-unfollow-prerequisites"}] to [{"phase":"hot","action":"rollover","name":"check-rollover-ready"}] in policy [ml-size-based-ilm-policy]
[00:17:26]                   │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] [autodetect/337707] [CResourceMonitor.cc@77] Setting model memory limit to 10 MB
[00:17:26]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Successfully set job state to [opened] for job [jobs_summary_fq_single_1624482427907]
[00:17:26]                   │ debg > AD job opened.
[00:17:26]                   │ debg Creating datafeed with id 'datafeed-jobs_summary_fq_single_1624482427907' ...
[00:17:27]                   │ debg Waiting up to 5000ms for 'datafeed-jobs_summary_fq_single_1624482427907' to exist...
[00:17:27]                   │ debg > Datafeed created.
[00:17:27]                   │ debg Starting datafeed 'datafeed-jobs_summary_fq_single_1624482427907' with start: '0', end: 'undefined'...
[00:17:27]                   │ info [o.e.x.m.d.DatafeedJob] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] Datafeed started (from: 1970-01-01T00:00:00.000Z to: real-time) with frequency [450000ms]
[00:17:27]                   │ debg > Datafeed started.
[00:17:27]                   │ debg Waiting up to 120000ms for datafeed state to be started...
[00:17:27]                   │ debg Fetching datafeed state for datafeed datafeed-jobs_summary_fq_single_1624482427907
[00:17:27]                   │ debg Creating anomaly detection job with id 'jobs_summary_fq_multi_1624482427907' ...
[00:17:27]                   │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-anomalies-shared/zbqmc_fFRW2kP_bB70wN-Q] update_mapping [_doc]
[00:17:27]                   │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-anomalies-shared/zbqmc_fFRW2kP_bB70wN-Q] update_mapping [_doc]
[00:17:27]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] 10000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:27]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] 20000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:27]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] 30000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:27]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] 40000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:27]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] 50000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:27]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] 60000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:28]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] 70000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:28]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] 80000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:28]                   │ info [o.e.x.m.d.DatafeedJob] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] Lookback has finished
[00:17:28]                   │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] [autodetect/337707] [CAnomalyJob.cc@1578] Pruning all models
[00:17:28]                   │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] [autodetect/337707] [CAnomalyJob.cc@1119] Background persist starting data copy
[00:17:28]                   │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] [autodetect/337707] [CPersistenceManager.cc@266] Background persist starting background thread
[00:17:28]                   │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-anomalies-shared/zbqmc_fFRW2kP_bB70wN-Q] update_mapping [_doc]
[00:17:29]                   │ debg Waiting up to 5000ms for 'jobs_summary_fq_multi_1624482427907' to exist...
[00:17:29]                   │ debg > AD job created.
[00:17:29]                   │ debg Opening anomaly detection job 'jobs_summary_fq_multi_1624482427907'...
[00:17:29]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Opening job [jobs_summary_fq_multi_1624482427907]
[00:17:29]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] Loading model snapshot [N/A], job latest_record_timestamp [N/A]
[00:17:29]                   │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] [autodetect/337817] [CResourceMonitor.cc@77] Setting model memory limit to 20 MB
[00:17:29]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Successfully set job state to [opened] for job [jobs_summary_fq_multi_1624482427907]
[00:17:29]                   │ debg > AD job opened.
[00:17:29]                   │ debg Creating datafeed with id 'datafeed-jobs_summary_fq_multi_1624482427907' ...
[00:17:30]                   │ debg Waiting up to 5000ms for 'datafeed-jobs_summary_fq_multi_1624482427907' to exist...
[00:17:30]                   │ debg > Datafeed created.
[00:17:30]                   │ debg Starting datafeed 'datafeed-jobs_summary_fq_multi_1624482427907' with start: '0', end: 'undefined'...
[00:17:30]                   │ info [o.e.x.m.d.DatafeedJob] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] Datafeed started (from: 1970-01-01T00:00:00.000Z to: real-time) with frequency [600000ms]
[00:17:30]                   │ debg > Datafeed started.
[00:17:30]                   │ debg Waiting up to 120000ms for datafeed state to be started...
[00:17:30]                   │ debg Fetching datafeed state for datafeed datafeed-jobs_summary_fq_multi_1624482427907
[00:17:30]                   └- ✓ pass  (6.1s) "apis Machine Learning jobs close_jobs sets up jobs"
[00:17:30]                 └-: rejects request
[00:17:30]                   └-> "before all" hook in "rejects request"
[00:17:30]                   └-: fails to close job ID supplied
[00:17:30]                     └-> "before all" hook for "as ML Unauthorized user"
[00:17:30]                 └-: close jobs fail because they are running
[00:17:30]                   └-> "before all" hook for "as ML Poweruser"
[00:17:30]                   └-> as ML Poweruser
[00:17:30]                     └-> "before each" hook: global before each for "as ML Poweruser"
[00:17:30]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] 10000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:30]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] attempt to stop datafeed [datafeed-jobs_summary_fq_single_1624482427907] [180]
[00:17:30]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] attempt to stop datafeed [datafeed-jobs_summary_fq_single_1624482427907] for job [jobs_summary_fq_single_1624482427907]
[00:17:30]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] try lock [30m] to stop datafeed [datafeed-jobs_summary_fq_single_1624482427907] for job [jobs_summary_fq_single_1624482427907]...
[00:17:30]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] stopping datafeed [datafeed-jobs_summary_fq_single_1624482427907] for job [jobs_summary_fq_single_1624482427907], acquired [true]...
[00:17:30]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] datafeed [datafeed-jobs_summary_fq_single_1624482427907] for job [jobs_summary_fq_single_1624482427907] has been stopped
[00:17:30]                     │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Closing job [jobs_summary_fq_single_1624482427907], because [close job (api)]
[00:17:30]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] [autodetect/337707] [CCmdSkeleton.cc@61] Handled 86274 records
[00:17:30]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] [autodetect/337707] [CAnomalyJob.cc@1578] Pruning all models
[00:17:30]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] 20000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:30]                     │ info [o.e.x.m.p.AbstractNativeProcess] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] State output finished
[00:17:30]                     │ info [o.e.x.m.j.p.a.o.AutodetectResultProcessor] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] 480 buckets parsed from autodetect output
[00:17:30]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] 30000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:30]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] 40000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:31]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] 50000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:31]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] 60000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:31]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] 70000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:31]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] 80000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:17:31]                     │ info [o.e.x.m.j.p.a.AutodetectCommunicator] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624482427907] job closed
[00:17:31]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] attempt to stop datafeed [datafeed-jobs_summary_fq_multi_1624482427907] [182]
[00:17:31]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] attempt to stop datafeed [datafeed-jobs_summary_fq_multi_1624482427907] for job [jobs_summary_fq_multi_1624482427907]
[00:17:31]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] try lock [30m] to stop datafeed [datafeed-jobs_summary_fq_multi_1624482427907] for job [jobs_summary_fq_multi_1624482427907]...
[00:17:33]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [no_realtime] attempt to stop datafeed [datafeed-jobs_summary_fq_multi_1624482427907] for job [jobs_summary_fq_multi_1624482427907]
[00:17:33]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] stopping datafeed [datafeed-jobs_summary_fq_multi_1624482427907] for job [jobs_summary_fq_multi_1624482427907], acquired [true]...
[00:17:33]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [no_realtime] datafeed [datafeed-jobs_summary_fq_multi_1624482427907] for job [jobs_summary_fq_multi_1624482427907] was already stopped
[00:17:33]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] datafeed [datafeed-jobs_summary_fq_multi_1624482427907] for job [jobs_summary_fq_multi_1624482427907] has been stopped
[00:17:33]                     │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Closing job [jobs_summary_fq_multi_1624482427907], because [close job (api)]
[00:17:33]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] [autodetect/337817] [CCmdSkeleton.cc@61] Handled 86274 records
[00:17:33]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] [autodetect/337817] [CAnomalyJob.cc@1578] Pruning all models
[00:17:34]                     │ info [o.e.x.m.p.AbstractNativeProcess] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] State output finished
[00:17:34]                     │ info [o.e.x.m.j.p.a.o.AutodetectResultProcessor] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] 120 buckets parsed from autodetect output
[00:17:35]                     │ info [o.e.x.m.j.p.a.AutodetectCommunicator] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624482427907] job closed
[00:17:35]                     └- ✖ fail: apis Machine Learning jobs close_jobs close jobs fail because they are running as ML Poweruser
[00:17:35]                     │       Error: expected true to sort of equal false
[00:17:35]                     │       + expected - actual
[00:17:35]                     │ 
[00:17:35]                     │       -true
[00:17:35]                     │       +false
[00:17:35]                     │       
[00:17:35]                     │       at Assertion.assert (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/expect/expect.js:100:11)
[00:17:35]                     │       at Assertion.eql (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/expect/expect.js:244:8)
[00:17:35]                     │       at forEach (test/api_integration/apis/ml/jobs/close_jobs.ts:165:40)
[00:17:35]                     │       at Array.forEach (<anonymous>)
[00:17:35]                     │       at Context.<anonymous> (test/api_integration/apis/ml/jobs/close_jobs.ts:164:29)
[00:17:35]                     │       at Object.apply (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/test/src/functional_test_runner/lib/mocha/wrap_function.js:73:16)
[00:17:35]                     │ 
[00:17:35]                     │ 

Stack Trace

Error: expected true to sort of equal false
    at Assertion.assert (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/expect/expect.js:100:11)
    at Assertion.eql (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/expect/expect.js:244:8)
    at forEach (test/api_integration/apis/ml/jobs/close_jobs.ts:165:40)
    at Array.forEach (<anonymous>)
    at Context.<anonymous> (test/api_integration/apis/ml/jobs/close_jobs.ts:164:29)
    at Object.apply (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/test/src/functional_test_runner/lib/mocha/wrap_function.js:73:16) {
  actual: 'true',
  expected: 'false',
  showDiff: true
}

Kibana Pipeline / general / X-Pack API Integration Tests.x-pack/test/api_integration/apis/ml/jobs/close_jobs·ts.apis Machine Learning jobs close_jobs close jobs fail because they are running as ML Poweruser

Link to Jenkins

Standard Out

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

[00:00:00]       │
[00:00:00]         └-: apis
[00:00:00]           └-> "before all" hook in "apis"
[00:09:12]           └-: Machine Learning
[00:09:12]             └-> "before all" hook in "Machine Learning"
[00:09:12]             └-> "before all" hook in "Machine Learning"
[00:09:12]               │ debg creating role ft_ml_source
[00:09:12]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_ml_source]
[00:09:12]               │ debg creating role ft_ml_source_readonly
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_ml_source_readonly]
[00:09:13]               │ debg creating role ft_ml_dest
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_ml_dest]
[00:09:13]               │ debg creating role ft_ml_dest_readonly
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_ml_dest_readonly]
[00:09:13]               │ debg creating role ft_ml_ui_extras
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_ml_ui_extras]
[00:09:13]               │ debg creating role ft_default_space_ml_all
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_default_space_ml_all]
[00:09:13]               │ debg creating role ft_default_space1_ml_all
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_default_space1_ml_all]
[00:09:13]               │ debg creating role ft_all_spaces_ml_all
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_all_spaces_ml_all]
[00:09:13]               │ debg creating role ft_default_space_ml_read
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_default_space_ml_read]
[00:09:13]               │ debg creating role ft_default_space1_ml_read
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_default_space1_ml_read]
[00:09:13]               │ debg creating role ft_all_spaces_ml_read
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_all_spaces_ml_read]
[00:09:13]               │ debg creating role ft_default_space_ml_none
[00:09:13]               │ info [o.e.x.s.a.r.TransportPutRoleAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added role [ft_default_space_ml_none]
[00:09:13]               │ debg creating user ft_ml_poweruser
[00:09:13]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_poweruser]
[00:09:13]               │ debg created user ft_ml_poweruser
[00:09:13]               │ debg creating user ft_ml_poweruser_spaces
[00:09:13]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_poweruser_spaces]
[00:09:13]               │ debg created user ft_ml_poweruser_spaces
[00:09:13]               │ debg creating user ft_ml_poweruser_space1
[00:09:13]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_poweruser_space1]
[00:09:13]               │ debg created user ft_ml_poweruser_space1
[00:09:13]               │ debg creating user ft_ml_poweruser_all_spaces
[00:09:13]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_poweruser_all_spaces]
[00:09:13]               │ debg created user ft_ml_poweruser_all_spaces
[00:09:13]               │ debg creating user ft_ml_viewer
[00:09:13]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_viewer]
[00:09:13]               │ debg created user ft_ml_viewer
[00:09:13]               │ debg creating user ft_ml_viewer_spaces
[00:09:13]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_viewer_spaces]
[00:09:13]               │ debg created user ft_ml_viewer_spaces
[00:09:13]               │ debg creating user ft_ml_viewer_space1
[00:09:14]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_viewer_space1]
[00:09:14]               │ debg created user ft_ml_viewer_space1
[00:09:14]               │ debg creating user ft_ml_viewer_all_spaces
[00:09:14]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_viewer_all_spaces]
[00:09:14]               │ debg created user ft_ml_viewer_all_spaces
[00:09:14]               │ debg creating user ft_ml_unauthorized
[00:09:14]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_unauthorized]
[00:09:14]               │ debg created user ft_ml_unauthorized
[00:09:14]               │ debg creating user ft_ml_unauthorized_spaces
[00:09:14]               │ info [o.e.x.s.a.u.TransportPutUserAction] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] added user [ft_ml_unauthorized_spaces]
[00:09:14]               │ debg created user ft_ml_unauthorized_spaces
[00:18:37]             └-: jobs
[00:18:37]               └-> "before all" hook in "jobs"
[00:18:49]               └-: close_jobs
[00:18:49]                 └-> "before all" hook for "sets up jobs"
[00:18:49]                 └-> "before all" hook for "sets up jobs"
[00:18:49]                   │ info [x-pack/test/functional/es_archives/ml/farequote] Loading "mappings.json"
[00:18:49]                   │ info [x-pack/test/functional/es_archives/ml/farequote] Loading "data.json.gz"
[00:18:49]                   │ info [x-pack/test/functional/es_archives/ml/farequote] Skipped restore for existing index "ft_farequote"
[00:18:50]                   │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:18:50]                   │ debg  > Not found
[00:18:50]                   │ debg Creating index pattern with title 'ft_farequote' and time field '@timestamp'
[00:18:50]                   │ debg Waiting up to 5000ms for index-pattern with title 'ft_farequote' to exist...
[00:18:50]                   │ debg Searching for 'index-pattern' with title 'ft_farequote'...
[00:18:50]                   │ debg  > Found '3591fc70-d464-11eb-a85f-e15d697a5f5f'
[00:18:50]                   │ debg  > Created with id '3591fc70-d464-11eb-a85f-e15d697a5f5f'
[00:18:50]                   │ debg applying update to kibana config: {"dateFormat:tz":"UTC"}
[00:18:51]                 └-> sets up jobs
[00:18:51]                   └-> "before each" hook: global before each for "sets up jobs"
[00:18:51]                   │ debg Creating anomaly detection job with id 'jobs_summary_fq_single_1624480041301' ...
[00:18:51]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-anomalies-shared] creating index, cause [api], templates [.ml-anomalies-], shards [1]/[1]
[00:18:51]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] updating number_of_replicas to [0] for indices [.ml-anomalies-shared]
[00:18:51]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-annotations-6] creating index, cause [api], templates [], shards [1]/[1]
[00:18:51]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] updating number_of_replicas to [0] for indices [.ml-annotations-6]
[00:18:51]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-config] creating index, cause [auto(bulk api)], templates [], shards [1]/[1]
[00:18:51]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] updating number_of_replicas to [0] for indices [.ml-config]
[00:18:51]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-notifications-000002] creating index, cause [auto(bulk api)], templates [.ml-notifications-000002], shards [1]/[1]
[00:18:51]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] updating number_of_replicas to [0] for indices [.ml-notifications-000002]
[00:18:53]                   │ debg Waiting up to 5000ms for 'jobs_summary_fq_single_1624480041301' to exist...
[00:18:53]                   │ debg > AD job created.
[00:18:53]                   │ debg Opening anomaly detection job 'jobs_summary_fq_single_1624480041301'...
[00:18:53]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Opening job [jobs_summary_fq_single_1624480041301]
[00:18:53]                   │ info [o.e.x.c.m.u.MlIndexAndAlias] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] About to create first concrete index [.ml-state-000001] with alias [.ml-state-write]
[00:18:53]                   │ info [o.e.c.m.MetadataCreateIndexService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-state-000001] creating index, cause [api], templates [.ml-state], shards [1]/[1]
[00:18:53]                   │ info [o.e.c.r.a.AllocationService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] updating number_of_replicas to [0] for indices [.ml-state-000001]
[00:18:53]                   │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] moving index [.ml-state-000001] from [null] to [{"phase":"new","action":"complete","name":"complete"}] in policy [ml-size-based-ilm-policy]
[00:18:53]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] Loading model snapshot [N/A], job latest_record_timestamp [N/A]
[00:18:53]                   │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] moving index [.ml-state-000001] from [{"phase":"new","action":"complete","name":"complete"}] to [{"phase":"hot","action":"unfollow","name":"branch-check-unfollow-prerequisites"}] in policy [ml-size-based-ilm-policy]
[00:18:53]                   │ info [o.e.x.i.IndexLifecycleTransition] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] moving index [.ml-state-000001] from [{"phase":"hot","action":"unfollow","name":"branch-check-unfollow-prerequisites"}] to [{"phase":"hot","action":"rollover","name":"check-rollover-ready"}] in policy [ml-size-based-ilm-policy]
[00:18:54]                   │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] [autodetect/243186] [CResourceMonitor.cc@77] Setting model memory limit to 10 MB
[00:18:54]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Successfully set job state to [opened] for job [jobs_summary_fq_single_1624480041301]
[00:18:54]                   │ debg > AD job opened.
[00:18:54]                   │ debg Creating datafeed with id 'datafeed-jobs_summary_fq_single_1624480041301' ...
[00:18:54]                   │ debg Waiting up to 5000ms for 'datafeed-jobs_summary_fq_single_1624480041301' to exist...
[00:18:54]                   │ debg > Datafeed created.
[00:18:54]                   │ debg Starting datafeed 'datafeed-jobs_summary_fq_single_1624480041301' with start: '0', end: 'undefined'...
[00:18:54]                   │ info [o.e.x.m.d.DatafeedJob] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] Datafeed started (from: 1970-01-01T00:00:00.000Z to: real-time) with frequency [450000ms]
[00:18:54]                   │ debg > Datafeed started.
[00:18:54]                   │ debg Waiting up to 120000ms for datafeed state to be started...
[00:18:54]                   │ debg Fetching datafeed state for datafeed datafeed-jobs_summary_fq_single_1624480041301
[00:18:54]                   │ debg Creating anomaly detection job with id 'jobs_summary_fq_multi_1624480041301' ...
[00:18:54]                   │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-anomalies-shared/uwAFx67pQ2SkaS34FExlMQ] update_mapping [_doc]
[00:18:54]                   │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-anomalies-shared/uwAFx67pQ2SkaS34FExlMQ] update_mapping [_doc]
[00:18:55]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] 10000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:55]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] 20000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:55]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] 30000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:55]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] 40000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:55]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] 50000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:55]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] 60000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:56]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] 70000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:56]                   │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] 80000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:56]                   │ debg Waiting up to 5000ms for 'jobs_summary_fq_multi_1624480041301' to exist...
[00:18:56]                   │ debg > AD job created.
[00:18:56]                   │ debg Opening anomaly detection job 'jobs_summary_fq_multi_1624480041301'...
[00:18:56]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Opening job [jobs_summary_fq_multi_1624480041301]
[00:18:56]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] Loading model snapshot [N/A], job latest_record_timestamp [N/A]
[00:18:56]                   │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] [autodetect/243186] [CAnomalyJob.cc@1578] Pruning all models
[00:18:56]                   │ info [o.e.x.m.d.DatafeedJob] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] Lookback has finished
[00:18:56]                   │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] [autodetect/243186] [CAnomalyJob.cc@1119] Background persist starting data copy
[00:18:56]                   │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] [autodetect/243186] [CPersistenceManager.cc@266] Background persist starting background thread
[00:18:56]                   │ info [o.e.c.m.MetadataMappingService] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [.ml-anomalies-shared/uwAFx67pQ2SkaS34FExlMQ] update_mapping [_doc]
[00:18:57]                   │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] [autodetect/243328] [CResourceMonitor.cc@77] Setting model memory limit to 20 MB
[00:18:57]                   │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Successfully set job state to [opened] for job [jobs_summary_fq_multi_1624480041301]
[00:18:57]                   │ debg > AD job opened.
[00:18:57]                   │ debg Creating datafeed with id 'datafeed-jobs_summary_fq_multi_1624480041301' ...
[00:18:57]                   │ debg Waiting up to 5000ms for 'datafeed-jobs_summary_fq_multi_1624480041301' to exist...
[00:18:57]                   │ debg > Datafeed created.
[00:18:57]                   │ debg Starting datafeed 'datafeed-jobs_summary_fq_multi_1624480041301' with start: '0', end: 'undefined'...
[00:18:57]                   │ info [o.e.x.m.d.DatafeedJob] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] Datafeed started (from: 1970-01-01T00:00:00.000Z to: real-time) with frequency [600000ms]
[00:18:57]                   │ debg > Datafeed started.
[00:18:57]                   │ debg Waiting up to 120000ms for datafeed state to be started...
[00:18:57]                   │ debg Fetching datafeed state for datafeed datafeed-jobs_summary_fq_multi_1624480041301
[00:18:57]                   └- ✓ pass  (6.1s) "apis Machine Learning jobs close_jobs sets up jobs"
[00:18:57]                 └-: rejects request
[00:18:57]                   └-> "before all" hook in "rejects request"
[00:18:57]                   └-: fails to close job ID supplied
[00:18:57]                     └-> "before all" hook for "as ML Unauthorized user"
[00:18:57]                 └-: close jobs fail because they are running
[00:18:57]                   └-> "before all" hook for "as ML Poweruser"
[00:18:57]                   └-> as ML Poweruser
[00:18:57]                     └-> "before each" hook: global before each for "as ML Poweruser"
[00:18:57]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] 10000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:58]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] attempt to stop datafeed [datafeed-jobs_summary_fq_single_1624480041301] [180]
[00:18:58]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] attempt to stop datafeed [datafeed-jobs_summary_fq_single_1624480041301] for job [jobs_summary_fq_single_1624480041301]
[00:18:58]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] try lock [30m] to stop datafeed [datafeed-jobs_summary_fq_single_1624480041301] for job [jobs_summary_fq_single_1624480041301]...
[00:18:58]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] stopping datafeed [datafeed-jobs_summary_fq_single_1624480041301] for job [jobs_summary_fq_single_1624480041301], acquired [true]...
[00:18:58]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] datafeed [datafeed-jobs_summary_fq_single_1624480041301] for job [jobs_summary_fq_single_1624480041301] has been stopped
[00:18:58]                     │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Closing job [jobs_summary_fq_single_1624480041301], because [close job (api)]
[00:18:58]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] [autodetect/243186] [CCmdSkeleton.cc@61] Handled 86274 records
[00:18:58]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] [autodetect/243186] [CAnomalyJob.cc@1578] Pruning all models
[00:18:58]                     │ info [o.e.x.m.p.AbstractNativeProcess] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] State output finished
[00:18:58]                     │ info [o.e.x.m.j.p.a.o.AutodetectResultProcessor] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] 480 buckets parsed from autodetect output
[00:18:58]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] 20000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:58]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] 30000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:58]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] 40000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:58]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] 50000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:58]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] 60000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:59]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] 70000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:59]                     │ info [o.e.x.m.j.p.DataCountsReporter] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] 80000 records written to autodetect; missingFieldCount=0, invalidDateCount=0, outOfOrderCount=0
[00:18:59]                     │ info [o.e.x.m.j.p.a.AutodetectCommunicator] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_single_1624480041301] job closed
[00:18:59]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] attempt to stop datafeed [datafeed-jobs_summary_fq_multi_1624480041301] [182]
[00:18:59]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] attempt to stop datafeed [datafeed-jobs_summary_fq_multi_1624480041301] for job [jobs_summary_fq_multi_1624480041301]
[00:18:59]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] try lock [30m] to stop datafeed [datafeed-jobs_summary_fq_multi_1624480041301] for job [jobs_summary_fq_multi_1624480041301]...
[00:19:01]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [no_realtime] attempt to stop datafeed [datafeed-jobs_summary_fq_multi_1624480041301] for job [jobs_summary_fq_multi_1624480041301]
[00:19:01]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] stopping datafeed [datafeed-jobs_summary_fq_multi_1624480041301] for job [jobs_summary_fq_multi_1624480041301], acquired [true]...
[00:19:01]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [no_realtime] datafeed [datafeed-jobs_summary_fq_multi_1624480041301] for job [jobs_summary_fq_multi_1624480041301] was already stopped
[00:19:01]                     │ info [o.e.x.m.d.DatafeedRunner] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [stop_datafeed (api)] datafeed [datafeed-jobs_summary_fq_multi_1624480041301] for job [jobs_summary_fq_multi_1624480041301] has been stopped
[00:19:01]                     │ info [o.e.x.m.j.p.a.AutodetectProcessManager] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] Closing job [jobs_summary_fq_multi_1624480041301], because [close job (api)]
[00:19:01]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] [autodetect/243328] [CCmdSkeleton.cc@61] Handled 86274 records
[00:19:01]                     │ info [o.e.x.m.p.l.CppLogMessageHandler] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] [autodetect/243328] [CAnomalyJob.cc@1578] Pruning all models
[00:19:01]                     │ info [o.e.x.m.p.AbstractNativeProcess] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] State output finished
[00:19:01]                     │ info [o.e.x.m.j.p.a.o.AutodetectResultProcessor] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] 120 buckets parsed from autodetect output
[00:19:02]                     │ info [o.e.x.m.j.p.a.AutodetectCommunicator] [kibana-ci-immutable-centos-tests-xxl-1624477275888831824] [jobs_summary_fq_multi_1624480041301] job closed
[00:19:02]                     └- ✖ fail: apis Machine Learning jobs close_jobs close jobs fail because they are running as ML Poweruser
[00:19:02]                     │       Error: expected true to sort of equal false
[00:19:02]                     │       + expected - actual
[00:19:02]                     │ 
[00:19:02]                     │       -true
[00:19:02]                     │       +false
[00:19:02]                     │       
[00:19:02]                     │       at Assertion.assert (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/expect/expect.js:100:11)
[00:19:02]                     │       at Assertion.eql (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/expect/expect.js:244:8)
[00:19:02]                     │       at forEach (test/api_integration/apis/ml/jobs/close_jobs.ts:165:40)
[00:19:02]                     │       at Array.forEach (<anonymous>)
[00:19:02]                     │       at Context.<anonymous> (test/api_integration/apis/ml/jobs/close_jobs.ts:164:29)
[00:19:02]                     │       at Object.apply (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/test/src/functional_test_runner/lib/mocha/wrap_function.js:73:16)
[00:19:02]                     │ 
[00:19:02]                     │ 

Stack Trace

Error: expected true to sort of equal false
    at Assertion.assert (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/expect/expect.js:100:11)
    at Assertion.eql (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/expect/expect.js:244:8)
    at forEach (test/api_integration/apis/ml/jobs/close_jobs.ts:165:40)
    at Array.forEach (<anonymous>)
    at Context.<anonymous> (test/api_integration/apis/ml/jobs/close_jobs.ts:164:29)
    at Object.apply (/dev/shm/workspace/parallel/3/kibana/node_modules/@kbn/test/src/functional_test_runner/lib/mocha/wrap_function.js:73:16) {
  actual: 'true',
  expected: 'false',
  showDiff: true
}

Metrics [docs]

✅ unchanged

History

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

pmuellr added a commit that referenced this pull request Jun 24, 2021
#103172)

resolves #98634

This adds a new object property to the event log kibana object named
task, with two properties to track the time the task was scheduled to
run, and the delay between when it was supposed to run and when it
actually started. This task property is only added to the appropriate
events.

	task: schema.maybe(
	  schema.object({
	    scheduled: ecsDate(),
	    schedule_delay: ecsNumber(),
	  })
	),

Note that these changes were previously merged to master in #102252 which had to be reverted - this PR contains the same commits, plus some additional ones to resolve the tests that were broken during the bad merge.
pmuellr added a commit to pmuellr/kibana that referenced this pull request Jun 24, 2021
elastic#103172)

resolves elastic#98634

This adds a new object property to the event log kibana object named
task, with two properties to track the time the task was scheduled to
run, and the delay between when it was supposed to run and when it
actually started. This task property is only added to the appropriate
events.

	task: schema.maybe(
	  schema.object({
	    scheduled: ecsDate(),
	    schedule_delay: ecsNumber(),
	  })
	),

Note that these changes were previously merged to master in elastic#102252 which had to be reverted - this PR contains the same commits, plus some additional ones to resolve the tests that were broken during the bad merge.
pmuellr added a commit that referenced this pull request Jun 24, 2021
#103172) (#103296)

resolves #98634

This adds a new object property to the event log kibana object named
task, with two properties to track the time the task was scheduled to
run, and the delay between when it was supposed to run and when it
actually started. This task property is only added to the appropriate
events.

	task: schema.maybe(
	  schema.object({
	    scheduled: ecsDate(),
	    schedule_delay: ecsNumber(),
	  })
	),

Note that these changes were previously merged to master in #102252 which had to be reverted - this PR contains the same commits, plus some additional ones to resolve the tests that were broken during the bad merge.
@pmuellr pmuellr added the backport:skip This commit does not require backporting label Jun 24, 2021
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
backport:skip This commit does not require backporting Feature:Actions Feature:Alerting Feature:EventLog Feature:Task Manager release_note:skip Skip the PR/issue when compiling release notes Team:ResponseOps Label for the ResponseOps team (formerly the Cases and Alerting teams) v7.14.0 v8.0.0
Projects
None yet
Development

Successfully merging this pull request may close these issues.

[Task Manager] Expose Task Manager metrics to task executors
6 participants