[21:49:54.844] New invocation is queued and will start shortly
[21:49:55.937] Starting the invocation (attempt 1)
[21:49:55.969] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:49:55.969] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:49:55.969] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022526746661475344",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9a77f9773e6577bbd1bab31ba5d468159a7db9e7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022526746661475344",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/9a77f9773e6577bbd1bab31ba5d468159a7db9e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a77f9773e6577bbd1bab31ba5d468159a7db9e7",
"gitiles_ref:refs/heads/master"
]
}
[21:49:57.324] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611611396057719",
"id": "8857096684725909200",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9a77f9773e6577bbd1bab31ba5d468159a7db9e7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611611397051878",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44895",
"builder:linux-chromeos-rel",
"buildset:commit/git/9a77f9773e6577bbd1bab31ba5d468159a7db9e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a77f9773e6577bbd1bab31ba5d468159a7db9e7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022526746661475344",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857096684725909200/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1611611397052035",
"url": "https://ci.chromium.org/b/8857096684725909200",
"utcnow_ts": "1611611397315532"
}
}
[21:49:57.324] Task URL: https://ci.chromium.org/b/8857096684725909200
[21:49:57.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:2:0) after 1m0s
[21:49:58.579] Received PubSub notification, asking Buildbucket for the build status
[21:49:58.716] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:57.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:2:0)
[21:50:57.428] Timer tick, asking Buildbucket for the build status
[21:50:58.018] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:58.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:4:0) after 1m0s
[21:51:58.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:4:0)
[21:51:58.174] Timer tick, asking Buildbucket for the build status
[21:51:58.305] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:58.305] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:5:0) after 1m0s
[21:52:58.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:5:0)
[21:52:58.343] Timer tick, asking Buildbucket for the build status
[21:52:58.581] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:58.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:6:0) after 1m0s
[21:53:58.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:6:0)
[21:53:58.774] Timer tick, asking Buildbucket for the build status
[21:53:58.937] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:58.937] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:7:0) after 1m0s
[21:54:59.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:7:0)
[21:54:59.071] Timer tick, asking Buildbucket for the build status
[21:54:59.232] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:59.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:8:0) after 1m0s
[21:55:59.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:8:0)
[21:55:59.315] Timer tick, asking Buildbucket for the build status
[21:55:59.635] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:59.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:9:0) after 1m0s
[21:56:59.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:9:0)
[21:56:59.722] Timer tick, asking Buildbucket for the build status
[21:56:59.795] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:59.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:10:0) after 1m0s
[21:57:59.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:10:0)
[21:57:59.845] Timer tick, asking Buildbucket for the build status
[21:58:00.104] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:00.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:11:0) after 1m0s
[21:59:00.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:11:0)
[21:59:00.195] Timer tick, asking Buildbucket for the build status
[21:59:00.315] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:00.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:12:0) after 1m0s
[22:00:00.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:12:0)
[22:00:00.391] Timer tick, asking Buildbucket for the build status
[22:00:00.952] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:00.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:13:0) after 1m0s
[22:01:01.464] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:13:0)
[22:01:01.464] Timer tick, asking Buildbucket for the build status
[22:01:01.594] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:01.594] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:14:0) after 1m0s
[22:02:01.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:14:0)
[22:02:01.704] Timer tick, asking Buildbucket for the build status
[22:02:01.867] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:01.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:15:0) after 1m0s
[22:03:01.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:15:0)
[22:03:01.889] Timer tick, asking Buildbucket for the build status
[22:03:01.963] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:01.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:16:0) after 1m0s
[22:04:02.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:16:0)
[22:04:02.025] Timer tick, asking Buildbucket for the build status
[22:04:02.105] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:02.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:17:0) after 1m0s
[22:05:02.170] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:17:0)
[22:05:02.170] Timer tick, asking Buildbucket for the build status
[22:05:02.271] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:02.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:18:0) after 1m0s
[22:06:02.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:18:0)
[22:06:02.278] Timer tick, asking Buildbucket for the build status
[22:06:02.419] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:02.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:19:0) after 1m0s
[22:07:02.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:19:0)
[22:07:02.491] Timer tick, asking Buildbucket for the build status
[22:07:02.583] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:02.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:20:0) after 1m0s
[22:08:02.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:20:0)
[22:08:02.587] Timer tick, asking Buildbucket for the build status
[22:08:02.675] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:02.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:21:0) after 1m0s
[22:09:02.737] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:21:0)
[22:09:02.737] Timer tick, asking Buildbucket for the build status
[22:09:04.118] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:04.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:22:0) after 1m0s
[22:10:04.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:22:0)
[22:10:04.154] Timer tick, asking Buildbucket for the build status
[22:10:04.481] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:04.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:23:0) after 1m0s
[22:11:04.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:23:0)
[22:11:04.457] Timer tick, asking Buildbucket for the build status
[22:11:04.522] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:04.522] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:24:0) after 1m0s
[22:12:04.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:24:0)
[22:12:04.570] Timer tick, asking Buildbucket for the build status
[22:12:04.639] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:04.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:25:0) after 1m0s
[22:13:04.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:25:0)
[22:13:04.675] Timer tick, asking Buildbucket for the build status
[22:13:04.739] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:04.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:26:0) after 1m0s
[22:14:04.820] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:26:0)
[22:14:04.820] Timer tick, asking Buildbucket for the build status
[22:14:04.931] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:04.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:27:0) after 1m0s
[22:15:04.965] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:27:0)
[22:15:04.965] Timer tick, asking Buildbucket for the build status
[22:15:05.113] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:05.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:28:0) after 1m0s
[22:16:05.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:28:0)
[22:16:05.196] Timer tick, asking Buildbucket for the build status
[22:16:05.349] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:05.349] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:29:0) after 1m0s
[22:17:05.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:29:0)
[22:17:05.462] Timer tick, asking Buildbucket for the build status
[22:17:05.637] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:05.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:30:0) after 1m0s
[22:18:05.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:30:0)
[22:18:05.673] Timer tick, asking Buildbucket for the build status
[22:18:05.742] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:05.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:31:0) after 1m0s
[22:19:05.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:31:0)
[22:19:05.786] Timer tick, asking Buildbucket for the build status
[22:19:05.936] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:05.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:32:0) after 1m0s
[22:20:05.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:32:0)
[22:20:05.953] Timer tick, asking Buildbucket for the build status
[22:20:06.355] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:06.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:33:0) after 1m0s
[22:21:06.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:33:0)
[22:21:06.393] Timer tick, asking Buildbucket for the build status
[22:21:06.456] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:06.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:34:0) after 1m0s
[22:22:06.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:34:0)
[22:22:06.481] Timer tick, asking Buildbucket for the build status
[22:22:06.546] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:06.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:35:0) after 1m0s
[22:23:06.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:35:0)
[22:23:06.583] Timer tick, asking Buildbucket for the build status
[22:23:06.647] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:06.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:36:0) after 1m0s
[22:24:06.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:36:0)
[22:24:06.707] Timer tick, asking Buildbucket for the build status
[22:24:06.898] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:06.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:37:0) after 1m0s
[22:25:06.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:37:0)
[22:25:06.964] Timer tick, asking Buildbucket for the build status
[22:25:07.283] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:07.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:38:0) after 1m0s
[22:26:07.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:38:0)
[22:26:07.337] Timer tick, asking Buildbucket for the build status
[22:26:07.413] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:07.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:39:0) after 1m0s
[22:27:07.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:39:0)
[22:27:07.439] Timer tick, asking Buildbucket for the build status
[22:27:09.075] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:09.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:40:0) after 1m0s
[22:28:09.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:40:0)
[22:28:09.148] Timer tick, asking Buildbucket for the build status
[22:28:09.246] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:09.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:41:0) after 1m0s
[22:29:09.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:41:0)
[22:29:09.280] Timer tick, asking Buildbucket for the build status
[22:29:09.333] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:09.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:42:0) after 1m0s
[22:30:09.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:42:0)
[22:30:09.354] Timer tick, asking Buildbucket for the build status
[22:30:09.547] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:09.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:43:0) after 1m0s
[22:31:09.607] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:43:0)
[22:31:09.607] Timer tick, asking Buildbucket for the build status
[22:31:09.669] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:09.669] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:44:0) after 1m0s
[22:32:09.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:44:0)
[22:32:09.714] Timer tick, asking Buildbucket for the build status
[22:32:09.933] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:09.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:45:0) after 1m0s
[22:33:09.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:45:0)
[22:33:09.994] Timer tick, asking Buildbucket for the build status
[22:33:10.192] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:10.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:46:0) after 1m0s
[22:34:10.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:46:0)
[22:34:10.295] Timer tick, asking Buildbucket for the build status
[22:34:10.519] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:10.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:47:0) after 1m0s
[22:35:10.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:47:0)
[22:35:10.555] Timer tick, asking Buildbucket for the build status
[22:35:10.849] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:10.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:48:0) after 1m0s
[22:36:10.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:48:0)
[22:36:10.874] Timer tick, asking Buildbucket for the build status
[22:36:10.967] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:10.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:49:0) after 1m0s
[22:37:11.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:49:0)
[22:37:11.001] Timer tick, asking Buildbucket for the build status
[22:37:11.122] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:11.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:50:0) after 1m0s
[22:38:11.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:50:0)
[22:38:11.151] Timer tick, asking Buildbucket for the build status
[22:38:11.284] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:11.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:51:0) after 1m0s
[22:39:11.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:51:0)
[22:39:11.331] Timer tick, asking Buildbucket for the build status
[22:39:11.481] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:11.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:52:0) after 1m0s
[22:40:11.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:52:0)
[22:40:11.551] Timer tick, asking Buildbucket for the build status
[22:40:11.787] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:11.787] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:53:0) after 1m0s
[22:41:11.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:53:0)
[22:41:11.776] Timer tick, asking Buildbucket for the build status
[22:41:12.324] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:12.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:54:0) after 1m0s
[22:42:12.373] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:54:0)
[22:42:12.373] Timer tick, asking Buildbucket for the build status
[22:42:13.307] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:13.307] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:55:0) after 1m0s
[22:43:13.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:55:0)
[22:43:13.358] Timer tick, asking Buildbucket for the build status
[22:43:13.572] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:13.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:56:0) after 1m0s
[22:44:13.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:56:0)
[22:44:13.556] Timer tick, asking Buildbucket for the build status
[22:44:13.869] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:13.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:57:0) after 1m0s
[22:45:13.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:57:0)
[22:45:13.973] Timer tick, asking Buildbucket for the build status
[22:45:14.656] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:14.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:58:0) after 1m0s
[22:46:14.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:58:0)
[22:46:14.698] Timer tick, asking Buildbucket for the build status
[22:46:14.856] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:14.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:59:0) after 1m0s
[22:47:14.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:59:0)
[22:47:14.846] Timer tick, asking Buildbucket for the build status
[22:47:15.028] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:15.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:60:0) after 1m0s
[22:48:15.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:60:0)
[22:48:15.186] Timer tick, asking Buildbucket for the build status
[22:48:15.344] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:15.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:61:0) after 1m0s
[22:49:15.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:61:0)
[22:49:15.395] Timer tick, asking Buildbucket for the build status
[22:49:15.645] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:15.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:62:0) after 1m0s
[22:50:15.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:62:0)
[22:50:15.647] Timer tick, asking Buildbucket for the build status
[22:50:15.759] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:15.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:63:0) after 1m0s
[22:51:15.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:63:0)
[22:51:15.757] Timer tick, asking Buildbucket for the build status
[22:51:16.260] Build 8857096684725909200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:16.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022526746661475344:64:0) after 1m0s
[22:51:33.684] Received PubSub notification, asking Buildbucket for the build status
[22:51:33.773] Build 8857096684725909200: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:51:33.774] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611615092506191",
"created_by": "project:chromium",
"created_ts": "1611611396057719",
"failure_reason": "BUILD_FAILURE",
"id": "8857096684725909200",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9a77f9773e6577bbd1bab31ba5d468159a7db9e7\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- KioskErrorScreenTest.OpenCertificateConfig\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"9a77f9773e6577bbd1bab31ba5d468159a7db9e7\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-120-8ubm\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44895, \"got_angle_revision\": \"3623868e656d0685f0ec3f7d13a2e9c1e348ba53\", \"got_dawn_revision\": \"c00276b2029b85b0cca43ced05173993beaf43e7\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"9a77f9773e6577bbd1bab31ba5d468159a7db9e7\", \"got_revision_cp\": \"refs/heads/master@{#846900}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"07aeb34c380e82877710ad10e53596c41c49abd3\", \"got_v8_revision_cp\": \"refs/heads/9.0.61@{#1}\", \"got_webrtc_revision\": \"ded6636cf43904448ee926d1f2b4352c8a957ca6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33068}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9a77f9773e6577bbd1bab31ba5d468159a7db9e7\", \"swarm_hashes_refs/heads/master(at){#846900}_without_patch\": {\"absl_hardening_tests\": \"96593f3862d26d163aafe9539849b76ad196c80e\", \"accessibility_unittests\": \"6b6187296a9ebbfb612d976ca47250079f35676a\", \"angle_unittests\": \"e34f54a055f0bc748adf488019ba3d3b8984df22\", \"app_list_unittests\": \"65f06a152eae6041f5c742aa934945afd23b9975\", \"app_shell_unittests\": \"151e68c0bb5ab6b8a3a2c826d7adf0271acc3259\", \"ash_unittests\": \"56838cab57e1637f84adcd075fb4e297b979fe53\", \"aura_unittests\": \"b22e71397006594a1778f64effac5ada30f04afe\", \"base_unittests\": \"7b50cef5a86f812ab9e66256b2ecfa503bf097b1\", \"base_util_unittests\": \"a26a9bbd2890d76a4a2ac3c6ada67dbab1b5d341\", \"blink_common_unittests\": \"315b40f7b66212fcaa18d2612ee2e590ae816e93\", \"blink_fuzzer_unittests\": \"845823043949f6433a81a76146bce96df1e2d440\", \"blink_heap_unittests\": \"4e0c6d301fdcdf3a7494ae4574d2a90f2c8061a1\", \"blink_platform_unittests\": \"51a6269b875e5cbb5bef6e0f7834631763f52308\", \"blink_unittests\": \"81ace0c74dbcf3e00643447473a66ed1cbbedfa8\", \"boringssl_crypto_tests\": \"433b055cbbb6f7a6071874dcf6f0a8d1c7ad9d64\", \"boringssl_ssl_tests\": \"b39f93e4797cf80f0a5e3d5d8ecb0a9bc7d39636\", \"browser_tests\": \"093b44ff867fb83f02a2b374a44a5ad6eb7efe04\", \"capture_unittests\": \"fe7a383aaa2e937d1d6dd2aa2f016210c6150f6e\", \"cast_unittests\": \"912e718d6bfdf128ba60613ee72c54e3b4b7ea1a\", \"cc_unittests\": \"0c4e59896ceb78a0c8ac647f9bdc48fd11d1e1e3\", \"chrome_app_unittests\": \"4720badfba8f9f9d92026db1083c9aefb8990088\", \"chromedriver_unittests\": \"cb750a6bbfaa5ac1b783edd087b98b8bc11fb463\", \"chromeos_components_unittests\": \"02d3a7d1338de6ba56c5c6bd05848b801112bf95\", \"chromeos_unittests\": \"3bdc7d4dedc1944d99d56cc7926eff27718e3b90\", \"components_browsertests\": \"d7c9c81693a459623ecb06e5c81be4412c471d4c\", \"components_unittests\": \"179e310c339d4c2dde0a3c6a79259fb67a69082a\", \"compositor_unittests\": \"9b0219ab0a3847cf377c3b97ae7d980ccf0874d8\", \"content_browsertests\": \"e11ecd5e0a9907a9b5088c692ba766d206fd34f6\", \"content_unittests\": \"f4fcd4495760ffc2e6072f9f7854ab4183b187d9\", \"crashpad_tests\": \"506c39980e06dd000967b2a8ee061e2527bcbfbb\", \"crypto_unittests\": \"f080103480aa6cffa5f199ce2028d31b4cddea67\", \"dbus_unittests\": \"24d7bed5f77ebd1824d04bc573b5f8f141e2e2e0\", \"device_unittests\": \"3ff5be8cd4b217bbb4aa62ffd8640d9117ff297b\", \"display_unittests\": \"72dbba05b5f12d46ea1c66f0d595d3429cc570cf\", \"events_unittests\": \"3630de168f1e1cd40730ee551b45b1533dfbef86\", \"exo_unittests\": \"e5ad0ae5a2b83850e9de7318be4a2583898c955c\", \"extensions_browsertests\": \"45fc5043f92a6682c3584348b031c844393e09d5\", \"extensions_unittests\": \"84de6a04561ae148ca99950fe969702a842ff938\", \"filesystem_service_unittests\": \"f5656a6dda3772344c0fc5559c027784a74dc61b\", \"gcm_unit_tests\": \"001d391e71638ef62ee9657cacfcea418cc60cfe\", \"gfx_unittests\": \"f5af835dda9a162a5a2df0e15d4a0d3b717e9ad0\", \"gin_unittests\": \"9f3079985528fcf8173418bdb243a97fcd6d5931\", \"gl_unittests_ozone\": \"b1dc5d95710375473669348247cbcf9d1b8ff404\", \"google_apis_unittests\": \"e873ebdbafdf481d35f66b21ebdd0235d5dd7e4a\", \"gpu_unittests\": \"8d761dce4c1012ecbfb872cf73fbe67f49037e52\", \"gwp_asan_unittests\": \"ceceaeabb503eabcb1d79a88e1f5627395d49fd2\", \"interactive_ui_tests\": \"1ce21ea7db109fb18ced85ef5b9fb96410cdd36c\", \"ipc_tests\": \"5866ee72ca97f2da528d78a9a780a80b8f41c57f\", \"jingle_unittests\": \"2e56d04340a4ecb6f5315294af901511c316af88\", \"keyboard_unittests\": \"a453e4746e7c8af6d2b9dd0a46eaf03ac7683868\", \"latency_unittests\": \"8d3ac8c9258650f41079c3f9dfc18f2510e32037\", \"libjingle_xmpp_unittests\": \"686f2e602b908eae362eac186b6bfdd224b6f7d5\", \"liburlpattern_unittests\": \"242ef21377740dcbf59c4fe4f2ad13b903e5e0b1\", \"media_blink_unittests\": \"4c5e46d1de02f08c15b6920df3fbd2bd6d4765e5\", \"media_unittests\": \"64e427779b7cc2912d1c2ed1002854065b9542c4\", \"message_center_unittests\": \"b3a5e92c3d640976d8c1701ebaa552ba4eadde7c\", \"midi_unittests\": \"3511a78e6cd58177883532a556fb1583ae9e6f01\", \"mojo_core_unittests\": \"ca95b0613eb4070e10e5b52c2992cf30b94d36e0\", \"mojo_unittests\": \"c048e7b91c389189ce08eff8e5a41b9c7cee40ee\", \"nacl_helper_nonsfi_unittests\": \"0b8cacbd8e0c8407c1b8a94f88dfb39f63022e49\", \"nacl_loader_unittests\": \"0d62da934c4c40a5bc29055323f72be3b7b38c43\", \"native_theme_unittests\": \"bc401c3e8d65575259b89feeca3903f0c63bbc07\", \"net_unittests\": \"8d8cb593015188201cb70778ff2bee9b9abad5cd\", \"ozone_gl_unittests\": \"e79bf4749eba325e0d15270dfa8a4c7782d9bd93\", \"ozone_unittests\": \"c752aacbb1aaedd1a16f4a7dbe8f57d746e185ab\", \"ozone_x11_unittests\": \"49bc79ed6d37635119f760d59235893d8b7f9e4d\", \"pdf_unittests\": \"0214cfe2f846e596e9a505a5c079d893dd5aa189\", \"perfetto_unittests\": \"52ee783aa9364cf2480a940ecbf8f465b78a0da7\", \"ppapi_unittests\": \"a0be53c4d71311665b37a67ef5b59b116e997827\", \"printing_unittests\": \"49e651a98bb97b571ad6020571da13cd6780b3a6\", \"remoting_unittests\": \"722a2434482a0a59b02276e7b2fccca374ab5f96\", \"sandbox_linux_unittests\": \"daf8415fab74362a2a941da2c16d6bb71feed773\", \"service_manager_unittests\": \"66dc92267d2c4ac7a311b9fa3a8ff35027c17555\", \"services_unittests\": \"5ccb960e1dae32d9c56c31d56a484d1d2ca2b104\", \"shell_dialogs_unittests\": \"f3f9405474b13e5647fbace86b9132ad0a9c91b4\", \"shell_encryption_unittests\": \"556420bd0ec7847096df8aa588a9c73387429ccf\", \"skia_unittests\": \"0262e58123b551f66e4a66fe4584802ea9498e32\", \"snapshot_unittests\": \"4e62789b4985efc75f6ea2d94f63d1d9e1ca655d\", \"sql_unittests\": \"4db67cf5b0f031daa0213f18f1e9bc9fe7d66a7f\", \"storage_unittests\": \"e4f2893c6d563359a66a92f30853d0e93a42bd0c\", \"sync_integration_tests\": \"eb8f31ab1abc99b735a108ba6f33032b19102dc4\", \"traffic_annotation_auditor_unittests\": \"efd0214eec72e40514caafc6f8979590e70e60e0\", \"ui_base_unittests\": \"34a2f868e3cd19874bd021090a9d2f665b599bfd\", \"ui_chromeos_unittests\": \"fce0e774c00740055c889c94096afbe6a7fbb505\", \"ui_touch_selection_unittests\": \"498a0f9c671d706a4d4e7be98ebb915c2545813f\", \"unit_tests\": \"21b01954a0533341895b4d36ca70d53f436f6cd9\", \"url_unittests\": \"e5279485c6e38630b21be906c4a39b413b4ccbff\", \"usage_time_limit_unittests\": \"0b7e23ae74d428fcbdd29215f638aba6b7a52154\", \"views_unittests\": \"55b75c282cc1f4d6370a413417a22ea3d19f28de\", \"viz_unittests\": \"c4d81bf614b5f85cc7ea1e326dbfa5cb355fee14\", \"wayland_client_perftests\": \"ba1426bdee2818ea492c5d0e7b48530c71544ebc\", \"wm_unittests\": \"f707d38d46535bf32f6ba204cc28f18e0a80c220\", \"wtf_unittests\": \"b304bd3489c2ac2b1b4c70d65c45ad85a3decab0\", \"zlib_unittests\": \"5d776b4d312991aad8f640bb144494101e9b47c4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_25c22b29ff9525bbfd333bb12e92a9faf5d35886dc9a7dacce03d872c45df5d1_v2\", \"builder_37a6efb75484d21403d66fa62f2f0e5111772231983ba4ec0093d39fca548be8_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a942695c23e0ee058ce93eb378e4719ec038e7b245f732574f7a4ff5c9d830ce_v2\", \"builder_cccbb10c846899f06deffa42caa19db86cd5babce89846fb5ee94576babbc4b9_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-120-8ubm\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5672-4713de1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- KioskErrorScreenTest.OpenCertificateConfig\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611611398019557",
"status": "COMPLETED",
"status_changed_ts": "1611611398415696",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44895",
"builder:linux-chromeos-rel",
"buildset:commit/git/9a77f9773e6577bbd1bab31ba5d468159a7db9e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a77f9773e6577bbd1bab31ba5d468159a7db9e7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022526746661475344",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857096684725909200/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:515457e4fa18f010",
"user_agent:luci-scheduler"
],
"updated_ts": "1611615093272276",
"url": "https://ci.chromium.org/b/8857096684725909200",
"utcnow_ts": "1611615093762444"
}
}
[22:51:33.774] Invocation finished in 1h1m38.946161416s with status FAILED