[18:06:52.727] New invocation is queued and will start shortly
[18:06:53.950] Starting the invocation (attempt 1)
[18:06:54.051] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:06:54.052] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:06:54.052] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026708239286824160",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0c18b3952d2a215818231a9c5d432794a9de5770\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026708239286824160",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0c18b3952d2a215818231a9c5d432794a9de5770",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c18b3952d2a215818231a9c5d432794a9de5770",
"gitiles_ref:refs/heads/master"
]
}
[18:06:55.098] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607623614281653",
"id": "8861278176990402144",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c18b3952d2a215818231a9c5d432794a9de5770\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607623614818505",
"tags": [
"build_address:luci.chromium.ci/win-asan/16144",
"builder:win-asan",
"buildset:commit/git/0c18b3952d2a215818231a9c5d432794a9de5770",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c18b3952d2a215818231a9c5d432794a9de5770",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026708239286824160",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8861278176990402144/+/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": "1607623614818649",
"url": "https://ci.chromium.org/b/8861278176990402144",
"utcnow_ts": "1607623615076739"
}
}
[18:06:55.098] Task URL: https://ci.chromium.org/b/8861278176990402144
[18:06:55.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:2:0) after 1m0s
[18:06:59.489] Received PubSub notification, asking Buildbucket for the build status
[18:06:59.798] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:55.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:2:0)
[18:07:55.132] Timer tick, asking Buildbucket for the build status
[18:07:56.038] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:56.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:4:0) after 1m0s
[18:08:56.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:4:0)
[18:08:56.191] Timer tick, asking Buildbucket for the build status
[18:08:56.282] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:56.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:5:0) after 1m0s
[18:09:56.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:5:0)
[18:09:56.336] Timer tick, asking Buildbucket for the build status
[18:09:56.460] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:56.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:6:0) after 1m0s
[18:10:56.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:6:0)
[18:10:56.576] Timer tick, asking Buildbucket for the build status
[18:10:56.707] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:56.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:7:0) after 1m0s
[18:11:56.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:7:0)
[18:11:56.765] Timer tick, asking Buildbucket for the build status
[18:11:56.864] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:56.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:8:0) after 1m0s
[18:12:56.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:8:0)
[18:12:56.901] Timer tick, asking Buildbucket for the build status
[18:12:57.019] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:57.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:9:0) after 1m0s
[18:13:57.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:9:0)
[18:13:57.005] Timer tick, asking Buildbucket for the build status
[18:13:57.073] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:57.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:10:0) after 1m0s
[18:14:57.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:10:0)
[18:14:57.084] Timer tick, asking Buildbucket for the build status
[18:14:57.242] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:57.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:11:0) after 1m0s
[18:15:57.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:11:0)
[18:15:57.255] Timer tick, asking Buildbucket for the build status
[18:15:57.313] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:57.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:12:0) after 1m0s
[18:16:57.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:12:0)
[18:16:57.400] Timer tick, asking Buildbucket for the build status
[18:16:57.540] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:57.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:13:0) after 1m0s
[18:17:57.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:13:0)
[18:17:57.564] Timer tick, asking Buildbucket for the build status
[18:17:57.763] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:57.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:14:0) after 1m0s
[18:18:57.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:14:0)
[18:18:57.801] Timer tick, asking Buildbucket for the build status
[18:18:57.933] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:57.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:15:0) after 1m0s
[18:19:57.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:15:0)
[18:19:57.967] Timer tick, asking Buildbucket for the build status
[18:19:58.038] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:58.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:16:0) after 1m0s
[18:20:58.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:16:0)
[18:20:58.079] Timer tick, asking Buildbucket for the build status
[18:20:58.145] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:58.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:17:0) after 1m0s
[18:21:58.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:17:0)
[18:21:58.169] Timer tick, asking Buildbucket for the build status
[18:21:58.268] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:58.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:18:0) after 1m0s
[18:22:58.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:18:0)
[18:22:58.343] Timer tick, asking Buildbucket for the build status
[18:22:58.466] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:58.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:19:0) after 1m0s
[18:23:58.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:19:0)
[18:23:58.536] Timer tick, asking Buildbucket for the build status
[18:23:58.654] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:58.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:20:0) after 1m0s
[18:24:58.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:20:0)
[18:24:58.706] Timer tick, asking Buildbucket for the build status
[18:24:58.779] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:58.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:21:0) after 1m0s
[18:25:58.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:21:0)
[18:25:58.874] Timer tick, asking Buildbucket for the build status
[18:25:58.935] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:58.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:22:0) after 1m0s
[18:26:58.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:22:0)
[18:26:58.959] Timer tick, asking Buildbucket for the build status
[18:26:59.075] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:59.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:23:0) after 1m0s
[18:27:59.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:23:0)
[18:27:59.129] Timer tick, asking Buildbucket for the build status
[18:27:59.216] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:59.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:24:0) after 1m0s
[18:28:59.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:24:0)
[18:28:59.287] Timer tick, asking Buildbucket for the build status
[18:28:59.350] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:59.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:25:0) after 1m0s
[18:29:59.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:25:0)
[18:29:59.393] Timer tick, asking Buildbucket for the build status
[18:29:59.708] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:59.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:26:0) after 1m0s
[18:30:59.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:26:0)
[18:30:59.792] Timer tick, asking Buildbucket for the build status
[18:31:00.173] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:00.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:27:0) after 1m0s
[18:32:00.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:27:0)
[18:32:00.264] Timer tick, asking Buildbucket for the build status
[18:32:00.409] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:00.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:28:0) after 1m0s
[18:33:00.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:28:0)
[18:33:00.938] Timer tick, asking Buildbucket for the build status
[18:33:01.040] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:01.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:29:0) after 1m0s
[18:34:01.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:29:0)
[18:34:01.389] Timer tick, asking Buildbucket for the build status
[18:34:02.254] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:02.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:30:0) after 1m0s
[18:35:02.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:30:0)
[18:35:02.332] Timer tick, asking Buildbucket for the build status
[18:35:02.661] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:02.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:31:0) after 1m0s
[18:36:02.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:31:0)
[18:36:02.752] Timer tick, asking Buildbucket for the build status
[18:36:04.080] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:04.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:32:0) after 1m0s
[18:37:04.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:32:0)
[18:37:04.070] Timer tick, asking Buildbucket for the build status
[18:37:04.268] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:04.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:33:0) after 1m0s
[18:38:04.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:33:0)
[18:38:04.385] Timer tick, asking Buildbucket for the build status
[18:38:04.981] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:04.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:34:0) after 1m0s
[18:39:05.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:34:0)
[18:39:05.093] Timer tick, asking Buildbucket for the build status
[18:39:05.226] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:05.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:35:0) after 1m0s
[18:40:05.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:35:0)
[18:40:05.266] Timer tick, asking Buildbucket for the build status
[18:40:05.633] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:05.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:36:0) after 1m0s
[18:41:05.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:36:0)
[18:41:05.674] Timer tick, asking Buildbucket for the build status
[18:41:05.778] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:05.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:37:0) after 1m0s
[18:42:05.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:37:0)
[18:42:05.901] Timer tick, asking Buildbucket for the build status
[18:42:06.115] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:06.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:38:0) after 1m0s
[18:43:06.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:38:0)
[18:43:06.182] Timer tick, asking Buildbucket for the build status
[18:43:06.355] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:06.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:39:0) after 1m0s
[18:44:06.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:39:0)
[18:44:06.507] Timer tick, asking Buildbucket for the build status
[18:44:06.669] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:06.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:40:0) after 1m0s
[18:45:06.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:40:0)
[18:45:06.700] Timer tick, asking Buildbucket for the build status
[18:45:06.834] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:06.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:41:0) after 1m0s
[18:46:06.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:41:0)
[18:46:06.859] Timer tick, asking Buildbucket for the build status
[18:46:07.036] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:07.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:42:0) after 1m0s
[18:47:07.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:42:0)
[18:47:07.069] Timer tick, asking Buildbucket for the build status
[18:47:07.534] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:07.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:43:0) after 1m0s
[18:48:07.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:43:0)
[18:48:07.603] Timer tick, asking Buildbucket for the build status
[18:48:07.743] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:07.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:44:0) after 1m0s
[18:49:07.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:44:0)
[18:49:07.813] Timer tick, asking Buildbucket for the build status
[18:49:07.892] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:07.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:45:0) after 1m0s
[18:50:07.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:45:0)
[18:50:07.951] Timer tick, asking Buildbucket for the build status
[18:50:08.054] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:08.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:46:0) after 1m0s
[18:51:08.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:46:0)
[18:51:08.187] Timer tick, asking Buildbucket for the build status
[18:51:08.601] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:08.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:47:0) after 1m0s
[18:52:08.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:47:0)
[18:52:08.658] Timer tick, asking Buildbucket for the build status
[18:52:08.876] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:08.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:48:0) after 1m0s
[18:53:08.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:48:0)
[18:53:08.904] Timer tick, asking Buildbucket for the build status
[18:53:08.980] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:08.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:49:0) after 1m0s
[18:54:09.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:49:0)
[18:54:09.064] Timer tick, asking Buildbucket for the build status
[18:54:09.157] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:09.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:50:0) after 1m0s
[18:55:09.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:50:0)
[18:55:09.180] Timer tick, asking Buildbucket for the build status
[18:55:09.295] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:09.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:51:0) after 1m0s
[18:56:09.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:51:0)
[18:56:09.349] Timer tick, asking Buildbucket for the build status
[18:56:09.558] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:09.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:52:0) after 1m0s
[18:57:09.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:52:0)
[18:57:09.564] Timer tick, asking Buildbucket for the build status
[18:57:09.683] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:09.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:53:0) after 1m0s
[18:58:09.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:53:0)
[18:58:09.714] Timer tick, asking Buildbucket for the build status
[18:58:09.846] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:09.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:54:0) after 1m0s
[18:59:09.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:54:0)
[18:59:09.955] Timer tick, asking Buildbucket for the build status
[18:59:10.075] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:10.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:55:0) after 1m0s
[19:00:10.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:55:0)
[19:00:10.149] Timer tick, asking Buildbucket for the build status
[19:00:10.271] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:10.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:56:0) after 1m0s
[19:01:10.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:56:0)
[19:01:10.312] Timer tick, asking Buildbucket for the build status
[19:01:10.791] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:10.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:57:0) after 1m0s
[19:02:10.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:57:0)
[19:02:10.859] Timer tick, asking Buildbucket for the build status
[19:02:11.477] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:11.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:58:0) after 1m0s
[19:03:11.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:58:0)
[19:03:11.530] Timer tick, asking Buildbucket for the build status
[19:03:12.030] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:12.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:59:0) after 1m0s
[19:04:12.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:59:0)
[19:04:12.034] Timer tick, asking Buildbucket for the build status
[19:04:12.308] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:12.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:60:0) after 1m0s
[19:05:12.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:60:0)
[19:05:12.343] Timer tick, asking Buildbucket for the build status
[19:05:12.476] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:12.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:61:0) after 1m0s
[19:06:12.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:61:0)
[19:06:12.505] Timer tick, asking Buildbucket for the build status
[19:06:12.633] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:12.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:62:0) after 1m0s
[19:07:12.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:62:0)
[19:07:12.630] Timer tick, asking Buildbucket for the build status
[19:07:12.756] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:12.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:63:0) after 1m0s
[19:08:12.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:63:0)
[19:08:12.787] Timer tick, asking Buildbucket for the build status
[19:08:12.888] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:12.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:64:0) after 1m0s
[19:09:12.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:64:0)
[19:09:12.916] Timer tick, asking Buildbucket for the build status
[19:09:13.216] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:13.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:65:0) after 1m0s
[19:10:13.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:65:0)
[19:10:13.289] Timer tick, asking Buildbucket for the build status
[19:10:13.397] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:13.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:66:0) after 1m0s
[19:11:13.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:66:0)
[19:11:13.439] Timer tick, asking Buildbucket for the build status
[19:11:13.520] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:13.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:67:0) after 1m0s
[19:12:13.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:67:0)
[19:12:13.616] Timer tick, asking Buildbucket for the build status
[19:12:13.748] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:13.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:68:0) after 1m0s
[19:13:13.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:68:0)
[19:13:13.773] Timer tick, asking Buildbucket for the build status
[19:13:13.904] Build 8861278176990402144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:13.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026708239286824160:69:0) after 1m0s
[19:13:21.020] Received PubSub notification, asking Buildbucket for the build status
[19:13:21.501] Build 8861278176990402144: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:13:21.501] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607627600160377",
"created_by": "project:chromium",
"created_ts": "1607623614281653",
"id": "8861278176990402144",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c18b3952d2a215818231a9c5d432794a9de5770\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"0c18b3952d2a215818231a9c5d432794a9de5770\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-6-3vmi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16144, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"32f1a8e812174be4507c4ab574bc3d14c1ba9a02\", \"got_dawn_revision\": \"6bcd4aceef100810284d61bd9dad0e9bd05e4c92\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"0c18b3952d2a215818231a9c5d432794a9de5770\", \"got_revision_cp\": \"refs/heads/master@{#835734}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"d94a77b304f4fcb503ea037be3e5d7f2a371c4b6\", \"got_v8_revision\": \"73cdeec67c6c85946bb91a2b74c63f93acd4cbe7\", \"got_v8_revision_cp\": \"refs/heads/8.9.129@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c18b3952d2a215818231a9c5d432794a9de5770\", \"swarm_hashes_refs/heads/master(at){#835734}_without_patch\": {\"absl_hardening_tests\": \"6c27b86726df8319fdf5cb6cb424ba3a82ec18c0\", \"accessibility_unittests\": \"706f709b423b52dde5ed4e315eabbacc941ea52f\", \"angle_unittests\": \"1797f5b3be570e48b52fc1b0ec9b8a45cc91b969\", \"app_shell_unittests\": \"ef85e34efa21daeb406d5b2615bbb362fe25d0e0\", \"aura_unittests\": \"aa3f4685ef1f5591fc4fc00a53e5b00f52586ab6\", \"base_unittests\": \"3d21e8c29603b92a32d034941240e570bbe671a5\", \"base_util_unittests\": \"43d9cf5299bf7a42f67649c233d8c914242bafa0\", \"blink_common_unittests\": \"7225fc69ca6566dff404f4e553a267d70c8e9d80\", \"blink_fuzzer_unittests\": \"52aa36b76447a9674764304e815188f9f72cd970\", \"blink_heap_unittests\": \"c77c770c4cf3edec11d9fdb1d60f95d3674b226b\", \"blink_platform_unittests\": \"1ca154693f0541caf55fac69082e20c06bb31be4\", \"blink_unittests\": \"dc82a79908028ab9630dd47590ae9a653857d0ff\", \"boringssl_crypto_tests\": \"b1f640b797b903f15c9343dbe97aeaf84571b95a\", \"boringssl_ssl_tests\": \"f7624b3da64775e1738ba5737e45dcb5800b9126\", \"browser_switcher_bho_unittests\": \"a4768a4b79fb2db0a76f1935fd5ee65a4746fafb\", \"browser_tests\": \"8c374236c29b9aafe500271175a54dd549ce452d\", \"capture_unittests\": \"47c9a25d3f22421d1699288088afcf798b8af5da\", \"cast_unittests\": \"6b39c875a335b020071d039446a0c6b29fabaae5\", \"cc_unittests\": \"bb5e1b4b3ccbf4966be24e3452ac552ad0e1d003\", \"chrome_app_unittests\": \"4b81bac9b7eb7d757df7390cec7852a36cdf7298\", \"chrome_cleaner_unittests\": \"2442b19fa39f5310334550fd48692958b7b802a6\", \"chrome_elf_unittests\": \"3304a2d82c1c59f2f0a288efeaca0cca1e02588c\", \"chromedriver_unittests\": \"d16b2ba3a9a9235197d92a333616b0a90980b9dd\", \"components_browsertests\": \"1efd4e896e4c0b04b3444326dbe01e18a7d6be94\", \"components_unittests\": \"aa1eb7978f9302ff3c44157ec6e3e5e1b9c3be6c\", \"compositor_unittests\": \"16080150f02ed4970aed15c8c21307e1e27481b2\", \"content_browsertests\": \"d9aec54e977eacae465261b08d37808d686a093b\", \"content_unittests\": \"e232c8b1b60b2b83035076965ea6e2e26559b3f4\", \"courgette_unittests\": \"0dd9fa74f3b9b00387bf12ca62559d6a4368bfd4\", \"crashpad_tests\": \"c90541a7b8171b13377fab8b3ab2416fda25d368\", \"cronet_tests\": \"19409dbcb318cd3340963f656db1071deb6ae638\", \"cronet_unittests\": \"6e6474ff1f51de764caf92569c0cabd6e41c8459\", \"crypto_unittests\": \"9ee6a4af669a53559246dfec3c46bd32cc62ad5b\", \"delayloads_unittests\": \"20358b0fa9ab718aca91b6aa0e4fadc1125281fd\", \"device_unittests\": \"621f26925bcb3563dcbfc119ddc3f1489ddad234\", \"display_unittests\": \"90f440b474996c2e2cadeb1a68c308754fee6d23\", \"elevation_service_unittests\": \"1e5446bff047f009116d0ab83e64b4cb11a3a72b\", \"events_unittests\": \"2392dff0c39fad46266fe4a2d2fc525f9d0ce161\", \"extensions_browsertests\": \"15c864180bbf43ad389cd909edd827e1012cd38b\", \"extensions_unittests\": \"347227897b19137ae03b399768eeb3bb21b159a2\", \"filesystem_service_unittests\": \"289943c0db76c7c8cbe5d1896a3e21b18016fd7f\", \"gcm_unit_tests\": \"99de9a941aee3e37cbbcb83d662f7fac5cfe4381\", \"gcp_unittests\": \"7b317c174049a0a9b4dab5b84f6a3ccd4fdaf493\", \"gfx_unittests\": \"9ae636bf70df260099e9579299ba82005dcd1d1f\", \"gin_unittests\": \"be3db495a3b939375650e63478fc03f4d54d9e35\", \"google_apis_unittests\": \"a30245c9c7095ed4209f7accd9284c5476180955\", \"gpu_unittests\": \"e59fa8b0fdae0af506e2f00f266e9370ec5682a0\", \"gwp_asan_unittests\": \"fca4f94eb1ce96b472bdbb0a6c0e7d1ed9c43757\", \"headless_browsertests\": \"7405bc0581d98f907a819d1aceb7499fdb913ae4\", \"headless_unittests\": \"bf59c2c99e6cf6317fea7d85b13da79cdbb346c5\", \"install_static_unittests\": \"a11c2174bc647d8aa9c6ef6a9ed34bf3a558456c\", \"installer_util_unittests\": \"aef1589913f1b1199de53cf66d0f8c83f0316877\", \"interactive_ui_tests\": \"504d3d3fd1d61c3e047b4267fbb23f37e672b5e4\", \"ipc_tests\": \"c51f81e333ace655df0ab1596007e4b002adc886\", \"jingle_unittests\": \"c8663bbfe8222b0ebec424dbf8d830ad7e5821bd\", \"latency_unittests\": \"76ab764e30e5f69ddc56050e56959f78c2425b97\", \"libjingle_xmpp_unittests\": \"96d209ab436192ad2f7f032e3b780b5e4543bf3b\", \"liburlpattern_unittests\": \"622afe121d620f6e722e26be9b699315a60556f6\", \"media_blink_unittests\": \"08e4df73010e8502de8dcb3aa57dd378b62a8199\", \"media_unittests\": \"b0c892c17a790a4e0533f51d62983009d1bca9aa\", \"message_center_unittests\": \"46fd0e708c511744643348c401a6c4c433e9eade\", \"midi_unittests\": \"c37ca1a6f9ff4b1f3155aedb8469825fd5271abf\", \"mojo_core_unittests\": \"af5519769d3b934ff37b67e3378ac532ccdbaedb\", \"mojo_unittests\": \"1c92fe34b533f8942b6568d2dad7e4cc7e9ac81d\", \"nacl_loader_unittests\": \"9dbd03c76bb5a4fc14bd8fa4ef47e8ac0a1d4ac2\", \"native_theme_unittests\": \"07fcf17f328d985d079a1ccc103374b43ea19130\", \"net_unittests\": \"83cb3817c8d954c30a39c45e5cbd6ef2039682ee\", \"notification_helper_unittests\": \"e6f201998197319dcaa1d7823d41292628441ec8\", \"pdf_unittests\": \"96d9e20901e9ef1b0d8848fe79147897dbe81ad9\", \"perfetto_unittests\": \"f042ba4c4f49def699995ff981f29645f838c011\", \"ppapi_unittests\": \"f2253b300354d9d0acc0afe14ca06cdbc327aa82\", \"printing_unittests\": \"6987371858f37c49a55a2ef71fee4f1a15ed93dc\", \"remoting_unittests\": \"a3e1fa0ee0036e6801d5d14d4d095e8b80725b7e\", \"sbox_integration_tests\": \"8ba7ba856de5f227f97b3783b00cd7da0800f148\", \"sbox_unittests\": \"c9d8243d273e5efea89439a849a2451a2928c81c\", \"sbox_validation_tests\": \"98229c42467f68c702a9e99c6caaa1396af2a74c\", \"service_manager_unittests\": \"7fd8018a1544b640fb4e10a4d73c55b06eabdfb8\", \"services_unittests\": \"2105ef5876b04d85ca9382dc126fbfc2da7e8635\", \"setup_unittests\": \"6ad4f52472b2113f63a40f24c4742b26555aa9af\", \"shell_dialogs_unittests\": \"8bf0b21864430c588587d3e7193c765e6df47c50\", \"skia_unittests\": \"10a50a4ec7505b554b73a3fd12e7ca65e9220459\", \"snapshot_unittests\": \"aad7e1b2badc74667db408f26b8adcf300163502\", \"sql_unittests\": \"5b9786bdab74bfa3ba61e46061d065bf001b6080\", \"storage_unittests\": \"ba07c17decb4eb99f4589295ca6482bf3e8ab084\", \"sync_integration_tests\": \"7d7f23cfeb19d2936eecce03db0644af0f95e6d7\", \"traffic_annotation_auditor_unittests\": \"25d906209ac8d1bcfa51a58f7035528fe0dc1c50\", \"ui_base_unittests\": \"d2a5e063562974338e867e34a2e029b2ce943d77\", \"ui_touch_selection_unittests\": \"a31c3e4c91a9b6f9422589eb224603d5643634b8\", \"unit_tests\": \"e27ad86d54cecfbc1c56d9531440f6eab35d93f4\", \"updater_tests\": \"5cd0ed1ee7bd87412668c28f94125509aa7763a4\", \"url_unittests\": \"fd8c4611c2abc46f2c667a23ecb4b9cad9667e29\", \"views_unittests\": \"7bb08cbe23d592390ab2daa85b5ab29d1f00f287\", \"viz_unittests\": \"d3686b92e4cbaa80939563697a690419e15e6abe\", \"vr_common_unittests\": \"421b1949efde96a5900590acb32d40164cc38994\", \"vr_pixeltests\": \"ae7d2001172ce986f17e2dc533a788c82274170f\", \"weblayer_browsertests\": \"74ba2b058b2d43c5be1da28e07c31760e1e45902\", \"weblayer_unittests\": \"558d04be2ceabd753a9d3d53f4355ef8ed158fa6\", \"wm_unittests\": \"68e544b55d5d2563e62570c86f2f7f452e5de143\", \"wtf_unittests\": \"a7d1350a468243ba3f1e9fa66dea1e795d457c4e\", \"zlib_unittests\": \"27070dfdb2cf8678e4dd0d4ef3cf66225bdfb272\", \"zucchini_unittests\": \"53800f340e96fb80bb53de2caa236486359fbab4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-3vmi\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607623616426039",
"status": "COMPLETED",
"status_changed_ts": "1607627600577198",
"tags": [
"build_address:luci.chromium.ci/win-asan/16144",
"builder:win-asan",
"buildset:commit/git/0c18b3952d2a215818231a9c5d432794a9de5770",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c18b3952d2a215818231a9c5d432794a9de5770",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026708239286824160",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8861278176990402144/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:5066a72aebea7510",
"user_agent:luci-scheduler"
],
"updated_ts": "1607627600581656",
"url": "https://ci.chromium.org/b/8861278176990402144",
"utcnow_ts": "1607627601442589"
}
}
[19:13:21.501] Invocation finished in 1h6m28.794084878s with status SUCCEEDED