[17:52:48.920] New invocation is queued and will start shortly
[17:52:50.641] Starting the invocation (attempt 1)
[17:52:50.672] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:52:50.673] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:52:50.673] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020457933395976400",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a726219746be4c74384cc73c1bf4f52ef823f2f5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020457933395976400",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a726219746be4c74384cc73c1bf4f52ef823f2f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a726219746be4c74384cc73c1bf4f52ef823f2f5",
"gitiles_ref:refs/heads/master"
]
}
[17:52:51.916] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613584370796310",
"id": "8855027870766883536",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a726219746be4c74384cc73c1bf4f52ef823f2f5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613584371744691",
"tags": [
"build_address:luci.chromium.ci/win-asan/17396",
"builder:win-asan",
"buildset:commit/git/a726219746be4c74384cc73c1bf4f52ef823f2f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a726219746be4c74384cc73c1bf4f52ef823f2f5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020457933395976400",
"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/8855027870766883536/+/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": "1613584371744803",
"url": "https://ci.chromium.org/b/8855027870766883536",
"utcnow_ts": "1613584371901570"
}
}
[17:52:51.916] Task URL: https://ci.chromium.org/b/8855027870766883536
[17:52:51.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:2:0) after 1m0s
[17:53:51.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:2:0)
[17:53:51.919] Timer tick, asking Buildbucket for the build status
[17:53:52.014] Build 8855027870766883536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:53:52.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:3:0) after 1m0s
[17:54:52.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:3:0)
[17:54:52.010] Timer tick, asking Buildbucket for the build status
[17:54:52.108] Build 8855027870766883536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:54:52.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:4:0) after 1m0s
[17:55:52.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:4:0)
[17:55:52.128] Timer tick, asking Buildbucket for the build status
[17:55:52.201] Build 8855027870766883536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:55:52.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:5:0) after 1m0s
[17:56:52.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:5:0)
[17:56:52.347] Timer tick, asking Buildbucket for the build status
[17:56:52.445] Build 8855027870766883536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:56:52.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:6:0) after 1m0s
[17:57:03.193] Received PubSub notification, asking Buildbucket for the build status
[17:57:03.342] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:52.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:6:0)
[17:57:52.459] Timer tick, asking Buildbucket for the build status
[17:57:52.759] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:52.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:8:0) after 1m0s
[17:58:52.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:8:0)
[17:58:52.815] Timer tick, asking Buildbucket for the build status
[17:58:52.936] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:52.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:9:0) after 1m0s
[17:59:52.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:9:0)
[17:59:52.926] Timer tick, asking Buildbucket for the build status
[17:59:53.054] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:53.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:10:0) after 1m0s
[18:00:53.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:10:0)
[18:00:53.071] Timer tick, asking Buildbucket for the build status
[18:00:53.194] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:53.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:11:0) after 1m0s
[18:01:53.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:11:0)
[18:01:53.210] Timer tick, asking Buildbucket for the build status
[18:01:53.320] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:53.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:12:0) after 1m0s
[18:02:53.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:12:0)
[18:02:53.337] Timer tick, asking Buildbucket for the build status
[18:02:53.457] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:53.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:13:0) after 1m0s
[18:03:53.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:13:0)
[18:03:53.478] Timer tick, asking Buildbucket for the build status
[18:03:53.689] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:53.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:14:0) after 1m0s
[18:04:53.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:14:0)
[18:04:53.674] Timer tick, asking Buildbucket for the build status
[18:04:53.789] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:53.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:15:0) after 1m0s
[18:05:53.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:15:0)
[18:05:53.832] Timer tick, asking Buildbucket for the build status
[18:05:53.904] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:53.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:16:0) after 1m0s
[18:06:53.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:16:0)
[18:06:53.925] Timer tick, asking Buildbucket for the build status
[18:06:54.032] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:54.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:17:0) after 1m0s
[18:07:54.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:17:0)
[18:07:54.143] Timer tick, asking Buildbucket for the build status
[18:07:54.298] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:54.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:18:0) after 1m0s
[18:08:54.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:18:0)
[18:08:54.310] Timer tick, asking Buildbucket for the build status
[18:08:54.498] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:54.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:19:0) after 1m0s
[18:09:54.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:19:0)
[18:09:54.540] Timer tick, asking Buildbucket for the build status
[18:09:54.719] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:54.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:20:0) after 1m0s
[18:10:54.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:20:0)
[18:10:54.709] Timer tick, asking Buildbucket for the build status
[18:10:54.778] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:54.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:21:0) after 1m0s
[18:11:54.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:21:0)
[18:11:54.794] Timer tick, asking Buildbucket for the build status
[18:11:55.362] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:55.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:22:0) after 1m0s
[18:12:55.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:22:0)
[18:12:55.535] Timer tick, asking Buildbucket for the build status
[18:12:55.714] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:55.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:23:0) after 1m0s
[18:13:55.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:23:0)
[18:13:55.717] Timer tick, asking Buildbucket for the build status
[18:13:55.887] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:55.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:24:0) after 1m0s
[18:14:55.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:24:0)
[18:14:55.911] Timer tick, asking Buildbucket for the build status
[18:14:56.556] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:56.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:25:0) after 1m0s
[18:15:56.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:25:0)
[18:15:56.578] Timer tick, asking Buildbucket for the build status
[18:15:56.682] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:56.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:26:0) after 1m0s
[18:16:56.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:26:0)
[18:16:56.720] Timer tick, asking Buildbucket for the build status
[18:16:56.835] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:56.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:27:0) after 1m0s
[18:17:56.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:27:0)
[18:17:56.856] Timer tick, asking Buildbucket for the build status
[18:17:56.945] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:56.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:28:0) after 1m0s
[18:18:56.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:28:0)
[18:18:56.974] Timer tick, asking Buildbucket for the build status
[18:18:57.355] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:57.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:29:0) after 1m0s
[18:19:57.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:29:0)
[18:19:57.378] Timer tick, asking Buildbucket for the build status
[18:19:57.823] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:57.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:30:0) after 1m0s
[18:20:58.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:30:0)
[18:20:58.012] Timer tick, asking Buildbucket for the build status
[18:20:58.113] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:58.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:31:0) after 1m0s
[18:21:58.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:31:0)
[18:21:58.198] Timer tick, asking Buildbucket for the build status
[18:21:58.308] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:58.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:32:0) after 1m0s
[18:22:58.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:32:0)
[18:22:58.363] Timer tick, asking Buildbucket for the build status
[18:22:58.455] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:58.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:33:0) after 1m0s
[18:23:58.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:33:0)
[18:23:58.501] Timer tick, asking Buildbucket for the build status
[18:23:58.580] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:58.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:34:0) after 1m0s
[18:24:58.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:34:0)
[18:24:58.602] Timer tick, asking Buildbucket for the build status
[18:24:58.683] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:58.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:35:0) after 1m0s
[18:25:58.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:35:0)
[18:25:58.704] Timer tick, asking Buildbucket for the build status
[18:25:59.041] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:59.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:36:0) after 1m0s
[18:26:59.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:36:0)
[18:26:59.126] Timer tick, asking Buildbucket for the build status
[18:26:59.254] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:59.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:37:0) after 1m0s
[18:27:59.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:37:0)
[18:27:59.829] Timer tick, asking Buildbucket for the build status
[18:27:59.968] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:59.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:38:0) after 1m0s
[18:29:00.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:38:0)
[18:29:00.012] Timer tick, asking Buildbucket for the build status
[18:29:00.175] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:00.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:39:0) after 1m0s
[18:30:00.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:39:0)
[18:30:00.230] Timer tick, asking Buildbucket for the build status
[18:30:00.384] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:00.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:40:0) after 1m0s
[18:31:00.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:40:0)
[18:31:00.672] Timer tick, asking Buildbucket for the build status
[18:31:00.839] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:00.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:41:0) after 1m0s
[18:32:00.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:41:0)
[18:32:00.889] Timer tick, asking Buildbucket for the build status
[18:32:00.997] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:00.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:42:0) after 1m0s
[18:33:01.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:42:0)
[18:33:01.025] Timer tick, asking Buildbucket for the build status
[18:33:01.458] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:01.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:43:0) after 1m0s
[18:34:01.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:43:0)
[18:34:01.468] Timer tick, asking Buildbucket for the build status
[18:34:01.582] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:01.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:44:0) after 1m0s
[18:35:01.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:44:0)
[18:35:01.607] Timer tick, asking Buildbucket for the build status
[18:35:01.801] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:01.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:45:0) after 1m0s
[18:36:01.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:45:0)
[18:36:01.825] Timer tick, asking Buildbucket for the build status
[18:36:01.935] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:01.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:46:0) after 1m0s
[18:37:02.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:46:0)
[18:37:02.015] Timer tick, asking Buildbucket for the build status
[18:37:02.194] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:02.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:47:0) after 1m0s
[18:38:02.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:47:0)
[18:38:02.220] Timer tick, asking Buildbucket for the build status
[18:38:02.400] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:02.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:48:0) after 1m0s
[18:39:02.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:48:0)
[18:39:02.382] Timer tick, asking Buildbucket for the build status
[18:39:02.536] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:02.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:49:0) after 1m0s
[18:40:02.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:49:0)
[18:40:02.594] Timer tick, asking Buildbucket for the build status
[18:40:02.787] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:02.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:50:0) after 1m0s
[18:41:02.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:50:0)
[18:41:02.846] Timer tick, asking Buildbucket for the build status
[18:41:03.471] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:03.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:51:0) after 1m0s
[18:42:03.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:51:0)
[18:42:03.603] Timer tick, asking Buildbucket for the build status
[18:42:03.836] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:03.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:52:0) after 1m0s
[18:43:03.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:52:0)
[18:43:03.909] Timer tick, asking Buildbucket for the build status
[18:43:04.150] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:04.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:53:0) after 1m0s
[18:44:04.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:53:0)
[18:44:04.260] Timer tick, asking Buildbucket for the build status
[18:44:04.481] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:04.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:54:0) after 1m0s
[18:45:04.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:54:0)
[18:45:04.565] Timer tick, asking Buildbucket for the build status
[18:45:04.746] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:04.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:55:0) after 1m0s
[18:46:04.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:55:0)
[18:46:04.792] Timer tick, asking Buildbucket for the build status
[18:46:04.954] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:04.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:56:0) after 1m0s
[18:47:05.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:56:0)
[18:47:05.277] Timer tick, asking Buildbucket for the build status
[18:47:05.411] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:05.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:57:0) after 1m0s
[18:48:05.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:57:0)
[18:48:05.501] Timer tick, asking Buildbucket for the build status
[18:48:05.717] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:05.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:58:0) after 1m0s
[18:49:05.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:58:0)
[18:49:05.689] Timer tick, asking Buildbucket for the build status
[18:49:05.787] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:05.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:59:0) after 1m0s
[18:50:05.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:59:0)
[18:50:05.841] Timer tick, asking Buildbucket for the build status
[18:50:05.994] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:05.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:60:0) after 1m0s
[18:51:06.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:60:0)
[18:51:06.067] Timer tick, asking Buildbucket for the build status
[18:51:06.164] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:06.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:61:0) after 1m0s
[18:52:06.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:61:0)
[18:52:06.299] Timer tick, asking Buildbucket for the build status
[18:52:06.707] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:06.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:62:0) after 1m0s
[18:53:06.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:62:0)
[18:53:06.810] Timer tick, asking Buildbucket for the build status
[18:53:07.006] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:07.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:63:0) after 1m0s
[18:54:07.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:63:0)
[18:54:07.099] Timer tick, asking Buildbucket for the build status
[18:54:07.179] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:07.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:64:0) after 1m0s
[18:55:07.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:64:0)
[18:55:07.255] Timer tick, asking Buildbucket for the build status
[18:55:07.411] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:07.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:65:0) after 1m0s
[18:56:07.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:65:0)
[18:56:07.499] Timer tick, asking Buildbucket for the build status
[18:56:07.706] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:07.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:66:0) after 1m0s
[18:57:07.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:66:0)
[18:57:07.756] Timer tick, asking Buildbucket for the build status
[18:57:08.065] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:08.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:67:0) after 1m0s
[18:58:08.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:67:0)
[18:58:08.088] Timer tick, asking Buildbucket for the build status
[18:58:08.185] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:08.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:68:0) after 1m0s
[18:59:08.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:68:0)
[18:59:08.240] Timer tick, asking Buildbucket for the build status
[18:59:08.320] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:08.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:69:0) after 1m0s
[19:00:08.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:69:0)
[19:00:08.308] Timer tick, asking Buildbucket for the build status
[19:00:08.662] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:08.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:70:0) after 1m0s
[19:01:08.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:70:0)
[19:01:08.726] Timer tick, asking Buildbucket for the build status
[19:01:08.962] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:08.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:71:0) after 1m0s
[19:02:09.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:71:0)
[19:02:09.078] Timer tick, asking Buildbucket for the build status
[19:02:09.240] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:09.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:72:0) after 1m0s
[19:03:09.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:72:0)
[19:03:09.285] Timer tick, asking Buildbucket for the build status
[19:03:09.381] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:09.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:73:0) after 1m0s
[19:04:09.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:73:0)
[19:04:09.454] Timer tick, asking Buildbucket for the build status
[19:04:09.607] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:09.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:74:0) after 1m0s
[19:05:09.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:74:0)
[19:05:09.682] Timer tick, asking Buildbucket for the build status
[19:05:09.818] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:09.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:75:0) after 1m0s
[19:06:09.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:75:0)
[19:06:09.865] Timer tick, asking Buildbucket for the build status
[19:06:09.967] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:09.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:76:0) after 1m0s
[19:07:10.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:76:0)
[19:07:10.387] Timer tick, asking Buildbucket for the build status
[19:07:10.568] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:10.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:77:0) after 1m0s
[19:08:10.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:77:0)
[19:08:10.656] Timer tick, asking Buildbucket for the build status
[19:08:10.776] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:10.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:78:0) after 1m0s
[19:09:10.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:78:0)
[19:09:10.912] Timer tick, asking Buildbucket for the build status
[19:09:11.173] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:11.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:79:0) after 1m0s
[19:10:11.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:79:0)
[19:10:11.209] Timer tick, asking Buildbucket for the build status
[19:10:11.482] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:11.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:80:0) after 1m0s
[19:11:11.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:80:0)
[19:11:11.563] Timer tick, asking Buildbucket for the build status
[19:11:11.861] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:11.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:81:0) after 1m0s
[19:12:12.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:81:0)
[19:12:12.053] Timer tick, asking Buildbucket for the build status
[19:12:12.314] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:12.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:82:0) after 1m0s
[19:13:12.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:82:0)
[19:13:12.424] Timer tick, asking Buildbucket for the build status
[19:13:13.190] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:13.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:83:0) after 1m0s
[19:14:13.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:83:0)
[19:14:13.275] Timer tick, asking Buildbucket for the build status
[19:14:13.427] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:13.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:84:0) after 1m0s
[19:15:13.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:84:0)
[19:15:13.615] Timer tick, asking Buildbucket for the build status
[19:15:13.925] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:13.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:85:0) after 1m0s
[19:16:14.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:85:0)
[19:16:14.016] Timer tick, asking Buildbucket for the build status
[19:16:14.240] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:14.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:86:0) after 1m0s
[19:17:14.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:86:0)
[19:17:14.409] Timer tick, asking Buildbucket for the build status
[19:17:14.888] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:14.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:87:0) after 1m0s
[19:18:14.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:87:0)
[19:18:14.921] Timer tick, asking Buildbucket for the build status
[19:18:15.819] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:15.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:88:0) after 1m0s
[19:19:15.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:88:0)
[19:19:15.923] Timer tick, asking Buildbucket for the build status
[19:19:16.007] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:16.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:89:0) after 1m0s
[19:20:16.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:89:0)
[19:20:16.053] Timer tick, asking Buildbucket for the build status
[19:20:16.190] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:16.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:90:0) after 1m0s
[19:21:16.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:90:0)
[19:21:16.266] Timer tick, asking Buildbucket for the build status
[19:21:16.381] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:16.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:91:0) after 1m0s
[19:22:16.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:91:0)
[19:22:16.397] Timer tick, asking Buildbucket for the build status
[19:22:16.815] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:16.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:92:0) after 1m0s
[19:23:16.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:92:0)
[19:23:16.878] Timer tick, asking Buildbucket for the build status
[19:23:17.039] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:17.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:93:0) after 1m0s
[19:24:17.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:93:0)
[19:24:17.113] Timer tick, asking Buildbucket for the build status
[19:24:17.274] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:17.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:94:0) after 1m0s
[19:25:17.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:94:0)
[19:25:17.313] Timer tick, asking Buildbucket for the build status
[19:25:17.714] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:17.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:95:0) after 1m0s
[19:26:17.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:95:0)
[19:26:17.762] Timer tick, asking Buildbucket for the build status
[19:26:17.952] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:17.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:96:0) after 1m0s
[19:27:18.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:96:0)
[19:27:18.022] Timer tick, asking Buildbucket for the build status
[19:27:18.126] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:18.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:97:0) after 1m0s
[19:28:18.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:97:0)
[19:28:18.173] Timer tick, asking Buildbucket for the build status
[19:28:18.466] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:18.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:98:0) after 1m0s
[19:29:18.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:98:0)
[19:29:18.540] Timer tick, asking Buildbucket for the build status
[19:29:18.738] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:18.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:99:0) after 1m0s
[19:30:18.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:99:0)
[19:30:18.778] Timer tick, asking Buildbucket for the build status
[19:30:19.012] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:19.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:100:0) after 1m0s
[19:31:19.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:100:0)
[19:31:19.003] Timer tick, asking Buildbucket for the build status
[19:31:19.508] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:19.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:101:0) after 1m0s
[19:32:19.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:101:0)
[19:32:19.585] Timer tick, asking Buildbucket for the build status
[19:32:19.717] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:19.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:102:0) after 1m0s
[19:33:19.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:102:0)
[19:33:19.766] Timer tick, asking Buildbucket for the build status
[19:33:19.970] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:19.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:103:0) after 1m0s
[19:34:19.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:103:0)
[19:34:19.949] Timer tick, asking Buildbucket for the build status
[19:34:20.055] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:20.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:104:0) after 1m0s
[19:35:20.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:104:0)
[19:35:20.141] Timer tick, asking Buildbucket for the build status
[19:35:20.281] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:20.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:105:0) after 1m0s
[19:36:20.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:105:0)
[19:36:20.352] Timer tick, asking Buildbucket for the build status
[19:36:20.822] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:20.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:106:0) after 1m0s
[19:37:20.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:106:0)
[19:37:20.866] Timer tick, asking Buildbucket for the build status
[19:37:21.123] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:21.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:107:0) after 1m0s
[19:38:21.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:107:0)
[19:38:21.120] Timer tick, asking Buildbucket for the build status
[19:38:21.269] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:21.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:108:0) after 1m0s
[19:39:21.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:108:0)
[19:39:21.316] Timer tick, asking Buildbucket for the build status
[19:39:21.520] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:21.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:109:0) after 1m0s
[19:40:21.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:109:0)
[19:40:21.566] Timer tick, asking Buildbucket for the build status
[19:40:22.015] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:22.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:110:0) after 1m0s
[19:41:22.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:110:0)
[19:41:22.017] Timer tick, asking Buildbucket for the build status
[19:41:23.132] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:23.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:111:0) after 1m0s
[19:42:23.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:111:0)
[19:42:23.214] Timer tick, asking Buildbucket for the build status
[19:42:23.352] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:23.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:112:0) after 1m0s
[19:43:23.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:112:0)
[19:43:23.434] Timer tick, asking Buildbucket for the build status
[19:43:23.556] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:23.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:113:0) after 1m0s
[19:44:23.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:113:0)
[19:44:23.575] Timer tick, asking Buildbucket for the build status
[19:44:23.751] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:23.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:114:0) after 1m0s
[19:45:23.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:114:0)
[19:45:23.820] Timer tick, asking Buildbucket for the build status
[19:45:23.917] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:23.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:115:0) after 1m0s
[19:46:23.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:115:0)
[19:46:23.946] Timer tick, asking Buildbucket for the build status
[19:46:24.068] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:24.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:116:0) after 1m0s
[19:47:24.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:116:0)
[19:47:24.146] Timer tick, asking Buildbucket for the build status
[19:47:24.253] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:24.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:117:0) after 1m0s
[19:48:24.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:117:0)
[19:48:24.326] Timer tick, asking Buildbucket for the build status
[19:48:24.443] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:24.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:118:0) after 1m0s
[19:49:24.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:118:0)
[19:49:24.625] Timer tick, asking Buildbucket for the build status
[19:49:24.719] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:24.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:119:0) after 1m0s
[19:50:34.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:119:0)
[19:50:34.772] Timer tick, asking Buildbucket for the build status
[19:50:44.854] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:44.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:120:0) after 1m0s
[19:51:45.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:120:0)
[19:51:45.215] Timer tick, asking Buildbucket for the build status
[19:51:45.496] Build 8855027870766883536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:45.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020457933395976400:121:0) after 1m0s
[19:52:26.419] Received PubSub notification, asking Buildbucket for the build status
[19:52:26.552] Build 8855027870766883536: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:52:26.552] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613591544366979",
"created_by": "project:chromium",
"created_ts": "1613584370796310",
"id": "8855027870766883536",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a726219746be4c74384cc73c1bf4f52ef823f2f5\"}}",
"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\": \"a726219746be4c74384cc73c1bf4f52ef823f2f5\", \"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-1-2dz0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17396, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"105b8c74e3259d77cd53283bc4a56df06fcbb2a8\", \"got_dawn_revision\": \"a5ba2827f5810b7b1cd6ee046e8df6e109bb02b6\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"a726219746be4c74384cc73c1bf4f52ef823f2f5\", \"got_revision_cp\": \"refs/heads/master@{#854839}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"bb04803b8daf08e186b327d18e14c78378f44e37\", \"got_v8_revision\": \"b34b12f070e389b1180a8a4451625cabacbfb29e\", \"got_v8_revision_cp\": \"refs/heads/9.0.200@{#1}\", \"got_webrtc_revision\": \"ebc563e927b0ea1db816021e83b20150c116e573\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33287}\", \"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\": \"a726219746be4c74384cc73c1bf4f52ef823f2f5\", \"swarm_hashes_refs/heads/master(at){#854839}_without_patch\": {\"absl_hardening_tests\": \"c0308aff4d1272f4baf7486c173a445799cec3e2\", \"accessibility_unittests\": \"c3b54f95bcd766b5fb360d8251c28a2020fc7906\", \"angle_unittests\": \"24b214a6b45ad12d4723ece0f29c3748efb7587d\", \"app_shell_unittests\": \"355a07564b27be899133eebaac4cac30974c3c8a\", \"aura_unittests\": \"b1dc7a01b1996b984b1c045e959e9e03e529bdf2\", \"base_unittests\": \"8549b6a0e31a79c7bc670a5e6dc449981986ff86\", \"base_util_unittests\": \"179b78513d5b9a713fd530d2cd2ce21baf1b5a6d\", \"blink_common_unittests\": \"3dad213428985b6d7b11e073b169483dbb615a62\", \"blink_fuzzer_unittests\": \"9eb5ed4d299920c62a38409e938aecebeaa688fa\", \"blink_heap_unittests\": \"6fafdc061c8baf6298184610e7ea76a342caf69f\", \"blink_platform_unittests\": \"bf8af930838ee10cc0047928497a0a12c801c110\", \"blink_unittests\": \"c564f809692bb92a84d4cbe0e70da448f2d9eaf7\", \"boringssl_crypto_tests\": \"874b27fe0f0a4db5ee1ca22ed04265eb569e539c\", \"boringssl_ssl_tests\": \"c360b55f6a11178069383723fd2b4b83e86ed7f5\", \"browser_switcher_bho_unittests\": \"ce34c901efc5ba6a592ead96c3b4ff21a9033dbd\", \"browser_tests\": \"333c58f607a32e52be70755b5f1f772d155d01a2\", \"capture_unittests\": \"9e67c77c14b5c681e4fa7d92f4a36a3b0c70f0f3\", \"cast_unittests\": \"1faa5190d8d2c396b3e6490061036c5fc76006b1\", \"cc_unittests\": \"ffeb1bfa933a5993a56d67724d8190f5b64f95b8\", \"chrome_app_unittests\": \"be2b9a596371dd10e5f28122b8c14b4440c187d3\", \"chrome_cleaner_unittests\": \"ee0ffba495d9e981ddd6d5d0c9a65203547a8c50\", \"chrome_elf_unittests\": \"b0c29ec7982f2edd5a1543df97b56718ede1cc50\", \"chromedriver_unittests\": \"7bd59e1778791a8f6a7dbf0c7074aff3d6cdb541\", \"components_browsertests\": \"e13b78c9503e2e803a751de31f7a33b93dd7575e\", \"components_unittests\": \"b6f5e5206c17ae02497e216cf7b7771e2e8e56cd\", \"compositor_unittests\": \"46e652712e8f6df6cd0d2f30ac792e497c281775\", \"content_browsertests\": \"afbc92f4fecba2c51ad471c31f497cc7611ed4d9\", \"content_unittests\": \"b894783c7a04a08265add786a47a7255ea07c8d5\", \"courgette_unittests\": \"3db3d9dcac845d0877a4c088d906b6052d377630\", \"crashpad_tests\": \"dcb87117924ecd1220519c96c112e3424ddb3376\", \"cronet_tests\": \"15f2c834b5d7a23b6a1bb3daddf6d9766630a464\", \"cronet_unittests\": \"203be44922b1ee53727472fb73c956826c3beec5\", \"crypto_unittests\": \"de8d2349a61126c4be38c2b7d5db810b0ea668e0\", \"delayloads_unittests\": \"25167bd98d0e280682758a333befc323c1d55a2d\", \"device_unittests\": \"7d9fc841160c06ec9176294ae9b31e8fa93aecaf\", \"display_unittests\": \"e2dd52ae93e1cbdd120838366bca836f97400e44\", \"elevation_service_unittests\": \"d19c32e95e79f57ab56b3794968d123a2a3535fb\", \"events_unittests\": \"54ed441574588d043196ec822a901c917b2145e8\", \"extensions_browsertests\": \"725a46cb148adb771dd306957735916dc2279e05\", \"extensions_unittests\": \"d9257aaed1a0a2d1aabc4fac41c9695ced764f4d\", \"filesystem_service_unittests\": \"e055dc8c6064a470b5a23f75d52781610659a471\", \"gcm_unit_tests\": \"b06c054cb9039876657908723da47f1e6990b3a9\", \"gcp_unittests\": \"2c774d0063a87248c7ca5db44d70fd1d99a832f8\", \"gfx_unittests\": \"4e3234dbcd955612428a5e28396126c21b4c1811\", \"gin_unittests\": \"8537c097b87fb286afd14613af4b98f7fb85ba78\", \"google_apis_unittests\": \"1a6dab984670fd25a3eda3eaf7e589f9e04815b2\", \"gpu_unittests\": \"f6fa4e1c09679995b2a7a3a2dbd7c71f53f4d3e8\", \"gwp_asan_unittests\": \"769f435b32c49367a25c80b2f692ec3e748a2eda\", \"headless_browsertests\": \"3f3d7f666b161ab10d728c3b02da5164170821ad\", \"headless_unittests\": \"76ad02819e9554d65500eb2d6665d213b6f5c579\", \"install_static_unittests\": \"e6934208212772b1abe5b3b77624b814ec4298ed\", \"installer_util_unittests\": \"531c2b61f7a49200214da8687048c973413d5d2c\", \"interactive_ui_tests\": \"55b53441c8ab2243d4364d83bb01b0e3b21ac1f4\", \"ipc_tests\": \"076b232a1e1f129056293bce2412e8a436ad3c1f\", \"jingle_unittests\": \"5587c00d8e435cdfce832318f216e581f0093290\", \"latency_unittests\": \"46d56effd665725f61aa95df87963e24ea587e0d\", \"libjingle_xmpp_unittests\": \"0b9ffd7a525e771325747d42975e66538fd9ea73\", \"liburlpattern_unittests\": \"dab8d16d5f4c3b227fb4c727d13907d19cf8ccad\", \"media_blink_unittests\": \"648ad0690e8987fed9ea4e062c31b8155dc9d974\", \"media_unittests\": \"dedf0c90477a5decfc26efe4bd6bbc77ecce4640\", \"message_center_unittests\": \"a87421e9d645bac9183a10d6289ae4f5e79617ad\", \"midi_unittests\": \"903774f27bcd3566b2e05f16b005cc6032e43029\", \"mojo_core_unittests\": \"f97e6f0025ac2f8ff8097a158db59d8b749f5934\", \"mojo_unittests\": \"456872cf4e2c6b7356ffd3b5b676596e1d6f9b9f\", \"nacl_loader_unittests\": \"250c3e1d454211c3fb12ef46da8815095e4ac628\", \"native_theme_unittests\": \"bbf1e1ab9e8877d16c697637c540214a3a3b0477\", \"net_unittests\": \"53c98cc8fc5887279c1e52cc036ee73b0a63da31\", \"notification_helper_unittests\": \"45fe9889dc94c271aae587694314f837e2c775d3\", \"pdf_unittests\": \"29e41153665fbedd967be549cde972c3686dbee8\", \"perfetto_unittests\": \"2570924207012b1b9ad6090e963fb0956031e545\", \"ppapi_unittests\": \"aeb418b37d7e9f07b534d14afd838d48cdcc00cb\", \"printing_unittests\": \"ec123d9395592310034fe1fc6315577fb40a8620\", \"remoting_unittests\": \"fd8a9c0094309f780cec5abc1988056e410c3dd9\", \"sbox_integration_tests\": \"a28ff4de8bb714afb53c33d77ba737ba0b9e4585\", \"sbox_unittests\": \"0a4304eb61ba8fc564dc6ea600bb226afe6bc6ad\", \"sbox_validation_tests\": \"864cb61c21f0df7a5227f99c743012f86d140f0b\", \"service_manager_unittests\": \"b4aeb3f8995258107ab457c3472a8e28e9d66f37\", \"services_unittests\": \"193abc39cdcf96ad22057b3efc40e41ac230cb4e\", \"setup_unittests\": \"5fcdd6e1b67e2c37609c3744887b3d8c9fbea238\", \"shell_dialogs_unittests\": \"4b66f0923aaddc7964c5690da973d4c7cc309d93\", \"skia_unittests\": \"4a2f6efb4ce81418c934dbaef33cbdc6d93ba71a\", \"snapshot_unittests\": \"2e12babbdc16f8d26158e04a511463b6059c2108\", \"sql_unittests\": \"5f6084b260f355af6cb4cddbe1c4dfd399dfb3ef\", \"storage_unittests\": \"081b6c358288eab9c595cfaa0bf1256f04257565\", \"sync_integration_tests\": \"dc0385ce9b0c99c244fd2e093d7ac0af93ce1b4a\", \"traffic_annotation_auditor_unittests\": \"08bf5122cf4e95c61ed50a8c317b23f97de747b4\", \"ui_base_unittests\": \"1342cb66931dcdbc0920be8fce9923b83ef6d936\", \"ui_touch_selection_unittests\": \"fcb7a0db310728f6b1b0ed2cf766988041e45bad\", \"unit_tests\": \"73d8ff2865f5e570da9a0a0629469d419a0bf5e1\", \"updater_tests\": \"357db012302fee6498478db78b1ec61d0d8870f1\", \"url_unittests\": \"3e045ce76775dd97717245939d0756bfeb9ba334\", \"views_unittests\": \"30ee65254ebe3a3382d59e03f866c60924494fdb\", \"viz_unittests\": \"49f7948073391292c79e09c71b770841f6514bcb\", \"vr_common_unittests\": \"7301adc4228824486f4d4ba7f85c94e443fd7378\", \"vr_pixeltests\": \"6a8adfa21f1dc89550e171774330702944026956\", \"weblayer_browsertests\": \"ed27f1a93620215be8a53c3487e46f42fbbbbb1f\", \"weblayer_unittests\": \"9b1cb9531bf0adba3106792ee4beb44309b35754\", \"wm_unittests\": \"9df9a056fd7134dd1a59b2cc1bdbf1a008ab8821\", \"wtf_unittests\": \"a5e3cd17c6a3f8d178ca32a943e971a9bca06761\", \"zlib_unittests\": \"ae9d4fd29a9cc0a988b0208b9a0ebac53e052924\", \"zucchini_unittests\": \"e35ac5427cbdc9abff7326f70fe8ded9455c0f8c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-1-2dz0\"], \"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\": [\"5700-b36f18b\"], \"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": "1613584621873397",
"status": "COMPLETED",
"status_changed_ts": "1613591544959379",
"tags": [
"build_address:luci.chromium.ci/win-asan/17396",
"builder:win-asan",
"buildset:commit/git/a726219746be4c74384cc73c1bf4f52ef823f2f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a726219746be4c74384cc73c1bf4f52ef823f2f5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020457933395976400",
"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/8855027870766883536/+/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:51c9f1191181ed10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613591544963343",
"url": "https://ci.chromium.org/b/8855027870766883536",
"utcnow_ts": "1613591546543667"
}
}
[19:52:26.552] Invocation finished in 1h59m37.646835897s with status SUCCEEDED