[20:20:48.844] New invocation is queued and will start shortly
[20:20:50.028] Starting the invocation (attempt 1)
[20:20:50.076] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:20:50.076] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:20:50.076] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028692946067626944",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"665b8382259e810d6d4fdfaf54cc4c5192782a13\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028692946067626944",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/665b8382259e810d6d4fdfaf54cc4c5192782a13",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/665b8382259e810d6d4fdfaf54cc4c5192782a13",
"gitiles_ref:refs/heads/master"
]
}
[20:20:50.997] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605730850315575",
"id": "8863262883858144288",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"665b8382259e810d6d4fdfaf54cc4c5192782a13\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605730850869163",
"tags": [
"build_address:luci.chromium.ci/win-asan/15710",
"builder:win-asan",
"buildset:commit/git/665b8382259e810d6d4fdfaf54cc4c5192782a13",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/665b8382259e810d6d4fdfaf54cc4c5192782a13",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028692946067626944",
"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/8863262883858144288/+/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": "1605730850869276",
"url": "https://ci.chromium.org/b/8863262883858144288",
"utcnow_ts": "1605730850988696"
}
}
[20:20:50.997] Task URL: https://ci.chromium.org/b/8863262883858144288
[20:20:50.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:2:0) after 1m0s
[20:21:51.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:2:0)
[20:21:51.011] Timer tick, asking Buildbucket for the build status
[20:21:51.589] Build 8863262883858144288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:51.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:3:0) after 1m0s
[20:22:51.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:3:0)
[20:22:51.632] Timer tick, asking Buildbucket for the build status
[20:22:51.711] Build 8863262883858144288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:51.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:4:0) after 1m0s
[20:23:51.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:4:0)
[20:23:51.786] Timer tick, asking Buildbucket for the build status
[20:23:52.032] Build 8863262883858144288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:52.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:5:0) after 1m0s
[20:24:52.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:5:0)
[20:24:52.062] Timer tick, asking Buildbucket for the build status
[20:24:53.638] Build 8863262883858144288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:53.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:6:0) after 1m0s
[20:25:33.181] Received PubSub notification, asking Buildbucket for the build status
[20:25:33.324] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:53.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:6:0)
[20:25:53.746] Timer tick, asking Buildbucket for the build status
[20:25:53.814] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:53.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:8:0) after 1m0s
[20:26:53.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:8:0)
[20:26:53.891] Timer tick, asking Buildbucket for the build status
[20:26:54.056] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:54.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:9:0) after 1m0s
[20:27:54.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:9:0)
[20:27:54.139] Timer tick, asking Buildbucket for the build status
[20:27:54.288] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:54.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:10:0) after 1m0s
[20:28:54.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:10:0)
[20:28:54.367] Timer tick, asking Buildbucket for the build status
[20:28:54.515] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:54.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:11:0) after 1m0s
[20:29:54.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:11:0)
[20:29:54.593] Timer tick, asking Buildbucket for the build status
[20:29:54.660] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:54.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:12:0) after 1m0s
[20:30:54.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:12:0)
[20:30:54.681] Timer tick, asking Buildbucket for the build status
[20:30:54.847] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:54.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:13:0) after 1m0s
[20:31:54.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:13:0)
[20:31:54.863] Timer tick, asking Buildbucket for the build status
[20:31:55.089] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:55.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:14:0) after 1m0s
[20:32:55.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:14:0)
[20:32:55.229] Timer tick, asking Buildbucket for the build status
[20:32:55.326] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:55.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:15:0) after 1m0s
[20:33:55.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:15:0)
[20:33:55.407] Timer tick, asking Buildbucket for the build status
[20:33:55.765] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:55.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:16:0) after 1m0s
[20:34:55.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:16:0)
[20:34:55.853] Timer tick, asking Buildbucket for the build status
[20:34:55.948] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:55.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:17:0) after 1m0s
[20:35:55.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:17:0)
[20:35:55.967] Timer tick, asking Buildbucket for the build status
[20:35:56.616] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:56.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:18:0) after 1m0s
[20:36:56.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:18:0)
[20:36:56.707] Timer tick, asking Buildbucket for the build status
[20:36:57.088] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:57.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:19:0) after 1m0s
[20:37:57.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:19:0)
[20:37:57.318] Timer tick, asking Buildbucket for the build status
[20:37:57.382] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:57.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:20:0) after 1m0s
[20:38:57.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:20:0)
[20:38:57.420] Timer tick, asking Buildbucket for the build status
[20:38:57.528] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:57.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:21:0) after 1m0s
[20:39:57.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:21:0)
[20:39:57.584] Timer tick, asking Buildbucket for the build status
[20:39:57.687] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:57.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:22:0) after 1m0s
[20:40:57.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:22:0)
[20:40:57.768] Timer tick, asking Buildbucket for the build status
[20:40:58.107] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:58.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:23:0) after 1m0s
[20:41:58.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:23:0)
[20:41:58.168] Timer tick, asking Buildbucket for the build status
[20:41:58.287] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:58.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:24:0) after 1m0s
[20:42:58.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:24:0)
[20:42:58.315] Timer tick, asking Buildbucket for the build status
[20:42:59.424] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:59.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:25:0) after 1m0s
[20:43:59.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:25:0)
[20:43:59.466] Timer tick, asking Buildbucket for the build status
[20:43:59.538] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:59.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:26:0) after 1m0s
[20:44:59.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:26:0)
[20:44:59.574] Timer tick, asking Buildbucket for the build status
[20:44:59.753] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:59.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:27:0) after 1m0s
[20:45:59.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:27:0)
[20:45:59.780] Timer tick, asking Buildbucket for the build status
[20:45:59.886] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:59.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:28:0) after 1m0s
[20:46:59.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:28:0)
[20:46:59.987] Timer tick, asking Buildbucket for the build status
[20:47:00.252] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:00.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:29:0) after 1m0s
[20:48:01.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:29:0)
[20:48:01.452] Timer tick, asking Buildbucket for the build status
[20:48:01.528] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:01.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:30:0) after 1m0s
[20:49:01.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:30:0)
[20:49:01.608] Timer tick, asking Buildbucket for the build status
[20:49:01.680] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:01.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:31:0) after 1m0s
[20:50:01.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:31:0)
[20:50:01.892] Timer tick, asking Buildbucket for the build status
[20:50:02.013] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:02.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:32:0) after 1m0s
[20:51:02.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:32:0)
[20:51:02.263] Timer tick, asking Buildbucket for the build status
[20:51:02.378] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:02.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:33:0) after 1m0s
[20:52:02.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:33:0)
[20:52:02.576] Timer tick, asking Buildbucket for the build status
[20:52:02.765] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:02.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:34:0) after 1m0s
[20:53:02.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:34:0)
[20:53:02.868] Timer tick, asking Buildbucket for the build status
[20:53:02.988] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:02.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:35:0) after 1m0s
[20:54:03.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:35:0)
[20:54:03.075] Timer tick, asking Buildbucket for the build status
[20:54:03.145] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:03.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:36:0) after 1m0s
[20:55:03.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:36:0)
[20:55:03.287] Timer tick, asking Buildbucket for the build status
[20:55:03.619] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:03.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:37:0) after 1m0s
[20:56:03.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:37:0)
[20:56:03.712] Timer tick, asking Buildbucket for the build status
[20:56:03.872] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:03.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:38:0) after 1m0s
[20:57:03.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:38:0)
[20:57:03.932] Timer tick, asking Buildbucket for the build status
[20:57:04.044] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:04.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:39:0) after 1m0s
[20:58:04.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:39:0)
[20:58:04.116] Timer tick, asking Buildbucket for the build status
[20:58:04.318] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:04.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:40:0) after 1m0s
[20:59:04.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:40:0)
[20:59:04.398] Timer tick, asking Buildbucket for the build status
[20:59:04.457] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:04.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:41:0) after 1m0s
[21:00:04.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:41:0)
[21:00:04.564] Timer tick, asking Buildbucket for the build status
[21:00:04.659] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:04.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:42:0) after 1m0s
[21:01:04.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:42:0)
[21:01:04.898] Timer tick, asking Buildbucket for the build status
[21:01:05.985] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:05.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:43:0) after 1m0s
[21:02:06.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:43:0)
[21:02:06.076] Timer tick, asking Buildbucket for the build status
[21:02:06.186] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:06.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:44:0) after 1m0s
[21:03:06.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:44:0)
[21:03:06.298] Timer tick, asking Buildbucket for the build status
[21:03:06.411] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:06.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:45:0) after 1m0s
[21:04:06.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:45:0)
[21:04:06.518] Timer tick, asking Buildbucket for the build status
[21:04:06.786] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:06.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:46:0) after 1m0s
[21:05:06.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:46:0)
[21:05:06.915] Timer tick, asking Buildbucket for the build status
[21:05:07.136] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:07.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:47:0) after 1m0s
[21:06:07.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:47:0)
[21:06:07.188] Timer tick, asking Buildbucket for the build status
[21:06:07.332] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:07.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:48:0) after 1m0s
[21:07:07.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:48:0)
[21:07:07.487] Timer tick, asking Buildbucket for the build status
[21:07:07.871] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:07.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:49:0) after 1m0s
[21:08:08.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:49:0)
[21:08:08.033] Timer tick, asking Buildbucket for the build status
[21:08:08.213] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:08.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:50:0) after 1m0s
[21:09:08.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:50:0)
[21:09:08.440] Timer tick, asking Buildbucket for the build status
[21:09:08.673] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:08.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:51:0) after 1m0s
[21:10:08.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:51:0)
[21:10:08.725] Timer tick, asking Buildbucket for the build status
[21:10:08.795] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:08.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:52:0) after 1m0s
[21:11:08.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:52:0)
[21:11:08.936] Timer tick, asking Buildbucket for the build status
[21:11:16.010] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:16.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:53:0) after 1m0s
[21:12:16.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:53:0)
[21:12:16.066] Timer tick, asking Buildbucket for the build status
[21:12:16.277] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:16.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:54:0) after 1m0s
[21:13:16.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:54:0)
[21:13:16.352] Timer tick, asking Buildbucket for the build status
[21:13:19.480] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:19.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:55:0) after 1m0s
[21:14:19.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:55:0)
[21:14:19.539] Timer tick, asking Buildbucket for the build status
[21:14:19.595] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:19.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:56:0) after 1m0s
[21:15:19.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:56:0)
[21:15:19.612] Timer tick, asking Buildbucket for the build status
[21:15:20.344] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:20.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:57:0) after 1m0s
[21:16:20.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:57:0)
[21:16:20.439] Timer tick, asking Buildbucket for the build status
[21:16:20.587] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:20.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:58:0) after 1m0s
[21:17:20.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:58:0)
[21:17:20.687] Timer tick, asking Buildbucket for the build status
[21:17:20.753] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:20.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:59:0) after 1m0s
[21:18:20.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:59:0)
[21:18:20.914] Timer tick, asking Buildbucket for the build status
[21:18:20.982] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:20.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:60:0) after 1m0s
[21:19:21.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:60:0)
[21:19:21.238] Timer tick, asking Buildbucket for the build status
[21:19:21.313] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:21.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:61:0) after 1m0s
[21:20:21.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:61:0)
[21:20:21.789] Timer tick, asking Buildbucket for the build status
[21:20:23.017] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:23.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:62:0) after 1m0s
[21:21:23.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:62:0)
[21:21:23.303] Timer tick, asking Buildbucket for the build status
[21:21:23.482] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:23.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:63:0) after 1m0s
[21:22:23.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:63:0)
[21:22:23.913] Timer tick, asking Buildbucket for the build status
[21:22:24.062] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:24.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:64:0) after 1m0s
[21:23:24.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:64:0)
[21:23:24.239] Timer tick, asking Buildbucket for the build status
[21:23:24.320] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:24.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:65:0) after 1m0s
[21:24:24.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:65:0)
[21:24:24.482] Timer tick, asking Buildbucket for the build status
[21:24:24.535] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:24.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:66:0) after 1m0s
[21:25:24.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:66:0)
[21:25:24.675] Timer tick, asking Buildbucket for the build status
[21:25:24.827] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:24.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:67:0) after 1m0s
[21:26:24.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:67:0)
[21:26:24.899] Timer tick, asking Buildbucket for the build status
[21:26:25.018] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:25.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:68:0) after 1m0s
[21:27:25.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:68:0)
[21:27:25.083] Timer tick, asking Buildbucket for the build status
[21:27:25.208] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:25.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:69:0) after 1m0s
[21:28:25.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:69:0)
[21:28:25.241] Timer tick, asking Buildbucket for the build status
[21:28:25.297] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:25.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:70:0) after 1m0s
[21:29:25.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:70:0)
[21:29:25.417] Timer tick, asking Buildbucket for the build status
[21:29:25.826] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:25.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:71:0) after 1m0s
[21:30:25.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:71:0)
[21:30:25.879] Timer tick, asking Buildbucket for the build status
[21:30:27.114] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:27.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:72:0) after 1m0s
[21:31:27.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:72:0)
[21:31:27.210] Timer tick, asking Buildbucket for the build status
[21:31:27.319] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:27.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:73:0) after 1m0s
[21:32:27.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:73:0)
[21:32:27.415] Timer tick, asking Buildbucket for the build status
[21:32:27.566] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:27.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:74:0) after 1m0s
[21:33:27.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:74:0)
[21:33:27.674] Timer tick, asking Buildbucket for the build status
[21:33:28.016] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:28.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:75:0) after 1m0s
[21:34:28.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:75:0)
[21:34:28.084] Timer tick, asking Buildbucket for the build status
[21:34:28.272] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:28.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:76:0) after 1m0s
[21:35:28.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:76:0)
[21:35:28.268] Timer tick, asking Buildbucket for the build status
[21:35:28.497] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:28.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:77:0) after 1m0s
[21:36:28.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:77:0)
[21:36:28.550] Timer tick, asking Buildbucket for the build status
[21:36:28.774] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:28.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:78:0) after 1m0s
[21:37:28.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:78:0)
[21:37:28.810] Timer tick, asking Buildbucket for the build status
[21:37:29.424] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:29.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:79:0) after 1m0s
[21:38:29.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:79:0)
[21:38:29.528] Timer tick, asking Buildbucket for the build status
[21:38:30.415] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:30.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:80:0) after 1m0s
[21:39:30.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:80:0)
[21:39:30.539] Timer tick, asking Buildbucket for the build status
[21:39:30.724] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:30.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:81:0) after 1m0s
[21:40:30.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:81:0)
[21:40:30.805] Timer tick, asking Buildbucket for the build status
[21:40:30.937] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:30.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:82:0) after 1m0s
[21:41:31.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:82:0)
[21:41:31.007] Timer tick, asking Buildbucket for the build status
[21:41:31.188] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:31.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:83:0) after 1m0s
[21:42:31.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:83:0)
[21:42:31.251] Timer tick, asking Buildbucket for the build status
[21:42:31.376] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:31.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:84:0) after 1m0s
[21:43:31.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:84:0)
[21:43:31.470] Timer tick, asking Buildbucket for the build status
[21:43:31.624] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:31.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:85:0) after 1m0s
[21:44:31.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:85:0)
[21:44:31.667] Timer tick, asking Buildbucket for the build status
[21:44:31.881] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:31.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:86:0) after 1m0s
[21:45:31.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:86:0)
[21:45:31.931] Timer tick, asking Buildbucket for the build status
[21:45:32.323] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:32.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:87:0) after 1m0s
[21:46:32.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:87:0)
[21:46:32.385] Timer tick, asking Buildbucket for the build status
[21:46:32.935] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:32.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:88:0) after 1m0s
[21:47:32.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:88:0)
[21:47:32.990] Timer tick, asking Buildbucket for the build status
[21:47:33.163] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:33.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:89:0) after 1m0s
[21:48:33.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:89:0)
[21:48:33.376] Timer tick, asking Buildbucket for the build status
[21:48:33.574] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:33.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:90:0) after 1m0s
[21:49:33.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:90:0)
[21:49:33.619] Timer tick, asking Buildbucket for the build status
[21:49:33.894] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:33.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:91:0) after 1m0s
[21:50:33.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:91:0)
[21:50:33.933] Timer tick, asking Buildbucket for the build status
[21:50:34.722] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:34.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:92:0) after 1m0s
[21:51:34.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:92:0)
[21:51:34.791] Timer tick, asking Buildbucket for the build status
[21:51:34.937] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:34.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:93:0) after 1m0s
[21:52:34.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:93:0)
[21:52:34.953] Timer tick, asking Buildbucket for the build status
[21:52:35.044] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:35.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:94:0) after 1m0s
[21:53:35.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:94:0)
[21:53:35.103] Timer tick, asking Buildbucket for the build status
[21:53:35.221] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:35.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:95:0) after 1m0s
[21:54:35.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:95:0)
[21:54:35.265] Timer tick, asking Buildbucket for the build status
[21:54:35.362] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:35.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:96:0) after 1m0s
[21:55:35.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:96:0)
[21:55:35.468] Timer tick, asking Buildbucket for the build status
[21:55:35.569] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:35.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:97:0) after 1m0s
[21:56:35.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:97:0)
[21:56:35.671] Timer tick, asking Buildbucket for the build status
[21:56:35.824] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:35.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:98:0) after 1m0s
[21:57:36.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:98:0)
[21:57:36.080] Timer tick, asking Buildbucket for the build status
[21:57:36.246] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:36.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:99:0) after 1m0s
[21:58:36.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:99:0)
[21:58:36.389] Timer tick, asking Buildbucket for the build status
[21:58:36.584] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:36.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:100:0) after 1m0s
[21:59:36.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:100:0)
[21:59:36.763] Timer tick, asking Buildbucket for the build status
[21:59:37.596] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:37.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:101:0) after 1m0s
[22:00:38.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:101:0)
[22:00:38.134] Timer tick, asking Buildbucket for the build status
[22:00:38.827] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:38.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:102:0) after 1m0s
[22:01:39.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:102:0)
[22:01:39.206] Timer tick, asking Buildbucket for the build status
[22:01:39.387] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:39.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:103:0) after 1m0s
[22:02:39.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:103:0)
[22:02:39.511] Timer tick, asking Buildbucket for the build status
[22:02:39.587] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:39.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:104:0) after 1m0s
[22:03:39.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:104:0)
[22:03:39.721] Timer tick, asking Buildbucket for the build status
[22:03:39.852] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:39.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:105:0) after 1m0s
[22:04:39.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:105:0)
[22:04:39.990] Timer tick, asking Buildbucket for the build status
[22:04:40.421] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:40.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:106:0) after 1m0s
[22:05:40.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:106:0)
[22:05:40.448] Timer tick, asking Buildbucket for the build status
[22:05:40.551] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:40.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:107:0) after 1m0s
[22:06:40.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:107:0)
[22:06:40.712] Timer tick, asking Buildbucket for the build status
[22:06:41.059] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:41.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:108:0) after 1m0s
[22:07:41.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:108:0)
[22:07:41.357] Timer tick, asking Buildbucket for the build status
[22:07:41.452] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:41.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:109:0) after 1m0s
[22:08:41.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:109:0)
[22:08:41.646] Timer tick, asking Buildbucket for the build status
[22:08:44.244] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:44.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:110:0) after 1m0s
[22:09:44.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:110:0)
[22:09:44.417] Timer tick, asking Buildbucket for the build status
[22:09:45.034] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:45.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:111:0) after 1m0s
[22:10:45.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:111:0)
[22:10:45.052] Timer tick, asking Buildbucket for the build status
[22:10:45.260] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:45.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:112:0) after 1m0s
[22:11:45.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:112:0)
[22:11:45.334] Timer tick, asking Buildbucket for the build status
[22:11:45.640] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:45.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:113:0) after 1m0s
[22:12:45.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:113:0)
[22:12:45.724] Timer tick, asking Buildbucket for the build status
[22:12:45.833] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:45.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:114:0) after 1m0s
[22:13:46.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:114:0)
[22:13:46.073] Timer tick, asking Buildbucket for the build status
[22:13:46.605] Build 8863262883858144288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:46.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028692946067626944:115:0) after 1m0s
[22:14:40.996] Received PubSub notification, asking Buildbucket for the build status
[22:14:41.102] Build 8863262883858144288: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:14:41.102] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605737679079637",
"created_by": "project:chromium",
"created_ts": "1605730850315575",
"id": "8863262883858144288",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"665b8382259e810d6d4fdfaf54cc4c5192782a13\"}}",
"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\": \"665b8382259e810d6d4fdfaf54cc4c5192782a13\", \"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-0-m5ra\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15710, \"clang_revision\": \"llvmorg-12-init-11780-g6ef07111-1\", \"got_angle_revision\": \"c91cb9e0abfec52bd3349683ee723f428b0f2153\", \"got_dawn_revision\": \"01e4450729219b18b58caa545c156b37a847c422\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"665b8382259e810d6d4fdfaf54cc4c5192782a13\", \"got_revision_cp\": \"refs/heads/master@{#828859}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"43fcd32b4faefdd5dd7cece1d87e43939cc1f360\", \"got_v8_revision\": \"de5334a069aad1bab04d8b23f71337be97314333\", \"got_v8_revision_cp\": \"refs/heads/8.9.8@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"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\": \"665b8382259e810d6d4fdfaf54cc4c5192782a13\", \"swarm_hashes_refs/heads/master(at){#828859}_without_patch\": {\"absl_hardening_tests\": \"990d737231e835dfe9827e0707e137bdbb0a3e28\", \"accessibility_unittests\": \"b252c983dda89cadc2d1b6d439e9f758d533e455\", \"angle_unittests\": \"f95ae302a0644c0ce7dd0a50bea3f2988cd6e818\", \"app_shell_unittests\": \"03b4055a964f4f42d037c6ad83e935fcc146659f\", \"aura_unittests\": \"3375f07fd2b080182fafdf808f2a0690042264d4\", \"base_unittests\": \"4f068d3c5832a6a6946d9e98b224cf4891c3d910\", \"base_util_unittests\": \"7148c3a5b3ce4d0bb628fea6efe804cdd6a07327\", \"blink_common_unittests\": \"734a516b3e9bda16f23a6cdf2faf71c1a74b5324\", \"blink_fuzzer_unittests\": \"65e48019aef3c0fee5ba9305fff26aa58a3059c4\", \"blink_heap_unittests\": \"c485bda9eb3c6813bd4ffd5ce5753da66729f15b\", \"blink_platform_unittests\": \"287df1f0a328fb599f6989749afd30e92b334059\", \"blink_unittests\": \"0a86a113bbb2863ef7efa04cbb18bf1a784c4537\", \"boringssl_crypto_tests\": \"3ae912fa36be16d2e6c489806106051f59971752\", \"boringssl_ssl_tests\": \"7017b60f5a29bffc3a8f737ceba7f6b45e400e93\", \"browser_switcher_bho_unittests\": \"49bbefffec11ad089232b1e85262e352af5d09e4\", \"browser_tests\": \"19c85f83466f8f3ca8fef8d5356cc91da42dc93c\", \"capture_unittests\": \"e6445b91e790763f9ba7f2d1702b9780d926801b\", \"cast_unittests\": \"7b2672b8693398de4282e531fbbd4664b6f09805\", \"cc_unittests\": \"f8f101e46af112f0c2199903cdc6ecd744d14ed1\", \"chrome_app_unittests\": \"bed3a27c2c7f35b6780715dc0ea4e2084ddc3ee2\", \"chrome_cleaner_unittests\": \"1b6eb722d0047b332759d3430508b9b227a00263\", \"chrome_elf_unittests\": \"ecd045ca7b34094f2e09926a0bb55460183164fb\", \"chromedriver_unittests\": \"316cc53f7c964f268584ce2992643bc4099b45a7\", \"components_browsertests\": \"6f8834d6ba585206ede227a576bf6d0e0216b5fd\", \"components_unittests\": \"1a6485cd9bcdaa2b0e06c7f46822b13ee24edcc4\", \"compositor_unittests\": \"282d93dd52bd37673ff7e480e6f37a8e426302a5\", \"content_browsertests\": \"a5fdcd1494383ac35ebeb9e0e7d57edf6aebbd61\", \"content_unittests\": \"2fc133901cd3ff6cbfd53cd710e660388681c637\", \"courgette_unittests\": \"e377e8f1da2bcdce5356534daab4cd99da00f0fc\", \"crashpad_tests\": \"296afb8350a8b16470ed1ab3fcd8a9d612f4e796\", \"cronet_tests\": \"ae5408e4dac5feccb077053f78737cda35d1ab52\", \"cronet_unittests\": \"93236fbe3a4bcc946abca247547b83d6a3c90d93\", \"crypto_unittests\": \"ab75ec01bf8345e27e17a21bb8f7a99fa9c19b71\", \"delayloads_unittests\": \"c8d4d105a8de8fe4359a7068f282de3ebad0d92d\", \"device_unittests\": \"f6b359ed8c82557a546dc5e0ad1a4b5e3e0fd644\", \"display_unittests\": \"e995a0c04e8712e4fe08c9cf476d90ee7a7d9ba4\", \"elevation_service_unittests\": \"07cde2bf08842ec5befb1bf22440538f5930ded9\", \"events_unittests\": \"8b4976c2b5c10b1b6176063feae995cbd40b448b\", \"extensions_browsertests\": \"14858a76d8c4d3ab60f2cced6a2605345624ab60\", \"extensions_unittests\": \"66f6346a0ad7d87feb9b8bb55d0b5f3763f77004\", \"filesystem_service_unittests\": \"7b47621a8429c5b07ffeda463c0b0f0871aed6b8\", \"gcm_unit_tests\": \"84e22abb0e07e35813c88a365ed4f458f1c3c738\", \"gcp_unittests\": \"aac71b2174a7f7ef9d984e9984045b74fd707a72\", \"gfx_unittests\": \"3a0a9b61f00c05e46927f10c1d91d06f333d7e45\", \"gin_unittests\": \"e29a891b3ed5a64fb8119ec180d32f333a30d8a3\", \"google_apis_unittests\": \"b518dcf2b16baf0901e54ac56d9448562addf401\", \"gpu_unittests\": \"e7bf9bc27e3ff7a01865bd38ba5fda67e1a27075\", \"gwp_asan_unittests\": \"21c693dac7952cca6c5754444b197f514c98dbe9\", \"headless_browsertests\": \"ed83b3474ae8856fc1f855ffca8c67ec18b5e3b3\", \"headless_unittests\": \"65d7648a8f6f004f774075a37e928ab6b49fbb96\", \"install_static_unittests\": \"78522ace93af17c77b23f4cbadd372a1762a44cf\", \"installer_util_unittests\": \"c4092c8ee38ea89b1f6b8bdcc97c995139f37080\", \"interactive_ui_tests\": \"318278083ac817c1a89c5ffaaa658088eecacf40\", \"ipc_tests\": \"ad60fbfe8b871c232f867ae867f0b11261bf5ab5\", \"jingle_unittests\": \"d59e5feb6cf5a3609a1e2f8c53cf957cc5265f91\", \"latency_unittests\": \"bf872ed72d508dbce6400f15c1c9775db22c27d7\", \"libjingle_xmpp_unittests\": \"72888293df4bedfdee70537780409cfc7cfd78f1\", \"liburlpattern_unittests\": \"e0ee7d7944b9ee504440e4f9e627b93e32e9a9fd\", \"media_blink_unittests\": \"a0aa414db8747140af3c605f6ad567eee45dab8f\", \"media_unittests\": \"e3cf8e67ceb2f73f55b56046e1d44553f0005c0a\", \"message_center_unittests\": \"100b581a7f5af6c1fce4f0b507a3b574111f38a7\", \"midi_unittests\": \"a176b2a28a39aec90ab9dba1f2c8d0f8ca2ce928\", \"mojo_core_unittests\": \"255afa9672f0cfea941b6ec68e94cd8af614ac39\", \"mojo_unittests\": \"73d1e3c4ec2b2033f14176623da23e0ff1113e99\", \"nacl_loader_unittests\": \"e9e7d04e1458ba879f5930ebe23b7f1f1c69d8aa\", \"native_theme_unittests\": \"04c156fd1be1c026f8a0c0fd4ed234a6bf6116b5\", \"net_unittests\": \"6dc69debcf4a46c2a06cd3404f3a99bd0868945b\", \"notification_helper_unittests\": \"b49800684e6bb23e6733ff87adaea7cc67d8e684\", \"pdf_unittests\": \"3b4ca3bdb3ea037e6ba3abba1661e17bb4780fbe\", \"perfetto_unittests\": \"1ce264301dd83db05ef84614d0f87e67bcd9022d\", \"ppapi_unittests\": \"d4f45a2f4b122070472e1b0a25ccf6902fe33e35\", \"printing_unittests\": \"1e363554a27a9fc410c38e6362197650975d4b09\", \"remoting_unittests\": \"e5723dad9fd276983f5082965c298e7c2c642dba\", \"sbox_integration_tests\": \"c852b4fb2f62199851e6a8df78fcbcdf0bd5f99c\", \"sbox_unittests\": \"dbcaa926e2dec8bc79372433699785a0e91f229c\", \"sbox_validation_tests\": \"62f9fc90634eaf0f706ee4dad2a061eecb048256\", \"service_manager_unittests\": \"0a8294c8485c399d990e336e1b317afdad9e7907\", \"services_unittests\": \"2d68e651c1208d8e82afae83e3da02a0c3b2cc6c\", \"setup_unittests\": \"b8005712ea25a4fb4fca61eb5e30aae135751c6d\", \"shell_dialogs_unittests\": \"a4553d629a4cd7d859a29d897b071f661ac5d4cb\", \"skia_unittests\": \"e1a4c752892d8f4c474a7af5ea401fcd52e77207\", \"snapshot_unittests\": \"55800f4a152db1cfea8540345fed398a60569f99\", \"sql_unittests\": \"2d39ac066e18d31a63e59d121132b0e0194a6367\", \"storage_unittests\": \"79706ae91dbd2785638be2d588df12be030560db\", \"sync_integration_tests\": \"a82d7d51131761e6e3658ecd7889847b8988e339\", \"traffic_annotation_auditor_unittests\": \"71b02ff22b129dbf54cefe5f85def73bac395fdf\", \"ui_base_unittests\": \"225e3448a343be663dd4c92be4d443bd86cc2ebd\", \"ui_touch_selection_unittests\": \"cc6006474ac35511686679f5d0dcda90038e6b3b\", \"unit_tests\": \"c8a3bf7f69f86cdec8419e9732c0934509e68cb9\", \"updater_tests\": \"c96e66e6d8e32f99fa80f17f1e31cf444be14c85\", \"url_unittests\": \"773d7ee439295d7e58783c7d507094095ad8916a\", \"views_unittests\": \"a7d8a95c9dffb5f5643fc0c39f9542e5e0821b40\", \"viz_unittests\": \"24175bde66c97d437efdb7002038365085142720\", \"vr_common_unittests\": \"7133bbf4073f2b10df608c67c7d5dcedf25a1d2e\", \"vr_pixeltests\": \"b3b1f8678c2dbeb2f5c88c3669f8dce85e73da72\", \"weblayer_browsertests\": \"f8677fccc9e0148ca0d9e0842e1e612c5ae53f62\", \"weblayer_unittests\": \"20e30b2fe8db5ee58ca27f540713c1819d5136d8\", \"wm_unittests\": \"def4dd7f9e5e956366fd6b18a628331914950ff2\", \"wtf_unittests\": \"266cfae86b1eeba17db71446a57e45cef76c77b1\", \"zlib_unittests\": \"aea3552d16ba0680d919671c4bb9d33cf38f264c\", \"zucchini_unittests\": \"38504db4100acea5a66b3efe02fbfbb512ec3770\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-0-m5ra\"], \"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\": [\"5635-1756fdb\"], \"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": "1605731130907391",
"status": "COMPLETED",
"status_changed_ts": "1605737679550170",
"tags": [
"build_address:luci.chromium.ci/win-asan/15710",
"builder:win-asan",
"buildset:commit/git/665b8382259e810d6d4fdfaf54cc4c5192782a13",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/665b8382259e810d6d4fdfaf54cc4c5192782a13",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028692946067626944",
"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/8863262883858144288/+/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:4ff5d5e745fb6f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605737679559968",
"url": "https://ci.chromium.org/b/8863262883858144288",
"utcnow_ts": "1605737681087658"
}
}
[22:14:41.102] Invocation finished in 1h53m52.276781034s with status SUCCEEDED