[06:00:38.450] New invocation is queued and will start shortly
[06:00:39.528] Starting the invocation (attempt 1)
[06:00:39.565] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:00:39.565] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:00:39.565] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019687366848244960",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"95badd61a39a4bec041a8fc6bbb1f55994ff5452\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019687366848244960",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/95badd61a39a4bec041a8fc6bbb1f55994ff5452",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/95badd61a39a4bec041a8fc6bbb1f55994ff5452",
"gitiles_ref:refs/heads/master"
]
}
[06:00:40.151] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614319239642954",
"id": "8854257304931800720",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"95badd61a39a4bec041a8fc6bbb1f55994ff5452\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614319240040263",
"tags": [
"build_address:luci.chromium.ci/win-asan/17540",
"builder:win-asan",
"buildset:commit/git/95badd61a39a4bec041a8fc6bbb1f55994ff5452",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/95badd61a39a4bec041a8fc6bbb1f55994ff5452",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019687366848244960",
"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/8854257304931800720/+/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": "1614319240040374",
"url": "https://ci.chromium.org/b/8854257304931800720",
"utcnow_ts": "1614319240143400"
}
}
[06:00:40.151] Task URL: https://ci.chromium.org/b/8854257304931800720
[06:00:40.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:2:0) after 1m0s
[06:01:32.539] Received PubSub notification, asking Buildbucket for the build status
[06:01:32.798] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:40.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:2:0)
[06:01:40.258] Timer tick, asking Buildbucket for the build status
[06:01:40.322] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:40.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:4:0) after 1m0s
[06:02:40.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:4:0)
[06:02:40.399] Timer tick, asking Buildbucket for the build status
[06:02:40.565] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:40.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:5:0) after 1m0s
[06:03:40.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:5:0)
[06:03:40.587] Timer tick, asking Buildbucket for the build status
[06:03:40.664] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:40.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:6:0) after 1m0s
[06:04:40.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:6:0)
[06:04:40.702] Timer tick, asking Buildbucket for the build status
[06:04:40.784] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:40.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:7:0) after 1m0s
[06:05:40.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:7:0)
[06:05:40.848] Timer tick, asking Buildbucket for the build status
[06:05:40.966] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:40.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:8:0) after 1m0s
[06:06:41.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:8:0)
[06:06:41.103] Timer tick, asking Buildbucket for the build status
[06:06:41.179] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:41.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:9:0) after 1m0s
[06:07:41.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:9:0)
[06:07:41.188] Timer tick, asking Buildbucket for the build status
[06:07:41.274] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:41.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:10:0) after 1m0s
[06:08:41.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:10:0)
[06:08:41.319] Timer tick, asking Buildbucket for the build status
[06:08:41.460] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:41.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:11:0) after 1m0s
[06:09:41.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:11:0)
[06:09:41.508] Timer tick, asking Buildbucket for the build status
[06:09:41.587] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:41.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:12:0) after 1m0s
[06:10:41.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:12:0)
[06:10:41.623] Timer tick, asking Buildbucket for the build status
[06:10:41.705] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:41.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:13:0) after 1m0s
[06:11:41.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:13:0)
[06:11:41.730] Timer tick, asking Buildbucket for the build status
[06:11:41.818] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:41.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:14:0) after 1m0s
[06:12:41.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:14:0)
[06:12:41.863] Timer tick, asking Buildbucket for the build status
[06:12:41.932] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:41.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:15:0) after 1m0s
[06:13:41.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:15:0)
[06:13:41.987] Timer tick, asking Buildbucket for the build status
[06:13:42.141] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:42.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:16:0) after 1m0s
[06:14:42.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:16:0)
[06:14:42.140] Timer tick, asking Buildbucket for the build status
[06:14:42.215] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:42.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:17:0) after 1m0s
[06:15:42.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:17:0)
[06:15:42.250] Timer tick, asking Buildbucket for the build status
[06:15:42.337] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:42.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:18:0) after 1m0s
[06:16:42.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:18:0)
[06:16:42.470] Timer tick, asking Buildbucket for the build status
[06:16:42.785] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:42.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:19:0) after 1m0s
[06:17:42.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:19:0)
[06:17:42.868] Timer tick, asking Buildbucket for the build status
[06:17:43.206] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:43.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:20:0) after 1m0s
[06:18:43.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:20:0)
[06:18:43.297] Timer tick, asking Buildbucket for the build status
[06:18:43.369] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:43.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:21:0) after 1m0s
[06:19:43.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:21:0)
[06:19:43.429] Timer tick, asking Buildbucket for the build status
[06:19:43.497] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:43.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:22:0) after 1m0s
[06:20:43.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:22:0)
[06:20:43.621] Timer tick, asking Buildbucket for the build status
[06:20:43.706] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:43.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:23:0) after 1m0s
[06:21:43.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:23:0)
[06:21:43.806] Timer tick, asking Buildbucket for the build status
[06:21:44.068] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:44.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:24:0) after 1m0s
[06:22:44.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:24:0)
[06:22:44.103] Timer tick, asking Buildbucket for the build status
[06:22:44.182] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:44.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:25:0) after 1m0s
[06:23:44.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:25:0)
[06:23:44.252] Timer tick, asking Buildbucket for the build status
[06:23:44.337] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:44.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:26:0) after 1m0s
[06:24:44.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:26:0)
[06:24:44.440] Timer tick, asking Buildbucket for the build status
[06:24:44.723] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:44.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:27:0) after 1m0s
[06:25:44.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:27:0)
[06:25:44.845] Timer tick, asking Buildbucket for the build status
[06:25:44.936] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:44.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:28:0) after 1m0s
[06:26:44.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:28:0)
[06:26:44.950] Timer tick, asking Buildbucket for the build status
[06:26:45.230] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:45.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:29:0) after 1m0s
[06:27:45.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:29:0)
[06:27:45.246] Timer tick, asking Buildbucket for the build status
[06:27:45.330] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:45.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:30:0) after 1m0s
[06:28:45.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:30:0)
[06:28:45.441] Timer tick, asking Buildbucket for the build status
[06:28:45.912] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:45.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:31:0) after 1m0s
[06:29:45.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:31:0)
[06:29:45.988] Timer tick, asking Buildbucket for the build status
[06:29:46.072] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:46.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:32:0) after 1m0s
[06:30:46.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:32:0)
[06:30:46.105] Timer tick, asking Buildbucket for the build status
[06:30:46.198] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:46.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:33:0) after 1m0s
[06:31:46.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:33:0)
[06:31:46.267] Timer tick, asking Buildbucket for the build status
[06:31:46.353] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:46.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:34:0) after 1m0s
[06:32:46.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:34:0)
[06:32:46.420] Timer tick, asking Buildbucket for the build status
[06:32:46.501] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:46.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:35:0) after 1m0s
[06:33:46.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:35:0)
[06:33:46.533] Timer tick, asking Buildbucket for the build status
[06:33:46.648] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:46.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:36:0) after 1m0s
[06:34:46.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:36:0)
[06:34:46.682] Timer tick, asking Buildbucket for the build status
[06:34:46.789] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:46.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:37:0) after 1m0s
[06:35:46.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:37:0)
[06:35:46.790] Timer tick, asking Buildbucket for the build status
[06:35:47.200] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:47.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:38:0) after 1m0s
[06:36:47.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:38:0)
[06:36:47.274] Timer tick, asking Buildbucket for the build status
[06:36:47.370] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:47.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:39:0) after 1m0s
[06:37:47.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:39:0)
[06:37:47.433] Timer tick, asking Buildbucket for the build status
[06:37:47.539] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:47.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:40:0) after 1m0s
[06:38:47.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:40:0)
[06:38:47.630] Timer tick, asking Buildbucket for the build status
[06:38:47.722] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:47.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:41:0) after 1m0s
[06:39:47.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:41:0)
[06:39:47.786] Timer tick, asking Buildbucket for the build status
[06:39:48.066] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:48.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:42:0) after 1m0s
[06:40:48.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:42:0)
[06:40:48.112] Timer tick, asking Buildbucket for the build status
[06:40:48.573] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:48.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:43:0) after 1m0s
[06:41:48.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:43:0)
[06:41:48.588] Timer tick, asking Buildbucket for the build status
[06:41:48.693] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:48.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:44:0) after 1m0s
[06:42:48.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:44:0)
[06:42:48.737] Timer tick, asking Buildbucket for the build status
[06:42:48.836] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:48.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:45:0) after 1m0s
[06:43:49.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:45:0)
[06:43:49.151] Timer tick, asking Buildbucket for the build status
[06:43:49.231] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:49.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:46:0) after 1m0s
[06:44:49.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:46:0)
[06:44:49.292] Timer tick, asking Buildbucket for the build status
[06:44:49.398] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:49.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:47:0) after 1m0s
[06:45:49.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:47:0)
[06:45:49.413] Timer tick, asking Buildbucket for the build status
[06:45:49.534] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:49.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:48:0) after 1m0s
[06:46:49.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:48:0)
[06:46:49.571] Timer tick, asking Buildbucket for the build status
[06:46:49.660] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:49.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:49:0) after 1m0s
[06:47:49.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:49:0)
[06:47:49.712] Timer tick, asking Buildbucket for the build status
[06:47:49.859] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:49.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:50:0) after 1m0s
[06:48:50.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:50:0)
[06:48:50.072] Timer tick, asking Buildbucket for the build status
[06:48:50.170] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:50.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:51:0) after 1m0s
[06:49:50.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:51:0)
[06:49:50.401] Timer tick, asking Buildbucket for the build status
[06:49:50.495] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:50.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:52:0) after 1m0s
[06:50:50.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:52:0)
[06:50:50.639] Timer tick, asking Buildbucket for the build status
[06:50:51.189] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:51.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:53:0) after 1m0s
[06:51:51.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:53:0)
[06:51:51.346] Timer tick, asking Buildbucket for the build status
[06:51:51.440] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:51.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:54:0) after 1m0s
[06:52:51.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:54:0)
[06:52:51.654] Timer tick, asking Buildbucket for the build status
[06:52:51.759] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:51.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:55:0) after 1m0s
[06:53:51.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:55:0)
[06:53:51.778] Timer tick, asking Buildbucket for the build status
[06:53:52.332] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:52.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:56:0) after 1m0s
[06:54:52.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:56:0)
[06:54:52.381] Timer tick, asking Buildbucket for the build status
[06:54:52.472] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:52.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:57:0) after 1m0s
[06:55:52.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:57:0)
[06:55:52.532] Timer tick, asking Buildbucket for the build status
[06:55:52.610] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:52.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:58:0) after 1m0s
[06:56:52.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:58:0)
[06:56:52.639] Timer tick, asking Buildbucket for the build status
[06:56:52.791] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:52.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:59:0) after 1m0s
[06:57:52.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:59:0)
[06:57:52.910] Timer tick, asking Buildbucket for the build status
[06:57:52.982] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:52.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:60:0) after 1m0s
[06:58:53.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:60:0)
[06:58:53.087] Timer tick, asking Buildbucket for the build status
[06:58:53.178] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:53.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:61:0) after 1m0s
[06:59:53.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:61:0)
[06:59:53.238] Timer tick, asking Buildbucket for the build status
[06:59:53.319] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:53.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:62:0) after 1m0s
[07:00:53.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:62:0)
[07:00:53.375] Timer tick, asking Buildbucket for the build status
[07:00:53.751] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:53.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:63:0) after 1m0s
[07:01:53.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:63:0)
[07:01:53.779] Timer tick, asking Buildbucket for the build status
[07:01:53.881] Build 8854257304931800720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:53.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019687366848244960:64:0) after 1m0s
[07:02:03.870] Received PubSub notification, asking Buildbucket for the build status
[07:02:04.178] Build 8854257304931800720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:02:04.178] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614322921827456",
"created_by": "project:chromium",
"created_ts": "1614319239642954",
"id": "8854257304931800720",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"95badd61a39a4bec041a8fc6bbb1f55994ff5452\"}}",
"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}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"95badd61a39a4bec041a8fc6bbb1f55994ff5452\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17540, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"77637f2dd222530c721018185a90e24a51e7a745\", \"got_dawn_revision\": \"87649ff09dee357f0412834527e4174b4989358f\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"95badd61a39a4bec041a8fc6bbb1f55994ff5452\", \"got_revision_cp\": \"refs/heads/master@{#858040}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"bb52bdf09516ca548c4aff50526eda561f239bc0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33341}\", \"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\": \"95badd61a39a4bec041a8fc6bbb1f55994ff5452\", \"swarm_hashes_refs/heads/master(at){#858040}_without_patch\": {\"absl_hardening_tests\": \"81450d4a59cf9e11196375f7c6c8c515700de2b8\", \"accessibility_unittests\": \"ab547df387a235abce91a8b7bc8e761dd648b686\", \"angle_unittests\": \"c95fc065ac8df3967b1c14cf8c25f6b04e407a3f\", \"app_shell_unittests\": \"3cc5ae0d376ec7b774cf39d11563d6e5be24362d\", \"aura_unittests\": \"88b8fbbb2c33488f674ea4859fa61939e55a9504\", \"base_unittests\": \"906740c15f10ed80bf925139d3e77ce4f87a7dd4\", \"base_util_unittests\": \"183ea2f869a5bacacb92e1d4efa996d21f62c1b5\", \"blink_common_unittests\": \"4bd61953f257f824ce91b42272522ee32c700cec\", \"blink_fuzzer_unittests\": \"9739bf3db127ce8c72f09f4726679091cbbac452\", \"blink_heap_unittests\": \"5d53840e2288c16f726cd2ec6bb4f13f9bd3eea1\", \"blink_platform_unittests\": \"e430728845ffaff618245c3afa5bf827cc72af17\", \"blink_unittests\": \"356831ee982697d8b6b37ae4eeaf3b1c28d86581\", \"boringssl_crypto_tests\": \"674ab1de077ecbf293466d4cd9be4a9ce028af5c\", \"boringssl_ssl_tests\": \"9c2e930ff8b74248dbb8f2c686bde225d63fe1b2\", \"browser_switcher_bho_unittests\": \"e6bf1ef4ede47b01747b72e946ac79e27de81f8c\", \"browser_tests\": \"d04506ddeb5062a5f855e4bfa941336a35defbb6\", \"capture_unittests\": \"940230cb467164e2a41fc6f3c2d12c0a3d4f2c87\", \"cast_unittests\": \"b9999938415f3c79dbb2c0b5a2512e2ed3eba6be\", \"cc_unittests\": \"199beee19cb855d4e2cc0741df2d9496740ea7c1\", \"chrome_app_unittests\": \"b1ade3a8c346152b1b5a7e75c650b225369233e9\", \"chrome_cleaner_unittests\": \"192bf6440db48299eca9bbd00194251661d4a6c5\", \"chrome_elf_unittests\": \"a8dfd31406877dcd98a3fa080f6e873c1756c64b\", \"chromedriver_unittests\": \"aa8460a8ef66b7e1510356be0a192bde1d0343a0\", \"color_unittests\": \"245e4cdae4a28caa80791d94660b7ef44a8b2d90\", \"components_browsertests\": \"21f79d0ae5e6230b6cb4dc1f9a65c06966534a55\", \"components_unittests\": \"690855281f9f627b3f5fbebcb9a512c43919698a\", \"compositor_unittests\": \"c393311a7b7b418c2ce5f514da4a07a9079a9650\", \"content_browsertests\": \"1f20ac928a6c3387e5c1d86c8a44c3218b18d98b\", \"content_unittests\": \"87c2857513f146b73782629962d92defe011ab52\", \"courgette_unittests\": \"c1b9625b0b838aeee98d56a7a29d6730a773af97\", \"crashpad_tests\": \"a2c135a197ccc896cab4bffb7654587287aa5ef8\", \"cronet_tests\": \"ff59d422d179c1d2650d012b92bfcd278768dfdb\", \"cronet_unittests\": \"baf01e0117e11e09a50d4ea6a7632c68f190ddb6\", \"crypto_unittests\": \"810f93774b6de6e1c72b90ecf67e53fb0ab2379b\", \"delayloads_unittests\": \"589e8bf5f6b8b8c2f98a6297871bc01be1a2fc18\", \"device_unittests\": \"e236d7fa2d2c95c31bbc6d8bba28558db648754f\", \"display_unittests\": \"fd5a51f998be0f2b7e435c60fdc624a13cd3fa81\", \"elevation_service_unittests\": \"837df431dd3212eaaf93165b9c31a5dd50f4dd0d\", \"events_unittests\": \"a4b0773ae494f33d0b366614b7f03a190eeb2544\", \"extensions_browsertests\": \"f69d704e26d13f393345471de53056d83b6c4600\", \"extensions_unittests\": \"3ac790a0b9980ce08b1fec75f2d82091cc5ad6c0\", \"filesystem_service_unittests\": \"ef919b359d66c916899656ab74a633054401a4ed\", \"gcm_unit_tests\": \"260757e09e943ee007b486a82f74c43ca3a60318\", \"gcp_unittests\": \"44f30484ef0703b1990951595fc84f7913b5c7b3\", \"gfx_unittests\": \"fc2a933e187d28960dfe3d4eb764b084384cc331\", \"gin_unittests\": \"77a9064652b5990f17c87d035091a2eba8cc212d\", \"google_apis_unittests\": \"e5e248ee0e337d524d75c5ad7c77b890f82024c2\", \"gpu_unittests\": \"8ae3231db105434d61bcfca0d684af5f125111db\", \"gwp_asan_unittests\": \"fba4bda01bdc4c369ac1aaae1a3b1814c13cf30e\", \"headless_browsertests\": \"9160a66daac32905bed9e047ff080fafe2fde072\", \"headless_unittests\": \"3accf5ef988ae9828de387d4793db795006e444c\", \"install_static_unittests\": \"f0102e26b06ea141fea09c403002a08276d314e4\", \"installer_util_unittests\": \"d986be198084cdaca9e89f4d82abfd708b4db3e4\", \"interactive_ui_tests\": \"58550558ae07100bfc3447b5040673327d1aee8a\", \"ipc_tests\": \"eb94de0b3b55b0584b8cbf623a1cfc1abf42beee\", \"jingle_unittests\": \"326a980bd1dfe279ffecd8954e6722160aa097d8\", \"latency_unittests\": \"b5fb73f4991241d71d0cb525844eba9183ca4610\", \"libjingle_xmpp_unittests\": \"fab0019103a07408bb35fb9b0bbb9fe7777a5293\", \"liburlpattern_unittests\": \"cd4b5716a0d5f5d2c2b19bf957292b58915f9a62\", \"media_blink_unittests\": \"5236c179701167c42cc183a0e87710ebaa6e5fad\", \"media_unittests\": \"bc0921d5a8ff63b539e0dea949b8dd608a9522b9\", \"message_center_unittests\": \"e6dc00d435a547453d443e864fc1332fc86e5cf7\", \"midi_unittests\": \"d3980e8000f8ffffdd153b72ee7ce77c3d5021dc\", \"mojo_core_unittests\": \"6ed81928f14e680ed1c6c422dafd1b632f0d3423\", \"mojo_unittests\": \"e9e53a6df30f43a670023ca77544f859b11bf22e\", \"nacl_loader_unittests\": \"e39cf22b2822b6b14437f850a242e10f1fa60624\", \"native_theme_unittests\": \"23acd2da7a412aadc92ad9e5d426fcdfbe78e39c\", \"net_unittests\": \"e9809cb9f47a8fcd519c277a6dbb5e1560c73110\", \"notification_helper_unittests\": \"588446bf517fe2b92bfcbb8d74cdbd7d9dbdd96f\", \"pdf_unittests\": \"93a1a58117ea60c56028803f83e7f8237ad53bf4\", \"perfetto_unittests\": \"212f88040123494d46da09f0d65a3d4929569640\", \"ppapi_unittests\": \"cfc9648c521eb87956abee879b3b98fd1d4a58d0\", \"printing_unittests\": \"69bdb6900ab498d765fdf011a9cbc4ffb2019591\", \"remoting_unittests\": \"6292c9c3ebe93ce16d180fccfffe9b5a1ec6991b\", \"sbox_integration_tests\": \"761e3f270e3bf207779a054b7e7938690a63bdc2\", \"sbox_unittests\": \"9072afd83d4383c1dd069c4ce494010bf63a438f\", \"sbox_validation_tests\": \"764495ee09df7ee67b33bec8f0180e5bb2aed46c\", \"service_manager_unittests\": \"4ea426bb68bb2b062f98727c66ad1ef3a32963bc\", \"services_unittests\": \"5b86178c105b939bcb7edaa992d23f830c8ff0a2\", \"setup_unittests\": \"24806e4445d388c39b5ef53d00595c3e53fb7a9d\", \"shell_dialogs_unittests\": \"e8d849c32636f8e3edfdc0563b8fd4a724c240ae\", \"skia_unittests\": \"61cb592e305a80feb3bdc79cade87b22b8e6d2e8\", \"snapshot_unittests\": \"0600f5f2209c1e5e2a00b8b79bed66dc6e06b5d6\", \"sql_unittests\": \"157d0c83a4e242868da944f17767efe96ba9c262\", \"storage_unittests\": \"62179fa0c4031e56b0dc4aaf008a4a3d6c9b700f\", \"sync_integration_tests\": \"d0b4ef5926cb8f094a0f330b52a4b454d3d26445\", \"traffic_annotation_auditor_unittests\": \"5e7d772d18987c5a49b9aa385431156471be1585\", \"ui_base_unittests\": \"9d1634f3d9f7f851f48e308ff88e554a0944b8c9\", \"ui_touch_selection_unittests\": \"0d8f726ba9f114247b0a8b950d0b460ecebc6666\", \"unit_tests\": \"6f5986d85c54d07dc2d23b0bf7076d049dc2ac0f\", \"updater_tests\": \"cf4dc498872d758597b69e641fbffb8dc25a5717\", \"url_unittests\": \"e19d33b327f1a7a657f7e5d58f2102d0a30a701f\", \"views_unittests\": \"6d39390b38f83d8087801ed1d75b72c48ae24e62\", \"viz_unittests\": \"cf71ce6a357d5c07f1eb66c5938aef17e193c794\", \"vr_common_unittests\": \"578f5b3a86e295ec327f2bdab08d9df0a8f3626c\", \"vr_pixeltests\": \"9d8cca98e6f794ef1828da1583f20d1217f63ef5\", \"weblayer_browsertests\": \"5fe43e1640e1c160dd52edc1138302fe5990fb34\", \"weblayer_unittests\": \"077ef778b3807a0290b8f87bdbc6fbffbd190c16\", \"wm_unittests\": \"2fbdc441fcec242d1af3515a29f14efd4d7b6c10\", \"wtf_unittests\": \"59f90692287066a7904ccadb525d8b315c30e52a\", \"zlib_unittests\": \"1e2790495518a78946c0bf8e63ab5d56caea82bd\", \"zucchini_unittests\": \"9d9838d7ad18fa13984eaf20affc280c4ed32692\"}}, \"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-3-xpf4\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5712-339372b\"], \"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": "1614319291775372",
"status": "COMPLETED",
"status_changed_ts": "1614322922115156",
"tags": [
"build_address:luci.chromium.ci/win-asan/17540",
"builder:win-asan",
"buildset:commit/git/95badd61a39a4bec041a8fc6bbb1f55994ff5452",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/95badd61a39a4bec041a8fc6bbb1f55994ff5452",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019687366848244960",
"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/8854257304931800720/+/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:51f5beec160ac310",
"user_agent:luci-scheduler"
],
"updated_ts": "1614322922146774",
"url": "https://ci.chromium.org/b/8854257304931800720",
"utcnow_ts": "1614322924161809"
}
}
[07:02:04.178] Invocation finished in 1h1m25.748079165s with status SUCCEEDED