[08:26:24.630] New invocation is queued and will start shortly
[08:26:25.791] Starting the invocation (attempt 1)
[08:26:25.829] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:26:25.830] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:26:25.830] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028647295485178544",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5bc4848837755620c622d8b797d8a7961ef3a608\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028647295485178544",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5bc4848837755620c622d8b797d8a7961ef3a608",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bc4848837755620c622d8b797d8a7961ef3a608",
"gitiles_ref:refs/heads/master"
]
}
[08:26:26.779] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605774385939699",
"id": "8863217233447408928",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5bc4848837755620c622d8b797d8a7961ef3a608\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605774386571698",
"tags": [
"build_address:luci.chromium.ci/win-asan/15719",
"builder:win-asan",
"buildset:commit/git/5bc4848837755620c622d8b797d8a7961ef3a608",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bc4848837755620c622d8b797d8a7961ef3a608",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028647295485178544",
"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/8863217233447408928/+/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": "1605774386571839",
"url": "https://ci.chromium.org/b/8863217233447408928",
"utcnow_ts": "1605774386750286"
}
}
[08:26:26.779] Task URL: https://ci.chromium.org/b/8863217233447408928
[08:26:26.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:2:0) after 1m0s
[08:27:26.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:2:0)
[08:27:26.923] Timer tick, asking Buildbucket for the build status
[08:27:27.040] Build 8863217233447408928: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:27.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:3:0) after 1m0s
[08:28:27.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:3:0)
[08:28:27.113] Timer tick, asking Buildbucket for the build status
[08:28:27.356] Build 8863217233447408928: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:28:27.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:4:0) after 1m0s
[08:28:40.106] Received PubSub notification, asking Buildbucket for the build status
[08:28:40.567] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:27.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:4:0)
[08:29:27.419] Timer tick, asking Buildbucket for the build status
[08:29:27.966] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:27.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:6:0) after 1m0s
[08:30:28.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:6:0)
[08:30:28.050] Timer tick, asking Buildbucket for the build status
[08:30:28.262] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:28.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:7:0) after 1m0s
[08:31:28.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:7:0)
[08:31:28.454] Timer tick, asking Buildbucket for the build status
[08:31:30.025] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:30.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:8:0) after 1m0s
[08:32:30.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:8:0)
[08:32:30.472] Timer tick, asking Buildbucket for the build status
[08:32:30.552] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:30.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:9:0) after 1m0s
[08:33:30.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:9:0)
[08:33:30.633] Timer tick, asking Buildbucket for the build status
[08:33:30.702] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:30.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:10:0) after 1m0s
[08:34:30.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:10:0)
[08:34:30.888] Timer tick, asking Buildbucket for the build status
[08:34:30.965] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:30.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:11:0) after 1m0s
[08:35:31.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:11:0)
[08:35:31.030] Timer tick, asking Buildbucket for the build status
[08:35:31.092] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:31.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:12:0) after 1m0s
[08:36:31.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:12:0)
[08:36:31.217] Timer tick, asking Buildbucket for the build status
[08:36:31.387] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:31.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:13:0) after 1m0s
[08:37:31.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:13:0)
[08:37:31.534] Timer tick, asking Buildbucket for the build status
[08:37:31.597] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:31.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:14:0) after 1m0s
[08:38:31.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:14:0)
[08:38:31.772] Timer tick, asking Buildbucket for the build status
[08:38:31.844] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:31.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:15:0) after 1m0s
[08:39:31.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:15:0)
[08:39:31.978] Timer tick, asking Buildbucket for the build status
[08:39:32.541] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:32.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:16:0) after 1m0s
[08:40:32.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:16:0)
[08:40:32.634] Timer tick, asking Buildbucket for the build status
[08:40:32.788] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:32.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:17:0) after 1m0s
[08:41:32.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:17:0)
[08:41:32.882] Timer tick, asking Buildbucket for the build status
[08:41:33.012] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:33.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:18:0) after 1m0s
[08:42:33.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:18:0)
[08:42:33.073] Timer tick, asking Buildbucket for the build status
[08:42:33.194] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:33.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:19:0) after 1m0s
[08:43:33.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:19:0)
[08:43:33.274] Timer tick, asking Buildbucket for the build status
[08:43:33.487] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:33.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:20:0) after 1m0s
[08:44:33.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:20:0)
[08:44:33.598] Timer tick, asking Buildbucket for the build status
[08:44:33.751] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:33.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:21:0) after 1m0s
[08:45:33.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:21:0)
[08:45:33.794] Timer tick, asking Buildbucket for the build status
[08:45:33.969] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:33.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:22:0) after 1m0s
[08:46:34.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:22:0)
[08:46:34.065] Timer tick, asking Buildbucket for the build status
[08:46:34.171] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:34.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:23:0) after 1m0s
[08:47:34.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:23:0)
[08:47:34.261] Timer tick, asking Buildbucket for the build status
[08:47:34.395] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:34.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:24:0) after 1m0s
[08:48:34.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:24:0)
[08:48:34.602] Timer tick, asking Buildbucket for the build status
[08:48:34.690] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:34.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:25:0) after 1m0s
[08:49:34.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:25:0)
[08:49:34.759] Timer tick, asking Buildbucket for the build status
[08:49:34.824] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:34.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:26:0) after 1m0s
[08:50:34.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:26:0)
[08:50:34.919] Timer tick, asking Buildbucket for the build status
[08:50:35.153] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:35.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:27:0) after 1m0s
[08:51:35.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:27:0)
[08:51:35.210] Timer tick, asking Buildbucket for the build status
[08:51:35.310] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:35.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:28:0) after 1m0s
[08:52:35.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:28:0)
[08:52:35.392] Timer tick, asking Buildbucket for the build status
[08:52:35.586] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:35.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:29:0) after 1m0s
[08:53:35.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:29:0)
[08:53:35.678] Timer tick, asking Buildbucket for the build status
[08:53:35.809] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:35.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:30:0) after 1m0s
[08:54:35.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:30:0)
[08:54:35.857] Timer tick, asking Buildbucket for the build status
[08:54:36.366] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:36.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:31:0) after 1m0s
[08:55:36.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:31:0)
[08:55:36.377] Timer tick, asking Buildbucket for the build status
[08:55:36.523] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:36.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:32:0) after 1m0s
[08:56:36.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:32:0)
[08:56:36.685] Timer tick, asking Buildbucket for the build status
[08:56:36.865] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:36.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:33:0) after 1m0s
[08:57:36.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:33:0)
[08:57:36.955] Timer tick, asking Buildbucket for the build status
[08:57:37.040] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:37.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:34:0) after 1m0s
[08:58:37.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:34:0)
[08:58:37.061] Timer tick, asking Buildbucket for the build status
[08:58:37.223] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:37.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:35:0) after 1m0s
[08:59:37.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:35:0)
[08:59:37.318] Timer tick, asking Buildbucket for the build status
[08:59:37.387] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:37.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:36:0) after 1m0s
[09:00:37.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:36:0)
[09:00:37.393] Timer tick, asking Buildbucket for the build status
[09:00:38.213] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:38.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:37:0) after 1m0s
[09:01:38.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:37:0)
[09:01:38.245] Timer tick, asking Buildbucket for the build status
[09:01:38.692] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:38.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:38:0) after 1m0s
[09:02:38.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:38:0)
[09:02:38.728] Timer tick, asking Buildbucket for the build status
[09:02:38.790] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:38.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:39:0) after 1m0s
[09:03:38.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:39:0)
[09:03:38.855] Timer tick, asking Buildbucket for the build status
[09:03:38.930] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:38.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:40:0) after 1m0s
[09:04:38.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:40:0)
[09:04:38.965] Timer tick, asking Buildbucket for the build status
[09:04:39.034] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:39.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:41:0) after 1m0s
[09:05:39.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:41:0)
[09:05:39.056] Timer tick, asking Buildbucket for the build status
[09:05:39.847] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:39.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:42:0) after 1m0s
[09:06:39.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:42:0)
[09:06:39.888] Timer tick, asking Buildbucket for the build status
[09:06:40.074] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:40.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:43:0) after 1m0s
[09:07:40.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:43:0)
[09:07:40.168] Timer tick, asking Buildbucket for the build status
[09:07:40.296] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:40.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:44:0) after 1m0s
[09:08:40.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:44:0)
[09:08:40.389] Timer tick, asking Buildbucket for the build status
[09:08:40.479] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:40.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:45:0) after 1m0s
[09:09:40.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:45:0)
[09:09:40.540] Timer tick, asking Buildbucket for the build status
[09:09:40.774] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:40.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:46:0) after 1m0s
[09:10:40.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:46:0)
[09:10:40.796] Timer tick, asking Buildbucket for the build status
[09:10:41.085] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:41.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:47:0) after 1m0s
[09:11:41.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:47:0)
[09:11:41.181] Timer tick, asking Buildbucket for the build status
[09:11:41.297] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:41.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:48:0) after 1m0s
[09:12:41.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:48:0)
[09:12:41.331] Timer tick, asking Buildbucket for the build status
[09:12:41.438] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:41.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:49:0) after 1m0s
[09:13:41.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:49:0)
[09:13:41.495] Timer tick, asking Buildbucket for the build status
[09:13:42.126] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:42.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:50:0) after 1m0s
[09:14:42.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:50:0)
[09:14:42.187] Timer tick, asking Buildbucket for the build status
[09:14:42.310] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:42.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:51:0) after 1m0s
[09:15:42.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:51:0)
[09:15:42.376] Timer tick, asking Buildbucket for the build status
[09:15:42.452] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:42.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:52:0) after 1m0s
[09:16:42.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:52:0)
[09:16:42.517] Timer tick, asking Buildbucket for the build status
[09:16:42.749] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:42.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:53:0) after 1m0s
[09:17:42.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:53:0)
[09:17:42.821] Timer tick, asking Buildbucket for the build status
[09:17:42.945] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:42.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:54:0) after 1m0s
[09:18:43.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:54:0)
[09:18:43.052] Timer tick, asking Buildbucket for the build status
[09:18:43.761] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:43.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:55:0) after 1m0s
[09:19:43.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:55:0)
[09:19:43.783] Timer tick, asking Buildbucket for the build status
[09:19:44.152] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:44.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:56:0) after 1m0s
[09:20:44.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:56:0)
[09:20:44.239] Timer tick, asking Buildbucket for the build status
[09:20:44.377] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:44.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:57:0) after 1m0s
[09:21:44.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:57:0)
[09:21:44.399] Timer tick, asking Buildbucket for the build status
[09:21:44.543] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:44.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:58:0) after 1m0s
[09:22:44.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:58:0)
[09:22:44.606] Timer tick, asking Buildbucket for the build status
[09:22:44.697] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:44.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:59:0) after 1m0s
[09:23:44.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:59:0)
[09:23:44.757] Timer tick, asking Buildbucket for the build status
[09:23:44.829] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:44.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:60:0) after 1m0s
[09:24:44.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:60:0)
[09:24:44.886] Timer tick, asking Buildbucket for the build status
[09:24:44.969] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:44.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:61:0) after 1m0s
[09:25:44.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:61:0)
[09:25:44.991] Timer tick, asking Buildbucket for the build status
[09:25:45.109] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:45.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:62:0) after 1m0s
[09:26:45.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:62:0)
[09:26:45.153] Timer tick, asking Buildbucket for the build status
[09:26:45.513] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:45.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:63:0) after 1m0s
[09:27:45.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:63:0)
[09:27:45.539] Timer tick, asking Buildbucket for the build status
[09:27:45.744] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:45.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:64:0) after 1m0s
[09:28:45.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:64:0)
[09:28:45.771] Timer tick, asking Buildbucket for the build status
[09:28:45.900] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:45.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:65:0) after 1m0s
[09:29:45.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:65:0)
[09:29:45.984] Timer tick, asking Buildbucket for the build status
[09:29:46.241] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:46.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:66:0) after 1m0s
[09:30:46.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:66:0)
[09:30:46.263] Timer tick, asking Buildbucket for the build status
[09:30:46.711] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:46.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:67:0) after 1m0s
[09:31:46.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:67:0)
[09:31:46.737] Timer tick, asking Buildbucket for the build status
[09:31:46.867] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:46.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:68:0) after 1m0s
[09:32:46.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:68:0)
[09:32:46.921] Timer tick, asking Buildbucket for the build status
[09:32:47.143] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:47.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:69:0) after 1m0s
[09:33:47.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:69:0)
[09:33:47.187] Timer tick, asking Buildbucket for the build status
[09:33:47.305] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:47.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:70:0) after 1m0s
[09:34:47.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:70:0)
[09:34:47.337] Timer tick, asking Buildbucket for the build status
[09:34:47.755] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:47.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:71:0) after 1m0s
[09:35:47.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:71:0)
[09:35:47.826] Timer tick, asking Buildbucket for the build status
[09:35:47.945] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:47.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:72:0) after 1m0s
[09:36:48.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:72:0)
[09:36:48.008] Timer tick, asking Buildbucket for the build status
[09:36:48.351] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:48.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:73:0) after 1m0s
[09:37:48.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:73:0)
[09:37:48.346] Timer tick, asking Buildbucket for the build status
[09:37:48.485] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:48.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:74:0) after 1m0s
[09:38:48.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:74:0)
[09:38:48.527] Timer tick, asking Buildbucket for the build status
[09:38:48.632] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:48.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:75:0) after 1m0s
[09:39:48.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:75:0)
[09:39:48.663] Timer tick, asking Buildbucket for the build status
[09:39:48.774] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:48.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:76:0) after 1m0s
[09:40:48.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:76:0)
[09:40:48.857] Timer tick, asking Buildbucket for the build status
[09:40:49.008] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:49.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:77:0) after 1m0s
[09:41:49.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:77:0)
[09:41:49.089] Timer tick, asking Buildbucket for the build status
[09:41:49.206] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:49.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:78:0) after 1m0s
[09:42:49.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:78:0)
[09:42:49.245] Timer tick, asking Buildbucket for the build status
[09:42:49.535] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:49.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:79:0) after 1m0s
[09:43:49.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:79:0)
[09:43:49.569] Timer tick, asking Buildbucket for the build status
[09:43:49.701] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:49.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:80:0) after 1m0s
[09:44:49.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:80:0)
[09:44:49.772] Timer tick, asking Buildbucket for the build status
[09:44:49.887] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:49.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:81:0) after 1m0s
[09:45:49.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:81:0)
[09:45:49.933] Timer tick, asking Buildbucket for the build status
[09:45:50.072] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:50.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:82:0) after 1m0s
[09:46:50.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:82:0)
[09:46:50.108] Timer tick, asking Buildbucket for the build status
[09:46:50.449] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:50.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:83:0) after 1m0s
[09:47:50.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:83:0)
[09:47:50.534] Timer tick, asking Buildbucket for the build status
[09:47:51.014] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:51.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:84:0) after 1m0s
[09:48:51.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:84:0)
[09:48:51.051] Timer tick, asking Buildbucket for the build status
[09:48:51.128] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:51.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:85:0) after 1m0s
[09:49:51.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:85:0)
[09:49:51.110] Timer tick, asking Buildbucket for the build status
[09:49:51.751] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:51.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:86:0) after 1m0s
[09:50:51.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:86:0)
[09:50:51.791] Timer tick, asking Buildbucket for the build status
[09:50:51.880] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:51.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:87:0) after 1m0s
[09:51:52.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:87:0)
[09:51:52.008] Timer tick, asking Buildbucket for the build status
[09:51:52.137] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:52.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:88:0) after 1m0s
[09:52:52.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:88:0)
[09:52:52.247] Timer tick, asking Buildbucket for the build status
[09:52:52.364] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:52.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:89:0) after 1m0s
[09:53:52.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:89:0)
[09:53:52.390] Timer tick, asking Buildbucket for the build status
[09:53:52.506] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:52.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:90:0) after 1m0s
[09:54:52.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:90:0)
[09:54:52.515] Timer tick, asking Buildbucket for the build status
[09:54:53.455] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:53.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:91:0) after 1m0s
[09:55:53.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:91:0)
[09:55:53.646] Timer tick, asking Buildbucket for the build status
[09:55:53.735] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:53.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:92:0) after 1m0s
[09:56:53.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:92:0)
[09:56:53.762] Timer tick, asking Buildbucket for the build status
[09:56:53.870] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:53.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:93:0) after 1m0s
[09:57:54.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:93:0)
[09:57:54.125] Timer tick, asking Buildbucket for the build status
[09:57:54.241] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:54.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:94:0) after 1m0s
[09:58:54.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:94:0)
[09:58:54.301] Timer tick, asking Buildbucket for the build status
[09:58:54.369] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:54.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:95:0) after 1m0s
[09:59:54.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:95:0)
[09:59:54.522] Timer tick, asking Buildbucket for the build status
[09:59:54.726] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:54.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:96:0) after 1m0s
[10:00:55.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:96:0)
[10:00:55.072] Timer tick, asking Buildbucket for the build status
[10:00:55.598] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:55.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:97:0) after 1m0s
[10:01:57.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:97:0)
[10:01:57.067] Timer tick, asking Buildbucket for the build status
[10:01:57.183] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:57.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:98:0) after 1m0s
[10:02:57.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:98:0)
[10:02:57.260] Timer tick, asking Buildbucket for the build status
[10:02:57.765] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:57.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:99:0) after 1m0s
[10:03:57.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:99:0)
[10:03:57.849] Timer tick, asking Buildbucket for the build status
[10:03:57.989] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:57.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:100:0) after 1m0s
[10:04:58.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:100:0)
[10:04:58.019] Timer tick, asking Buildbucket for the build status
[10:04:58.562] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:58.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:101:0) after 1m0s
[10:05:58.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:101:0)
[10:05:58.606] Timer tick, asking Buildbucket for the build status
[10:05:59.040] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:59.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:102:0) after 1m0s
[10:06:59.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:102:0)
[10:06:59.198] Timer tick, asking Buildbucket for the build status
[10:06:59.828] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:59.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:103:0) after 1m0s
[10:07:59.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:103:0)
[10:07:59.946] Timer tick, asking Buildbucket for the build status
[10:08:00.255] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:00.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:104:0) after 1m0s
[10:09:00.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:104:0)
[10:09:00.326] Timer tick, asking Buildbucket for the build status
[10:09:00.419] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:00.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:105:0) after 1m0s
[10:10:01.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:105:0)
[10:10:01.028] Timer tick, asking Buildbucket for the build status
[10:10:01.155] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:01.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:106:0) after 1m0s
[10:11:01.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:106:0)
[10:11:01.413] Timer tick, asking Buildbucket for the build status
[10:11:01.819] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:01.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:107:0) after 1m0s
[10:12:01.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:107:0)
[10:12:01.902] Timer tick, asking Buildbucket for the build status
[10:12:02.851] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:02.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:108:0) after 1m0s
[10:13:02.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:108:0)
[10:13:02.932] Timer tick, asking Buildbucket for the build status
[10:13:03.348] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:03.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:109:0) after 1m0s
[10:14:03.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:109:0)
[10:14:03.500] Timer tick, asking Buildbucket for the build status
[10:14:03.649] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:03.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:110:0) after 1m0s
[10:15:03.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:110:0)
[10:15:03.740] Timer tick, asking Buildbucket for the build status
[10:15:03.848] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:03.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:111:0) after 1m0s
[10:16:03.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:111:0)
[10:16:03.875] Timer tick, asking Buildbucket for the build status
[10:16:04.218] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:04.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:112:0) after 1m0s
[10:17:04.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:112:0)
[10:17:04.333] Timer tick, asking Buildbucket for the build status
[10:17:04.468] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:04.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:113:0) after 1m0s
[10:18:04.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:113:0)
[10:18:04.538] Timer tick, asking Buildbucket for the build status
[10:18:04.626] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:04.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:114:0) after 1m0s
[10:19:04.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:114:0)
[10:19:04.684] Timer tick, asking Buildbucket for the build status
[10:19:04.781] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:04.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:115:0) after 1m0s
[10:20:05.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:115:0)
[10:20:05.095] Timer tick, asking Buildbucket for the build status
[10:20:05.252] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:05.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:116:0) after 1m0s
[10:21:05.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:116:0)
[10:21:05.743] Timer tick, asking Buildbucket for the build status
[10:21:05.863] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:05.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:117:0) after 1m0s
[10:22:06.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:117:0)
[10:22:06.008] Timer tick, asking Buildbucket for the build status
[10:22:06.114] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:06.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:118:0) after 1m0s
[10:23:06.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:118:0)
[10:23:06.143] Timer tick, asking Buildbucket for the build status
[10:23:06.223] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:06.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:119:0) after 1m0s
[10:24:06.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:119:0)
[10:24:06.288] Timer tick, asking Buildbucket for the build status
[10:24:06.470] Build 8863217233447408928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:06.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028647295485178544:120:0) after 1m0s
[10:24:16.867] Received PubSub notification, asking Buildbucket for the build status
[10:24:16.952] Build 8863217233447408928: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:24:16.952] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605781455052484",
"created_by": "project:chromium",
"created_ts": "1605774385939699",
"failure_reason": "BUILD_FAILURE",
"id": "8863217233447408928",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5bc4848837755620c622d8b797d8a7961ef3a608\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}, \"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\": \"5bc4848837755620c622d8b797d8a7961ef3a608\", \"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-5-fw95\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15719, \"clang_revision\": \"llvmorg-12-init-11780-g6ef07111-1\", \"got_angle_revision\": \"8b76d37f9f9af22be8f1a31056b8a28992990662\", \"got_dawn_revision\": \"ce207be41fd6d9f1334ca6f251812511dc63faca\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"5bc4848837755620c622d8b797d8a7961ef3a608\", \"got_revision_cp\": \"refs/heads/master@{#829133}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"060020166679a265a2bc8250f4d78a7a8c29de89\", \"got_v8_revision\": \"8645ae9d96dc816b29ce5ff58430c8e8d07be20a\", \"got_v8_revision_cp\": \"refs/heads/8.9.12@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5bc4848837755620c622d8b797d8a7961ef3a608\", \"swarm_hashes_refs/heads/master(at){#829133}_without_patch\": {\"absl_hardening_tests\": \"990d737231e835dfe9827e0707e137bdbb0a3e28\", \"accessibility_unittests\": \"6dd71a02d8b812fffd6456be1187d0cb0225e576\", \"angle_unittests\": \"7dcdc7281f023bec91919ae6884a2564d00431fe\", \"app_shell_unittests\": \"c2fcc3ee45b1a2d582277eb10b6b3af14719d4ce\", \"aura_unittests\": \"0e75a63871aa40aad70d5f3e6d7887024b50b1b1\", \"base_unittests\": \"8f3c410804f22267d6f2d47a1657e15483d06e30\", \"base_util_unittests\": \"d3c07f270a830a1465079bba0241af290e49aa6e\", \"blink_common_unittests\": \"3c2a7bc05b62f2deebf928a73ef5c418344d92b7\", \"blink_fuzzer_unittests\": \"d1c5eb5bb22bdd23518999b4d1d982bba3734a44\", \"blink_heap_unittests\": \"17144527229748ba82de1d1b0c75417c2352e7b6\", \"blink_platform_unittests\": \"927fc4d8a41baba87a8f6b0cdc49ca710b5cf227\", \"blink_unittests\": \"fbaebc75d0ec811ec55f877ba9497bc7f351fa86\", \"boringssl_crypto_tests\": \"aeca3c1bc5b282b79535ab9348be19472fac00cf\", \"boringssl_ssl_tests\": \"ccc68487c7ac10d164eed2ef2ed828755eb794c2\", \"browser_switcher_bho_unittests\": \"49bbefffec11ad089232b1e85262e352af5d09e4\", \"browser_tests\": \"d3f8dc02546affc35f374ab8421456da3b6ed5e2\", \"capture_unittests\": \"bd244d264a80ff533072481000593e4bef53a8a5\", \"cast_unittests\": \"87d13f642c7067fbbd32a5885d78d0cb5fec62de\", \"cc_unittests\": \"328101a3ba7073cd7fb5a813f70f58a00a3511c4\", \"chrome_app_unittests\": \"7a6da57e46b2664ed063844927f1a4b7be293d21\", \"chrome_cleaner_unittests\": \"f188c520976c94a47400c8758418bdd80cc1275f\", \"chrome_elf_unittests\": \"1f900a678ce2af96c252ad3f055de54ddf225c37\", \"chromedriver_unittests\": \"28491a3395a98abe9a96e9b69ab951fc3681cc62\", \"components_browsertests\": \"8d3c603d3eeb97062e2fafc6a32a71d7903a7663\", \"components_unittests\": \"4247b8830a65c2fc55e756c3464846004d20270e\", \"compositor_unittests\": \"2ad429b2c593a7379ced67158b77f9bf4c5bdf41\", \"content_browsertests\": \"5c447c7370faf2a93f01b9746880e58746d4c382\", \"content_unittests\": \"c7cfa1163531a85999a7e41dc6aba4076f0ea0bf\", \"courgette_unittests\": \"e377e8f1da2bcdce5356534daab4cd99da00f0fc\", \"crashpad_tests\": \"296afb8350a8b16470ed1ab3fcd8a9d612f4e796\", \"cronet_tests\": \"8c34a62acdfcc915f1763e2db68a2d569fd1f994\", \"cronet_unittests\": \"d584c374b93b8a36ed5021df770506f0cf9bb829\", \"crypto_unittests\": \"74bbadfcc7484afeda584c458f146270f44b26c5\", \"delayloads_unittests\": \"05e9fcf84dfcbaacb15529e84aba763e6da55600\", \"device_unittests\": \"c0b6a2a9bcdb093896a830c6942b3f8b3c9a98e0\", \"display_unittests\": \"1a03d054eb2ea65c8fbddec938dced86b31e527a\", \"elevation_service_unittests\": \"858c56d4099887dbc771567ffdfb799339a6ab97\", \"events_unittests\": \"c08e42d57ba90f4bff513e1010302ab0011039e9\", \"extensions_browsertests\": \"184361e3ad16d80635ba363abcb6a7a79bd87968\", \"extensions_unittests\": \"4e00ee662d9d71c0a80b65e5ed0c369b828bb3aa\", \"filesystem_service_unittests\": \"b2459fd14f75249f564fd2dc493207b986d9b15f\", \"gcm_unit_tests\": \"1629ada2504aae06f1779249067d399b2899cc25\", \"gcp_unittests\": \"22e58f5b574c11e11b0a48cd4391f4f6e0324376\", \"gfx_unittests\": \"ad51ee6735c5638c4f9acce6646a743d66a3124f\", \"gin_unittests\": \"42539b128bc62b9e8112b8067e45b94fcfab4c22\", \"google_apis_unittests\": \"eac68b322ee0eeea2faad199a1eeec0b3ebe2fd8\", \"gpu_unittests\": \"0370b0b995fece956e5ead476281ce8f5343e1f9\", \"gwp_asan_unittests\": \"21c693dac7952cca6c5754444b197f514c98dbe9\", \"headless_browsertests\": \"2cf3ffa2d7f521288b88ac80cd83a5d69fe27a0d\", \"headless_unittests\": \"0194f9589f23dc3762ce85c8940a0dabc60406e4\", \"install_static_unittests\": \"76f66f8d0932489d7184370f6bf9277b93fdca36\", \"installer_util_unittests\": \"2fb2d69259d7b98275cfa0fb00e4f74f592d83cb\", \"interactive_ui_tests\": \"95bb8f4f62dcd4242afc30300ec9f7084f9d721b\", \"ipc_tests\": \"a8abdfac085d826416e7660d9b21ecb50e3cc467\", \"jingle_unittests\": \"967a7b93b0fac835724b667c8d0a096ef05d617e\", \"latency_unittests\": \"e6d50003039315c0bd0bb6cca980af1100892856\", \"libjingle_xmpp_unittests\": \"896ddfc6230fe0c2ff0f06f472350441f2557d39\", \"liburlpattern_unittests\": \"e0ee7d7944b9ee504440e4f9e627b93e32e9a9fd\", \"media_blink_unittests\": \"28eea67cde54bd6b14df96403dba95234b357e90\", \"media_unittests\": \"228f69c0512f5cf4bc22c3db6f361c9d7c40b408\", \"message_center_unittests\": \"1237234b5dcc2329c2bdc7f6fd3c29900e8ba213\", \"midi_unittests\": \"36c8da313091aacbe254c573aae4c08e1a24e684\", \"mojo_core_unittests\": \"686e6d4f003c9899ab940db271f66aeab9f9628c\", \"mojo_unittests\": \"ed6ade365fa9d26b5e8cf4fe1d38d56bbee88659\", \"nacl_loader_unittests\": \"a34a4cd423a49596149145c054df4d4ffff5a967\", \"native_theme_unittests\": \"35a5d81676cdcc27aeef5c62faa63491f8dc4d17\", \"net_unittests\": \"8f940cb4816dd047f88c053bae68467d33b49bb5\", \"notification_helper_unittests\": \"f1f09a12011708029fd50e940ad4f1d03b39d711\", \"pdf_unittests\": \"483925fef43157257e65bb0548c670d01a7114c4\", \"perfetto_unittests\": \"1ce264301dd83db05ef84614d0f87e67bcd9022d\", \"ppapi_unittests\": \"ffa7292f7bab55d586d78b27bf7778e57b60de56\", \"printing_unittests\": \"0ff0f8b1cf99a2a1d9b67925e2c412132fe68b20\", \"remoting_unittests\": \"fdbb94135204042c8413e542b9aa7b3755833e7c\", \"sbox_integration_tests\": \"c852b4fb2f62199851e6a8df78fcbcdf0bd5f99c\", \"sbox_unittests\": \"dbcaa926e2dec8bc79372433699785a0e91f229c\", \"sbox_validation_tests\": \"62f9fc90634eaf0f706ee4dad2a061eecb048256\", \"service_manager_unittests\": \"d1a8817113cea857ac6130a0709c38d3313c3cfd\", \"services_unittests\": \"2dba1493a99ff79a59b1b28667d6a23edb8c9791\", \"setup_unittests\": \"ea0f2221e34be6f9c79607f6244d6cf0486b3ca8\", \"shell_dialogs_unittests\": \"369ff8ee600f32c52b064e447de5700d3b309574\", \"skia_unittests\": \"f43074b6a7bcc906b46e162a99c0a344469acd88\", \"snapshot_unittests\": \"38b1fe296994c377ef9ba96bbb163acf044466ed\", \"sql_unittests\": \"2d39ac066e18d31a63e59d121132b0e0194a6367\", \"storage_unittests\": \"4afa6b50de4a69fb4b1fa98b4a4a869c43dfd4dd\", \"sync_integration_tests\": \"159c352df654d12ac47db1b7471231ee8ca994d8\", \"traffic_annotation_auditor_unittests\": \"5172ec8caa64b18a32dadeefbed6348bf20290e5\", \"ui_base_unittests\": \"b7aef58c63478a878adafe47aef3c1fe174ebca8\", \"ui_touch_selection_unittests\": \"6b2d78fece9a82cbc14d7a9f536205abb11486be\", \"unit_tests\": \"71da8bc2f03a47c7e8cb9af98c5a01b88fd5adf3\", \"updater_tests\": \"58e06cecd4c9791bf2ad8e7d2b0d3f82bc6bd698\", \"url_unittests\": \"ca716c81cc84bee6a7d2803b434eb44b75dcb44e\", \"views_unittests\": \"5255b007f71977ab06881b768a2bb7ab74bb98dd\", \"viz_unittests\": \"88dd28498edbfb9cec0c5d2a321887747388dc14\", \"vr_common_unittests\": \"c4373595d3c9e92a286649f2d9ddaf0aa69a9002\", \"vr_pixeltests\": \"428a18d84e28727a2ebb349a256c76fa587e8916\", \"weblayer_browsertests\": \"e6dd94aefc8acf733285087d5da0aee60349d327\", \"weblayer_unittests\": \"db169f461fb559c0eaf329e9a3e51ae7ff903384\", \"wm_unittests\": \"216cd7f70679f1751da5e35e9a765d42ec593a06\", \"wtf_unittests\": \"5818a29c38a65e115a5944ebb02716a753a8aa62\", \"zlib_unittests\": \"aea3552d16ba0680d919671c4bb9d33cf38f264c\", \"zucchini_unittests\": \"38504db4100acea5a66b3efe02fbfbb512ec3770\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"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-5-fw95\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithNoFilesShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605774517089645",
"status": "COMPLETED",
"status_changed_ts": "1605774517617090",
"tags": [
"build_address:luci.chromium.ci/win-asan/15719",
"builder:win-asan",
"buildset:commit/git/5bc4848837755620c622d8b797d8a7961ef3a608",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5bc4848837755620c622d8b797d8a7961ef3a608",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028647295485178544",
"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/8863217233447408928/+/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:4ff86e2f66177a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605781456231896",
"url": "https://ci.chromium.org/b/8863217233447408928",
"utcnow_ts": "1605781456943063"
}
}
[10:24:16.952] Invocation finished in 1h57m52.338793308s with status FAILED