[09:29:46.919] New invocation is queued and will start shortly
[09:29:48.142] Starting the invocation (attempt 1)
[09:29:48.182] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:29:48.182] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:29:48.182] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022482714806458848",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cd05d63af2628400241ee1347390dff95445aa36\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022482714806458848",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/cd05d63af2628400241ee1347390dff95445aa36",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cd05d63af2628400241ee1347390dff95445aa36",
"gitiles_ref:refs/heads/master"
]
}
[09:29:48.948] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611653388344639",
"id": "8857052652621664592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cd05d63af2628400241ee1347390dff95445aa36\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611653388733791",
"tags": [
"build_address:luci.chromium.ci/win-asan/17017",
"builder:win-asan",
"buildset:commit/git/cd05d63af2628400241ee1347390dff95445aa36",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cd05d63af2628400241ee1347390dff95445aa36",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022482714806458848",
"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/8857052652621664592/+/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": "1611653388733938",
"url": "https://ci.chromium.org/b/8857052652621664592",
"utcnow_ts": "1611653388938072"
}
}
[09:29:48.948] Task URL: https://ci.chromium.org/b/8857052652621664592
[09:29:48.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:2:0) after 1m0s
[09:30:48.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:2:0)
[09:30:48.997] Timer tick, asking Buildbucket for the build status
[09:30:49.477] Build 8857052652621664592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:30:49.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:3:0) after 1m0s
[09:31:49.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:3:0)
[09:31:49.721] Timer tick, asking Buildbucket for the build status
[09:31:49.795] Build 8857052652621664592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:31:49.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:4:0) after 1m0s
[09:32:50.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:4:0)
[09:32:50.046] Timer tick, asking Buildbucket for the build status
[09:32:50.167] Build 8857052652621664592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:50.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:5:0) after 1m0s
[09:33:50.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:5:0)
[09:33:50.353] Timer tick, asking Buildbucket for the build status
[09:33:50.442] Build 8857052652621664592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:33:50.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:6:0) after 1m0s
[09:34:27.193] Received PubSub notification, asking Buildbucket for the build status
[09:34:27.307] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:50.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:6:0)
[09:34:50.628] Timer tick, asking Buildbucket for the build status
[09:34:50.750] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:50.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:8:0) after 1m0s
[09:35:50.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:8:0)
[09:35:50.824] Timer tick, asking Buildbucket for the build status
[09:35:50.962] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:50.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:9:0) after 1m0s
[09:36:51.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:9:0)
[09:36:51.394] Timer tick, asking Buildbucket for the build status
[09:36:51.532] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:51.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:10:0) after 1m0s
[09:37:51.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:10:0)
[09:37:51.705] Timer tick, asking Buildbucket for the build status
[09:37:51.760] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:51.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:11:0) after 1m0s
[09:38:51.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:11:0)
[09:38:51.857] Timer tick, asking Buildbucket for the build status
[09:38:51.918] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:51.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:12:0) after 1m0s
[09:39:52.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:12:0)
[09:39:52.006] Timer tick, asking Buildbucket for the build status
[09:39:52.186] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:52.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:13:0) after 1m0s
[09:40:52.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:13:0)
[09:40:52.279] Timer tick, asking Buildbucket for the build status
[09:40:52.340] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:52.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:14:0) after 1m0s
[09:41:52.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:14:0)
[09:41:52.342] Timer tick, asking Buildbucket for the build status
[09:41:52.400] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:52.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:15:0) after 1m0s
[09:42:52.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:15:0)
[09:42:52.409] Timer tick, asking Buildbucket for the build status
[09:42:52.483] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:52.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:16:0) after 1m0s
[09:43:52.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:16:0)
[09:43:52.548] Timer tick, asking Buildbucket for the build status
[09:43:52.719] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:52.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:17:0) after 1m0s
[09:44:52.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:17:0)
[09:44:52.739] Timer tick, asking Buildbucket for the build status
[09:44:52.901] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:52.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:18:0) after 1m0s
[09:45:52.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:18:0)
[09:45:52.963] Timer tick, asking Buildbucket for the build status
[09:45:53.113] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:53.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:19:0) after 1m0s
[09:46:53.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:19:0)
[09:46:53.188] Timer tick, asking Buildbucket for the build status
[09:46:53.267] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:53.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:20:0) after 1m0s
[09:47:53.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:20:0)
[09:47:53.370] Timer tick, asking Buildbucket for the build status
[09:47:53.479] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:53.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:21:0) after 1m0s
[09:48:53.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:21:0)
[09:48:53.524] Timer tick, asking Buildbucket for the build status
[09:48:53.645] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:53.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:22:0) after 1m0s
[09:49:53.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:22:0)
[09:49:53.702] Timer tick, asking Buildbucket for the build status
[09:49:54.109] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:54.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:23:0) after 1m0s
[09:50:54.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:23:0)
[09:50:54.210] Timer tick, asking Buildbucket for the build status
[09:50:54.287] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:54.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:24:0) after 1m0s
[09:51:54.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:24:0)
[09:51:54.331] Timer tick, asking Buildbucket for the build status
[09:51:54.413] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:54.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:25:0) after 1m0s
[09:52:54.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:25:0)
[09:52:54.388] Timer tick, asking Buildbucket for the build status
[09:52:54.484] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:54.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:26:0) after 1m0s
[09:53:54.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:26:0)
[09:53:54.548] Timer tick, asking Buildbucket for the build status
[09:53:54.649] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:54.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:27:0) after 1m0s
[09:54:54.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:27:0)
[09:54:54.716] Timer tick, asking Buildbucket for the build status
[09:54:55.001] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:55.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:28:0) after 1m0s
[09:55:55.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:28:0)
[09:55:55.186] Timer tick, asking Buildbucket for the build status
[09:55:55.585] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:55.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:29:0) after 1m0s
[09:56:55.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:29:0)
[09:56:55.679] Timer tick, asking Buildbucket for the build status
[09:56:55.847] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:55.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:30:0) after 1m0s
[09:57:55.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:30:0)
[09:57:55.868] Timer tick, asking Buildbucket for the build status
[09:57:56.027] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:56.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:31:0) after 1m0s
[09:58:56.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:31:0)
[09:58:56.012] Timer tick, asking Buildbucket for the build status
[09:58:56.077] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:56.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:32:0) after 1m0s
[09:59:56.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:32:0)
[09:59:56.138] Timer tick, asking Buildbucket for the build status
[09:59:56.267] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:56.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:33:0) after 1m0s
[10:00:56.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:33:0)
[10:00:56.350] Timer tick, asking Buildbucket for the build status
[10:00:57.049] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:57.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:34:0) after 1m0s
[10:01:57.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:34:0)
[10:01:57.233] Timer tick, asking Buildbucket for the build status
[10:01:57.329] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:57.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:35:0) after 1m0s
[10:02:57.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:35:0)
[10:02:57.382] Timer tick, asking Buildbucket for the build status
[10:02:57.539] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:57.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:36:0) after 1m0s
[10:03:57.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:36:0)
[10:03:57.882] Timer tick, asking Buildbucket for the build status
[10:03:57.965] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:57.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:37:0) after 1m0s
[10:04:58.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:37:0)
[10:04:58.015] Timer tick, asking Buildbucket for the build status
[10:04:58.520] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:58.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:38:0) after 1m0s
[10:05:58.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:38:0)
[10:05:58.503] Timer tick, asking Buildbucket for the build status
[10:05:58.580] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:58.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:39:0) after 1m0s
[10:06:58.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:39:0)
[10:06:58.623] Timer tick, asking Buildbucket for the build status
[10:06:58.700] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:58.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:40:0) after 1m0s
[10:07:58.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:40:0)
[10:07:58.892] Timer tick, asking Buildbucket for the build status
[10:07:58.995] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:58.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:41:0) after 1m0s
[10:08:59.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:41:0)
[10:08:59.144] Timer tick, asking Buildbucket for the build status
[10:08:59.281] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:59.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:42:0) after 1m0s
[10:09:59.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:42:0)
[10:09:59.356] Timer tick, asking Buildbucket for the build status
[10:09:59.505] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:59.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:43:0) after 1m0s
[10:10:59.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:43:0)
[10:10:59.569] Timer tick, asking Buildbucket for the build status
[10:10:59.642] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:59.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:44:0) after 1m0s
[10:11:59.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:44:0)
[10:11:59.691] Timer tick, asking Buildbucket for the build status
[10:11:59.748] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:59.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:45:0) after 1m0s
[10:12:59.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:45:0)
[10:12:59.839] Timer tick, asking Buildbucket for the build status
[10:12:59.970] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:59.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:46:0) after 1m0s
[10:14:00.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:46:0)
[10:14:00.005] Timer tick, asking Buildbucket for the build status
[10:14:00.261] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:00.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:47:0) after 1m0s
[10:15:00.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:47:0)
[10:15:00.333] Timer tick, asking Buildbucket for the build status
[10:15:00.553] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:00.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:48:0) after 1m0s
[10:16:00.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:48:0)
[10:16:00.586] Timer tick, asking Buildbucket for the build status
[10:16:00.654] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:00.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:49:0) after 1m0s
[10:17:00.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:49:0)
[10:17:00.769] Timer tick, asking Buildbucket for the build status
[10:17:00.909] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:00.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:50:0) after 1m0s
[10:18:00.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:50:0)
[10:18:00.891] Timer tick, asking Buildbucket for the build status
[10:18:01.197] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:01.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:51:0) after 1m0s
[10:19:01.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:51:0)
[10:19:01.295] Timer tick, asking Buildbucket for the build status
[10:19:01.490] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:01.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:52:0) after 1m0s
[10:20:01.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:52:0)
[10:20:01.534] Timer tick, asking Buildbucket for the build status
[10:20:01.763] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:01.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:53:0) after 1m0s
[10:21:01.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:53:0)
[10:21:01.770] Timer tick, asking Buildbucket for the build status
[10:21:01.829] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:01.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:54:0) after 1m0s
[10:22:01.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:54:0)
[10:22:01.833] Timer tick, asking Buildbucket for the build status
[10:22:01.909] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:01.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:55:0) after 1m0s
[10:23:01.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:55:0)
[10:23:01.915] Timer tick, asking Buildbucket for the build status
[10:23:02.004] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:02.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:56:0) after 1m0s
[10:24:02.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:56:0)
[10:24:02.005] Timer tick, asking Buildbucket for the build status
[10:24:02.085] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:02.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:57:0) after 1m0s
[10:25:02.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:57:0)
[10:25:02.113] Timer tick, asking Buildbucket for the build status
[10:25:02.698] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:02.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:58:0) after 1m0s
[10:26:02.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:58:0)
[10:26:02.785] Timer tick, asking Buildbucket for the build status
[10:26:02.886] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:02.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:59:0) after 1m0s
[10:27:02.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:59:0)
[10:27:02.929] Timer tick, asking Buildbucket for the build status
[10:27:02.997] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:02.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:60:0) after 1m0s
[10:28:03.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:60:0)
[10:28:03.130] Timer tick, asking Buildbucket for the build status
[10:28:03.214] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:03.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:61:0) after 1m0s
[10:29:03.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:61:0)
[10:29:03.305] Timer tick, asking Buildbucket for the build status
[10:29:03.393] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:03.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:62:0) after 1m0s
[10:30:03.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:62:0)
[10:30:03.515] Timer tick, asking Buildbucket for the build status
[10:30:03.708] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:03.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:63:0) after 1m0s
[10:31:03.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:63:0)
[10:31:03.715] Timer tick, asking Buildbucket for the build status
[10:31:04.049] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:04.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:64:0) after 1m0s
[10:32:04.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:64:0)
[10:32:04.109] Timer tick, asking Buildbucket for the build status
[10:32:04.185] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:04.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:65:0) after 1m0s
[10:33:04.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:65:0)
[10:33:04.230] Timer tick, asking Buildbucket for the build status
[10:33:04.499] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:04.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:66:0) after 1m0s
[10:34:04.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:66:0)
[10:34:04.484] Timer tick, asking Buildbucket for the build status
[10:34:04.742] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:04.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:67:0) after 1m0s
[10:35:04.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:67:0)
[10:35:04.816] Timer tick, asking Buildbucket for the build status
[10:35:05.159] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:05.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:68:0) after 1m0s
[10:36:05.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:68:0)
[10:36:05.230] Timer tick, asking Buildbucket for the build status
[10:36:05.459] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:05.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:69:0) after 1m0s
[10:37:05.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:69:0)
[10:37:05.494] Timer tick, asking Buildbucket for the build status
[10:37:05.592] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:05.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:70:0) after 1m0s
[10:38:05.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:70:0)
[10:38:05.682] Timer tick, asking Buildbucket for the build status
[10:38:05.836] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:05.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:71:0) after 1m0s
[10:39:05.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:71:0)
[10:39:05.896] Timer tick, asking Buildbucket for the build status
[10:39:06.532] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:06.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:72:0) after 1m0s
[10:40:06.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:72:0)
[10:40:06.568] Timer tick, asking Buildbucket for the build status
[10:40:06.687] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:06.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:73:0) after 1m0s
[10:41:06.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:73:0)
[10:41:06.782] Timer tick, asking Buildbucket for the build status
[10:41:06.915] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:06.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:74:0) after 1m0s
[10:42:06.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:74:0)
[10:42:06.995] Timer tick, asking Buildbucket for the build status
[10:42:08.297] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:08.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:75:0) after 1m0s
[10:43:08.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:75:0)
[10:43:08.367] Timer tick, asking Buildbucket for the build status
[10:43:08.499] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:08.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:76:0) after 1m0s
[10:44:08.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:76:0)
[10:44:08.598] Timer tick, asking Buildbucket for the build status
[10:44:08.723] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:08.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:77:0) after 1m0s
[10:45:08.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:77:0)
[10:45:08.769] Timer tick, asking Buildbucket for the build status
[10:45:09.059] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:09.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:78:0) after 1m0s
[10:46:09.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:78:0)
[10:46:09.584] Timer tick, asking Buildbucket for the build status
[10:46:10.081] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:10.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:79:0) after 1m0s
[10:47:10.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:79:0)
[10:47:10.483] Timer tick, asking Buildbucket for the build status
[10:47:10.763] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:10.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:80:0) after 1m0s
[10:48:10.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:80:0)
[10:48:10.837] Timer tick, asking Buildbucket for the build status
[10:48:11.041] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:11.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:81:0) after 1m0s
[10:49:11.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:81:0)
[10:49:11.224] Timer tick, asking Buildbucket for the build status
[10:49:11.376] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:11.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:82:0) after 1m0s
[10:50:11.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:82:0)
[10:50:11.429] Timer tick, asking Buildbucket for the build status
[10:50:11.564] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:11.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:83:0) after 1m0s
[10:51:11.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:83:0)
[10:51:11.639] Timer tick, asking Buildbucket for the build status
[10:51:11.802] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:11.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:84:0) after 1m0s
[10:52:11.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:84:0)
[10:52:11.841] Timer tick, asking Buildbucket for the build status
[10:52:12.420] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:12.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:85:0) after 1m0s
[10:53:12.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:85:0)
[10:53:12.545] Timer tick, asking Buildbucket for the build status
[10:53:12.671] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:12.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:86:0) after 1m0s
[10:54:12.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:86:0)
[10:54:12.720] Timer tick, asking Buildbucket for the build status
[10:54:12.811] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:12.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:87:0) after 1m0s
[10:55:12.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:87:0)
[10:55:12.888] Timer tick, asking Buildbucket for the build status
[10:55:13.044] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:13.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:88:0) after 1m0s
[10:56:13.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:88:0)
[10:56:13.105] Timer tick, asking Buildbucket for the build status
[10:56:13.247] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:13.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:89:0) after 1m0s
[10:57:13.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:89:0)
[10:57:13.340] Timer tick, asking Buildbucket for the build status
[10:57:14.015] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:14.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:90:0) after 1m0s
[10:58:14.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:90:0)
[10:58:14.037] Timer tick, asking Buildbucket for the build status
[10:58:14.129] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:14.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:91:0) after 1m0s
[10:59:14.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:91:0)
[10:59:14.148] Timer tick, asking Buildbucket for the build status
[10:59:14.290] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:14.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:92:0) after 1m0s
[11:00:14.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:92:0)
[11:00:14.316] Timer tick, asking Buildbucket for the build status
[11:00:15.691] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:15.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:93:0) after 1m0s
[11:01:15.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:93:0)
[11:01:15.725] Timer tick, asking Buildbucket for the build status
[11:01:16.116] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:16.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:94:0) after 1m0s
[11:02:16.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:94:0)
[11:02:16.100] Timer tick, asking Buildbucket for the build status
[11:02:16.310] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:16.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:95:0) after 1m0s
[11:03:16.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:95:0)
[11:03:16.360] Timer tick, asking Buildbucket for the build status
[11:03:16.966] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:16.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:96:0) after 1m0s
[11:04:17.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:96:0)
[11:04:17.007] Timer tick, asking Buildbucket for the build status
[11:04:17.203] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:17.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:97:0) after 1m0s
[11:05:17.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:97:0)
[11:05:17.320] Timer tick, asking Buildbucket for the build status
[11:05:17.749] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:17.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:98:0) after 1m0s
[11:06:17.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:98:0)
[11:06:17.795] Timer tick, asking Buildbucket for the build status
[11:06:17.960] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:17.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:99:0) after 1m0s
[11:07:17.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:99:0)
[11:07:17.947] Timer tick, asking Buildbucket for the build status
[11:07:18.124] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:18.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:100:0) after 1m0s
[11:08:18.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:100:0)
[11:08:18.195] Timer tick, asking Buildbucket for the build status
[11:08:18.426] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:18.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:101:0) after 1m0s
[11:09:18.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:101:0)
[11:09:18.424] Timer tick, asking Buildbucket for the build status
[11:09:18.543] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:18.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:102:0) after 1m0s
[11:10:18.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:102:0)
[11:10:18.613] Timer tick, asking Buildbucket for the build status
[11:10:18.821] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:18.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:103:0) after 1m0s
[11:11:18.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:103:0)
[11:11:18.884] Timer tick, asking Buildbucket for the build status
[11:11:19.373] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:19.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:104:0) after 1m0s
[11:12:19.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:104:0)
[11:12:19.471] Timer tick, asking Buildbucket for the build status
[11:12:19.762] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:19.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:105:0) after 1m0s
[11:13:19.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:105:0)
[11:13:19.864] Timer tick, asking Buildbucket for the build status
[11:13:19.980] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:19.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:106:0) after 1m0s
[11:14:20.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:106:0)
[11:14:20.117] Timer tick, asking Buildbucket for the build status
[11:14:20.771] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:20.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:107:0) after 1m0s
[11:15:20.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:107:0)
[11:15:20.751] Timer tick, asking Buildbucket for the build status
[11:15:20.969] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:20.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:108:0) after 1m0s
[11:16:21.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:108:0)
[11:16:21.164] Timer tick, asking Buildbucket for the build status
[11:16:21.350] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:21.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:109:0) after 1m0s
[11:17:21.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:109:0)
[11:17:21.495] Timer tick, asking Buildbucket for the build status
[11:17:21.600] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:21.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:110:0) after 1m0s
[11:18:21.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:110:0)
[11:18:21.644] Timer tick, asking Buildbucket for the build status
[11:18:21.741] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:21.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:111:0) after 1m0s
[11:19:21.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:111:0)
[11:19:21.827] Timer tick, asking Buildbucket for the build status
[11:19:21.934] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:21.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:112:0) after 1m0s
[11:20:21.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:112:0)
[11:20:21.946] Timer tick, asking Buildbucket for the build status
[11:20:22.165] Build 8857052652621664592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:22.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022482714806458848:113:0) after 1m0s
[11:20:34.645] Received PubSub notification, asking Buildbucket for the build status
[11:20:34.707] Build 8857052652621664592: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:20:34.707] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611660034094788",
"created_by": "project:chromium",
"created_ts": "1611653388344639",
"failure_reason": "BUILD_FAILURE",
"id": "8857052652621664592",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cd05d63af2628400241ee1347390dff95445aa36\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- AdsPageLoadMetricsObserverBrowserTest.DisallowedAdFrames_NotMeasured\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}, \"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\": \"cd05d63af2628400241ee1347390dff95445aa36\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-1-o1ts\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17017, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"9bbcd86ae719866273598c988709c7ec3d462e1d\", \"got_dawn_revision\": \"b3177d418e3b3d14fe99c63badebb191c510830e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"cd05d63af2628400241ee1347390dff95445aa36\", \"got_revision_cp\": \"refs/heads/master@{#847092}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"ea1f7d10db1f22f3327f4c0bc690695bc1a73aa0\", \"got_v8_revision\": \"5cec537384426022e2ff108b720e7df61cc1c52d\", \"got_v8_revision_cp\": \"refs/heads/9.0.69@{#1}\", \"got_webrtc_revision\": \"ded6636cf43904448ee926d1f2b4352c8a957ca6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33068}\", \"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\": \"cd05d63af2628400241ee1347390dff95445aa36\", \"swarm_hashes_refs/heads/master(at){#847092}_without_patch\": {\"absl_hardening_tests\": \"95b58bf8cdce404e7e673ab5540c0637b9171bb7\", \"accessibility_unittests\": \"40171fdfa9c2f613e87d969f52b6bae31b934f23\", \"angle_unittests\": \"44083ebd86aeed3e0a5a92f021a566ff7ca0334e\", \"app_shell_unittests\": \"87d35ebd3bfc9e61be15bfb36ff4efc1da070b5e\", \"aura_unittests\": \"2a5fcc76fd2e9c232e3dd4ecea0b12d76df47a18\", \"base_unittests\": \"f7bffd75387abbc9dd15348c51e5c1f24ca7cfb8\", \"base_util_unittests\": \"8a93e4acb71b98f2b4ab503d7fc2047591f73b64\", \"blink_common_unittests\": \"d7de430deb3f072ea4a61926268924533486ff68\", \"blink_fuzzer_unittests\": \"89bc26dbf6238034878dce467dfdae3285d09bf5\", \"blink_heap_unittests\": \"eb3f392c2f8a34fe854e4b84fc21e1090180ced3\", \"blink_platform_unittests\": \"553c8e3dba2f504d127cfd8efd616f4be515580a\", \"blink_unittests\": \"61a5b0e01dee2951d10592709aa575e6fa0f3355\", \"boringssl_crypto_tests\": \"8b8d4e06dcec0ccb3632bfc89449d90d3f74451f\", \"boringssl_ssl_tests\": \"85bdacf7a56383560f9e1dda0f00847b5bcf83a8\", \"browser_switcher_bho_unittests\": \"cd45ae66b56b56d21e41e81ee3db35118127e337\", \"browser_tests\": \"68cac1738fbac9f6eb828537c770bea351aa9e4b\", \"capture_unittests\": \"b7d57b323b2a0f0f378948a1d7c3ecd25a240411\", \"cast_unittests\": \"e1638c5d06b2838b5438dba88be7efaff6732ec0\", \"cc_unittests\": \"26c8eda3f998a3a8682199e24bccbcdfa5aba6ed\", \"chrome_app_unittests\": \"1da6e0addd5b1098f36ee55851b5e95b5ff4c176\", \"chrome_cleaner_unittests\": \"7d607fe46d9a06cfe70839bb5981ad93978012b4\", \"chrome_elf_unittests\": \"f962438d81f3a37d6283ae1275361c3d2fe98be6\", \"chromedriver_unittests\": \"5d4520214cbd0de38e623ccc17c23e077c45db29\", \"components_browsertests\": \"5fe390302df2d0b0d167ba4c9200aa692c991095\", \"components_unittests\": \"2295fb44c21576a125c9ec26fb77a2238e249717\", \"compositor_unittests\": \"4e4a4baa6074fdc045b36f32a5a7c6b19798feb6\", \"content_browsertests\": \"cd0366fe8928392baae713c604b509f5640131d7\", \"content_unittests\": \"a81dec85e18a0445d9fce9a261721b60a6112a60\", \"courgette_unittests\": \"25d5f19dc99fb14454e7538febb0e9725771b072\", \"crashpad_tests\": \"1309216c58b9af993b1c0b5aff2fe889b47ccb90\", \"cronet_tests\": \"497fc3d020bd6dc550e8c952549d4a71af9575e6\", \"cronet_unittests\": \"8db18e7d0e76ae990c22dd588b1cbff91d037808\", \"crypto_unittests\": \"c438d65665032e2805764b28fea8a73b0fdbdf0f\", \"delayloads_unittests\": \"8270cd84f5321fe6e7efc793654c69aedd7a5d03\", \"device_unittests\": \"27c1badc8fd7ae756fb352290a474662c4178501\", \"display_unittests\": \"c2f4bb487a50736384afb5d998e77cb4a2aa0033\", \"elevation_service_unittests\": \"5cf1717b15d98234bcf80de0bd3c653eb850f009\", \"events_unittests\": \"aac90c181267f237eefbfab58b164f2dbf59aa1d\", \"extensions_browsertests\": \"c58d38cbfd78263e85c58fd64cf178b038d9c40f\", \"extensions_unittests\": \"2a1a3f1cbf99abaa99eb52cd17520d870377f639\", \"filesystem_service_unittests\": \"f0388b0f0e46c77d556f43578c5038f5f63a31d6\", \"gcm_unit_tests\": \"3050f308a08ddf67992289233b5027cd5e69ffc0\", \"gcp_unittests\": \"84d645270ad3a3f44b794b5786f1f377eb00aff5\", \"gfx_unittests\": \"c14d161c9d1a81614712a66d4d54b805e18a6333\", \"gin_unittests\": \"657eace6e7da6c71b1366ba8a7245c9e12c1d2f2\", \"google_apis_unittests\": \"fdc7cda43a90729f420402796432de0589fef989\", \"gpu_unittests\": \"e80ae2f62ccb1f7b552b2926557bb5c428a8ad45\", \"gwp_asan_unittests\": \"972ff49dde130ecdf78732a3ce70638d65c2e4e9\", \"headless_browsertests\": \"522164098ea3ab2bbe312826e09a0553d9e3ced8\", \"headless_unittests\": \"a0be8def7aae796f85c107d6ecee502f5ba8ec09\", \"install_static_unittests\": \"61e49d47764ee27ec54a3d9c87f6433af93bd9c5\", \"installer_util_unittests\": \"279db02247e1d2f29348c279322dfe71f1081381\", \"interactive_ui_tests\": \"a5fb792e55fb90b33957e8c86d085ecea69a74b5\", \"ipc_tests\": \"be235cbad7458647223a367ea0234378cfd6d2a0\", \"jingle_unittests\": \"3297f12a4a889d920b810bd668b0d665542178e8\", \"latency_unittests\": \"25c18fa4e382579e681abd65ebf2e629e61bec16\", \"libjingle_xmpp_unittests\": \"bd621d53f98b3a31f33a3f2a517b0581420451bd\", \"liburlpattern_unittests\": \"d7c5a6bc7ef33511ba8396a5dd6c7189ee9e4288\", \"media_blink_unittests\": \"73f97bceba5375d649c7d4543e0a94263fb6a8af\", \"media_unittests\": \"1b983f4704a64921114fdd0c6681c83ca5660161\", \"message_center_unittests\": \"ee4e067d14fc9120d877270049268e239e569522\", \"midi_unittests\": \"491f73466e059aa163f8be73516990a170b9b5e9\", \"mojo_core_unittests\": \"7d3a11bb5703f1969a5360dd47f4179a3aa043cc\", \"mojo_unittests\": \"8d4deefb652035f69d189147e4e71b7c459a0100\", \"nacl_loader_unittests\": \"4e4b854747ae47d9052e709b859e75a879a502c4\", \"native_theme_unittests\": \"36c96060a54fa3c50ac5027f439164fa333d1c27\", \"net_unittests\": \"57b85c87bc7f5ebb2ff5c8be73ac22c169385d18\", \"notification_helper_unittests\": \"fb636d33fc9537aa9980f506edc67d44bb64a702\", \"pdf_unittests\": \"cb463a6f6a639256d49cbb6deb50404b130c207f\", \"perfetto_unittests\": \"d1c312f0bd6cda1d0104e6fe3c45a795f03016da\", \"ppapi_unittests\": \"791fbe47a697ef4d24473069596c5f59aaf8139d\", \"printing_unittests\": \"4577b0c04a4647bdca7944d0550dcd9dae6ca4f9\", \"remoting_unittests\": \"3edd047d00402ca8ea32ecb97b7ef7fcdfb770aa\", \"sbox_integration_tests\": \"54f8430392120ab46639be8c2a1952d87c21998b\", \"sbox_unittests\": \"a999084c4021267847acf4f533bcd897e70f5bb1\", \"sbox_validation_tests\": \"e047e2c6c3e2aadb3d35e548993f0e6293f71628\", \"service_manager_unittests\": \"f5c55ab4facbc5f66bac5307d0c835514d4e9fb8\", \"services_unittests\": \"08302c64478fea7fc3321ce1dcecd87cb4ab536f\", \"setup_unittests\": \"77a65e1b77977eacd819a2b9ca1f671b9493c84e\", \"shell_dialogs_unittests\": \"1816accdbd66026a91561e372515b525e948b8d7\", \"skia_unittests\": \"9ae620db70926e6c7589aae40cf3618a987cc302\", \"snapshot_unittests\": \"847474a2dd0fcc707a740beefb9459f4646ede8a\", \"sql_unittests\": \"0540816fef3490937a07a175062a16253109d9fe\", \"storage_unittests\": \"8f216683dc88b68f12b7ffafa9966b79725d5fbc\", \"sync_integration_tests\": \"c904fae023f37b7e5a7772c78c476ba58639b64f\", \"traffic_annotation_auditor_unittests\": \"2eee3da842b3c2e19500ea74b31ff1a64797bffc\", \"ui_base_unittests\": \"f20a506f85b9644250baf4c58f634f64fc25b905\", \"ui_touch_selection_unittests\": \"0b1f02297113d49fe507db7b5d8ac00a13f4fcfd\", \"unit_tests\": \"ed1e1385aa34c8e83eb6b8ff84ade813eebf3ad9\", \"updater_tests\": \"c6b808440b80ba796281845ec894481a0a15551d\", \"url_unittests\": \"096014e35680e9d65092ed1985d6c1283b7ac4ca\", \"views_unittests\": \"24313dfab4d0812ec0b8c70ffb984b2571bea717\", \"viz_unittests\": \"7d1999bad5f2add1aeb8e13d86849ba64f29aa68\", \"vr_common_unittests\": \"16ab932649364ed4d88eeb7147ddcb4dc64fdad1\", \"vr_pixeltests\": \"6549482034eff62025e0bc729a01b13fd6395dcc\", \"weblayer_browsertests\": \"4dca0734967f1318c0658e9229a2f06730e63889\", \"weblayer_unittests\": \"2952f4b26bfaae3436d517726481786e1a843c98\", \"wm_unittests\": \"09ac050cf900c8f7b9ab02882b40c0f7838693da\", \"wtf_unittests\": \"b2a60431d5125407e0d2d3f16954363286e41953\", \"zlib_unittests\": \"00af7d59f956e6ae4f04802bcf43cb7c7d77c41d\", \"zucchini_unittests\": \"c5a5694d667e5ac9d12382f429e2ae3384e01c56\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_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-1-o1ts\"], \"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\": [\"5672-4713de1\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"2 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- AdsPageLoadMetricsObserverBrowserTest.DisallowedAdFrames_NotMeasured\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611653666594341",
"status": "COMPLETED",
"status_changed_ts": "1611653666929118",
"tags": [
"build_address:luci.chromium.ci/win-asan/17017",
"builder:win-asan",
"buildset:commit/git/cd05d63af2628400241ee1347390dff95445aa36",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cd05d63af2628400241ee1347390dff95445aa36",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022482714806458848",
"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/8857052652621664592/+/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:5156d8a2db64b910",
"user_agent:luci-scheduler"
],
"updated_ts": "1611660034367688",
"url": "https://ci.chromium.org/b/8857052652621664592",
"utcnow_ts": "1611660034698029"
}
}
[11:20:34.707] Invocation finished in 1h50m47.829637088s with status FAILED