[14:53:26.266] New invocation is queued and will start shortly
[14:53:27.328] Starting the invocation (attempt 1)
[14:53:27.361] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:53:27.362] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:53:27.362] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021103397637531344",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9a6b98b40bf108d42848f3156130e53a972a0896\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021103397637531344",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9a6b98b40bf108d42848f3156130e53a972a0896",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a6b98b40bf108d42848f3156130e53a972a0896",
"gitiles_ref:refs/heads/master"
]
}
[14:53:28.197] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612968807455615",
"id": "8855673335711985040",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9a6b98b40bf108d42848f3156130e53a972a0896\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612968808053828",
"tags": [
"build_address:luci.chromium.ci/win-asan/17272",
"builder:win-asan",
"buildset:commit/git/9a6b98b40bf108d42848f3156130e53a972a0896",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a6b98b40bf108d42848f3156130e53a972a0896",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021103397637531344",
"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/8855673335711985040/+/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": "1612968808053978",
"url": "https://ci.chromium.org/b/8855673335711985040",
"utcnow_ts": "1612968808189499"
}
}
[14:53:28.197] Task URL: https://ci.chromium.org/b/8855673335711985040
[14:53:28.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:2:0) after 1m0s
[14:54:28.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:2:0)
[14:54:28.257] Timer tick, asking Buildbucket for the build status
[14:54:28.530] Build 8855673335711985040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:54:28.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:3:0) after 1m0s
[14:55:28.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:3:0)
[14:55:28.534] Timer tick, asking Buildbucket for the build status
[14:55:28.927] Build 8855673335711985040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:55:28.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:4:0) after 1m0s
[14:56:23.466] Received PubSub notification, asking Buildbucket for the build status
[14:56:23.731] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:28.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:4:0)
[14:56:28.964] Timer tick, asking Buildbucket for the build status
[14:56:29.218] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:29.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:6:0) after 1m0s
[14:57:29.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:6:0)
[14:57:29.248] Timer tick, asking Buildbucket for the build status
[14:57:29.348] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:29.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:7:0) after 1m0s
[14:58:29.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:7:0)
[14:58:29.381] Timer tick, asking Buildbucket for the build status
[14:58:29.715] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:29.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:8:0) after 1m0s
[14:59:29.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:8:0)
[14:59:29.747] Timer tick, asking Buildbucket for the build status
[14:59:30.081] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:30.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:9:0) after 1m0s
[15:00:30.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:9:0)
[15:00:30.205] Timer tick, asking Buildbucket for the build status
[15:00:30.334] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:30.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:10:0) after 1m0s
[15:01:30.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:10:0)
[15:01:30.352] Timer tick, asking Buildbucket for the build status
[15:01:30.447] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:30.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:11:0) after 1m0s
[15:02:30.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:11:0)
[15:02:30.483] Timer tick, asking Buildbucket for the build status
[15:02:30.658] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:30.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:12:0) after 1m0s
[15:03:30.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:12:0)
[15:03:30.695] Timer tick, asking Buildbucket for the build status
[15:03:31.034] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:31.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:13:0) after 1m0s
[15:04:31.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:13:0)
[15:04:31.194] Timer tick, asking Buildbucket for the build status
[15:04:31.267] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:31.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:14:0) after 1m0s
[15:05:31.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:14:0)
[15:05:31.323] Timer tick, asking Buildbucket for the build status
[15:05:31.430] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:31.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:15:0) after 1m0s
[15:06:31.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:15:0)
[15:06:31.588] Timer tick, asking Buildbucket for the build status
[15:06:32.055] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:32.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:16:0) after 1m0s
[15:07:32.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:16:0)
[15:07:32.144] Timer tick, asking Buildbucket for the build status
[15:07:32.295] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:32.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:17:0) after 1m0s
[15:08:32.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:17:0)
[15:08:32.317] Timer tick, asking Buildbucket for the build status
[15:08:32.513] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:32.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:18:0) after 1m0s
[15:09:32.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:18:0)
[15:09:32.593] Timer tick, asking Buildbucket for the build status
[15:09:33.179] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:33.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:19:0) after 1m0s
[15:10:33.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:19:0)
[15:10:33.335] Timer tick, asking Buildbucket for the build status
[15:10:33.512] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:33.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:20:0) after 1m0s
[15:11:33.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:20:0)
[15:11:33.761] Timer tick, asking Buildbucket for the build status
[15:11:34.404] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:34.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:21:0) after 1m0s
[15:12:34.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:21:0)
[15:12:34.649] Timer tick, asking Buildbucket for the build status
[15:12:34.722] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:34.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:22:0) after 1m0s
[15:13:34.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:22:0)
[15:13:34.841] Timer tick, asking Buildbucket for the build status
[15:13:35.234] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:35.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:23:0) after 1m0s
[15:14:35.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:23:0)
[15:14:35.493] Timer tick, asking Buildbucket for the build status
[15:14:35.944] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:35.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:24:0) after 1m0s
[15:15:36.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:24:0)
[15:15:36.411] Timer tick, asking Buildbucket for the build status
[15:15:36.533] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:36.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:25:0) after 1m0s
[15:16:36.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:25:0)
[15:16:36.986] Timer tick, asking Buildbucket for the build status
[15:16:37.129] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:37.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:26:0) after 1m0s
[15:17:37.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:26:0)
[15:17:37.228] Timer tick, asking Buildbucket for the build status
[15:17:37.522] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:37.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:27:0) after 1m0s
[15:18:37.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:27:0)
[15:18:37.561] Timer tick, asking Buildbucket for the build status
[15:18:38.011] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:38.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:28:0) after 1m0s
[15:19:38.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:28:0)
[15:19:38.068] Timer tick, asking Buildbucket for the build status
[15:19:38.311] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:38.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:29:0) after 1m0s
[15:20:38.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:29:0)
[15:20:38.350] Timer tick, asking Buildbucket for the build status
[15:20:38.438] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:38.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:30:0) after 1m0s
[15:21:38.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:30:0)
[15:21:38.749] Timer tick, asking Buildbucket for the build status
[15:21:38.841] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:38.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:31:0) after 1m0s
[15:22:39.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:31:0)
[15:22:39.098] Timer tick, asking Buildbucket for the build status
[15:22:39.221] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:39.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:32:0) after 1m0s
[15:23:39.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:32:0)
[15:23:39.449] Timer tick, asking Buildbucket for the build status
[15:23:39.703] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:39.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:33:0) after 1m0s
[15:24:39.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:33:0)
[15:24:39.859] Timer tick, asking Buildbucket for the build status
[15:24:40.241] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:40.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:34:0) after 1m0s
[15:25:40.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:34:0)
[15:25:40.377] Timer tick, asking Buildbucket for the build status
[15:25:40.507] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:40.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:35:0) after 1m0s
[15:26:40.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:35:0)
[15:26:40.517] Timer tick, asking Buildbucket for the build status
[15:26:41.404] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:41.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:36:0) after 1m0s
[15:27:41.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:36:0)
[15:27:41.531] Timer tick, asking Buildbucket for the build status
[15:27:41.955] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:41.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:37:0) after 1m0s
[15:28:42.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:37:0)
[15:28:42.021] Timer tick, asking Buildbucket for the build status
[15:28:42.267] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:42.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:38:0) after 1m0s
[15:29:42.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:38:0)
[15:29:42.294] Timer tick, asking Buildbucket for the build status
[15:29:42.504] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:42.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:39:0) after 1m0s
[15:30:42.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:39:0)
[15:30:42.606] Timer tick, asking Buildbucket for the build status
[15:30:42.757] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:42.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:40:0) after 1m0s
[15:31:42.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:40:0)
[15:31:42.794] Timer tick, asking Buildbucket for the build status
[15:31:43.003] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:43.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:41:0) after 1m0s
[15:32:43.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:41:0)
[15:32:43.206] Timer tick, asking Buildbucket for the build status
[15:32:43.402] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:43.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:42:0) after 1m0s
[15:33:43.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:42:0)
[15:33:43.492] Timer tick, asking Buildbucket for the build status
[15:33:43.637] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:43.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:43:0) after 1m0s
[15:34:43.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:43:0)
[15:34:43.724] Timer tick, asking Buildbucket for the build status
[15:34:43.818] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:43.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:44:0) after 1m0s
[15:35:43.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:44:0)
[15:35:43.854] Timer tick, asking Buildbucket for the build status
[15:35:44.130] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:44.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:45:0) after 1m0s
[15:36:44.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:45:0)
[15:36:44.203] Timer tick, asking Buildbucket for the build status
[15:36:44.414] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:44.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:46:0) after 1m0s
[15:37:44.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:46:0)
[15:37:44.615] Timer tick, asking Buildbucket for the build status
[15:37:44.717] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:44.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:47:0) after 1m0s
[15:38:44.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:47:0)
[15:38:44.747] Timer tick, asking Buildbucket for the build status
[15:38:45.048] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:45.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:48:0) after 1m0s
[15:39:45.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:48:0)
[15:39:45.195] Timer tick, asking Buildbucket for the build status
[15:39:45.383] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:45.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:49:0) after 1m0s
[15:40:45.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:49:0)
[15:40:45.586] Timer tick, asking Buildbucket for the build status
[15:40:45.662] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:45.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:50:0) after 1m0s
[15:41:45.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:50:0)
[15:41:45.760] Timer tick, asking Buildbucket for the build status
[15:41:45.873] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:45.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:51:0) after 1m0s
[15:42:46.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:51:0)
[15:42:46.021] Timer tick, asking Buildbucket for the build status
[15:42:46.142] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:46.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:52:0) after 1m0s
[15:43:46.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:52:0)
[15:43:46.179] Timer tick, asking Buildbucket for the build status
[15:43:46.502] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:46.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:53:0) after 1m0s
[15:44:46.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:53:0)
[15:44:46.631] Timer tick, asking Buildbucket for the build status
[15:44:46.739] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:46.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:54:0) after 1m0s
[15:45:47.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:54:0)
[15:45:47.741] Timer tick, asking Buildbucket for the build status
[15:45:49.071] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:49.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:55:0) after 1m0s
[15:46:49.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:55:0)
[15:46:49.313] Timer tick, asking Buildbucket for the build status
[15:46:49.416] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:49.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:56:0) after 1m0s
[15:47:49.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:56:0)
[15:47:49.578] Timer tick, asking Buildbucket for the build status
[15:47:49.816] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:49.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:57:0) after 1m0s
[15:48:49.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:57:0)
[15:48:49.966] Timer tick, asking Buildbucket for the build status
[15:48:50.564] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:50.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:58:0) after 1m0s
[15:49:50.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:58:0)
[15:49:50.748] Timer tick, asking Buildbucket for the build status
[15:49:51.916] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:51.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:59:0) after 1m0s
[15:50:51.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:59:0)
[15:50:51.964] Timer tick, asking Buildbucket for the build status
[15:50:52.462] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:52.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:60:0) after 1m0s
[15:51:52.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:60:0)
[15:51:52.808] Timer tick, asking Buildbucket for the build status
[15:51:53.578] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:53.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:61:0) after 1m0s
[15:52:53.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:61:0)
[15:52:53.726] Timer tick, asking Buildbucket for the build status
[15:52:54.002] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:54.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:62:0) after 1m0s
[15:53:54.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:62:0)
[15:53:54.064] Timer tick, asking Buildbucket for the build status
[15:53:54.418] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:54.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:63:0) after 1m0s
[15:54:54.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:63:0)
[15:54:54.480] Timer tick, asking Buildbucket for the build status
[15:54:54.529] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:54.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:64:0) after 1m0s
[15:55:54.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:64:0)
[15:55:54.637] Timer tick, asking Buildbucket for the build status
[15:55:55.043] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:55.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:65:0) after 1m0s
[15:56:55.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:65:0)
[15:56:55.082] Timer tick, asking Buildbucket for the build status
[15:56:55.371] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:55.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:66:0) after 1m0s
[15:57:55.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:66:0)
[15:57:55.472] Timer tick, asking Buildbucket for the build status
[15:57:55.632] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:55.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:67:0) after 1m0s
[15:58:55.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:67:0)
[15:58:55.768] Timer tick, asking Buildbucket for the build status
[15:58:56.327] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:56.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:68:0) after 1m0s
[15:59:56.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:68:0)
[15:59:56.379] Timer tick, asking Buildbucket for the build status
[15:59:56.641] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:56.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:69:0) after 1m0s
[16:00:56.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:69:0)
[16:00:56.622] Timer tick, asking Buildbucket for the build status
[16:00:57.011] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:57.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:70:0) after 1m0s
[16:01:57.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:70:0)
[16:01:57.119] Timer tick, asking Buildbucket for the build status
[16:01:57.414] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:57.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:71:0) after 1m0s
[16:02:57.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:71:0)
[16:02:57.544] Timer tick, asking Buildbucket for the build status
[16:02:58.028] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:58.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:72:0) after 1m0s
[16:03:58.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:72:0)
[16:03:58.071] Timer tick, asking Buildbucket for the build status
[16:03:58.702] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:58.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:73:0) after 1m0s
[16:04:58.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:73:0)
[16:04:58.729] Timer tick, asking Buildbucket for the build status
[16:04:59.071] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:59.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:74:0) after 1m0s
[16:05:59.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:74:0)
[16:05:59.092] Timer tick, asking Buildbucket for the build status
[16:05:59.505] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:59.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:75:0) after 1m0s
[16:06:59.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:75:0)
[16:06:59.615] Timer tick, asking Buildbucket for the build status
[16:06:59.797] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:59.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:76:0) after 1m0s
[16:07:59.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:76:0)
[16:07:59.884] Timer tick, asking Buildbucket for the build status
[16:08:00.233] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:00.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:77:0) after 1m0s
[16:09:00.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:77:0)
[16:09:00.592] Timer tick, asking Buildbucket for the build status
[16:09:00.903] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:00.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:78:0) after 1m0s
[16:10:00.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:78:0)
[16:10:00.976] Timer tick, asking Buildbucket for the build status
[16:10:01.240] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:01.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:79:0) after 1m0s
[16:11:01.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:79:0)
[16:11:01.465] Timer tick, asking Buildbucket for the build status
[16:11:01.727] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:01.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:80:0) after 1m0s
[16:12:01.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:80:0)
[16:12:01.891] Timer tick, asking Buildbucket for the build status
[16:12:02.130] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:02.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:81:0) after 1m0s
[16:13:02.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:81:0)
[16:13:02.267] Timer tick, asking Buildbucket for the build status
[16:13:02.678] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:02.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:82:0) after 1m0s
[16:14:02.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:82:0)
[16:14:02.673] Timer tick, asking Buildbucket for the build status
[16:14:02.847] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:02.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:83:0) after 1m0s
[16:15:02.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:83:0)
[16:15:02.913] Timer tick, asking Buildbucket for the build status
[16:15:03.223] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:03.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:84:0) after 1m0s
[16:16:03.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:84:0)
[16:16:03.301] Timer tick, asking Buildbucket for the build status
[16:16:03.784] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:03.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:85:0) after 1m0s
[16:17:04.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:85:0)
[16:17:04.371] Timer tick, asking Buildbucket for the build status
[16:17:07.071] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:07.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:86:0) after 1m0s
[16:18:07.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:86:0)
[16:18:07.207] Timer tick, asking Buildbucket for the build status
[16:18:07.738] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:07.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:87:0) after 1m0s
[16:19:07.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:87:0)
[16:19:07.727] Timer tick, asking Buildbucket for the build status
[16:19:07.903] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:07.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:88:0) after 1m0s
[16:20:08.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:88:0)
[16:20:08.199] Timer tick, asking Buildbucket for the build status
[16:20:08.724] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:08.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:89:0) after 1m0s
[16:21:09.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:89:0)
[16:21:09.071] Timer tick, asking Buildbucket for the build status
[16:21:09.813] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:09.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:90:0) after 1m0s
[16:22:09.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:90:0)
[16:22:09.961] Timer tick, asking Buildbucket for the build status
[16:22:10.202] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:10.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:91:0) after 1m0s
[16:23:10.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:91:0)
[16:23:10.534] Timer tick, asking Buildbucket for the build status
[16:23:10.939] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:10.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:92:0) after 1m0s
[16:24:11.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:92:0)
[16:24:11.065] Timer tick, asking Buildbucket for the build status
[16:24:11.302] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:11.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:93:0) after 1m0s
[16:25:11.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:93:0)
[16:25:11.606] Timer tick, asking Buildbucket for the build status
[16:25:12.151] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:12.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:94:0) after 1m0s
[16:26:12.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:94:0)
[16:26:12.196] Timer tick, asking Buildbucket for the build status
[16:26:12.510] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:12.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:95:0) after 1m0s
[16:27:12.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:95:0)
[16:27:12.759] Timer tick, asking Buildbucket for the build status
[16:27:14.252] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:14.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:96:0) after 1m0s
[16:28:15.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:96:0)
[16:28:15.303] Timer tick, asking Buildbucket for the build status
[16:28:15.537] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:15.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:97:0) after 1m0s
[16:29:15.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:97:0)
[16:29:15.679] Timer tick, asking Buildbucket for the build status
[16:29:15.851] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:15.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:98:0) after 1m0s
[16:30:16.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:98:0)
[16:30:16.006] Timer tick, asking Buildbucket for the build status
[16:30:16.461] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:16.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:99:0) after 1m0s
[16:31:16.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:99:0)
[16:31:16.697] Timer tick, asking Buildbucket for the build status
[16:31:17.418] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:17.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:100:0) after 1m0s
[16:32:17.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:100:0)
[16:32:17.496] Timer tick, asking Buildbucket for the build status
[16:32:17.655] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:17.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:101:0) after 1m0s
[16:33:17.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:101:0)
[16:33:17.873] Timer tick, asking Buildbucket for the build status
[16:33:18.113] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:18.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:102:0) after 1m0s
[16:34:18.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:102:0)
[16:34:18.261] Timer tick, asking Buildbucket for the build status
[16:34:19.028] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:19.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:103:0) after 1m0s
[16:35:19.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:103:0)
[16:35:19.048] Timer tick, asking Buildbucket for the build status
[16:35:19.227] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:19.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:104:0) after 1m0s
[16:36:20.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:104:0)
[16:36:20.201] Timer tick, asking Buildbucket for the build status
[16:36:20.704] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:20.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:105:0) after 1m0s
[16:37:20.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:105:0)
[16:37:20.879] Timer tick, asking Buildbucket for the build status
[16:37:21.031] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:21.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:106:0) after 1m0s
[16:38:21.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:106:0)
[16:38:21.333] Timer tick, asking Buildbucket for the build status
[16:38:21.701] Build 8855673335711985040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:21.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021103397637531344:107:0) after 1m0s
[16:38:45.669] Received PubSub notification, asking Buildbucket for the build status
[16:38:46.210] Build 8855673335711985040: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:38:46.210] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612975124106162",
"created_by": "project:chromium",
"created_ts": "1612968807455615",
"failure_reason": "BUILD_FAILURE",
"id": "8855673335711985040",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9a6b98b40bf108d42848f3156130e53a972a0896\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ServiceWorker/ExecuteScriptApiTest.ExecuteScriptInFrame/0\"}, \"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\": \"9a6b98b40bf108d42848f3156130e53a972a0896\", \"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-3-02em\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17272, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"80308e20a5612bc107fa164fa0f848905e3a1bf1\", \"got_dawn_revision\": \"02fd17c75403a399672c65aa98e577c7c2ae9010\", \"got_nacl_revision\": \"4de55da44c4b511989359381e8b7a88a01fe0634\", \"got_revision\": \"9a6b98b40bf108d42848f3156130e53a972a0896\", \"got_revision_cp\": \"refs/heads/master@{#852626}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"f910730c400d8b5c879d021ddc11cabfc57ebf1a\", \"got_v8_revision_cp\": \"refs/heads/9.0.156@{#1}\", \"got_webrtc_revision\": \"ad8a00d25c91ba28ae987d994c362cb183b6e7ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33212}\", \"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\": \"9a6b98b40bf108d42848f3156130e53a972a0896\", \"swarm_hashes_refs/heads/master(at){#852626}_without_patch\": {\"absl_hardening_tests\": \"cde780505880b9584ebb74ef472c1418cbb17fc8\", \"accessibility_unittests\": \"af085247760c05bfab401653579f294db86798e8\", \"angle_unittests\": \"109e1271da96673e80ad46624e631e5a84fcc462\", \"app_shell_unittests\": \"31d4143c5d211f4b98d3df667d44980bdd0f8010\", \"aura_unittests\": \"9ffe1b63c7890aec2cdb035c50fc297ee2ebc49e\", \"base_unittests\": \"47a5ce0fee1188c7f46b766976fa6440952c4562\", \"base_util_unittests\": \"9f32f283778197d2a8b6ce464731c90218788625\", \"blink_common_unittests\": \"14769d8cab91debe18d2eabedfc7469c83c55bf7\", \"blink_fuzzer_unittests\": \"38047bcef3340fe84297db1909a2c4686a75efe1\", \"blink_heap_unittests\": \"9350c88dbf8d4c002ab04b54d93336a375c2b9df\", \"blink_platform_unittests\": \"6112ac1fb77985bd706b872c230a4b4d33c4d178\", \"blink_unittests\": \"def3bbe62457a139ebe5ff8801ee9072db6e3889\", \"boringssl_crypto_tests\": \"d90624676f3db78100b443984ba107cc920f211a\", \"boringssl_ssl_tests\": \"597157931946f87b7994f2ad5d184a757762fbc4\", \"browser_switcher_bho_unittests\": \"b6f422b71b6629bb4c66e6739feaa49406e3f204\", \"browser_tests\": \"bb6770df7f5d47f89d825ec46e66ec3cd2ac700b\", \"capture_unittests\": \"84a71370fb35b16422afacbcca8cb18a0b63b8a9\", \"cast_unittests\": \"dd80b02317a727ae1a120d9cc252c55c94556044\", \"cc_unittests\": \"77fd4cd24cd73060a9dd27a54429748162fa3edb\", \"chrome_app_unittests\": \"cacfe764fbb1e52354059b3010400a47df31f939\", \"chrome_cleaner_unittests\": \"b97604ad404aec02a6372fff56b6006b4bc893ff\", \"chrome_elf_unittests\": \"795db02f3774a9939c309b5c74a10767b12c1772\", \"chromedriver_unittests\": \"880019a3c5381ef816f21813025f142cb9241101\", \"components_browsertests\": \"f1ade8f205fad53c8144243682778c305530507c\", \"components_unittests\": \"ffef18849cb63e7188c52139c9bcf892ffef07b0\", \"compositor_unittests\": \"a7fa6a70f8934ffc14c772de59ca5dbf101cd582\", \"content_browsertests\": \"b4629da2a30ec26655dae0edc1e350b00a20e527\", \"content_unittests\": \"b42487b18a175dbc9f400d72eb2db78005d7193e\", \"courgette_unittests\": \"87d85228af83d1bf1bcdd71d51c1fe8214c3741b\", \"crashpad_tests\": \"eb01f8c7c025d418eb76644636ced3ac60d0eed7\", \"cronet_tests\": \"100245bf34b0b2ad19a19ba971e704caa418bb17\", \"cronet_unittests\": \"dfcd9180c231253f2e0982cd4e8f264f340c6946\", \"crypto_unittests\": \"c3309436aca2ec89414df9853da9184c3ba6c2b3\", \"delayloads_unittests\": \"651d3b43a2f1ef61341e9bd2801e46a9f39caae2\", \"device_unittests\": \"c0ac3ab715680b4f840bf65fc09557c359fcc8f8\", \"display_unittests\": \"d178cfe65bf5935f128d61c5c8cb20b7676b5dee\", \"elevation_service_unittests\": \"9ae4cca749328ad91872f7a8d2af711f6484331b\", \"events_unittests\": \"c93acccb38589901167ddc92266345adef670bb7\", \"extensions_browsertests\": \"2ccc9da6c4b666778fd7ac2632e583a0d2fb333d\", \"extensions_unittests\": \"a7a5bee46665def2c32d973b42a2afe3e273c5ea\", \"filesystem_service_unittests\": \"7d8034f3d349a142f8f7418405c0ce4ddb1295a4\", \"gcm_unit_tests\": \"01126bdd40f941ef1cef2b28f5c70169e8c20e88\", \"gcp_unittests\": \"080288c0a1b958074a5448b024bed2a613840645\", \"gfx_unittests\": \"e24ea03a9ff1109c5d385bf8979336a44d3b9f42\", \"gin_unittests\": \"f0f045e79d65c068c606b39fc7e2b14f8f83cf3d\", \"google_apis_unittests\": \"c70feba6ea0b8e79a9ea164187f1ac463cb1120e\", \"gpu_unittests\": \"578bd9736142e1a8340be6b0312c2aeac8413c3d\", \"gwp_asan_unittests\": \"4225b8cfea78af70d446fa4c68c44a56051fec90\", \"headless_browsertests\": \"0a429bc5df4c6290e0077efdf02970f839a46f01\", \"headless_unittests\": \"edcc0340758d9544637c87a2f591957a7a5133ce\", \"install_static_unittests\": \"a3f7e8ba5f8fa22e292690d1a00a44f78cfeb235\", \"installer_util_unittests\": \"88e456f9503d1bdb3ba6f9a1187968a10709114d\", \"interactive_ui_tests\": \"fc59d1511906a4f96c0efa0802845baca818a9a4\", \"ipc_tests\": \"f0f667edf5ed29ce95d9beee23081f131a745c82\", \"jingle_unittests\": \"c32362bdce4bbb5aaa2474170f06fbd44b8cbc8f\", \"latency_unittests\": \"06e67bed3c0a5ff70016793e51da94095898017b\", \"libjingle_xmpp_unittests\": \"babb2ceb09b31917767368f99bada755032bba53\", \"liburlpattern_unittests\": \"354c99e7dfebe901907a45a9e6b589c15dc2c6e7\", \"media_blink_unittests\": \"b1ed399ea6a7a7c0abfae1326373c8e6fbe88e92\", \"media_unittests\": \"cbc7790b2c84d6d8d8a23a3b1c5e6dcf5371cdf8\", \"message_center_unittests\": \"b1b1a433770c02856d6b7cdb07dbbe52e9d985b0\", \"midi_unittests\": \"743348fb8b27f75beb6f81ad5c77b0b2fdea2bd6\", \"mojo_core_unittests\": \"dd963342ae6149af81fa23fedd6e63fe7835798b\", \"mojo_unittests\": \"7ea2bd50391971a712b5ba71ef9efd639955aee5\", \"nacl_loader_unittests\": \"fb8d13292182addf525b99c2dcc2df6b1d7da757\", \"native_theme_unittests\": \"feb90fa79f78311db5029939b949e5a047559849\", \"net_unittests\": \"b9bfbd60e7e0b227ea11527085c3aca82756d767\", \"notification_helper_unittests\": \"db83e972f51e9b816c65fc2aac88362883ccda13\", \"pdf_unittests\": \"1005a124f236f802909624f37755b0309ec5ef40\", \"perfetto_unittests\": \"eebc7d2139c71047edaedd9251fc70f10eb8b509\", \"ppapi_unittests\": \"7d995da7d4fd2d8f7cb597826a18cac8a7914649\", \"printing_unittests\": \"f95b8f93526bc6e0d2abd2b0733845271b615833\", \"remoting_unittests\": \"af02fe05bc6849115cfc60f2353c2d36bcb614ae\", \"sbox_integration_tests\": \"49ad584bb00cfe7688476a9abd0d7b6323d8e70a\", \"sbox_unittests\": \"6eb9eb2345f9fe5074ed26518d97b3dcf8a05b8c\", \"sbox_validation_tests\": \"864e4ecce0d9ada67d09d9e9e53fcadadbc762b9\", \"service_manager_unittests\": \"050538e5856d07c9203b3928ffb7e0a64ae26ad6\", \"services_unittests\": \"86571fb4e86d103a4bd6f46287d419df206bfd19\", \"setup_unittests\": \"c7aef51dbeae9488240d7d3c959c555bef22ab99\", \"shell_dialogs_unittests\": \"ef5ec26b09c1b90bd4ed3fc003754874bd9d8119\", \"skia_unittests\": \"c449b1689405021defdfdc5843547a5cae33b4d9\", \"snapshot_unittests\": \"0475aca64c0080d75fa2fd196fb0fb97d664583a\", \"sql_unittests\": \"94c25398eaf5a8f0b5560ffd67160ba3b02cbc2d\", \"storage_unittests\": \"abac82ac2c318228623ebc4f585c66343908b8a4\", \"sync_integration_tests\": \"9085145539d11b914cf0c9fe80cd8cfab5120591\", \"traffic_annotation_auditor_unittests\": \"677f7ed3468bdfe20e6655b2fa885336f3c0fe7e\", \"ui_base_unittests\": \"d4314b85a54265beb69d11c004c59708e238e5e6\", \"ui_touch_selection_unittests\": \"3d58b2cb1363c9ee1e87c15c05c0728e9cf0dab1\", \"unit_tests\": \"0fb939943c827106cbfb473bc6e1e13fe0a0d42a\", \"updater_tests\": \"a62021fb51b274b06e4e99e61d9080cf2c907db7\", \"url_unittests\": \"49492a7ac79e909f81226e0d1d9b93bfd1daaa8e\", \"views_unittests\": \"c23d6d5c2b11198f775ebba924f82c6cef0462a1\", \"viz_unittests\": \"77e2c300b0f7b72cab19364ab0e9ac7c964ea98a\", \"vr_common_unittests\": \"0226ad1be842bf3c3711678482a35a9e9e71fbf8\", \"vr_pixeltests\": \"e5e4e51c56cba5c6bbbe565864b0b367d0e49b78\", \"weblayer_browsertests\": \"a226fc7c8581d061540e9a2e9e0dc6251a0b5c84\", \"weblayer_unittests\": \"b9c99b91d40fe9775c29b846f7b5cb09aea678e9\", \"wm_unittests\": \"91d9d634cdfdfc1aeceba44c8ea4666fbe9770f4\", \"wtf_unittests\": \"79e6ec4517b5815502bb3d535d08b7fc9b257ae8\", \"zlib_unittests\": \"bdac36073bc8d85d93e29e7e5a13df25956661e4\", \"zucchini_unittests\": \"7ac2340370852a787a26b21f5134c5e876c46937\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-02em\"], \"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\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ServiceWorker/ExecuteScriptApiTest.ExecuteScriptInFrame/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612968982657664",
"status": "COMPLETED",
"status_changed_ts": "1612968983203650",
"tags": [
"build_address:luci.chromium.ci/win-asan/17272",
"builder:win-asan",
"buildset:commit/git/9a6b98b40bf108d42848f3156130e53a972a0896",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a6b98b40bf108d42848f3156130e53a972a0896",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021103397637531344",
"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/8855673335711985040/+/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:51a54059a485b310",
"user_agent:luci-scheduler"
],
"updated_ts": "1612975124847168",
"url": "https://ci.chromium.org/b/8855673335711985040",
"utcnow_ts": "1612975126123418"
}
}
[16:38:46.210] Invocation finished in 1h45m19.9751232s with status FAILED