[19:53:55.710] New invocation is queued and will start shortly
[19:53:56.893] Starting the invocation (attempt 1)
[19:53:56.928] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:53:56.928] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:53:56.928] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017823001589155632",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8235a853755047c6ea7c4f16b925bf4bc8d81d86\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9017823001589155632",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"gitiles_ref:refs/heads/master"
]
}
[19:53:58.408] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616097237113644",
"id": "8852392939455738912",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8235a853755047c6ea7c4f16b925bf4bc8d81d86\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616097238167535",
"tags": [
"build_address:luci.chromium.ci/win-asan/17857",
"builder:win-asan",
"buildset:commit/git/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017823001589155632",
"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/8852392939455738912/+/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": "1616097238167711",
"url": "https://ci.chromium.org/b/8852392939455738912",
"utcnow_ts": "1616097238389190"
}
}
[19:53:58.408] Task URL: https://ci.chromium.org/b/8852392939455738912
[19:53:58.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:2:0) after 1m0s
[19:54:58.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:2:0)
[19:54:58.400] Timer tick, asking Buildbucket for the build status
[19:54:58.477] Build 8852392939455738912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:58.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:3:0) after 1m0s
[19:55:58.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:3:0)
[19:55:58.575] Timer tick, asking Buildbucket for the build status
[19:55:58.686] Build 8852392939455738912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:58.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:4:0) after 1m0s
[19:56:55.216] Received PubSub notification, asking Buildbucket for the build status
[19:56:55.330] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:58.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:4:0)
[19:56:58.722] Timer tick, asking Buildbucket for the build status
[19:56:58.807] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:58.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:6:0) after 1m0s
[19:57:58.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:6:0)
[19:57:58.805] Timer tick, asking Buildbucket for the build status
[19:57:58.872] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:58.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:7:0) after 1m0s
[19:58:58.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:7:0)
[19:58:58.921] Timer tick, asking Buildbucket for the build status
[19:58:58.984] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:58.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:8:0) after 1m0s
[19:59:58.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:8:0)
[19:59:58.993] Timer tick, asking Buildbucket for the build status
[19:59:59.066] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:59.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:9:0) after 1m0s
[20:00:59.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:9:0)
[20:00:59.085] Timer tick, asking Buildbucket for the build status
[20:00:59.158] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:59.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:10:0) after 1m0s
[20:01:59.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:10:0)
[20:01:59.165] Timer tick, asking Buildbucket for the build status
[20:01:59.285] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:59.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:11:0) after 1m0s
[20:02:59.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:11:0)
[20:02:59.273] Timer tick, asking Buildbucket for the build status
[20:02:59.446] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:59.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:12:0) after 1m0s
[20:03:59.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:12:0)
[20:03:59.478] Timer tick, asking Buildbucket for the build status
[20:03:59.556] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:59.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:13:0) after 1m0s
[20:04:59.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:13:0)
[20:04:59.554] Timer tick, asking Buildbucket for the build status
[20:04:59.648] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:59.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:14:0) after 1m0s
[20:05:59.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:14:0)
[20:05:59.673] Timer tick, asking Buildbucket for the build status
[20:05:59.741] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:59.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:15:0) after 1m0s
[20:06:59.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:15:0)
[20:06:59.795] Timer tick, asking Buildbucket for the build status
[20:06:59.888] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:59.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:16:0) after 1m0s
[20:08:00.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:16:0)
[20:08:00.021] Timer tick, asking Buildbucket for the build status
[20:08:00.158] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:00.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:17:0) after 1m0s
[20:09:00.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:17:0)
[20:09:00.181] Timer tick, asking Buildbucket for the build status
[20:09:00.258] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:00.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:18:0) after 1m0s
[20:10:00.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:18:0)
[20:10:00.272] Timer tick, asking Buildbucket for the build status
[20:10:00.428] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:00.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:19:0) after 1m0s
[20:11:00.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:19:0)
[20:11:00.435] Timer tick, asking Buildbucket for the build status
[20:11:00.503] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:00.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:20:0) after 1m0s
[20:12:00.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:20:0)
[20:12:00.748] Timer tick, asking Buildbucket for the build status
[20:12:00.892] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:00.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:21:0) after 1m0s
[20:13:00.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:21:0)
[20:13:00.997] Timer tick, asking Buildbucket for the build status
[20:13:01.253] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:01.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:22:0) after 1m0s
[20:14:01.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:22:0)
[20:14:01.276] Timer tick, asking Buildbucket for the build status
[20:14:01.355] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:01.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:23:0) after 1m0s
[20:15:01.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:23:0)
[20:15:01.476] Timer tick, asking Buildbucket for the build status
[20:15:01.584] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:01.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:24:0) after 1m0s
[20:16:01.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:24:0)
[20:16:01.681] Timer tick, asking Buildbucket for the build status
[20:16:01.761] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:01.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:25:0) after 1m0s
[20:17:02.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:25:0)
[20:17:02.189] Timer tick, asking Buildbucket for the build status
[20:17:02.306] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:02.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:26:0) after 1m0s
[20:18:02.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:26:0)
[20:18:02.392] Timer tick, asking Buildbucket for the build status
[20:18:02.603] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:02.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:27:0) after 1m0s
[20:19:02.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:27:0)
[20:19:02.803] Timer tick, asking Buildbucket for the build status
[20:19:02.881] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:02.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:28:0) after 1m0s
[20:20:02.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:28:0)
[20:20:02.981] Timer tick, asking Buildbucket for the build status
[20:20:03.299] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:03.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:29:0) after 1m0s
[20:21:03.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:29:0)
[20:21:03.331] Timer tick, asking Buildbucket for the build status
[20:21:03.403] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:03.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:30:0) after 1m0s
[20:22:03.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:30:0)
[20:22:03.465] Timer tick, asking Buildbucket for the build status
[20:22:03.749] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:03.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:31:0) after 1m0s
[20:23:03.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:31:0)
[20:23:03.759] Timer tick, asking Buildbucket for the build status
[20:23:03.844] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:03.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:32:0) after 1m0s
[20:24:04.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:32:0)
[20:24:04.027] Timer tick, asking Buildbucket for the build status
[20:24:04.155] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:04.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:33:0) after 1m0s
[20:25:04.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:33:0)
[20:25:04.316] Timer tick, asking Buildbucket for the build status
[20:25:04.946] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:04.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:34:0) after 1m0s
[20:26:05.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:34:0)
[20:26:05.006] Timer tick, asking Buildbucket for the build status
[20:26:05.406] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:05.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:35:0) after 1m0s
[20:27:05.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:35:0)
[20:27:05.645] Timer tick, asking Buildbucket for the build status
[20:27:05.706] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:05.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:36:0) after 1m0s
[20:28:05.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:36:0)
[20:28:05.820] Timer tick, asking Buildbucket for the build status
[20:28:05.886] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:05.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:37:0) after 1m0s
[20:29:05.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:37:0)
[20:29:05.860] Timer tick, asking Buildbucket for the build status
[20:29:06.210] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:06.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:38:0) after 1m0s
[20:30:06.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:38:0)
[20:30:06.294] Timer tick, asking Buildbucket for the build status
[20:30:06.358] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:06.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:39:0) after 1m0s
[20:31:06.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:39:0)
[20:31:06.664] Timer tick, asking Buildbucket for the build status
[20:31:06.727] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:06.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:40:0) after 1m0s
[20:32:06.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:40:0)
[20:32:06.855] Timer tick, asking Buildbucket for the build status
[20:32:06.924] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:06.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:41:0) after 1m0s
[20:33:06.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:41:0)
[20:33:06.959] Timer tick, asking Buildbucket for the build status
[20:33:07.049] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:07.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:42:0) after 1m0s
[20:34:07.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:42:0)
[20:34:07.099] Timer tick, asking Buildbucket for the build status
[20:34:07.178] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:07.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:43:0) after 1m0s
[20:35:07.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:43:0)
[20:35:07.250] Timer tick, asking Buildbucket for the build status
[20:35:07.418] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:07.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:44:0) after 1m0s
[20:36:07.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:44:0)
[20:36:07.505] Timer tick, asking Buildbucket for the build status
[20:36:07.586] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:07.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:45:0) after 1m0s
[20:37:07.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:45:0)
[20:37:07.693] Timer tick, asking Buildbucket for the build status
[20:37:07.750] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:07.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:46:0) after 1m0s
[20:38:07.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:46:0)
[20:38:07.738] Timer tick, asking Buildbucket for the build status
[20:38:07.812] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:07.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:47:0) after 1m0s
[20:39:07.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:47:0)
[20:39:07.904] Timer tick, asking Buildbucket for the build status
[20:39:08.038] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:08.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:48:0) after 1m0s
[20:40:08.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:48:0)
[20:40:08.108] Timer tick, asking Buildbucket for the build status
[20:40:08.215] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:08.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:49:0) after 1m0s
[20:41:08.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:49:0)
[20:41:08.237] Timer tick, asking Buildbucket for the build status
[20:41:08.353] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:08.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:50:0) after 1m0s
[20:42:08.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:50:0)
[20:42:08.502] Timer tick, asking Buildbucket for the build status
[20:42:08.630] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:08.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:51:0) after 1m0s
[20:43:08.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:51:0)
[20:43:08.746] Timer tick, asking Buildbucket for the build status
[20:43:08.976] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:08.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:52:0) after 1m0s
[20:44:09.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:52:0)
[20:44:09.048] Timer tick, asking Buildbucket for the build status
[20:44:09.220] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:09.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:53:0) after 1m0s
[20:45:09.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:53:0)
[20:45:09.327] Timer tick, asking Buildbucket for the build status
[20:45:09.403] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:09.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:54:0) after 1m0s
[20:46:09.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:54:0)
[20:46:09.508] Timer tick, asking Buildbucket for the build status
[20:46:09.570] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:09.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:55:0) after 1m0s
[20:47:09.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:55:0)
[20:47:09.638] Timer tick, asking Buildbucket for the build status
[20:47:09.720] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:09.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:56:0) after 1m0s
[20:48:09.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:56:0)
[20:48:09.720] Timer tick, asking Buildbucket for the build status
[20:48:09.792] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:09.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:57:0) after 1m0s
[20:49:09.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:57:0)
[20:49:09.843] Timer tick, asking Buildbucket for the build status
[20:49:10.031] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:10.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:58:0) after 1m0s
[20:50:10.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:58:0)
[20:50:10.296] Timer tick, asking Buildbucket for the build status
[20:50:10.389] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:10.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:59:0) after 1m0s
[20:51:10.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:59:0)
[20:51:10.518] Timer tick, asking Buildbucket for the build status
[20:51:10.899] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:10.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:60:0) after 1m0s
[20:52:11.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:60:0)
[20:52:11.086] Timer tick, asking Buildbucket for the build status
[20:52:11.169] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:11.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:61:0) after 1m0s
[20:53:11.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:61:0)
[20:53:11.303] Timer tick, asking Buildbucket for the build status
[20:53:11.405] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:11.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:62:0) after 1m0s
[20:54:11.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:62:0)
[20:54:11.487] Timer tick, asking Buildbucket for the build status
[20:54:11.713] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:11.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:63:0) after 1m0s
[20:55:11.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:63:0)
[20:55:11.737] Timer tick, asking Buildbucket for the build status
[20:55:11.808] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:11.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:64:0) after 1m0s
[20:56:11.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:64:0)
[20:56:11.854] Timer tick, asking Buildbucket for the build status
[20:56:12.160] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:12.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:65:0) after 1m0s
[20:57:12.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:65:0)
[20:57:12.191] Timer tick, asking Buildbucket for the build status
[20:57:12.643] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:12.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:66:0) after 1m0s
[20:58:12.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:66:0)
[20:58:12.700] Timer tick, asking Buildbucket for the build status
[20:58:12.882] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:12.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:67:0) after 1m0s
[20:59:12.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:67:0)
[20:59:12.951] Timer tick, asking Buildbucket for the build status
[20:59:13.228] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:13.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:68:0) after 1m0s
[21:00:13.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:68:0)
[21:00:13.221] Timer tick, asking Buildbucket for the build status
[21:00:13.596] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:13.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:69:0) after 1m0s
[21:01:13.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:69:0)
[21:01:13.643] Timer tick, asking Buildbucket for the build status
[21:01:13.721] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:13.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:70:0) after 1m0s
[21:02:13.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:70:0)
[21:02:13.752] Timer tick, asking Buildbucket for the build status
[21:02:13.867] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:13.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:71:0) after 1m0s
[21:03:13.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:71:0)
[21:03:13.912] Timer tick, asking Buildbucket for the build status
[21:03:14.013] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:14.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:72:0) after 1m0s
[21:04:14.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:72:0)
[21:04:14.025] Timer tick, asking Buildbucket for the build status
[21:04:14.200] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:14.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:73:0) after 1m0s
[21:05:14.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:73:0)
[21:05:14.310] Timer tick, asking Buildbucket for the build status
[21:05:14.398] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:14.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:74:0) after 1m0s
[21:06:14.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:74:0)
[21:06:14.501] Timer tick, asking Buildbucket for the build status
[21:06:14.858] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:14.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:75:0) after 1m0s
[21:07:14.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:75:0)
[21:07:14.923] Timer tick, asking Buildbucket for the build status
[21:07:15.032] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:15.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:76:0) after 1m0s
[21:08:15.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:76:0)
[21:08:15.050] Timer tick, asking Buildbucket for the build status
[21:08:15.156] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:15.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:77:0) after 1m0s
[21:09:15.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:77:0)
[21:09:15.218] Timer tick, asking Buildbucket for the build status
[21:09:15.395] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:15.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:78:0) after 1m0s
[21:10:15.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:78:0)
[21:10:15.450] Timer tick, asking Buildbucket for the build status
[21:10:15.639] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:15.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:79:0) after 1m0s
[21:11:15.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:79:0)
[21:11:15.684] Timer tick, asking Buildbucket for the build status
[21:11:15.774] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:15.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:80:0) after 1m0s
[21:12:15.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:80:0)
[21:12:15.827] Timer tick, asking Buildbucket for the build status
[21:12:15.907] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:15.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:81:0) after 1m0s
[21:13:15.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:81:0)
[21:13:15.939] Timer tick, asking Buildbucket for the build status
[21:13:16.080] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:16.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:82:0) after 1m0s
[21:14:16.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:82:0)
[21:14:16.141] Timer tick, asking Buildbucket for the build status
[21:14:16.292] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:16.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:83:0) after 1m0s
[21:15:16.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:83:0)
[21:15:16.323] Timer tick, asking Buildbucket for the build status
[21:15:16.405] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:16.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:84:0) after 1m0s
[21:16:16.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:84:0)
[21:16:16.469] Timer tick, asking Buildbucket for the build status
[21:16:16.595] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:16.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:85:0) after 1m0s
[21:17:16.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:85:0)
[21:17:16.629] Timer tick, asking Buildbucket for the build status
[21:17:16.708] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:16.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:86:0) after 1m0s
[21:18:16.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:86:0)
[21:18:16.725] Timer tick, asking Buildbucket for the build status
[21:18:16.808] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:16.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:87:0) after 1m0s
[21:19:16.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:87:0)
[21:19:16.828] Timer tick, asking Buildbucket for the build status
[21:19:17.044] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:17.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:88:0) after 1m0s
[21:20:17.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:88:0)
[21:20:17.133] Timer tick, asking Buildbucket for the build status
[21:20:17.256] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:17.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:89:0) after 1m0s
[21:21:17.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:89:0)
[21:21:17.407] Timer tick, asking Buildbucket for the build status
[21:21:17.753] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:17.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:90:0) after 1m0s
[21:22:17.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:90:0)
[21:22:17.734] Timer tick, asking Buildbucket for the build status
[21:22:17.817] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:17.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:91:0) after 1m0s
[21:23:17.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:91:0)
[21:23:17.952] Timer tick, asking Buildbucket for the build status
[21:23:18.025] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:18.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:92:0) after 1m0s
[21:24:18.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:92:0)
[21:24:18.167] Timer tick, asking Buildbucket for the build status
[21:24:18.255] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:18.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:93:0) after 1m0s
[21:25:18.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:93:0)
[21:25:18.243] Timer tick, asking Buildbucket for the build status
[21:25:18.486] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:18.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:94:0) after 1m0s
[21:26:18.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:94:0)
[21:26:18.765] Timer tick, asking Buildbucket for the build status
[21:26:18.864] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:18.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:95:0) after 1m0s
[21:27:18.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:95:0)
[21:27:18.936] Timer tick, asking Buildbucket for the build status
[21:27:19.061] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:19.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:96:0) after 1m0s
[21:28:19.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:96:0)
[21:28:19.154] Timer tick, asking Buildbucket for the build status
[21:28:19.295] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:19.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:97:0) after 1m0s
[21:29:19.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:97:0)
[21:29:19.326] Timer tick, asking Buildbucket for the build status
[21:29:19.458] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:19.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:98:0) after 1m0s
[21:30:19.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:98:0)
[21:30:19.500] Timer tick, asking Buildbucket for the build status
[21:30:19.582] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:19.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:99:0) after 1m0s
[21:31:19.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:99:0)
[21:31:19.846] Timer tick, asking Buildbucket for the build status
[21:31:20.133] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:20.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:100:0) after 1m0s
[21:32:20.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:100:0)
[21:32:20.336] Timer tick, asking Buildbucket for the build status
[21:32:20.827] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:20.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:101:0) after 1m0s
[21:33:21.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:101:0)
[21:33:21.011] Timer tick, asking Buildbucket for the build status
[21:33:21.516] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:21.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:102:0) after 1m0s
[21:34:21.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:102:0)
[21:34:21.697] Timer tick, asking Buildbucket for the build status
[21:34:21.786] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:21.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:103:0) after 1m0s
[21:35:21.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:103:0)
[21:35:21.838] Timer tick, asking Buildbucket for the build status
[21:35:21.959] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:21.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:104:0) after 1m0s
[21:36:22.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:104:0)
[21:36:22.101] Timer tick, asking Buildbucket for the build status
[21:36:22.181] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:22.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:105:0) after 1m0s
[21:37:22.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:105:0)
[21:37:22.291] Timer tick, asking Buildbucket for the build status
[21:37:22.375] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:22.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:106:0) after 1m0s
[21:38:22.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:106:0)
[21:38:22.354] Timer tick, asking Buildbucket for the build status
[21:38:22.646] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:22.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:107:0) after 1m0s
[21:39:22.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:107:0)
[21:39:22.762] Timer tick, asking Buildbucket for the build status
[21:39:22.901] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:22.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:108:0) after 1m0s
[21:40:22.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:108:0)
[21:40:22.953] Timer tick, asking Buildbucket for the build status
[21:40:23.044] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:23.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:109:0) after 1m0s
[21:41:23.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:109:0)
[21:41:23.179] Timer tick, asking Buildbucket for the build status
[21:41:23.279] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:23.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:110:0) after 1m0s
[21:42:23.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:110:0)
[21:42:23.387] Timer tick, asking Buildbucket for the build status
[21:42:23.556] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:23.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:111:0) after 1m0s
[21:43:23.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:111:0)
[21:43:23.732] Timer tick, asking Buildbucket for the build status
[21:43:23.813] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:23.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:112:0) after 1m0s
[21:44:23.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:112:0)
[21:44:23.892] Timer tick, asking Buildbucket for the build status
[21:44:24.030] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:24.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:113:0) after 1m0s
[21:45:24.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:113:0)
[21:45:24.126] Timer tick, asking Buildbucket for the build status
[21:45:24.203] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:24.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:114:0) after 1m0s
[21:46:24.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:114:0)
[21:46:24.274] Timer tick, asking Buildbucket for the build status
[21:46:24.422] Build 8852392939455738912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:24.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017823001589155632:115:0) after 1m0s
[21:47:00.096] Received PubSub notification, asking Buildbucket for the build status
[21:47:00.880] Build 8852392939455738912: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:47:00.880] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616104019415986",
"created_by": "project:chromium",
"created_ts": "1616097237113644",
"id": "8852392939455738912",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8235a853755047c6ea7c4f16b925bf4bc8d81d86\"}}",
"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\": \"8235a853755047c6ea7c4f16b925bf4bc8d81d86\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-6-xj00\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17857, \"clang_revision\": \"llvmorg-13-init-4720-g7bafe336-1\", \"got_angle_revision\": \"1f1b4ffeff1356cc474dd8b4f55189ade357c855\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"8235a853755047c6ea7c4f16b925bf4bc8d81d86\", \"got_revision_cp\": \"refs/heads/master@{#864393}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"10c783109050db482a0b7e7f7b31d6befa0848fa\", \"got_v8_revision_cp\": \"refs/heads/9.1.156@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"is_cached\": true, \"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\": \"8235a853755047c6ea7c4f16b925bf4bc8d81d86\", \"swarm_hashes_refs/heads/master(at){#864393}_without_patch\": {\"absl_hardening_tests\": \"b80cda0e7f4f92fd64329fafcbace940af5402fd\", \"accessibility_unittests\": \"0942b7932542b84d20ec65507373f58da55bfb5c\", \"angle_unittests\": \"4ead09c385271e8d588f00d56f701730aa98f55c\", \"app_shell_unittests\": \"333b7409303477e96292ab9401ca3d9c5107af16\", \"aura_unittests\": \"92666480b9c29f2ec8d3665f079a9c98c481385f\", \"base_unittests\": \"75f7baca177831bd878cf82fca333fc19309777f\", \"base_util_unittests\": \"78c1da685305184ca12a279b166eb9f59d5259b0\", \"blink_common_unittests\": \"32a26e61c0da9b61fa80e10dde2708a424dffae4\", \"blink_fuzzer_unittests\": \"5d2b82db6fcb0553206071d5e9610d9c188037e7\", \"blink_heap_unittests\": \"26141042ed6b6b6650eac27b63dcf59dd039e48e\", \"blink_platform_unittests\": \"4f88eb792103774de108172e5ee84bbb3b1300c0\", \"blink_unittests\": \"905f94d2b74c80a86ae296e47f654ed6ba3f0a37\", \"boringssl_crypto_tests\": \"158ce2a0e3d6a0fc400f041a360ede3c68cd6b64\", \"boringssl_ssl_tests\": \"c107fd8d5650d8195a0f79aa02ea1e4aa6f17b32\", \"browser_switcher_bho_unittests\": \"5a86ed3fc5ef7d8a647ddcd5a935c188a9199c49\", \"browser_tests\": \"5c7b905bd248e5e74c8a26cd5c09b7f054cab849\", \"capture_unittests\": \"3a3af08e73eb074604c1eea8a45feb7bc2be0137\", \"cast_unittests\": \"ee08184ccc4b558b0a974cfd9c697b8112296ef4\", \"cc_unittests\": \"5d77bf5f591fa46c0b42e829ac56d97a4578eda5\", \"chrome_app_unittests\": \"c22ca7d1b75a50557a580eb25fdd132effa25288\", \"chrome_cleaner_unittests\": \"bba1b7b454853e4aaecd969059a2d79e1668f786\", \"chrome_elf_unittests\": \"7f5ef1325e3b0600fdfb856e53c3549d711b2539\", \"chromedriver_unittests\": \"42ff0828e8adc3c99ab8f512d62ea8446f8016d7\", \"color_unittests\": \"0a4d67131c0b9c9f1c29aa599904ea6780fe5f45\", \"components_browsertests\": \"77d096c92a6149c43ab407d5bc63767fe78d79c9\", \"components_unittests\": \"faac224d08ea09ab8f19b872c5ccb44a95a6b446\", \"compositor_unittests\": \"14415df8efb9648eb90a6c0ace3a7e6eefe7adc8\", \"content_browsertests\": \"c68e02cc7f0764d9c48ff1f899ca01aa5c8a4372\", \"content_unittests\": \"6737fbc83f5ad3ac90b41419a9c43103d44bbb42\", \"courgette_unittests\": \"2b7f707f476a5a70dc4f8f7638d98d2fcdeca485\", \"crashpad_tests\": \"4ee069b25cc7e3505731bf9540ebf17e7005c475\", \"cronet_tests\": \"0c06815f7e4925181d6e652482e21ae50ff05a1b\", \"cronet_unittests\": \"e0ed80c9e8d0cc86dd5aa8f27d2ff438c2ae4fce\", \"crypto_unittests\": \"cef02a09227ae34e218194225ce63f07ff0a1125\", \"delayloads_unittests\": \"2a5687b4ebff8f1f279d44eab3ebea58f2731a71\", \"device_unittests\": \"a73e90ac58f517634ddf9bb4d258ec9ba9e25dba\", \"display_unittests\": \"06c839d80e7efbd539db8b3a45760fda96790935\", \"elevation_service_unittests\": \"d655fce7e74be0282cfded19686ca0b3d5fd09f9\", \"events_unittests\": \"e1c6f650b40686fa1c5b9d76d9d6ee8483da03fa\", \"extensions_browsertests\": \"5e967fb92d5c417f0c85a0e441af63ea6b0211dc\", \"extensions_unittests\": \"26ddbc1332fe67afba1919430875a990a645acf6\", \"filesystem_service_unittests\": \"45b7dc3b21988a1139070aec40c78f9c7666fd17\", \"gcm_unit_tests\": \"adc95395833cecf400da1fc3a33110be60d942b3\", \"gcp_unittests\": \"6af665df29ab14eb891c0227691b3b739fbfa1a4\", \"gfx_unittests\": \"948032fea7606f8c29b59e271ff53402b017a1cf\", \"gin_unittests\": \"ba7cf2c9420ef5275d5142e573d62af463d29dd4\", \"google_apis_unittests\": \"24df7b0e11f47b3480217d7787d4bbc09b357080\", \"gpu_unittests\": \"f1ee818f52f76346adb82b75ddd5c26bb2d32376\", \"gwp_asan_unittests\": \"edb8703568d9266e5c4608d23f00b46cfbfcf34f\", \"headless_browsertests\": \"02197e7d2db37feda785921ff9e08ffc1847ce81\", \"headless_unittests\": \"3f75c2fb98eb13ada5faa37f98f38948eb9d49db\", \"install_static_unittests\": \"a48706bc6754509f3909d5a1bea48c34b061a65e\", \"installer_util_unittests\": \"1adad8809dba13e4a675f2198ccb17470960dc12\", \"interactive_ui_tests\": \"7aa026ff69b5722a24e96a6ec598bfdf16df025b\", \"ipc_tests\": \"5adf85af65cc6517b45c5e157b080ad2cdf23dc4\", \"jingle_unittests\": \"d7020dbe41b6eec40080cb1559a7aa44cf6e4052\", \"latency_unittests\": \"bdac0d9fa4673633a8283eae8796a8b23b67a912\", \"libjingle_xmpp_unittests\": \"a51f3f5188d7d4af9eb5ce31b8d6303ff8069e67\", \"liburlpattern_unittests\": \"03ce0a0a8b73daecfed69c78d17a63b55ae99250\", \"media_blink_unittests\": \"d615ba298b4eea234285fe6d6730630c6fde6cc7\", \"media_unittests\": \"42b93e1261fc6d2b2bb63eee9fafcbd3da35db03\", \"message_center_unittests\": \"2ace7fbdc4d48d051ed363c6356fa4ada99850a1\", \"midi_unittests\": \"1a14dce135c01489a23e4494828b09f5197426e8\", \"mojo_core_unittests\": \"e25719302bd17e98025688340aded498b8983491\", \"mojo_unittests\": \"f59e7ce082a3bc367c4a9422957ea96283c72d8f\", \"nacl_loader_unittests\": \"33df24eac93536990259b1e2ffa348def45ec4ea\", \"native_theme_unittests\": \"2a7af5e6d1dd3ee469b9986c1bec39f547426650\", \"net_unittests\": \"71d2f36e8a05b6b21977d15906ef0215798b0183\", \"notification_helper_unittests\": \"5104f6545360afbffc53e1c5ef2ee7482ee9f17f\", \"pdf_unittests\": \"7aa41c5d8133adb58edb9908c34c346e90418362\", \"perfetto_unittests\": \"bbf62db426e80c489d947a714080586f4c90ba03\", \"ppapi_unittests\": \"1425ba9a750328fc58fc284a31f5ff1d5403684b\", \"printing_unittests\": \"92ae3d0e82ace6b123fd7487432cd049c8dd926b\", \"remoting_unittests\": \"377d0ca799cddc9fe1f5e29a78684df1d454342f\", \"sbox_integration_tests\": \"b00196b28d6eafe348f7eb3795679a2ea9d30a64\", \"sbox_unittests\": \"c2d0071635424149d90c3467400785a4f21ff706\", \"sbox_validation_tests\": \"622bfbd0dee5c98384a60534423cc09369ab6e7b\", \"service_manager_unittests\": \"1062fa1e87e8ddb03b237be27fcdf1b5ff9b481b\", \"services_unittests\": \"15d4b81bbb19933f6253395d6616e48715f27281\", \"setup_unittests\": \"7e01d81a065dcf1d0b06e81481c15476f8c14325\", \"shell_dialogs_unittests\": \"2677ff9d51fc5b2b9e66035aaa194bbcff34800f\", \"skia_unittests\": \"6eb3c9c880240cad40b71006843335fe5d431fab\", \"snapshot_unittests\": \"c8bccdc57c58a5dffd8a1c11f15b47343886c3e3\", \"sql_unittests\": \"9f15cf8fa2a57f18f6e067dddb854e35615db3d9\", \"storage_unittests\": \"1c61874b7221031b2ebaf509a815de1d73792e31\", \"sync_integration_tests\": \"37398ef0dd65b1987c9add488210906ce65293e2\", \"traffic_annotation_auditor_unittests\": \"ee01272ffe572b9432f9259dc8fec0005b2dd5df\", \"ui_base_unittests\": \"c669d32a467f234a5ddd5babe9c07cd3ddea0ab0\", \"ui_touch_selection_unittests\": \"4c0dd58bdc548966d14828c5bbadcd946fa927ee\", \"unit_tests\": \"59ab86475006eeb3af5821880e141c4409a24a48\", \"updater_tests\": \"60957205d054b9b17dfd313eafcc0ebc54d201d0\", \"url_unittests\": \"c0c09b319abddc5ff845080a62c1f8304050a4cf\", \"views_unittests\": \"9c5fdf6a8ffe0d198af00965136a198e22982316\", \"viz_unittests\": \"e5dd7b6060b266eba18d88f372e5272b093c1c85\", \"vr_common_unittests\": \"98195ba4f04088295608d36af130b2db7fff79f8\", \"vr_pixeltests\": \"6d75bb8c07f32c3e17c9e7718a1d1c764e399f99\", \"weblayer_browsertests\": \"e68f6e38f002e107b6f82c21bc630f03617d0328\", \"weblayer_unittests\": \"a004bfe2b73113e879183c31c7702d78cc0db9dd\", \"wm_unittests\": \"f8597cb8cfa0e3c6c3f7618229d93368773a170e\", \"wtf_unittests\": \"1aed3226d501ad3f952a572b375cd3a6219c5324\", \"zlib_unittests\": \"30b2096ce7d0b26d30c9d46741f721f877adef93\", \"zucchini_unittests\": \"546922e4c73ece19800bb6665355a0d0d73ee13a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-6-xj00\"], \"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\", \"2.7\", \"2.7.13\"], \"server_version\": [\"5776-cb9e157\"], \"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": "1616097414831677",
"status": "COMPLETED",
"status_changed_ts": "1616104019680853",
"tags": [
"build_address:luci.chromium.ci/win-asan/17857",
"builder:win-asan",
"buildset:commit/git/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8235a853755047c6ea7c4f16b925bf4bc8d81d86",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017823001589155632",
"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/8852392939455738912/+/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:525fb870fd47c710",
"user_agent:luci-scheduler"
],
"updated_ts": "1616104019685482",
"url": "https://ci.chromium.org/b/8852392939455738912",
"utcnow_ts": "1616104020859184"
}
}
[21:47:00.880] Invocation finished in 1h53m5.186839633s with status SUCCEEDED