[11:23:58.320] New invocation is queued and will start shortly
[11:23:59.384] Starting the invocation (attempt 1)
[11:23:59.414] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:23:59.414] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:23:59.414] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020482397303351856",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8e25fb4589580c20961a243b9521f5691960721e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020482397303351856",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8e25fb4589580c20961a243b9521f5691960721e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8e25fb4589580c20961a243b9521f5691960721e",
"gitiles_ref:refs/heads/master"
]
}
[11:24:01.049] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613561039583320",
"id": "8855052335316595232",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8e25fb4589580c20961a243b9521f5691960721e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613561040863955",
"tags": [
"build_address:luci.chromium.ci/win-asan/17391",
"builder:win-asan",
"buildset:commit/git/8e25fb4589580c20961a243b9521f5691960721e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8e25fb4589580c20961a243b9521f5691960721e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020482397303351856",
"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/8855052335316595232/+/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": "1613561040864110",
"url": "https://ci.chromium.org/b/8855052335316595232",
"utcnow_ts": "1613561041041902"
}
}
[11:24:01.049] Task URL: https://ci.chromium.org/b/8855052335316595232
[11:24:01.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:2:0) after 1m0s
[11:25:01.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:2:0)
[11:25:01.043] Timer tick, asking Buildbucket for the build status
[11:25:01.143] Build 8855052335316595232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:25:01.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:3:0) after 1m0s
[11:26:01.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:3:0)
[11:26:01.128] Timer tick, asking Buildbucket for the build status
[11:26:01.273] Build 8855052335316595232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:26:01.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:4:0) after 1m0s
[11:26:58.543] Received PubSub notification, asking Buildbucket for the build status
[11:26:58.631] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:01.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:4:0)
[11:27:01.314] Timer tick, asking Buildbucket for the build status
[11:27:01.678] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:01.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:6:0) after 1m0s
[11:28:01.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:6:0)
[11:28:01.693] Timer tick, asking Buildbucket for the build status
[11:28:01.829] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:01.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:7:0) after 1m0s
[11:29:01.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:7:0)
[11:29:01.866] Timer tick, asking Buildbucket for the build status
[11:29:02.013] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:02.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:8:0) after 1m0s
[11:30:02.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:8:0)
[11:30:02.081] Timer tick, asking Buildbucket for the build status
[11:30:02.202] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:02.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:9:0) after 1m0s
[11:31:02.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:9:0)
[11:31:02.216] Timer tick, asking Buildbucket for the build status
[11:31:02.301] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:02.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:10:0) after 1m0s
[11:32:02.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:10:0)
[11:32:02.304] Timer tick, asking Buildbucket for the build status
[11:32:02.404] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:02.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:11:0) after 1m0s
[11:33:02.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:11:0)
[11:33:02.465] Timer tick, asking Buildbucket for the build status
[11:33:02.552] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:02.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:12:0) after 1m0s
[11:34:02.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:12:0)
[11:34:02.585] Timer tick, asking Buildbucket for the build status
[11:34:02.686] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:02.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:13:0) after 1m0s
[11:35:02.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:13:0)
[11:35:02.690] Timer tick, asking Buildbucket for the build status
[11:35:02.845] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:02.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:14:0) after 1m0s
[11:36:02.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:14:0)
[11:36:02.865] Timer tick, asking Buildbucket for the build status
[11:36:03.109] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:03.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:15:0) after 1m0s
[11:37:03.259] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:15:0)
[11:37:03.259] Timer tick, asking Buildbucket for the build status
[11:37:03.352] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:03.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:16:0) after 1m0s
[11:38:03.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:16:0)
[11:38:03.338] Timer tick, asking Buildbucket for the build status
[11:38:03.419] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:03.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:17:0) after 1m0s
[11:39:03.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:17:0)
[11:39:03.518] Timer tick, asking Buildbucket for the build status
[11:39:03.733] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:03.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:18:0) after 1m0s
[11:40:03.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:18:0)
[11:40:03.743] Timer tick, asking Buildbucket for the build status
[11:40:03.871] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:03.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:19:0) after 1m0s
[11:41:03.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:19:0)
[11:41:03.907] Timer tick, asking Buildbucket for the build status
[11:41:03.999] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:03.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:20:0) after 1m0s
[11:42:04.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:20:0)
[11:42:04.051] Timer tick, asking Buildbucket for the build status
[11:42:04.188] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:04.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:21:0) after 1m0s
[11:43:04.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:21:0)
[11:43:04.323] Timer tick, asking Buildbucket for the build status
[11:43:04.557] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:04.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:22:0) after 1m0s
[11:44:04.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:22:0)
[11:44:04.575] Timer tick, asking Buildbucket for the build status
[11:44:04.726] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:04.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:23:0) after 1m0s
[11:45:04.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:23:0)
[11:45:04.790] Timer tick, asking Buildbucket for the build status
[11:45:05.031] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:05.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:24:0) after 1m0s
[11:46:05.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:24:0)
[11:46:05.202] Timer tick, asking Buildbucket for the build status
[11:46:05.331] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:05.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:25:0) after 1m0s
[11:47:05.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:25:0)
[11:47:05.454] Timer tick, asking Buildbucket for the build status
[11:47:05.532] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:05.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:26:0) after 1m0s
[11:48:05.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:26:0)
[11:48:05.625] Timer tick, asking Buildbucket for the build status
[11:48:05.721] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:05.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:27:0) after 1m0s
[11:49:05.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:27:0)
[11:49:05.817] Timer tick, asking Buildbucket for the build status
[11:49:05.903] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:05.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:28:0) after 1m0s
[11:50:06.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:28:0)
[11:50:06.000] Timer tick, asking Buildbucket for the build status
[11:50:06.098] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:06.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:29:0) after 1m0s
[11:51:06.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:29:0)
[11:51:06.126] Timer tick, asking Buildbucket for the build status
[11:51:06.365] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:06.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:30:0) after 1m0s
[11:52:06.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:30:0)
[11:52:06.382] Timer tick, asking Buildbucket for the build status
[11:52:06.463] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:06.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:31:0) after 1m0s
[11:53:06.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:31:0)
[11:53:06.582] Timer tick, asking Buildbucket for the build status
[11:53:06.674] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:06.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:32:0) after 1m0s
[11:54:06.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:32:0)
[11:54:06.716] Timer tick, asking Buildbucket for the build status
[11:54:07.006] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:07.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:33:0) after 1m0s
[11:55:07.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:33:0)
[11:55:07.691] Timer tick, asking Buildbucket for the build status
[11:55:08.018] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:08.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:34:0) after 1m0s
[11:56:09.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:34:0)
[11:56:09.343] Timer tick, asking Buildbucket for the build status
[11:56:09.430] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:09.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:35:0) after 1m0s
[11:57:09.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:35:0)
[11:57:09.439] Timer tick, asking Buildbucket for the build status
[11:57:09.737] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:09.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:36:0) after 1m0s
[11:58:09.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:36:0)
[11:58:09.814] Timer tick, asking Buildbucket for the build status
[11:58:09.895] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:09.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:37:0) after 1m0s
[11:59:09.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:37:0)
[11:59:09.964] Timer tick, asking Buildbucket for the build status
[11:59:10.044] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:10.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:38:0) after 1m0s
[12:00:10.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:38:0)
[12:00:10.054] Timer tick, asking Buildbucket for the build status
[12:00:10.423] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:10.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:39:0) after 1m0s
[12:01:10.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:39:0)
[12:01:10.529] Timer tick, asking Buildbucket for the build status
[12:01:10.628] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:10.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:40:0) after 1m0s
[12:02:11.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:40:0)
[12:02:11.880] Timer tick, asking Buildbucket for the build status
[12:02:12.018] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:12.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:41:0) after 1m0s
[12:03:12.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:41:0)
[12:03:12.255] Timer tick, asking Buildbucket for the build status
[12:03:12.358] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:12.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:42:0) after 1m0s
[12:04:12.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:42:0)
[12:04:12.510] Timer tick, asking Buildbucket for the build status
[12:04:12.612] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:12.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:43:0) after 1m0s
[12:05:12.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:43:0)
[12:05:12.714] Timer tick, asking Buildbucket for the build status
[12:05:12.841] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:12.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:44:0) after 1m0s
[12:06:13.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:44:0)
[12:06:13.060] Timer tick, asking Buildbucket for the build status
[12:06:13.195] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:13.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:45:0) after 1m0s
[12:07:13.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:45:0)
[12:07:13.321] Timer tick, asking Buildbucket for the build status
[12:07:13.398] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:13.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:46:0) after 1m0s
[12:08:13.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:46:0)
[12:08:13.435] Timer tick, asking Buildbucket for the build status
[12:08:13.520] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:13.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:47:0) after 1m0s
[12:09:13.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:47:0)
[12:09:13.548] Timer tick, asking Buildbucket for the build status
[12:09:13.620] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:13.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:48:0) after 1m0s
[12:10:13.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:48:0)
[12:10:13.704] Timer tick, asking Buildbucket for the build status
[12:10:14.092] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:14.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:49:0) after 1m0s
[12:11:14.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:49:0)
[12:11:14.137] Timer tick, asking Buildbucket for the build status
[12:11:14.249] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:14.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:50:0) after 1m0s
[12:12:14.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:50:0)
[12:12:14.273] Timer tick, asking Buildbucket for the build status
[12:12:14.393] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:14.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:51:0) after 1m0s
[12:13:14.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:51:0)
[12:13:14.440] Timer tick, asking Buildbucket for the build status
[12:13:14.546] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:14.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:52:0) after 1m0s
[12:14:14.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:52:0)
[12:14:14.580] Timer tick, asking Buildbucket for the build status
[12:14:14.650] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:14.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:53:0) after 1m0s
[12:15:14.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:53:0)
[12:15:14.672] Timer tick, asking Buildbucket for the build status
[12:15:14.748] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:14.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:54:0) after 1m0s
[12:16:14.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:54:0)
[12:16:14.821] Timer tick, asking Buildbucket for the build status
[12:16:14.900] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:14.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:55:0) after 1m0s
[12:17:14.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:55:0)
[12:17:14.969] Timer tick, asking Buildbucket for the build status
[12:17:15.194] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:15.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:56:0) after 1m0s
[12:18:15.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:56:0)
[12:18:15.241] Timer tick, asking Buildbucket for the build status
[12:18:15.406] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:15.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:57:0) after 1m0s
[12:19:15.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:57:0)
[12:19:15.447] Timer tick, asking Buildbucket for the build status
[12:19:15.524] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:15.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:58:0) after 1m0s
[12:20:15.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:58:0)
[12:20:15.569] Timer tick, asking Buildbucket for the build status
[12:20:15.884] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:15.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:59:0) after 1m0s
[12:21:15.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:59:0)
[12:21:15.951] Timer tick, asking Buildbucket for the build status
[12:21:16.045] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:16.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:60:0) after 1m0s
[12:22:16.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:60:0)
[12:22:16.071] Timer tick, asking Buildbucket for the build status
[12:22:16.148] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:16.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:61:0) after 1m0s
[12:23:16.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:61:0)
[12:23:16.212] Timer tick, asking Buildbucket for the build status
[12:23:16.304] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:16.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:62:0) after 1m0s
[12:24:16.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:62:0)
[12:24:16.366] Timer tick, asking Buildbucket for the build status
[12:24:16.446] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:16.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:63:0) after 1m0s
[12:25:16.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:63:0)
[12:25:16.493] Timer tick, asking Buildbucket for the build status
[12:25:16.586] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:16.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:64:0) after 1m0s
[12:26:16.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:64:0)
[12:26:16.603] Timer tick, asking Buildbucket for the build status
[12:26:16.720] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:16.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:65:0) after 1m0s
[12:27:16.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:65:0)
[12:27:16.878] Timer tick, asking Buildbucket for the build status
[12:27:16.977] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:16.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:66:0) after 1m0s
[12:28:16.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:66:0)
[12:28:16.997] Timer tick, asking Buildbucket for the build status
[12:28:17.173] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:17.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:67:0) after 1m0s
[12:29:17.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:67:0)
[12:29:17.168] Timer tick, asking Buildbucket for the build status
[12:29:17.332] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:17.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:68:0) after 1m0s
[12:30:17.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:68:0)
[12:30:17.354] Timer tick, asking Buildbucket for the build status
[12:30:17.604] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:17.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:69:0) after 1m0s
[12:31:17.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:69:0)
[12:31:17.631] Timer tick, asking Buildbucket for the build status
[12:31:17.816] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:17.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:70:0) after 1m0s
[12:32:17.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:70:0)
[12:32:17.839] Timer tick, asking Buildbucket for the build status
[12:32:18.231] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:18.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:71:0) after 1m0s
[12:33:18.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:71:0)
[12:33:18.323] Timer tick, asking Buildbucket for the build status
[12:33:18.455] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:18.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:72:0) after 1m0s
[12:34:18.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:72:0)
[12:34:18.508] Timer tick, asking Buildbucket for the build status
[12:34:18.893] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:18.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:73:0) after 1m0s
[12:35:18.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:73:0)
[12:35:18.911] Timer tick, asking Buildbucket for the build status
[12:35:19.068] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:19.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:74:0) after 1m0s
[12:36:19.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:74:0)
[12:36:19.299] Timer tick, asking Buildbucket for the build status
[12:36:19.390] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:19.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:75:0) after 1m0s
[12:37:19.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:75:0)
[12:37:19.587] Timer tick, asking Buildbucket for the build status
[12:37:19.861] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:19.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:76:0) after 1m0s
[12:38:19.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:76:0)
[12:38:19.987] Timer tick, asking Buildbucket for the build status
[12:38:20.135] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:20.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:77:0) after 1m0s
[12:39:20.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:77:0)
[12:39:20.216] Timer tick, asking Buildbucket for the build status
[12:39:20.361] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:20.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:78:0) after 1m0s
[12:40:20.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:78:0)
[12:40:20.476] Timer tick, asking Buildbucket for the build status
[12:40:20.609] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:20.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:79:0) after 1m0s
[12:41:20.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:79:0)
[12:41:20.730] Timer tick, asking Buildbucket for the build status
[12:41:20.821] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:20.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:80:0) after 1m0s
[12:42:20.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:80:0)
[12:42:20.870] Timer tick, asking Buildbucket for the build status
[12:42:20.970] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:20.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:81:0) after 1m0s
[12:43:21.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:81:0)
[12:43:21.023] Timer tick, asking Buildbucket for the build status
[12:43:21.338] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:21.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:82:0) after 1m0s
[12:44:22.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:82:0)
[12:44:22.512] Timer tick, asking Buildbucket for the build status
[12:44:22.749] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:22.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:83:0) after 1m0s
[12:45:22.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:83:0)
[12:45:22.985] Timer tick, asking Buildbucket for the build status
[12:45:23.139] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:23.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:84:0) after 1m0s
[12:46:23.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:84:0)
[12:46:23.261] Timer tick, asking Buildbucket for the build status
[12:46:23.796] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:23.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:85:0) after 1m0s
[12:47:23.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:85:0)
[12:47:23.882] Timer tick, asking Buildbucket for the build status
[12:47:23.989] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:23.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:86:0) after 1m0s
[12:48:23.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:86:0)
[12:48:23.998] Timer tick, asking Buildbucket for the build status
[12:48:24.209] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:24.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:87:0) after 1m0s
[12:49:24.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:87:0)
[12:49:24.261] Timer tick, asking Buildbucket for the build status
[12:49:24.356] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:24.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:88:0) after 1m0s
[12:50:24.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:88:0)
[12:50:24.390] Timer tick, asking Buildbucket for the build status
[12:50:24.564] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:24.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:89:0) after 1m0s
[12:51:24.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:89:0)
[12:51:24.598] Timer tick, asking Buildbucket for the build status
[12:51:26.003] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:26.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:90:0) after 1m0s
[12:52:26.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:90:0)
[12:52:26.011] Timer tick, asking Buildbucket for the build status
[12:52:26.109] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:26.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:91:0) after 1m0s
[12:53:26.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:91:0)
[12:53:26.419] Timer tick, asking Buildbucket for the build status
[12:53:26.519] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:26.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:92:0) after 1m0s
[12:54:26.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:92:0)
[12:54:26.649] Timer tick, asking Buildbucket for the build status
[12:54:27.120] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:27.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:93:0) after 1m0s
[12:55:27.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:93:0)
[12:55:27.162] Timer tick, asking Buildbucket for the build status
[12:55:27.350] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:27.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:94:0) after 1m0s
[12:56:27.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:94:0)
[12:56:27.401] Timer tick, asking Buildbucket for the build status
[12:56:27.527] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:27.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:95:0) after 1m0s
[12:57:27.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:95:0)
[12:57:27.619] Timer tick, asking Buildbucket for the build status
[12:57:27.754] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:27.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:96:0) after 1m0s
[12:58:27.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:96:0)
[12:58:27.798] Timer tick, asking Buildbucket for the build status
[12:58:28.153] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:28.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:97:0) after 1m0s
[12:59:28.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:97:0)
[12:59:28.254] Timer tick, asking Buildbucket for the build status
[12:59:28.424] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:28.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:98:0) after 1m0s
[13:00:28.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:98:0)
[13:00:28.447] Timer tick, asking Buildbucket for the build status
[13:00:28.722] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:28.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:99:0) after 1m0s
[13:01:28.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:99:0)
[13:01:28.774] Timer tick, asking Buildbucket for the build status
[13:01:29.876] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:29.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:100:0) after 1m0s
[13:02:29.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:100:0)
[13:02:29.915] Timer tick, asking Buildbucket for the build status
[13:02:30.399] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:30.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:101:0) after 1m0s
[13:03:30.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:101:0)
[13:03:30.490] Timer tick, asking Buildbucket for the build status
[13:03:30.586] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:30.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:102:0) after 1m0s
[13:04:30.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:102:0)
[13:04:30.615] Timer tick, asking Buildbucket for the build status
[13:04:30.709] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:30.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:103:0) after 1m0s
[13:05:30.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:103:0)
[13:05:30.724] Timer tick, asking Buildbucket for the build status
[13:05:30.874] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:30.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:104:0) after 1m0s
[13:06:30.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:104:0)
[13:06:30.856] Timer tick, asking Buildbucket for the build status
[13:06:30.954] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:30.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:105:0) after 1m0s
[13:07:31.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:105:0)
[13:07:31.019] Timer tick, asking Buildbucket for the build status
[13:07:31.123] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:31.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:106:0) after 1m0s
[13:08:31.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:106:0)
[13:08:31.176] Timer tick, asking Buildbucket for the build status
[13:08:31.335] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:31.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:107:0) after 1m0s
[13:09:31.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:107:0)
[13:09:31.388] Timer tick, asking Buildbucket for the build status
[13:09:31.780] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:31.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:108:0) after 1m0s
[13:10:31.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:108:0)
[13:10:31.833] Timer tick, asking Buildbucket for the build status
[13:10:32.552] Build 8855052335316595232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:32.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020482397303351856:109:0) after 1m0s
[13:11:20.085] Received PubSub notification, asking Buildbucket for the build status
[13:11:20.527] Build 8855052335316595232: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:11:20.528] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613567479441232",
"created_by": "project:chromium",
"created_ts": "1613561039583320",
"id": "8855052335316595232",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8e25fb4589580c20961a243b9521f5691960721e\"}}",
"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\": \"8e25fb4589580c20961a243b9521f5691960721e\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-0-6l07\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17391, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"3c28b2a0b4813f94730b2e8ca29d490bfb5d4c41\", \"got_dawn_revision\": \"a5ba2827f5810b7b1cd6ee046e8df6e109bb02b6\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"8e25fb4589580c20961a243b9521f5691960721e\", \"got_revision_cp\": \"refs/heads/master@{#854724}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"bb04803b8daf08e186b327d18e14c78378f44e37\", \"got_v8_revision\": \"972f21b5ed71a418e8ed86f3b39bb7c2b7f175ac\", \"got_v8_revision_cp\": \"refs/heads/9.0.197@{#1}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"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\": \"8e25fb4589580c20961a243b9521f5691960721e\", \"swarm_hashes_refs/heads/master(at){#854724}_without_patch\": {\"absl_hardening_tests\": \"fe9ef3e17841aad3b481db582f28ed93ba5a0864\", \"accessibility_unittests\": \"a5efa5a4f0b347e7031241ad091ad6afa40238d8\", \"angle_unittests\": \"7b2fcd8d34153572f753edb7e9e131b7f9a96efe\", \"app_shell_unittests\": \"25127532ed5fff60657b5e51cdd0482eabe0a79e\", \"aura_unittests\": \"d5e8581135a579f830741964ec54628c990b4fa4\", \"base_unittests\": \"7050ed91bb6dbeb8daf68ffe91cf2be690b9c021\", \"base_util_unittests\": \"7f5a5825e56f20c211a1bff9629acd83f5a46931\", \"blink_common_unittests\": \"ad91e0055676983c447fc6d40de98d58c329dc59\", \"blink_fuzzer_unittests\": \"4801b0abb8352abf3d6a61e9c234cc07dd7725ab\", \"blink_heap_unittests\": \"73d79c4f989859a934bd64767b5a9c280e768d68\", \"blink_platform_unittests\": \"4ead8bcd2e94b55a5f803c6381b9e34320cbb27d\", \"blink_unittests\": \"ec5431c818370c6ab9474a94e223100a58e2036b\", \"boringssl_crypto_tests\": \"c7a407ed5f23ed9871379f2af284e43d35801d53\", \"boringssl_ssl_tests\": \"223af59be7d667ce7b0163ec23b7815b311da7b8\", \"browser_switcher_bho_unittests\": \"5ae2375d8eb6758749d8d24b6349d244fdaea22c\", \"browser_tests\": \"394084135819012e43094a9b999a9c52f6f35f99\", \"capture_unittests\": \"098d494186115bd56839e59757cbceeb39b3ae4b\", \"cast_unittests\": \"a1a3340984e33df9f458bae3c561e7bc38c31daa\", \"cc_unittests\": \"d700a8b35849780644dd63b276add203c0e1ad7f\", \"chrome_app_unittests\": \"52df5e595ec2389b45610da6499893fba074956c\", \"chrome_cleaner_unittests\": \"bc58dec2d6d46c6bf1adc60f6a982bea2f658d88\", \"chrome_elf_unittests\": \"b016a8686fea95a1661352a5e8739006a319cf53\", \"chromedriver_unittests\": \"9dc924b83632b93fe6837901280e3de2bcac8bd7\", \"components_browsertests\": \"2da8c6fa25af86b99b9a63ec21867e13628ae47d\", \"components_unittests\": \"377c5614b4d38b33fe0b9e722eb1f4af62d7ee2e\", \"compositor_unittests\": \"f7cd4948b1e3c7c7637837b8c3e69e9862154242\", \"content_browsertests\": \"addced075680ac1b0fd1ff17d6cf369ecfc5404b\", \"content_unittests\": \"954808cfbfb3e4c8dbbdf146f273d26efd164ae4\", \"courgette_unittests\": \"03f42732ff1556d3b842f42ea23e02a237084fb5\", \"crashpad_tests\": \"a22554ec6dd2e0c450ca48e5c539916a35a9fa27\", \"cronet_tests\": \"79b75c698e0c28209cf9a80859e38f926afd40d4\", \"cronet_unittests\": \"e42ee9eee0234f20334da262d07048fd2e3c6aa8\", \"crypto_unittests\": \"1199b89d0550902bf138030ae812b33c0f82e13b\", \"delayloads_unittests\": \"6054616a051ece0faf229fc0da46f03a0a5add1d\", \"device_unittests\": \"54c9e19fd74abafaf630bf0650060f1f1e59697e\", \"display_unittests\": \"c76a2b49a66ef37b4c206def5a9e5132e2b73e55\", \"elevation_service_unittests\": \"1dce7208671ad9136d3bd136560f8cb7114c2656\", \"events_unittests\": \"875c73b673904bb5cde56d4f7173c2dc5d7c9ab7\", \"extensions_browsertests\": \"9d746fb70e0eac0809e4eca58992db35f4c7ef4a\", \"extensions_unittests\": \"2344ee4d38b9fdc75c8f533e59e59823f2577c20\", \"filesystem_service_unittests\": \"26b7505c91ac508110283769e63bf6da9b5b14cd\", \"gcm_unit_tests\": \"3a24beea2802ee2cdcc443844dc9f4165d21b91e\", \"gcp_unittests\": \"4905b2086d772fe78784ce4bdc27fd7bdf473fa0\", \"gfx_unittests\": \"d4a97c65852b8d3defc42de961285510eb9fcc8d\", \"gin_unittests\": \"002f5c16dc78b79f266e79a7e6b643c6ab9bf820\", \"google_apis_unittests\": \"21da77551cb9f750ee68d88f15f6e7fea37af848\", \"gpu_unittests\": \"c46a40cfcbdcc6af4f353c8ab27b9b2c4d75c227\", \"gwp_asan_unittests\": \"6a0919c636a60575e4f41f8eb962a46c547c0197\", \"headless_browsertests\": \"c2511afd140a910ce5bb399aa60bf819380b0e44\", \"headless_unittests\": \"20a6011a0fd9ef130890dd1e72ccf352ec95fb2d\", \"install_static_unittests\": \"71fff2857b80403247ce821788d6dde9525ca8d2\", \"installer_util_unittests\": \"1e79c063d6759afab5356536e0e79fadafcf77ca\", \"interactive_ui_tests\": \"a5a9f6499a684c88ea26f5f23564850be6633a68\", \"ipc_tests\": \"c58c5a0e499eff0e28b9d3f5d451af0236b3d268\", \"jingle_unittests\": \"2d6658a1c0ab43967f445e7bcd5daa5b80c9d9ea\", \"latency_unittests\": \"df69335b417c72984352f7f6395e6474563fad5c\", \"libjingle_xmpp_unittests\": \"9d201994e647987189737798b58de6cdc344bb93\", \"liburlpattern_unittests\": \"46f825e523ee1cded72e6bae1b1edc8d16dc49eb\", \"media_blink_unittests\": \"27fc0c7ea42d1eaf3e26b4b91746443dd4f9de16\", \"media_unittests\": \"ef60cd6f95ba7bd70383201150cca542fe4baa14\", \"message_center_unittests\": \"94ef1eb9ce97806191b0c47e7c9aa20536a092a0\", \"midi_unittests\": \"bcc9583a7f3a712fe2c306e3bca3017274b88bfe\", \"mojo_core_unittests\": \"db1bdfcf32b7a99cbbd1ea16d901174a8ec27393\", \"mojo_unittests\": \"9cabe7fa4bc029f25f85993ca6068548839fc093\", \"nacl_loader_unittests\": \"92e0e4b419344f5bf043aa8cd8207d6eda8e86cb\", \"native_theme_unittests\": \"dcfd77b209cff164b377851cf0be6075556794b6\", \"net_unittests\": \"e10ac41f909b955d9217b427c3614dc0f3af4c84\", \"notification_helper_unittests\": \"d2c905996dfa48ad1476d9dbcc4ede4229f55d80\", \"pdf_unittests\": \"1ec0c59d429b62c444a59b2b569edb68f1215b85\", \"perfetto_unittests\": \"9dcac98b5666b049bd2098d62e5dce86fd4ee2e3\", \"ppapi_unittests\": \"43f9e896f4c4b18f942bd2531a3eacf52cfdc332\", \"printing_unittests\": \"ceb3a4f849888ff773da8044e217b6b0c6f7ff0c\", \"remoting_unittests\": \"d4e539269ce55be794139f2d0b02d4dec96bae55\", \"sbox_integration_tests\": \"1731fb78d9e4bef292064b5ce4c40c6c3cc067a6\", \"sbox_unittests\": \"3a65a85f300cbcf6c838bb2f346f8bf581b43606\", \"sbox_validation_tests\": \"9fa43bea7580563460944868c759679e8a1b20f5\", \"service_manager_unittests\": \"1efe3e27020776cfe1781f5bba32605eacbec811\", \"services_unittests\": \"ee1df94d9334fcb19c7306b5c1ca1a2afb94fc05\", \"setup_unittests\": \"e780e935f99eedeb1d8df196fb702d5d630a375f\", \"shell_dialogs_unittests\": \"32142e6df4a62bb7c8a2f5afb27308b4d395f389\", \"skia_unittests\": \"529ff69497c8db43ab6be03e366b7e031bde30cd\", \"snapshot_unittests\": \"cacb578fa150a13cd8300b03d634447e245e209d\", \"sql_unittests\": \"4fab75230e0c5461f1e544e480b98e3ca40be059\", \"storage_unittests\": \"89d3c9c933402eba3edbc78fa41443acd55a786b\", \"sync_integration_tests\": \"d8382a73a24cd92e849d360f9d0b7bda9cce1443\", \"traffic_annotation_auditor_unittests\": \"eb1e72e6d2b242cce5bd0077434a4fa641373923\", \"ui_base_unittests\": \"075b615f979fa5d91ebf52d95be063935f1613cf\", \"ui_touch_selection_unittests\": \"f2e0da6e1988df3afb6b9b714ec8452e209a631d\", \"unit_tests\": \"47b76ff13299ad5224ef8586a60a2c6748b7c5c1\", \"updater_tests\": \"b635c5743879b2e1f1b05ce83cb847ac59bf9f0f\", \"url_unittests\": \"5155f6a9411fe4808ecd927a8be3ff2e9b54372a\", \"views_unittests\": \"97fea128eb6bcfdf26cff8a7714a629e9af588d6\", \"viz_unittests\": \"ec221c15f7524ee4c53f5993d74469c44f46f103\", \"vr_common_unittests\": \"8bc8ca8c8bf8f62fc6dd153cc718ce57a605a63c\", \"vr_pixeltests\": \"5b7316464f79afbf9459a76b74536eea70b9060d\", \"weblayer_browsertests\": \"4152c69bc5399afa94239406420580e619433382\", \"weblayer_unittests\": \"76705b996747b60476213b819659b7f020b33ee5\", \"wm_unittests\": \"b89344e01c96e191648b1e000fe940e752abf34a\", \"wtf_unittests\": \"aa99e9359bc38af36c4fd60797b28553ac381104\", \"zlib_unittests\": \"3f112b6deef78f5129d8b77b31f0b10093869875\", \"zucchini_unittests\": \"90a43ed6e724e13365704dd28382b2f0b3c4d6f8\"}}, \"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-0-6l07\"], \"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": "1613561217689318",
"status": "COMPLETED",
"status_changed_ts": "1613567479690553",
"tags": [
"build_address:luci.chromium.ci/win-asan/17391",
"builder:win-asan",
"buildset:commit/git/8e25fb4589580c20961a243b9521f5691960721e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8e25fb4589580c20961a243b9521f5691960721e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020482397303351856",
"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/8855052335316595232/+/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:51c88d1a5ea6f410",
"user_agent:luci-scheduler"
],
"updated_ts": "1613567479694976",
"url": "https://ci.chromium.org/b/8855052335316595232",
"utcnow_ts": "1613567480509174"
}
}
[13:11:20.530] Invocation finished in 1h47m22.224390135s with status SUCCEEDED