[13:24:37.616] New invocation is queued and will start shortly
[13:24:38.782] Starting the invocation (attempt 1)
[13:24:38.820] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:24:38.820] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:24:38.820] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017938091292087840",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"927191cbf902ca1b73bccb061cbe279d290bf20b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9017938091292087840",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/927191cbf902ca1b73bccb061cbe279d290bf20b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/927191cbf902ca1b73bccb061cbe279d290bf20b",
"gitiles_ref:refs/heads/master"
]
}
[13:24:40.194] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615987478929616",
"id": "8852508029252905168",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"927191cbf902ca1b73bccb061cbe279d290bf20b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615987479826959",
"tags": [
"build_address:luci.chromium.ci/win-asan/17835",
"builder:win-asan",
"buildset:commit/git/927191cbf902ca1b73bccb061cbe279d290bf20b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/927191cbf902ca1b73bccb061cbe279d290bf20b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017938091292087840",
"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/8852508029252905168/+/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": "1615987479827072",
"url": "https://ci.chromium.org/b/8852508029252905168",
"utcnow_ts": "1615987480178303"
}
}
[13:24:40.194] Task URL: https://ci.chromium.org/b/8852508029252905168
[13:24:40.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:2:0) after 1m0s
[13:25:40.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:2:0)
[13:25:40.167] Timer tick, asking Buildbucket for the build status
[13:25:40.371] Build 8852508029252905168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:25:40.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:3:0) after 1m0s
[13:26:40.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:3:0)
[13:26:40.465] Timer tick, asking Buildbucket for the build status
[13:26:40.642] Build 8852508029252905168: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:26:40.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:4:0) after 1m0s
[13:27:12.882] Received PubSub notification, asking Buildbucket for the build status
[13:27:13.351] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:40.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:4:0)
[13:27:40.676] Timer tick, asking Buildbucket for the build status
[13:27:40.735] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:40.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:6:0) after 1m0s
[13:28:40.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:6:0)
[13:28:40.778] Timer tick, asking Buildbucket for the build status
[13:28:41.060] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:41.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:7:0) after 1m0s
[13:29:41.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:7:0)
[13:29:41.136] Timer tick, asking Buildbucket for the build status
[13:29:41.205] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:41.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:8:0) after 1m0s
[13:30:41.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:8:0)
[13:30:41.245] Timer tick, asking Buildbucket for the build status
[13:30:41.310] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:41.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:9:0) after 1m0s
[13:31:41.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:9:0)
[13:31:41.410] Timer tick, asking Buildbucket for the build status
[13:31:41.654] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:41.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:10:0) after 1m0s
[13:32:41.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:10:0)
[13:32:41.719] Timer tick, asking Buildbucket for the build status
[13:32:41.947] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:41.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:11:0) after 1m0s
[13:33:41.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:11:0)
[13:33:41.954] Timer tick, asking Buildbucket for the build status
[13:33:42.020] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:42.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:12:0) after 1m0s
[13:34:42.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:12:0)
[13:34:42.220] Timer tick, asking Buildbucket for the build status
[13:34:42.440] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:42.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:13:0) after 1m0s
[13:35:42.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:13:0)
[13:35:42.496] Timer tick, asking Buildbucket for the build status
[13:35:42.579] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:42.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:14:0) after 1m0s
[13:36:42.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:14:0)
[13:36:42.632] Timer tick, asking Buildbucket for the build status
[13:36:42.697] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:42.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:15:0) after 1m0s
[13:37:42.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:15:0)
[13:37:42.841] Timer tick, asking Buildbucket for the build status
[13:37:42.911] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:42.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:16:0) after 1m0s
[13:38:43.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:16:0)
[13:38:43.021] Timer tick, asking Buildbucket for the build status
[13:38:43.544] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:43.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:17:0) after 1m0s
[13:39:43.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:17:0)
[13:39:43.566] Timer tick, asking Buildbucket for the build status
[13:39:43.851] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:43.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:18:0) after 1m0s
[13:40:43.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:18:0)
[13:40:43.882] Timer tick, asking Buildbucket for the build status
[13:40:44.091] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:44.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:19:0) after 1m0s
[13:41:44.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:19:0)
[13:41:44.204] Timer tick, asking Buildbucket for the build status
[13:41:44.308] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:44.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:20:0) after 1m0s
[13:42:44.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:20:0)
[13:42:44.340] Timer tick, asking Buildbucket for the build status
[13:42:44.404] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:44.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:21:0) after 1m0s
[13:43:44.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:21:0)
[13:43:44.587] Timer tick, asking Buildbucket for the build status
[13:43:44.694] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:44.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:22:0) after 1m0s
[13:44:44.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:22:0)
[13:44:44.694] Timer tick, asking Buildbucket for the build status
[13:44:44.773] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:44.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:23:0) after 1m0s
[13:45:45.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:23:0)
[13:45:45.088] Timer tick, asking Buildbucket for the build status
[13:45:45.160] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:45.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:24:0) after 1m0s
[13:46:45.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:24:0)
[13:46:45.271] Timer tick, asking Buildbucket for the build status
[13:46:45.332] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:45.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:25:0) after 1m0s
[13:47:45.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:25:0)
[13:47:45.466] Timer tick, asking Buildbucket for the build status
[13:47:45.551] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:45.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:26:0) after 1m0s
[13:48:45.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:26:0)
[13:48:45.697] Timer tick, asking Buildbucket for the build status
[13:48:45.790] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:45.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:27:0) after 1m0s
[13:49:46.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:27:0)
[13:49:46.348] Timer tick, asking Buildbucket for the build status
[13:49:47.084] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:47.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:28:0) after 1m0s
[13:50:47.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:28:0)
[13:50:47.091] Timer tick, asking Buildbucket for the build status
[13:50:47.163] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:47.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:29:0) after 1m0s
[13:51:47.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:29:0)
[13:51:47.396] Timer tick, asking Buildbucket for the build status
[13:51:47.455] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:47.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:30:0) after 1m0s
[13:52:47.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:30:0)
[13:52:47.833] Timer tick, asking Buildbucket for the build status
[13:52:47.923] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:47.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:31:0) after 1m0s
[13:53:48.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:31:0)
[13:53:48.110] Timer tick, asking Buildbucket for the build status
[13:53:48.183] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:48.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:32:0) after 1m0s
[13:54:48.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:32:0)
[13:54:48.269] Timer tick, asking Buildbucket for the build status
[13:54:48.392] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:48.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:33:0) after 1m0s
[13:55:48.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:33:0)
[13:55:48.388] Timer tick, asking Buildbucket for the build status
[13:55:48.459] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:48.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:34:0) after 1m0s
[13:56:49.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:34:0)
[13:56:49.622] Timer tick, asking Buildbucket for the build status
[13:56:49.967] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:49.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:35:0) after 1m0s
[13:57:50.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:35:0)
[13:57:50.190] Timer tick, asking Buildbucket for the build status
[13:57:50.327] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:50.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:36:0) after 1m0s
[13:58:50.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:36:0)
[13:58:50.513] Timer tick, asking Buildbucket for the build status
[13:58:50.596] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:50.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:37:0) after 1m0s
[13:59:50.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:37:0)
[13:59:50.776] Timer tick, asking Buildbucket for the build status
[13:59:50.853] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:50.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:38:0) after 1m0s
[14:00:51.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:38:0)
[14:00:51.035] Timer tick, asking Buildbucket for the build status
[14:00:51.209] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:51.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:39:0) after 1m0s
[14:01:51.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:39:0)
[14:01:51.317] Timer tick, asking Buildbucket for the build status
[14:01:51.415] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:51.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:40:0) after 1m0s
[14:02:51.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:40:0)
[14:02:51.461] Timer tick, asking Buildbucket for the build status
[14:02:51.546] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:51.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:41:0) after 1m0s
[14:03:51.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:41:0)
[14:03:51.582] Timer tick, asking Buildbucket for the build status
[14:03:51.696] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:51.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:42:0) after 1m0s
[14:04:51.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:42:0)
[14:04:51.755] Timer tick, asking Buildbucket for the build status
[14:04:51.895] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:51.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:43:0) after 1m0s
[14:05:51.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:43:0)
[14:05:51.985] Timer tick, asking Buildbucket for the build status
[14:05:52.144] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:52.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:44:0) after 1m0s
[14:06:52.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:44:0)
[14:06:52.219] Timer tick, asking Buildbucket for the build status
[14:06:52.349] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:52.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:45:0) after 1m0s
[14:07:52.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:45:0)
[14:07:52.436] Timer tick, asking Buildbucket for the build status
[14:07:52.506] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:52.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:46:0) after 1m0s
[14:08:52.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:46:0)
[14:08:52.556] Timer tick, asking Buildbucket for the build status
[14:08:52.674] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:52.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:47:0) after 1m0s
[14:09:52.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:47:0)
[14:09:52.718] Timer tick, asking Buildbucket for the build status
[14:09:52.827] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:52.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:48:0) after 1m0s
[14:10:52.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:48:0)
[14:10:52.901] Timer tick, asking Buildbucket for the build status
[14:10:52.998] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:52.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:49:0) after 1m0s
[14:11:53.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:49:0)
[14:11:53.023] Timer tick, asking Buildbucket for the build status
[14:11:53.217] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:53.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:50:0) after 1m0s
[14:12:53.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:50:0)
[14:12:53.277] Timer tick, asking Buildbucket for the build status
[14:12:53.577] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:53.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:51:0) after 1m0s
[14:13:53.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:51:0)
[14:13:53.738] Timer tick, asking Buildbucket for the build status
[14:13:53.798] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:53.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:52:0) after 1m0s
[14:14:53.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:52:0)
[14:14:53.901] Timer tick, asking Buildbucket for the build status
[14:14:53.981] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:53.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:53:0) after 1m0s
[14:15:54.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:53:0)
[14:15:54.036] Timer tick, asking Buildbucket for the build status
[14:15:54.348] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:54.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:54:0) after 1m0s
[14:16:54.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:54:0)
[14:16:54.444] Timer tick, asking Buildbucket for the build status
[14:16:54.576] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:54.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:55:0) after 1m0s
[14:17:54.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:55:0)
[14:17:54.618] Timer tick, asking Buildbucket for the build status
[14:17:54.704] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:54.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:56:0) after 1m0s
[14:18:54.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:56:0)
[14:18:54.745] Timer tick, asking Buildbucket for the build status
[14:18:54.870] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:54.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:57:0) after 1m0s
[14:19:54.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:57:0)
[14:19:54.957] Timer tick, asking Buildbucket for the build status
[14:19:55.102] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:55.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:58:0) after 1m0s
[14:20:55.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:58:0)
[14:20:55.162] Timer tick, asking Buildbucket for the build status
[14:20:55.244] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:55.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:59:0) after 1m0s
[14:21:55.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:59:0)
[14:21:55.331] Timer tick, asking Buildbucket for the build status
[14:21:55.432] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:55.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:60:0) after 1m0s
[14:22:55.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:60:0)
[14:22:55.527] Timer tick, asking Buildbucket for the build status
[14:22:55.582] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:55.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:61:0) after 1m0s
[14:23:55.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:61:0)
[14:23:55.645] Timer tick, asking Buildbucket for the build status
[14:23:55.720] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:55.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:62:0) after 1m0s
[14:24:55.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:62:0)
[14:24:55.825] Timer tick, asking Buildbucket for the build status
[14:24:55.928] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:55.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:63:0) after 1m0s
[14:25:56.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:63:0)
[14:25:56.021] Timer tick, asking Buildbucket for the build status
[14:25:56.097] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:56.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:64:0) after 1m0s
[14:26:56.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:64:0)
[14:26:56.157] Timer tick, asking Buildbucket for the build status
[14:26:56.664] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:56.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:65:0) after 1m0s
[14:27:56.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:65:0)
[14:27:56.703] Timer tick, asking Buildbucket for the build status
[14:27:56.770] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:56.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:66:0) after 1m0s
[14:28:56.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:66:0)
[14:28:56.807] Timer tick, asking Buildbucket for the build status
[14:28:56.869] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:56.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:67:0) after 1m0s
[14:29:56.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:67:0)
[14:29:56.875] Timer tick, asking Buildbucket for the build status
[14:29:57.222] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:57.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:68:0) after 1m0s
[14:30:57.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:68:0)
[14:30:57.225] Timer tick, asking Buildbucket for the build status
[14:30:57.305] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:57.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:69:0) after 1m0s
[14:31:57.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:69:0)
[14:31:57.311] Timer tick, asking Buildbucket for the build status
[14:31:57.381] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:57.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:70:0) after 1m0s
[14:32:57.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:70:0)
[14:32:57.484] Timer tick, asking Buildbucket for the build status
[14:32:57.555] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:57.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:71:0) after 1m0s
[14:33:57.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:71:0)
[14:33:57.638] Timer tick, asking Buildbucket for the build status
[14:33:57.708] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:57.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:72:0) after 1m0s
[14:34:57.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:72:0)
[14:34:57.682] Timer tick, asking Buildbucket for the build status
[14:34:57.939] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:57.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:73:0) after 1m0s
[14:35:57.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:73:0)
[14:35:57.987] Timer tick, asking Buildbucket for the build status
[14:35:58.064] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:58.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:74:0) after 1m0s
[14:36:58.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:74:0)
[14:36:58.189] Timer tick, asking Buildbucket for the build status
[14:36:58.259] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:58.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:75:0) after 1m0s
[14:37:58.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:75:0)
[14:37:58.432] Timer tick, asking Buildbucket for the build status
[14:37:58.543] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:58.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:76:0) after 1m0s
[14:38:59.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:76:0)
[14:38:59.043] Timer tick, asking Buildbucket for the build status
[14:38:59.202] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:59.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:77:0) after 1m0s
[14:39:59.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:77:0)
[14:39:59.365] Timer tick, asking Buildbucket for the build status
[14:39:59.513] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:59.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:78:0) after 1m0s
[14:40:59.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:78:0)
[14:40:59.731] Timer tick, asking Buildbucket for the build status
[14:40:59.926] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:59.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:79:0) after 1m0s
[14:42:00.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:79:0)
[14:42:00.100] Timer tick, asking Buildbucket for the build status
[14:42:00.305] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:00.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:80:0) after 1m0s
[14:43:00.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:80:0)
[14:43:00.410] Timer tick, asking Buildbucket for the build status
[14:43:00.522] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:00.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:81:0) after 1m0s
[14:44:00.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:81:0)
[14:44:00.626] Timer tick, asking Buildbucket for the build status
[14:44:00.705] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:00.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:82:0) after 1m0s
[14:45:00.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:82:0)
[14:45:00.789] Timer tick, asking Buildbucket for the build status
[14:45:00.900] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:00.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:83:0) after 1m0s
[14:46:01.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:83:0)
[14:46:01.138] Timer tick, asking Buildbucket for the build status
[14:46:01.258] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:01.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:84:0) after 1m0s
[14:47:01.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:84:0)
[14:47:01.841] Timer tick, asking Buildbucket for the build status
[14:47:01.953] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:01.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:85:0) after 1m0s
[14:48:02.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:85:0)
[14:48:02.107] Timer tick, asking Buildbucket for the build status
[14:48:02.192] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:02.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:86:0) after 1m0s
[14:49:02.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:86:0)
[14:49:02.430] Timer tick, asking Buildbucket for the build status
[14:49:02.663] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:02.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:87:0) after 1m0s
[14:50:02.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:87:0)
[14:50:02.728] Timer tick, asking Buildbucket for the build status
[14:50:02.914] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:02.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:88:0) after 1m0s
[14:51:03.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:88:0)
[14:51:03.055] Timer tick, asking Buildbucket for the build status
[14:51:03.163] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:03.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:89:0) after 1m0s
[14:52:03.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:89:0)
[14:52:03.181] Timer tick, asking Buildbucket for the build status
[14:52:03.498] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:03.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:90:0) after 1m0s
[14:53:03.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:90:0)
[14:53:03.567] Timer tick, asking Buildbucket for the build status
[14:53:03.713] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:03.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:91:0) after 1m0s
[14:54:03.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:91:0)
[14:54:03.752] Timer tick, asking Buildbucket for the build status
[14:54:03.839] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:03.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:92:0) after 1m0s
[14:55:03.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:92:0)
[14:55:03.914] Timer tick, asking Buildbucket for the build status
[14:55:04.195] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:04.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:93:0) after 1m0s
[14:56:04.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:93:0)
[14:56:04.319] Timer tick, asking Buildbucket for the build status
[14:56:04.640] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:04.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:94:0) after 1m0s
[14:57:04.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:94:0)
[14:57:04.699] Timer tick, asking Buildbucket for the build status
[14:57:04.793] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:04.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:95:0) after 1m0s
[14:58:04.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:95:0)
[14:58:04.860] Timer tick, asking Buildbucket for the build status
[14:58:04.934] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:04.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:96:0) after 1m0s
[14:59:04.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:96:0)
[14:59:04.993] Timer tick, asking Buildbucket for the build status
[14:59:05.078] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:05.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:97:0) after 1m0s
[15:00:05.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:97:0)
[15:00:05.073] Timer tick, asking Buildbucket for the build status
[15:00:05.173] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:05.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:98:0) after 1m0s
[15:01:05.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:98:0)
[15:01:05.320] Timer tick, asking Buildbucket for the build status
[15:01:05.394] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:05.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:99:0) after 1m0s
[15:02:05.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:99:0)
[15:02:05.483] Timer tick, asking Buildbucket for the build status
[15:02:05.821] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:05.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:100:0) after 1m0s
[15:03:05.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:100:0)
[15:03:05.906] Timer tick, asking Buildbucket for the build status
[15:03:06.048] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:06.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:101:0) after 1m0s
[15:04:06.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:101:0)
[15:04:06.182] Timer tick, asking Buildbucket for the build status
[15:04:06.607] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:06.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:102:0) after 1m0s
[15:05:06.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:102:0)
[15:05:06.620] Timer tick, asking Buildbucket for the build status
[15:05:06.705] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:06.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:103:0) after 1m0s
[15:06:06.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:103:0)
[15:06:06.741] Timer tick, asking Buildbucket for the build status
[15:06:06.810] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:06.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:104:0) after 1m0s
[15:07:06.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:104:0)
[15:07:06.927] Timer tick, asking Buildbucket for the build status
[15:07:07.043] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:07.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:105:0) after 1m0s
[15:08:07.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:105:0)
[15:08:07.071] Timer tick, asking Buildbucket for the build status
[15:08:07.149] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:07.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:106:0) after 1m0s
[15:09:07.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:106:0)
[15:09:07.217] Timer tick, asking Buildbucket for the build status
[15:09:07.323] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:07.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:107:0) after 1m0s
[15:10:07.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:107:0)
[15:10:07.374] Timer tick, asking Buildbucket for the build status
[15:10:07.547] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:07.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:108:0) after 1m0s
[15:11:07.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:108:0)
[15:11:07.609] Timer tick, asking Buildbucket for the build status
[15:11:07.688] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:07.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:109:0) after 1m0s
[15:12:07.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:109:0)
[15:12:07.771] Timer tick, asking Buildbucket for the build status
[15:12:07.928] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:07.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:110:0) after 1m0s
[15:13:07.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:110:0)
[15:13:07.962] Timer tick, asking Buildbucket for the build status
[15:13:08.041] Build 8852508029252905168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:08.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017938091292087840:111:0) after 1m0s
[15:13:25.895] Received PubSub notification, asking Buildbucket for the build status
[15:13:26.036] Build 8852508029252905168: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:13:26.036] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615994005501562",
"created_by": "project:chromium",
"created_ts": "1615987478929616",
"failure_reason": "BUILD_FAILURE",
"id": "8852508029252905168",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"927191cbf902ca1b73bccb061cbe279d290bf20b\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"927191cbf902ca1b73bccb061cbe279d290bf20b\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-0-rwcz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17835, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"b3394db36779b618b824d91f52cfd3dc3136c21f\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"927191cbf902ca1b73bccb061cbe279d290bf20b\", \"got_revision_cp\": \"refs/heads/master@{#863736}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"07c60e0c75db7c20980bbc2aa51f3468985a60da\", \"got_v8_revision_cp\": \"refs/heads/9.1.142@{#1}\", \"got_webrtc_revision\": \"ab6335041130fdc0365a604f49668eac9194f9d6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33479}\", \"is_cached\": true, \"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\": \"927191cbf902ca1b73bccb061cbe279d290bf20b\", \"swarm_hashes_refs/heads/master(at){#863736}_without_patch\": {\"absl_hardening_tests\": \"e17fc02e8c3753caf0d5fc92198c075a194e839c\", \"accessibility_unittests\": \"1eaadd01e33014e978d4ddc818b23e6e9ee889df\", \"angle_unittests\": \"b475ad7a568eaa4d409f80379de7b37ae1325fee\", \"app_shell_unittests\": \"0e2d7a9a0a7feb78864f7265166e1ec7f1d3c967\", \"aura_unittests\": \"e90f2d8bab03bab439185fb16b7254eb7fb8623b\", \"base_unittests\": \"48c047b7e2a41311de2f90937485a43d698913e3\", \"base_util_unittests\": \"67045f8d3fb98bca6938d08e47bb8cbdc4cc1393\", \"blink_common_unittests\": \"8372f953894ad2b645b0de99a78bc22d7ae1311a\", \"blink_fuzzer_unittests\": \"4653642b6f4f5181bc655bccff1e4158299040ba\", \"blink_heap_unittests\": \"b36fb9491105fcd81f78af084dc4bf96475733b8\", \"blink_platform_unittests\": \"a7ffff676b7298ef82793c0432947a429e6d8fb9\", \"blink_unittests\": \"1ae3e53b78204e9f53df6b25fdcd1f2e4ed2e9fe\", \"boringssl_crypto_tests\": \"ac06724533a67f062d5547294a8d5f5c7fc2e003\", \"boringssl_ssl_tests\": \"07a49606971d56e09da8c598337246bd08df711c\", \"browser_switcher_bho_unittests\": \"bc1f1179b5de779155f307cb4de8e1dfbb77784c\", \"browser_tests\": \"2e646076eb93353aaeacb9bfbca5f22765c57039\", \"capture_unittests\": \"d3fc031477d104a1d13ac1edc99727fec96e6fba\", \"cast_unittests\": \"035630a8e8e9b34a4e8be8fcb29fedc820b49510\", \"cc_unittests\": \"17637e7c8532545cf23471244959a43011590604\", \"chrome_app_unittests\": \"1ef379310447c6c0c944d863ffeac2a114dcd09e\", \"chrome_cleaner_unittests\": \"e2f3aeab06b5a03c16a4dc764d620be281265236\", \"chrome_elf_unittests\": \"70eb1cd33a5d0dc2437e4733c4cb5b71a8a72866\", \"chromedriver_unittests\": \"04885a3f8444993fc502d53114073bb57217398a\", \"color_unittests\": \"cb1f70d717461d08adf145f03399cdfd0f14c572\", \"components_browsertests\": \"44dc56e578c6c3171ddba8cc896ecd7dfb44527c\", \"components_unittests\": \"6dc06d3a0563331f2bd904ebac07c79795477b9c\", \"compositor_unittests\": \"e254764ad032c91490b5833c824dda082ce7b1ff\", \"content_browsertests\": \"fc96ae8a73c7e721d11be49e2dd84edd7d3b34e6\", \"content_unittests\": \"752b6e225033842c0067a3dea296615f005dc20e\", \"courgette_unittests\": \"ce3d791441c82bfae6938209459f4089a27830d6\", \"crashpad_tests\": \"29b07103947099cc3f745ca7e08e80418434882c\", \"cronet_tests\": \"07cf93f11c5bb0a3d7fa8313bbead0bdadc0d708\", \"cronet_unittests\": \"245cd72302c1bd10ea4da346fa34ac416bbedf96\", \"crypto_unittests\": \"9e845b865d613dca14391295e91911c7bd4c6acf\", \"delayloads_unittests\": \"96a936a74b5c953afdfc0d06fe46ab841b1b0ad5\", \"device_unittests\": \"2c0e5b6127c98e59fd115624e050d38b9c7d6808\", \"display_unittests\": \"9a86ab2b1f5b7a2da183977560a8090e8164c1d7\", \"elevation_service_unittests\": \"3f3bcf38d88cd1adea4c28aa313f293312ee58a3\", \"events_unittests\": \"5f4c19ef122e90c80ca1300b74435cef5c896018\", \"extensions_browsertests\": \"9c1e36b812f01696f466f49ffe3a3d1978df02ba\", \"extensions_unittests\": \"35c0f60193f94c21462ff2b625895251e5a17597\", \"filesystem_service_unittests\": \"017d8a46f5fb28834428a32991795f4246801ee0\", \"gcm_unit_tests\": \"0b1ab938b8bca0e60ae8bd5bf41f2b1f3192bcf5\", \"gcp_unittests\": \"7a0b2ceb6a9048e041e0af271d71cd1a46349dd6\", \"gfx_unittests\": \"cd6c51c36f583136dcf9386d16493ac17c0118c0\", \"gin_unittests\": \"e30d4b9178d926bd161438b7d013090773404c62\", \"google_apis_unittests\": \"6e51ed1d9671eb0cb07508ae8fca10230ac26025\", \"gpu_unittests\": \"37066e40bc8b208e30da3e7629621c40ef827560\", \"gwp_asan_unittests\": \"dc3f1a3a27ff01deffc0caea306b70cfd1b07de5\", \"headless_browsertests\": \"c2975c6394a8156a6736ed88a5d2f20074b129f2\", \"headless_unittests\": \"690cc1d0a17f70c9e22dfd7fbe579a6b7d8787fe\", \"install_static_unittests\": \"eeefa6a64f6cd0b5cdad1c1c8434a1286fd7b375\", \"installer_util_unittests\": \"8ebc972b77fc3250406b55f6da68bb3d89bfc343\", \"interactive_ui_tests\": \"503c63867e23c8f242549fa3f1d95061efc2b4f4\", \"ipc_tests\": \"6ad0d4476337210922c7c8dc52f31e6b580c54ef\", \"jingle_unittests\": \"212447fea8c4239b7ecfa365c55fde72e5fec6d3\", \"latency_unittests\": \"d99ba17b2bae20062d5fee734cebb6a6d90f1cdb\", \"libjingle_xmpp_unittests\": \"4003566fa139a1baa014cb7eb4cbc4d84ed5d597\", \"liburlpattern_unittests\": \"8d94c2d97d04fa41dae83f7449f4d7b61d2e3377\", \"media_blink_unittests\": \"1fdbf96bbe309822d94770d0b9e60571d6039e3d\", \"media_unittests\": \"bda9ae08c0d48981f8b3beffb9bf1f7e8fa9528f\", \"message_center_unittests\": \"0716fc0819109b6969e395a65fa2ece644d499b3\", \"midi_unittests\": \"fa2b86ca5d722ff9f4a9df3a9adebdef7a2dbb1f\", \"mojo_core_unittests\": \"97ee597ca6fd1d9c523c898984c7e7107755c6ae\", \"mojo_unittests\": \"2cd4086a5ad591b3df2e22019548451a63e45904\", \"nacl_loader_unittests\": \"6dba1010baf0f924591b18a156296294efa510f9\", \"native_theme_unittests\": \"b6742194bb1db89bdd69b60b9bd7438e0c313316\", \"net_unittests\": \"9fc3d1118b80446725574c9ab908eaf04633007b\", \"notification_helper_unittests\": \"578da0110c5c89e9511eb1815ed0d5a1b8baabe8\", \"pdf_unittests\": \"3fd713e56c6d7c11c33d621c2b96405a94b460b7\", \"perfetto_unittests\": \"ea1d718b235c520f7e658d3b575303323350f95f\", \"ppapi_unittests\": \"fcc46c474576835e2513602ca822df7912de7075\", \"printing_unittests\": \"ea7db93a5422f3cb48b9001fc1a5250e4accd987\", \"remoting_unittests\": \"e2e34e1856dde8daf06bc9b5f56b68899c34e52d\", \"sbox_integration_tests\": \"a875b140ca8a71e9fbd3040d7a5fea2aff6b4b6b\", \"sbox_unittests\": \"43090ce5e9ae5c05a8af529e87e4f44aae493bc3\", \"sbox_validation_tests\": \"2e560c6bfedc3f6a948ae446c1306c3d16d32af0\", \"service_manager_unittests\": \"721d47e7d79474c5d1528e521c6d47522c5c0bfb\", \"services_unittests\": \"2d06d96e465b2e06bf4b932462191c53b9f55441\", \"setup_unittests\": \"88a7e3690d4440254236e205593f54df798c23e3\", \"shell_dialogs_unittests\": \"252a2c3acd2960d3b7ba9e161f2ab4d965a56884\", \"skia_unittests\": \"468b71ab3cf42af355a055ffedd0585d11bbab19\", \"snapshot_unittests\": \"b4afa6f08d61d69d215048df8dede3833a2c9b25\", \"sql_unittests\": \"8f141c340b0c3ccd1cc607370a1fd247e9c40134\", \"storage_unittests\": \"8eb87f6d24c2b2cee515cfd0996e3c1130188430\", \"sync_integration_tests\": \"7a328da548a7dcff5b5285acfc01e9c3f5f6a1f0\", \"traffic_annotation_auditor_unittests\": \"ddd9544ea07953d7cfedfba69558e4826badd015\", \"ui_base_unittests\": \"64b2d69a03b0263d32416c485cf7139fa942c20c\", \"ui_touch_selection_unittests\": \"618fd3d1a4d12be6504c7dada66ec4f919b8fcd5\", \"unit_tests\": \"d9702eeb4d95db2a69668d5da63caf306f35c3b4\", \"updater_tests\": \"3c7b8bf409f4474253aafc0e341517d9a95945eb\", \"url_unittests\": \"48eef7dc1da2c0f31bdd9d115b6971c3981ccf31\", \"views_unittests\": \"f98afc0f1625ab5d0c74602211c02dc87bed2968\", \"viz_unittests\": \"12ced09b28235968a3002fa31f3cab09c24872ed\", \"vr_common_unittests\": \"ad941443c74864f75c5f6c6f6c27ba068bdbf1bd\", \"vr_pixeltests\": \"d5fa17137ec39823ac4672e956c7bffb40625430\", \"weblayer_browsertests\": \"b08cb36414b1b01984aa285a9549ac80ab67f23a\", \"weblayer_unittests\": \"40060a8a175501170fc1b91ed1326509c9d57d9e\", \"wm_unittests\": \"56019d7d87f7d3cdb79723082eeebe568e8739ba\", \"wtf_unittests\": \"c94abb6abe127be89c9082e7d656b4bdc9de1b30\", \"zlib_unittests\": \"7853b677b7c5bbaa42bccf9f3c48523bdb25c3e9\", \"zucchini_unittests\": \"13242fe8d5ecee574ac83146d3b6c88d3591bb83\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-0-rwcz\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithGoodModelFilesShouldDeleteDownloadFileButHaveContentExtracted\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615987632298983",
"status": "COMPLETED",
"status_changed_ts": "1615987632631742",
"tags": [
"build_address:luci.chromium.ci/win-asan/17835",
"builder:win-asan",
"buildset:commit/git/927191cbf902ca1b73bccb061cbe279d290bf20b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/927191cbf902ca1b73bccb061cbe279d290bf20b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017938091292087840",
"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/8852508029252905168/+/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:52592da66e8d0a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615994005501918",
"url": "https://ci.chromium.org/b/8852508029252905168",
"utcnow_ts": "1615994006023616"
}
}
[15:13:26.036] Invocation finished in 1h48m48.434306086s with status FAILED