[21:06:45.666] New invocation is queued and will start shortly
[21:06:46.733] Starting the invocation (attempt 1)
[21:06:46.768] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:06:46.768] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:06:46.768] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020989313182733760",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5baf6ef980ff42d7a86821fce389aee91110c361\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020989313182733760",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5baf6ef980ff42d7a86821fce389aee91110c361",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5baf6ef980ff42d7a86821fce389aee91110c361",
"gitiles_ref:refs/heads/master"
]
}
[21:06:47.394] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613077606851549",
"id": "8855559251276949216",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5baf6ef980ff42d7a86821fce389aee91110c361\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613077607271156",
"tags": [
"build_address:luci.chromium.ci/win-asan/17288",
"builder:win-asan",
"buildset:commit/git/5baf6ef980ff42d7a86821fce389aee91110c361",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5baf6ef980ff42d7a86821fce389aee91110c361",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020989313182733760",
"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/8855559251276949216/+/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": "1613077607271272",
"url": "https://ci.chromium.org/b/8855559251276949216",
"utcnow_ts": "1613077607385218"
}
}
[21:06:47.394] Task URL: https://ci.chromium.org/b/8855559251276949216
[21:06:47.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:2:0) after 1m0s
[21:07:47.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:2:0)
[21:07:47.424] Timer tick, asking Buildbucket for the build status
[21:07:47.541] Build 8855559251276949216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:47.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:3:0) after 1m0s
[21:08:23.041] Received PubSub notification, asking Buildbucket for the build status
[21:08:23.224] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:47.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:3:0)
[21:08:47.557] Timer tick, asking Buildbucket for the build status
[21:08:47.628] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:47.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:5:0) after 1m0s
[21:09:47.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:5:0)
[21:09:47.721] Timer tick, asking Buildbucket for the build status
[21:09:47.826] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:47.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:6:0) after 1m0s
[21:10:47.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:6:0)
[21:10:47.911] Timer tick, asking Buildbucket for the build status
[21:10:48.024] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:48.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:7:0) after 1m0s
[21:11:48.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:7:0)
[21:11:48.031] Timer tick, asking Buildbucket for the build status
[21:11:48.330] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:48.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:8:0) after 1m0s
[21:12:48.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:8:0)
[21:12:48.426] Timer tick, asking Buildbucket for the build status
[21:12:48.528] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:48.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:9:0) after 1m0s
[21:13:48.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:9:0)
[21:13:48.556] Timer tick, asking Buildbucket for the build status
[21:13:48.612] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:48.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:10:0) after 1m0s
[21:14:48.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:10:0)
[21:14:48.633] Timer tick, asking Buildbucket for the build status
[21:14:48.679] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:48.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:11:0) after 1m0s
[21:15:48.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:11:0)
[21:15:48.741] Timer tick, asking Buildbucket for the build status
[21:15:48.798] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:48.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:12:0) after 1m0s
[21:16:48.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:12:0)
[21:16:48.853] Timer tick, asking Buildbucket for the build status
[21:16:48.990] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:48.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:13:0) after 1m0s
[21:17:49.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:13:0)
[21:17:49.014] Timer tick, asking Buildbucket for the build status
[21:17:49.319] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:49.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:14:0) after 1m0s
[21:18:49.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:14:0)
[21:18:49.368] Timer tick, asking Buildbucket for the build status
[21:18:49.631] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:49.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:15:0) after 1m0s
[21:19:49.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:15:0)
[21:19:49.676] Timer tick, asking Buildbucket for the build status
[21:19:49.815] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:49.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:16:0) after 1m0s
[21:20:49.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:16:0)
[21:20:49.835] Timer tick, asking Buildbucket for the build status
[21:20:49.918] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:49.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:17:0) after 1m0s
[21:21:49.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:17:0)
[21:21:49.939] Timer tick, asking Buildbucket for the build status
[21:21:50.048] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:50.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:18:0) after 1m0s
[21:22:50.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:18:0)
[21:22:50.115] Timer tick, asking Buildbucket for the build status
[21:22:50.241] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:50.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:19:0) after 1m0s
[21:23:50.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:19:0)
[21:23:50.293] Timer tick, asking Buildbucket for the build status
[21:23:50.472] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:50.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:20:0) after 1m0s
[21:24:50.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:20:0)
[21:24:50.577] Timer tick, asking Buildbucket for the build status
[21:24:50.791] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:50.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:21:0) after 1m0s
[21:25:50.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:21:0)
[21:25:50.842] Timer tick, asking Buildbucket for the build status
[21:25:51.845] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:51.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:22:0) after 1m0s
[21:26:51.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:22:0)
[21:26:51.894] Timer tick, asking Buildbucket for the build status
[21:26:51.992] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:51.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:23:0) after 1m0s
[21:27:52.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:23:0)
[21:27:52.023] Timer tick, asking Buildbucket for the build status
[21:27:52.115] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:52.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:24:0) after 1m0s
[21:28:52.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:24:0)
[21:28:52.155] Timer tick, asking Buildbucket for the build status
[21:28:52.291] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:52.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:25:0) after 1m0s
[21:29:52.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:25:0)
[21:29:52.317] Timer tick, asking Buildbucket for the build status
[21:29:52.512] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:52.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:26:0) after 1m0s
[21:30:52.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:26:0)
[21:30:52.520] Timer tick, asking Buildbucket for the build status
[21:30:52.720] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:52.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:27:0) after 1m0s
[21:31:52.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:27:0)
[21:31:52.751] Timer tick, asking Buildbucket for the build status
[21:31:52.969] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:52.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:28:0) after 1m0s
[21:32:53.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:28:0)
[21:32:53.069] Timer tick, asking Buildbucket for the build status
[21:32:53.343] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:53.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:29:0) after 1m0s
[21:33:53.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:29:0)
[21:33:53.394] Timer tick, asking Buildbucket for the build status
[21:33:54.130] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:54.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:30:0) after 1m0s
[21:34:54.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:30:0)
[21:34:54.201] Timer tick, asking Buildbucket for the build status
[21:34:54.327] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:54.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:31:0) after 1m0s
[21:35:54.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:31:0)
[21:35:54.357] Timer tick, asking Buildbucket for the build status
[21:35:54.425] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:54.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:32:0) after 1m0s
[21:36:54.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:32:0)
[21:36:54.442] Timer tick, asking Buildbucket for the build status
[21:36:54.758] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:54.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:33:0) after 1m0s
[21:37:54.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:33:0)
[21:37:54.783] Timer tick, asking Buildbucket for the build status
[21:37:55.186] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:55.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:34:0) after 1m0s
[21:38:55.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:34:0)
[21:38:55.256] Timer tick, asking Buildbucket for the build status
[21:38:55.427] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:55.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:35:0) after 1m0s
[21:39:55.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:35:0)
[21:39:55.509] Timer tick, asking Buildbucket for the build status
[21:39:55.575] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:55.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:36:0) after 1m0s
[21:40:55.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:36:0)
[21:40:55.658] Timer tick, asking Buildbucket for the build status
[21:40:55.755] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:55.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:37:0) after 1m0s
[21:41:55.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:37:0)
[21:41:55.779] Timer tick, asking Buildbucket for the build status
[21:41:55.870] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:55.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:38:0) after 1m0s
[21:42:55.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:38:0)
[21:42:55.917] Timer tick, asking Buildbucket for the build status
[21:42:55.973] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:55.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:39:0) after 1m0s
[21:43:56.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:39:0)
[21:43:56.098] Timer tick, asking Buildbucket for the build status
[21:43:56.179] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:56.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:40:0) after 1m0s
[21:44:56.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:40:0)
[21:44:56.203] Timer tick, asking Buildbucket for the build status
[21:44:56.260] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:56.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:41:0) after 1m0s
[21:45:56.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:41:0)
[21:45:56.439] Timer tick, asking Buildbucket for the build status
[21:45:56.492] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:56.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:42:0) after 1m0s
[21:46:56.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:42:0)
[21:46:56.554] Timer tick, asking Buildbucket for the build status
[21:46:56.999] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:56.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:43:0) after 1m0s
[21:47:57.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:43:0)
[21:47:57.020] Timer tick, asking Buildbucket for the build status
[21:47:57.137] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:57.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:44:0) after 1m0s
[21:48:57.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:44:0)
[21:48:57.148] Timer tick, asking Buildbucket for the build status
[21:48:57.339] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:57.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:45:0) after 1m0s
[21:49:57.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:45:0)
[21:49:57.401] Timer tick, asking Buildbucket for the build status
[21:49:57.610] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:57.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:46:0) after 1m0s
[21:50:57.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:46:0)
[21:50:57.647] Timer tick, asking Buildbucket for the build status
[21:50:57.742] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:57.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:47:0) after 1m0s
[21:51:57.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:47:0)
[21:51:57.987] Timer tick, asking Buildbucket for the build status
[21:51:58.260] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:58.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:48:0) after 1m0s
[21:52:58.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:48:0)
[21:52:58.272] Timer tick, asking Buildbucket for the build status
[21:52:58.559] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:58.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:49:0) after 1m0s
[21:53:58.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:49:0)
[21:53:58.619] Timer tick, asking Buildbucket for the build status
[21:53:58.897] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:58.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:50:0) after 1m0s
[21:54:59.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:50:0)
[21:54:59.009] Timer tick, asking Buildbucket for the build status
[21:54:59.544] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:59.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:51:0) after 1m0s
[21:55:59.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:51:0)
[21:55:59.598] Timer tick, asking Buildbucket for the build status
[21:55:59.662] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:59.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:52:0) after 1m0s
[21:56:59.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:52:0)
[21:56:59.706] Timer tick, asking Buildbucket for the build status
[21:56:59.851] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:59.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:53:0) after 1m0s
[21:57:59.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:53:0)
[21:57:59.977] Timer tick, asking Buildbucket for the build status
[21:58:00.149] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:00.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:54:0) after 1m0s
[21:59:00.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:54:0)
[21:59:00.126] Timer tick, asking Buildbucket for the build status
[21:59:00.223] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:00.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:55:0) after 1m0s
[22:00:00.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:55:0)
[22:00:00.224] Timer tick, asking Buildbucket for the build status
[22:00:00.304] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:00.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:56:0) after 1m0s
[22:01:01.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:56:0)
[22:01:01.296] Timer tick, asking Buildbucket for the build status
[22:01:01.563] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:01.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:57:0) after 1m0s
[22:02:01.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:57:0)
[22:02:01.664] Timer tick, asking Buildbucket for the build status
[22:02:02.234] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:02.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:58:0) after 1m0s
[22:03:02.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:58:0)
[22:03:02.268] Timer tick, asking Buildbucket for the build status
[22:03:02.900] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:02.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:59:0) after 1m0s
[22:04:02.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:59:0)
[22:04:02.933] Timer tick, asking Buildbucket for the build status
[22:04:03.131] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:03.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:60:0) after 1m0s
[22:05:03.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:60:0)
[22:05:03.228] Timer tick, asking Buildbucket for the build status
[22:05:03.328] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:03.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:61:0) after 1m0s
[22:06:03.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:61:0)
[22:06:03.402] Timer tick, asking Buildbucket for the build status
[22:06:03.784] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:03.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:62:0) after 1m0s
[22:07:03.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:62:0)
[22:07:03.806] Timer tick, asking Buildbucket for the build status
[22:07:03.911] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:03.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:63:0) after 1m0s
[22:08:03.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:63:0)
[22:08:03.950] Timer tick, asking Buildbucket for the build status
[22:08:04.050] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:04.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:64:0) after 1m0s
[22:09:04.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:64:0)
[22:09:04.080] Timer tick, asking Buildbucket for the build status
[22:09:04.210] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:04.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:65:0) after 1m0s
[22:10:04.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:65:0)
[22:10:04.235] Timer tick, asking Buildbucket for the build status
[22:10:04.499] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:04.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:66:0) after 1m0s
[22:11:04.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:66:0)
[22:11:04.549] Timer tick, asking Buildbucket for the build status
[22:11:04.723] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:04.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:67:0) after 1m0s
[22:12:04.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:67:0)
[22:12:04.744] Timer tick, asking Buildbucket for the build status
[22:12:05.046] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:05.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:68:0) after 1m0s
[22:13:05.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:68:0)
[22:13:05.102] Timer tick, asking Buildbucket for the build status
[22:13:05.215] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:05.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:69:0) after 1m0s
[22:14:05.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:69:0)
[22:14:05.360] Timer tick, asking Buildbucket for the build status
[22:14:05.432] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:05.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:70:0) after 1m0s
[22:15:05.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:70:0)
[22:15:05.490] Timer tick, asking Buildbucket for the build status
[22:15:05.704] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:05.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:71:0) after 1m0s
[22:16:05.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:71:0)
[22:16:05.800] Timer tick, asking Buildbucket for the build status
[22:16:05.912] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:05.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:72:0) after 1m0s
[22:17:05.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:72:0)
[22:17:05.945] Timer tick, asking Buildbucket for the build status
[22:17:06.686] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:06.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:73:0) after 1m0s
[22:18:06.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:73:0)
[22:18:06.815] Timer tick, asking Buildbucket for the build status
[22:18:06.921] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:06.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:74:0) after 1m0s
[22:19:07.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:74:0)
[22:19:07.058] Timer tick, asking Buildbucket for the build status
[22:19:07.229] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:07.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:75:0) after 1m0s
[22:20:07.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:75:0)
[22:20:07.315] Timer tick, asking Buildbucket for the build status
[22:20:07.365] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:07.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:76:0) after 1m0s
[22:21:07.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:76:0)
[22:21:07.531] Timer tick, asking Buildbucket for the build status
[22:21:07.725] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:07.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:77:0) after 1m0s
[22:22:07.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:77:0)
[22:22:07.824] Timer tick, asking Buildbucket for the build status
[22:22:07.963] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:07.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:78:0) after 1m0s
[22:23:08.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:78:0)
[22:23:08.056] Timer tick, asking Buildbucket for the build status
[22:23:08.830] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:08.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:79:0) after 1m0s
[22:24:08.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:79:0)
[22:24:08.816] Timer tick, asking Buildbucket for the build status
[22:24:08.885] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:08.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:80:0) after 1m0s
[22:25:08.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:80:0)
[22:25:08.991] Timer tick, asking Buildbucket for the build status
[22:25:09.537] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:09.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:81:0) after 1m0s
[22:26:09.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:81:0)
[22:26:09.628] Timer tick, asking Buildbucket for the build status
[22:26:10.639] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:10.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:82:0) after 1m0s
[22:27:10.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:82:0)
[22:27:10.817] Timer tick, asking Buildbucket for the build status
[22:27:11.155] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:11.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:83:0) after 1m0s
[22:28:11.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:83:0)
[22:28:11.178] Timer tick, asking Buildbucket for the build status
[22:28:11.569] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:11.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:84:0) after 1m0s
[22:29:11.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:84:0)
[22:29:11.623] Timer tick, asking Buildbucket for the build status
[22:29:12.201] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:12.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:85:0) after 1m0s
[22:30:12.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:85:0)
[22:30:12.253] Timer tick, asking Buildbucket for the build status
[22:30:12.653] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:12.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:86:0) after 1m0s
[22:31:12.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:86:0)
[22:31:12.673] Timer tick, asking Buildbucket for the build status
[22:31:13.140] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:13.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:87:0) after 1m0s
[22:32:13.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:87:0)
[22:32:13.205] Timer tick, asking Buildbucket for the build status
[22:32:13.313] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:13.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:88:0) after 1m0s
[22:33:13.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:88:0)
[22:33:13.362] Timer tick, asking Buildbucket for the build status
[22:33:13.454] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:13.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:89:0) after 1m0s
[22:34:13.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:89:0)
[22:34:13.546] Timer tick, asking Buildbucket for the build status
[22:34:13.699] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:13.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:90:0) after 1m0s
[22:35:13.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:90:0)
[22:35:13.746] Timer tick, asking Buildbucket for the build status
[22:35:13.858] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:13.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:91:0) after 1m0s
[22:36:13.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:91:0)
[22:36:13.934] Timer tick, asking Buildbucket for the build status
[22:36:14.053] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:14.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:92:0) after 1m0s
[22:37:14.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:92:0)
[22:37:14.062] Timer tick, asking Buildbucket for the build status
[22:37:14.235] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:14.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:93:0) after 1m0s
[22:38:14.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:93:0)
[22:38:14.300] Timer tick, asking Buildbucket for the build status
[22:38:14.504] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:14.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:94:0) after 1m0s
[22:39:14.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:94:0)
[22:39:14.577] Timer tick, asking Buildbucket for the build status
[22:39:14.814] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:14.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:95:0) after 1m0s
[22:40:14.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:95:0)
[22:40:14.886] Timer tick, asking Buildbucket for the build status
[22:40:15.115] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:15.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:96:0) after 1m0s
[22:41:15.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:96:0)
[22:41:15.279] Timer tick, asking Buildbucket for the build status
[22:41:15.576] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:15.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:97:0) after 1m0s
[22:42:15.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:97:0)
[22:42:15.636] Timer tick, asking Buildbucket for the build status
[22:42:15.753] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:15.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:98:0) after 1m0s
[22:43:15.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:98:0)
[22:43:15.741] Timer tick, asking Buildbucket for the build status
[22:43:16.132] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:16.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:99:0) after 1m0s
[22:44:16.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:99:0)
[22:44:16.213] Timer tick, asking Buildbucket for the build status
[22:44:16.444] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:16.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:100:0) after 1m0s
[22:45:16.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:100:0)
[22:45:16.518] Timer tick, asking Buildbucket for the build status
[22:45:16.602] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:16.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:101:0) after 1m0s
[22:46:16.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:101:0)
[22:46:16.670] Timer tick, asking Buildbucket for the build status
[22:46:16.777] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:16.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:102:0) after 1m0s
[22:47:16.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:102:0)
[22:47:16.914] Timer tick, asking Buildbucket for the build status
[22:47:17.038] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:17.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:103:0) after 1m0s
[22:48:17.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:103:0)
[22:48:17.072] Timer tick, asking Buildbucket for the build status
[22:48:17.412] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:17.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:104:0) after 1m0s
[22:49:17.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:104:0)
[22:49:17.462] Timer tick, asking Buildbucket for the build status
[22:49:17.581] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:17.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:105:0) after 1m0s
[22:50:17.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:105:0)
[22:50:17.674] Timer tick, asking Buildbucket for the build status
[22:50:18.240] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:18.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:106:0) after 1m0s
[22:51:18.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:106:0)
[22:51:18.345] Timer tick, asking Buildbucket for the build status
[22:51:18.434] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:18.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:107:0) after 1m0s
[22:52:18.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:107:0)
[22:52:18.460] Timer tick, asking Buildbucket for the build status
[22:52:18.573] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:18.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:108:0) after 1m0s
[22:53:18.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:108:0)
[22:53:18.696] Timer tick, asking Buildbucket for the build status
[22:53:18.847] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:18.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:109:0) after 1m0s
[22:54:19.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:109:0)
[22:54:19.017] Timer tick, asking Buildbucket for the build status
[22:54:19.254] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:19.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:110:0) after 1m0s
[22:55:19.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:110:0)
[22:55:19.384] Timer tick, asking Buildbucket for the build status
[22:55:19.795] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:19.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:111:0) after 1m0s
[22:56:19.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:111:0)
[22:56:19.814] Timer tick, asking Buildbucket for the build status
[22:56:19.991] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:19.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:112:0) after 1m0s
[22:57:20.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:112:0)
[22:57:20.068] Timer tick, asking Buildbucket for the build status
[22:57:20.221] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:20.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:113:0) after 1m0s
[22:58:20.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:113:0)
[22:58:20.253] Timer tick, asking Buildbucket for the build status
[22:58:20.357] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:20.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:114:0) after 1m0s
[22:59:20.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:114:0)
[22:59:20.414] Timer tick, asking Buildbucket for the build status
[22:59:20.567] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:20.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:115:0) after 1m0s
[23:00:20.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:115:0)
[23:00:20.617] Timer tick, asking Buildbucket for the build status
[23:00:20.973] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:20.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:116:0) after 1m0s
[23:01:21.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:116:0)
[23:01:21.022] Timer tick, asking Buildbucket for the build status
[23:01:21.442] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:21.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:117:0) after 1m0s
[23:02:21.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:117:0)
[23:02:21.424] Timer tick, asking Buildbucket for the build status
[23:02:21.599] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:21.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:118:0) after 1m0s
[23:03:21.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:118:0)
[23:03:21.648] Timer tick, asking Buildbucket for the build status
[23:03:21.728] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:21.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:119:0) after 1m0s
[23:04:21.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:119:0)
[23:04:21.789] Timer tick, asking Buildbucket for the build status
[23:04:21.959] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:21.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:120:0) after 1m0s
[23:05:22.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:120:0)
[23:05:22.022] Timer tick, asking Buildbucket for the build status
[23:05:22.135] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:22.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:121:0) after 1m0s
[23:06:22.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:121:0)
[23:06:22.193] Timer tick, asking Buildbucket for the build status
[23:06:22.340] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:22.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:122:0) after 1m0s
[23:07:22.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:122:0)
[23:07:22.484] Timer tick, asking Buildbucket for the build status
[23:07:22.617] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:22.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:123:0) after 1m0s
[23:08:22.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:123:0)
[23:08:22.683] Timer tick, asking Buildbucket for the build status
[23:08:22.793] Build 8855559251276949216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:22.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020989313182733760:124:0) after 1m0s
[23:08:36.828] Received PubSub notification, asking Buildbucket for the build status
[23:08:53.493] Build 8855559251276949216: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:08:53.493] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613084916258625",
"created_by": "project:chromium",
"created_ts": "1613077606851549",
"failure_reason": "BUILD_FAILURE",
"id": "8855559251276949216",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5baf6ef980ff42d7a86821fce389aee91110c361\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PersistentBackground/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\": \"5baf6ef980ff42d7a86821fce389aee91110c361\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-1-ckf5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17288, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e9f42997b213f1b287aa31698e22b2bca6976853\", \"got_nacl_revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"got_revision\": \"5baf6ef980ff42d7a86821fce389aee91110c361\", \"got_revision_cp\": \"refs/heads/master@{#853227}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"00ea60a40e748000e049a0551eb8d3f46a0db714\", \"got_v8_revision_cp\": \"refs/heads/9.0.165@{#1}\", \"got_webrtc_revision\": \"f28d7e89b7a3c985d83f59d16b9f3c3f45ef7997\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33224}\", \"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\": \"5baf6ef980ff42d7a86821fce389aee91110c361\", \"swarm_hashes_refs/heads/master(at){#853227}_without_patch\": {\"absl_hardening_tests\": \"bbd8a0b8802a5f7c1737c6c3f89cd33a3e9be78a\", \"accessibility_unittests\": \"42c7d74b343260abb14126cb236b6fcb49b217c8\", \"angle_unittests\": \"d9b9bf8938f8c127265736dd1e26c83141f7513f\", \"app_shell_unittests\": \"20c96227d088af14158c29587c4c92fd03254f16\", \"aura_unittests\": \"d928be8b52c5a7a8297ccb9575c9b8b82ef3c7c5\", \"base_unittests\": \"ade3cedd89736e97d427d2c1810381af3dcc3e66\", \"base_util_unittests\": \"f612041255580ea03100d3e16774b5fd5cccb4b7\", \"blink_common_unittests\": \"555f99b2719d557a7bd0c8135605bc6f4f106551\", \"blink_fuzzer_unittests\": \"63b1558f92e8c276cb42c44c6fcadfbe89ac57ff\", \"blink_heap_unittests\": \"610af51bb78a675085a93e50ed8612128f016394\", \"blink_platform_unittests\": \"564f5bf8eb054e68960aedb571b88b6db8ff21a1\", \"blink_unittests\": \"352fe5ef4402c66e9f192e2d5d0cd624adb04e38\", \"boringssl_crypto_tests\": \"845490150db0602155af4c7a7ca1b42979f518c0\", \"boringssl_ssl_tests\": \"05f1f9616697ee7788ea47a280c4b1b26bbed0d3\", \"browser_switcher_bho_unittests\": \"737c63e4722ac77edb67b02d6921a5e158398ef7\", \"browser_tests\": \"133089dbcb0b84ccf8c057070d390ed9de36bfa4\", \"capture_unittests\": \"19423f549c04b928f9d76c315115a9d46301d069\", \"cast_unittests\": \"932a257c6c96260ac02441af8732c8a7c51d6cb8\", \"cc_unittests\": \"2ee91ad28ce7bbde20ba9080f1e5292ab5c7a742\", \"chrome_app_unittests\": \"48103ff6ecab6aa3e5e9321af29ebcbd04da6021\", \"chrome_cleaner_unittests\": \"d1796654605a5c538955aa7482b80d5251122bc1\", \"chrome_elf_unittests\": \"34d669abcae282732584a49a4b99a44f3bcfbb19\", \"chromedriver_unittests\": \"85e69e84180cf95821f79dca667cde5350a3e5b7\", \"components_browsertests\": \"ba9300f7d1558af18171d4b866740bdb1ccb2a5f\", \"components_unittests\": \"492745a8479437ad26615e2a3f50a84a051a108c\", \"compositor_unittests\": \"298ffee7c1af12f4e4e9c98d8afc685afca4d349\", \"content_browsertests\": \"67b8015522af62bb90aeef5e57e2d860529ac4c2\", \"content_unittests\": \"c70f96a3f0e9addfb74e6fdba1636a9b96b52ad6\", \"courgette_unittests\": \"a2376c8e2e610c0a37ef08b9168978d23768e378\", \"crashpad_tests\": \"f164e5bbb45e1617ce5c2fa573fed3775a4048f7\", \"cronet_tests\": \"0a74126ce1ac6098ff2c4afe78e3646cc85971ce\", \"cronet_unittests\": \"a626390fc19bacd35899b9cc50aef90e3db02326\", \"crypto_unittests\": \"f56d32ca024db303c08fd33fd5aac186bd064d43\", \"delayloads_unittests\": \"d422cc0f0164102b3fd5ba4d950f03c27680b3ba\", \"device_unittests\": \"c03485386b95693ae8b9b5b66e915e1b1d54047a\", \"display_unittests\": \"055a469c44ba9d9ae9abe9e912d356131a38f096\", \"elevation_service_unittests\": \"883c063c03b07e02f220dd7e53ebad9a61c408d1\", \"events_unittests\": \"7072c15060e1c63e800f0f161a27802e9de93543\", \"extensions_browsertests\": \"6ee5deb655345e45cbe5e1c4b9d2e8cf33352952\", \"extensions_unittests\": \"c191753b6064bb97dc2d2179cb06684c4e94a2d6\", \"filesystem_service_unittests\": \"39ba166466f626e63429428ae6078dd8fdbbcceb\", \"gcm_unit_tests\": \"d7c68c8f46139d76cea4aacfdd2abf723465fc82\", \"gcp_unittests\": \"dd1a9a62812146fb6e6fe13fefdc27282aad94c9\", \"gfx_unittests\": \"248c06ffcf07b520e46baf48ca4e3e1c24b10ac0\", \"gin_unittests\": \"949264cc4ebf971755f148b60a60754f8501ca1e\", \"google_apis_unittests\": \"84bcf286b3f350710fe133498171479b6b7a27f7\", \"gpu_unittests\": \"c6b9d1ae1975ad9696153b29a213f15d77fb4c06\", \"gwp_asan_unittests\": \"844e8f7eaedbe15e6b74f70e63c97c93ec9ed6cc\", \"headless_browsertests\": \"032cd0fd672c578796a648eb751d8e9e2b86f653\", \"headless_unittests\": \"1427e94eb04dcecb725db5c1008e9d534d2e3e45\", \"install_static_unittests\": \"2be0954a28db40e27f818f4c4dfe021e05cdf0ac\", \"installer_util_unittests\": \"3ebf429aa2cb259fd0b943a387ec3829e579d084\", \"interactive_ui_tests\": \"f9325791e60016d993d18232697ee46376cf7d58\", \"ipc_tests\": \"e94913d4b1ae61a2be7f790f0505214bf201df9c\", \"jingle_unittests\": \"315484a8da5c7b876e5b84c576c456ef0f80ad16\", \"latency_unittests\": \"fef1915713ec24ded105e70b25116c8d67b03a16\", \"libjingle_xmpp_unittests\": \"19a8f499dc7803491317ea2e9541a40be9e2697e\", \"liburlpattern_unittests\": \"a27808193af1645124894b12c20264fd3e71916c\", \"media_blink_unittests\": \"d4447de105c765b2deeb861e7aabf201fada560b\", \"media_unittests\": \"cd5ff9aa6f753c244839a92ad72bf74b95764d0a\", \"message_center_unittests\": \"11d5f65f6869f0f1bc803692535bbb3e9e6d31e8\", \"midi_unittests\": \"a6fdb67900d8079f7d6fc908ee0aee8238b223a6\", \"mojo_core_unittests\": \"3c299b39069940f85db7b4ba575b3765181bce26\", \"mojo_unittests\": \"5b4abd1d57c51b0e0450bc50a513ecf564d3e04d\", \"nacl_loader_unittests\": \"eea187436020c1ba9432787a4c4e34712136a0f1\", \"native_theme_unittests\": \"6c28ce1623717d41613b0cffada52059ba27d830\", \"net_unittests\": \"d399c3fe7fc28c9b50c81bb0677165b83ca601eb\", \"notification_helper_unittests\": \"6a004b5071373f012e04d4d85fdfca2956350f0b\", \"pdf_unittests\": \"4f1b1dd7a19d26170b6087ab1ff398dea168a9e6\", \"perfetto_unittests\": \"75f8f7c22fc3c56c7c3d19b25d38502031327555\", \"ppapi_unittests\": \"03892f16dc1dade084e1451cc280de8dc5e11376\", \"printing_unittests\": \"7cfbcc9dfe94c56df456c2c30fb083f0dc785d4b\", \"remoting_unittests\": \"2260c973c281bebf29df7553a5351a4940cad01a\", \"sbox_integration_tests\": \"7ad0cf01e9b8734c5614934d0ece8b7a0732660f\", \"sbox_unittests\": \"eeda30004807a8a858fbd235b55ca6f91ee4c8e3\", \"sbox_validation_tests\": \"ccee402a04e27d2f7cbc0206af4e001024531db9\", \"service_manager_unittests\": \"688823ada96c3031573ce079505aa905e06ff769\", \"services_unittests\": \"ce9580cfd1adfcbfbdad433c89b557ac7f4a28a3\", \"setup_unittests\": \"8d6a0d970a03e4172351660bde2b4ff856f7f870\", \"shell_dialogs_unittests\": \"2f9765cb9e9947da8eec844ba431977ae52a5358\", \"skia_unittests\": \"5668cfb6c73d99214cb86b908ed4229b2012c495\", \"snapshot_unittests\": \"7155163635ebfd35837194c3aea0fe48aea48b94\", \"sql_unittests\": \"3a352052b4d7f1fbf96b8fdbbbed996be655498e\", \"storage_unittests\": \"96d56e3fc2ba516ce1c4b2c696ad3e1a92169759\", \"sync_integration_tests\": \"483f92746032b970a9ae228c8afa72dade166856\", \"traffic_annotation_auditor_unittests\": \"79b1800c5f384cac913162125e58cc66adfc47c5\", \"ui_base_unittests\": \"75163ce3ef943bdcd0818283852fa0ee8bff46d0\", \"ui_touch_selection_unittests\": \"86f6df57e647e97c62c00b47f56fb05ce8d240e9\", \"unit_tests\": \"c4bebc17806aee3fd18df70520c172e633b0804d\", \"updater_tests\": \"fc21e5f262c2453822753ec3bf0176df71048368\", \"url_unittests\": \"d72a4573d1a3f68bc2817bfb21fb2a80f534dd1d\", \"views_unittests\": \"3de366d467385cfba3f3cfaed9efa477ec918cdd\", \"viz_unittests\": \"2a78e6634ac9392ee743d7e501691689a351932c\", \"vr_common_unittests\": \"36ad36f894775525b4630609dadf847fc5b91b40\", \"vr_pixeltests\": \"53bb5f4cc71535e649d5422cbc8dc219f171bc7b\", \"weblayer_browsertests\": \"9b72ff071f8d0a3b2f85acb4d971c8879ead8771\", \"weblayer_unittests\": \"eda7bb5b99f650780a8d9a8036cf34fe35877ba3\", \"wm_unittests\": \"8c8b5675a6df63aabe8e436f11e3127cbf5393fc\", \"wtf_unittests\": \"26d401c23646c6ec6d60df542b04558faa9b8bc9\", \"zlib_unittests\": \"f9d9b855b27b579d0865b6a4abbd8ab35eef3b1e\", \"zucchini_unittests\": \"67181ce86a19b43e05a2422020c5d1d86cc73585\"}}, \"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-1-ckf5\"], \"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- PersistentBackground/ExecuteScriptApiTest.ExecuteScriptInFrame/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613077702383705",
"status": "COMPLETED",
"status_changed_ts": "1613077702762362",
"tags": [
"build_address:luci.chromium.ci/win-asan/17288",
"builder:win-asan",
"buildset:commit/git/5baf6ef980ff42d7a86821fce389aee91110c361",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5baf6ef980ff42d7a86821fce389aee91110c361",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020989313182733760",
"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/8855559251276949216/+/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:51abbc7d686fc710",
"user_agent:luci-scheduler"
],
"updated_ts": "1613084916582437",
"url": "https://ci.chromium.org/b/8855559251276949216",
"utcnow_ts": "1613084933483485"
}
}
[23:08:53.493] Invocation finished in 2h2m7.842451192s with status FAILED