[14:23:34.216] New invocation is queued and will start shortly
[14:23:35.372] Starting the invocation (attempt 1)
[14:23:35.411] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:23:35.411] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:23:35.411] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023189006949427776",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3572a7c461ef6056adff7c300e7fae7493fe571e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023189006949427776",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/3572a7c461ef6056adff7c300e7fae7493fe571e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3572a7c461ef6056adff7c300e7fae7493fe571e",
"gitiles_ref:refs/heads/master"
]
}
[14:23:36.131] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610979815513303",
"id": "8857758944926781056",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3572a7c461ef6056adff7c300e7fae7493fe571e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610979815962351",
"tags": [
"build_address:luci.chromium.ci/win-asan/16873",
"builder:win-asan",
"buildset:commit/git/3572a7c461ef6056adff7c300e7fae7493fe571e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3572a7c461ef6056adff7c300e7fae7493fe571e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023189006949427776",
"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/8857758944926781056/+/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": "1610979815962471",
"url": "https://ci.chromium.org/b/8857758944926781056",
"utcnow_ts": "1610979816115800"
}
}
[14:23:36.131] Task URL: https://ci.chromium.org/b/8857758944926781056
[14:23:36.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:2:0) after 1m0s
[14:23:43.953] Received PubSub notification, asking Buildbucket for the build status
[14:23:44.034] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:36.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:2:0)
[14:24:36.222] Timer tick, asking Buildbucket for the build status
[14:24:36.561] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:36.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:4:0) after 1m0s
[14:25:36.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:4:0)
[14:25:36.579] Timer tick, asking Buildbucket for the build status
[14:25:36.840] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:36.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:5:0) after 1m0s
[14:26:36.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:5:0)
[14:26:36.869] Timer tick, asking Buildbucket for the build status
[14:26:37.481] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:37.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:6:0) after 1m0s
[14:27:37.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:6:0)
[14:27:37.529] Timer tick, asking Buildbucket for the build status
[14:27:38.082] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:38.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:7:0) after 1m0s
[14:28:38.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:7:0)
[14:28:38.128] Timer tick, asking Buildbucket for the build status
[14:28:38.256] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:38.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:8:0) after 1m0s
[14:29:38.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:8:0)
[14:29:38.304] Timer tick, asking Buildbucket for the build status
[14:29:38.788] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:38.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:9:0) after 1m0s
[14:30:38.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:9:0)
[14:30:38.840] Timer tick, asking Buildbucket for the build status
[14:30:38.934] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:38.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:10:0) after 1m0s
[14:31:39.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:10:0)
[14:31:39.001] Timer tick, asking Buildbucket for the build status
[14:31:39.146] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:39.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:11:0) after 1m0s
[14:32:39.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:11:0)
[14:32:39.218] Timer tick, asking Buildbucket for the build status
[14:32:39.378] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:39.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:12:0) after 1m0s
[14:33:39.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:12:0)
[14:33:39.398] Timer tick, asking Buildbucket for the build status
[14:33:39.465] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:39.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:13:0) after 1m0s
[14:34:39.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:13:0)
[14:34:39.509] Timer tick, asking Buildbucket for the build status
[14:34:39.619] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:39.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:14:0) after 1m0s
[14:35:39.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:14:0)
[14:35:39.654] Timer tick, asking Buildbucket for the build status
[14:35:40.219] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:40.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:15:0) after 1m0s
[14:36:40.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:15:0)
[14:36:40.292] Timer tick, asking Buildbucket for the build status
[14:36:41.010] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:41.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:16:0) after 1m0s
[14:37:41.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:16:0)
[14:37:41.043] Timer tick, asking Buildbucket for the build status
[14:37:41.205] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:41.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:17:0) after 1m0s
[14:38:41.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:17:0)
[14:38:41.225] Timer tick, asking Buildbucket for the build status
[14:38:41.337] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:41.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:18:0) after 1m0s
[14:39:41.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:18:0)
[14:39:41.404] Timer tick, asking Buildbucket for the build status
[14:39:41.555] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:41.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:19:0) after 1m0s
[14:40:41.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:19:0)
[14:40:41.573] Timer tick, asking Buildbucket for the build status
[14:40:43.005] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:43.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:20:0) after 1m0s
[14:41:43.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:20:0)
[14:41:43.052] Timer tick, asking Buildbucket for the build status
[14:41:43.171] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:43.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:21:0) after 1m0s
[14:42:43.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:21:0)
[14:42:43.218] Timer tick, asking Buildbucket for the build status
[14:42:43.337] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:43.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:22:0) after 1m0s
[14:43:43.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:22:0)
[14:43:43.365] Timer tick, asking Buildbucket for the build status
[14:43:43.467] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:43.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:23:0) after 1m0s
[14:44:43.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:23:0)
[14:44:43.491] Timer tick, asking Buildbucket for the build status
[14:44:43.606] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:43.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:24:0) after 1m0s
[14:45:43.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:24:0)
[14:45:43.630] Timer tick, asking Buildbucket for the build status
[14:45:44.124] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:44.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:25:0) after 1m0s
[14:46:44.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:25:0)
[14:46:44.156] Timer tick, asking Buildbucket for the build status
[14:46:44.517] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:44.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:26:0) after 1m0s
[14:47:44.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:26:0)
[14:47:44.544] Timer tick, asking Buildbucket for the build status
[14:47:44.612] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:44.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:27:0) after 1m0s
[14:48:44.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:27:0)
[14:48:44.635] Timer tick, asking Buildbucket for the build status
[14:48:44.785] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:44.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:28:0) after 1m0s
[14:49:44.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:28:0)
[14:49:44.788] Timer tick, asking Buildbucket for the build status
[14:49:44.916] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:44.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:29:0) after 1m0s
[14:50:44.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:29:0)
[14:50:44.964] Timer tick, asking Buildbucket for the build status
[14:50:45.130] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:45.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:30:0) after 1m0s
[14:51:45.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:30:0)
[14:51:45.189] Timer tick, asking Buildbucket for the build status
[14:51:45.308] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:45.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:31:0) after 1m0s
[14:52:45.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:31:0)
[14:52:45.322] Timer tick, asking Buildbucket for the build status
[14:52:45.450] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:45.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:32:0) after 1m0s
[14:53:45.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:32:0)
[14:53:45.473] Timer tick, asking Buildbucket for the build status
[14:53:45.880] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:45.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:33:0) after 1m0s
[14:54:45.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:33:0)
[14:54:45.889] Timer tick, asking Buildbucket for the build status
[14:54:46.104] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:46.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:34:0) after 1m0s
[14:55:46.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:34:0)
[14:55:46.131] Timer tick, asking Buildbucket for the build status
[14:55:46.210] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:46.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:35:0) after 1m0s
[14:56:46.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:35:0)
[14:56:46.220] Timer tick, asking Buildbucket for the build status
[14:56:46.333] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:46.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:36:0) after 1m0s
[14:57:46.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:36:0)
[14:57:46.355] Timer tick, asking Buildbucket for the build status
[14:57:46.453] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:46.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:37:0) after 1m0s
[14:58:46.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:37:0)
[14:58:46.473] Timer tick, asking Buildbucket for the build status
[14:58:46.595] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:46.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:38:0) after 1m0s
[14:59:46.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:38:0)
[14:59:46.610] Timer tick, asking Buildbucket for the build status
[14:59:46.846] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:46.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:39:0) after 1m0s
[15:00:46.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:39:0)
[15:00:46.881] Timer tick, asking Buildbucket for the build status
[15:00:47.064] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:47.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:40:0) after 1m0s
[15:01:47.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:40:0)
[15:01:47.114] Timer tick, asking Buildbucket for the build status
[15:01:47.412] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:47.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:41:0) after 1m0s
[15:02:47.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:41:0)
[15:02:47.465] Timer tick, asking Buildbucket for the build status
[15:02:47.934] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:47.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:42:0) after 1m0s
[15:03:47.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:42:0)
[15:03:47.951] Timer tick, asking Buildbucket for the build status
[15:03:48.115] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:48.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:43:0) after 1m0s
[15:04:48.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:43:0)
[15:04:48.098] Timer tick, asking Buildbucket for the build status
[15:04:48.717] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:48.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:44:0) after 1m0s
[15:05:48.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:44:0)
[15:05:48.737] Timer tick, asking Buildbucket for the build status
[15:05:49.261] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:49.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:45:0) after 1m0s
[15:06:49.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:45:0)
[15:06:49.302] Timer tick, asking Buildbucket for the build status
[15:06:49.863] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:49.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:46:0) after 1m0s
[15:07:49.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:46:0)
[15:07:49.903] Timer tick, asking Buildbucket for the build status
[15:07:50.046] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:50.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:47:0) after 1m0s
[15:08:50.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:47:0)
[15:08:50.096] Timer tick, asking Buildbucket for the build status
[15:08:50.494] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:50.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:48:0) after 1m0s
[15:09:50.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:48:0)
[15:09:50.529] Timer tick, asking Buildbucket for the build status
[15:09:50.777] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:50.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:49:0) after 1m0s
[15:10:50.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:49:0)
[15:10:50.791] Timer tick, asking Buildbucket for the build status
[15:10:51.014] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:51.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:50:0) after 1m0s
[15:11:51.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:50:0)
[15:11:51.043] Timer tick, asking Buildbucket for the build status
[15:11:51.137] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:51.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:51:0) after 1m0s
[15:12:51.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:51:0)
[15:12:51.229] Timer tick, asking Buildbucket for the build status
[15:12:52.597] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:52.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:52:0) after 1m0s
[15:13:52.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:52:0)
[15:13:52.655] Timer tick, asking Buildbucket for the build status
[15:13:52.894] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:52.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:53:0) after 1m0s
[15:14:52.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:53:0)
[15:14:52.992] Timer tick, asking Buildbucket for the build status
[15:14:53.088] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:53.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:54:0) after 1m0s
[15:15:53.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:54:0)
[15:15:53.244] Timer tick, asking Buildbucket for the build status
[15:15:53.804] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:53.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:55:0) after 1m0s
[15:16:53.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:55:0)
[15:16:53.910] Timer tick, asking Buildbucket for the build status
[15:16:55.013] Build 8857758944926781056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:55.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023189006949427776:56:0) after 1m0s
[15:17:08.145] Received PubSub notification, asking Buildbucket for the build status
[15:17:08.286] Build 8857758944926781056: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:17:08.286] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610983027429442",
"created_by": "project:chromium",
"created_ts": "1610979815513303",
"failure_reason": "BUILD_FAILURE",
"id": "8857758944926781056",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3572a7c461ef6056adff7c300e7fae7493fe571e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithBadModelInfoFileShouldDeleteTempFile\"}, \"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\": \"3572a7c461ef6056adff7c300e7fae7493fe571e\", \"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-9-kl3f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16873, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"68f15c734d5cef3bfa3f8d86654fd7a5808679ec\", \"got_dawn_revision\": \"5c20f1ec9ca95008eb452e04133c332af3a2b3fa\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"3572a7c461ef6056adff7c300e7fae7493fe571e\", \"got_revision_cp\": \"refs/heads/master@{#844514}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"149733cead636de93d96c5c64f30168d5f6bb03f\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"12971a22d265c7ba1c3c388f5d487580939a1162\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33014}\", \"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\": \"3572a7c461ef6056adff7c300e7fae7493fe571e\", \"swarm_hashes_refs/heads/master(at){#844514}_without_patch\": {\"absl_hardening_tests\": \"b2bd6c342bca56214f0326f5b3d59c0751b1230f\", \"accessibility_unittests\": \"0305ac7256df98a8e6b04db377cf28bfd1d8c97e\", \"angle_unittests\": \"4df80b0ca2989d95fc3e5d16e4c8a8c0fdec6103\", \"app_shell_unittests\": \"0808a2b0227a4b74b04a7da6e6d911e02922b36d\", \"aura_unittests\": \"8c8f64822e8c68b08667e791afd3023b098fa7cb\", \"base_unittests\": \"f52dd605afd8909d08e613cf5bd15bd3989e3e6f\", \"base_util_unittests\": \"fa7da58c0b3c623357afaaf808657bf369fa6bf4\", \"blink_common_unittests\": \"3b83853471827cc97ca73ed350ae48f6df46fe27\", \"blink_fuzzer_unittests\": \"2c6527b9e7894af7a5665baa8476f364379237da\", \"blink_heap_unittests\": \"7f0ab1abf9615383e7490f151f2beb1afa9f9a53\", \"blink_platform_unittests\": \"1d349ba123b9282928be7bdc87edd34445289379\", \"blink_unittests\": \"da013f7c5093f30f4aa223bc20fdabc7a3fa2a13\", \"boringssl_crypto_tests\": \"25552210dd63fca1c39520553252f3131cd083b1\", \"boringssl_ssl_tests\": \"b7c157ff01e7583885cb849c36f66181c5b50f78\", \"browser_switcher_bho_unittests\": \"824d32e969cea297d684eb91c38f1981d0ef79a6\", \"browser_tests\": \"1351e747991823a9fd8a509a894c297bec11197f\", \"capture_unittests\": \"c8a80953bcad11e0afe8db9cc25a79fb89acc180\", \"cast_unittests\": \"011083a70e91d20055da19a9fb573ecee5c98748\", \"cc_unittests\": \"3d652164428fc3596d215d10e59fa8e8f0840354\", \"chrome_app_unittests\": \"553b7347fd174474d565e58d0e73d0a179830eb5\", \"chrome_cleaner_unittests\": \"86fa756f318d880f94a1318cdf2c9200cd6fc0fe\", \"chrome_elf_unittests\": \"98f4da51556c5729e5fbe13c9a40d4c4f470c8f1\", \"chromedriver_unittests\": \"f2305ca563a0e0f9b626a29ee85b67eca492d3d7\", \"components_browsertests\": \"f10f2375895d874c681575b4daf6c51bfc013a91\", \"components_unittests\": \"d8d48485d597d5e94537164ab51769ce26bc5344\", \"compositor_unittests\": \"bd6a059e34e040f46999752714c0dcd54cca7b90\", \"content_browsertests\": \"2d9d02786a04e3db3969e8b4b0e299a8a5891ec0\", \"content_unittests\": \"43632fdebfef03f3ae4559a30b3c169f8ccc8733\", \"courgette_unittests\": \"252468d642eccf1659a534c16845a2a44b9e7acc\", \"crashpad_tests\": \"5bf305981a908aa1fd664ffde728754b53f9ff26\", \"cronet_tests\": \"156bb7d14ceb2706635ae912b989d960e307a11f\", \"cronet_unittests\": \"2f20c0319db483e47d6c4b164ab479459e7ae80b\", \"crypto_unittests\": \"d427ee9490c70742ed60a17dfe54a4cd6d4de2ca\", \"delayloads_unittests\": \"8e66af22afbbbfee823a43bc4f5c6674356f5e42\", \"device_unittests\": \"46e779ed25cfbe3946be82792ff3e687db6a7b0a\", \"display_unittests\": \"98227a7ce9d9961aba38ffde4ff5f792a59ff76f\", \"elevation_service_unittests\": \"cd538cd44f993cc651afdb66e74b5c9a91caa6be\", \"events_unittests\": \"05ab273fb82bd0be3f19f3568e43ae8dd6a36f86\", \"extensions_browsertests\": \"5092f0f36f735d7c9484dcab7186615bcb637dbb\", \"extensions_unittests\": \"c88ec843602d2546c602dba23ab028a10f9fdd4a\", \"filesystem_service_unittests\": \"b82db50dec44f57ce4a343c321b0fd47e1141b69\", \"gcm_unit_tests\": \"be8feaf29156d15f746c722bd1dfa2657d91cde1\", \"gcp_unittests\": \"c7c4b375e078274055666431ce1397b5c5062453\", \"gfx_unittests\": \"144dbb4e9e665a9e883609a6bb6f62ab4fe678b3\", \"gin_unittests\": \"dfe3efda79287d95d0d2aec0bd10093fdfaf00cd\", \"google_apis_unittests\": \"331f4f223224e2ac8ef862851022127a043d586d\", \"gpu_unittests\": \"8d82f97194445d98dc14361a47fda6a451c5c696\", \"gwp_asan_unittests\": \"3ac71994306fdcb4b00649fff300287475a0d197\", \"headless_browsertests\": \"89afe6a5749482517abe9424f9fc3d9a0702b52d\", \"headless_unittests\": \"b1ef9231d988d12a9dd9b71d93d14e3f0dc8aad5\", \"install_static_unittests\": \"d4a3944e7b44fbdddaefb10c69d7c90589e275c4\", \"installer_util_unittests\": \"283c57ba9794b3e01abe641fb1b9f1d0a692ea9a\", \"interactive_ui_tests\": \"b033d6e40f256bdcc85f88c6af6d851f656d6e87\", \"ipc_tests\": \"42094e68ea655daa13b21776e2f3fcdeb4f4f704\", \"jingle_unittests\": \"604a914567bb5932f1f2a223c9081d9952644bb6\", \"latency_unittests\": \"909ca51757d7c2be56f851725254297b1008c656\", \"libjingle_xmpp_unittests\": \"e68818a0491aaaa7b46f8d7cfa6c5d9fb14dffc3\", \"liburlpattern_unittests\": \"98734a0021b09f8e13d427f64c23a4c31ceebfa0\", \"media_blink_unittests\": \"ca46430a07a59892cbed0323e59ec75b10536739\", \"media_unittests\": \"45c76bde8b0a40ccf15d2c08e98d58d823d7872b\", \"message_center_unittests\": \"1ba390050d80cfc6a0b59758616c8350d2a1ba7b\", \"midi_unittests\": \"d84a591284d36d9248dd889581a996b522169a6b\", \"mojo_core_unittests\": \"516bc3fe88a70c529250d484ed5a57414d8bfce7\", \"mojo_unittests\": \"04be2fc06dfacd48a6ab5133a24628687cf224b2\", \"nacl_loader_unittests\": \"b121b39f495eb9c73929277980eb445afd3e9391\", \"native_theme_unittests\": \"06f4410c74686ddcef52dc109d50b4ec16769a90\", \"net_unittests\": \"6d1033db3423b70472766946fc23bb81cad8cd4b\", \"notification_helper_unittests\": \"991198649d0d3e25983cad6ce07476b87c84ab2a\", \"pdf_unittests\": \"3d96c6d5c5eb6d669a708c9daa968c4387b01dde\", \"perfetto_unittests\": \"2bb57c4988d49ded34d092f7a5654759f48a795f\", \"ppapi_unittests\": \"3e7a28d401fd8a699c3fbf4b76bcf25d522a9678\", \"printing_unittests\": \"4c40195da5d5f650bec6b99083e46b56e357a308\", \"remoting_unittests\": \"eafd3575dbb11c5e3df43192afc85e086395a0e7\", \"sbox_integration_tests\": \"1d469d045d60e1ffa5eebf1a84d3484bf2371080\", \"sbox_unittests\": \"129e8fb43ef315c61f61c0b92cbba1d495900180\", \"sbox_validation_tests\": \"2268053318ee6a817b17db7a33340629f7241862\", \"service_manager_unittests\": \"708d4a752cb70fb18e800e9490d0af8cc311f16e\", \"services_unittests\": \"4a310cbc4f396611f11aef9e3b8451c08f217aac\", \"setup_unittests\": \"ad281627c24c6cd37923867c32e3e7c99cd4895d\", \"shell_dialogs_unittests\": \"c204766b61953d4ff9a0d713a9d7c1c77e354c97\", \"skia_unittests\": \"b19a91e11fb5be72bc088157bda0f5e4a6fc07cd\", \"snapshot_unittests\": \"ac2154c82f07a34c9c81aa93a0362477fe4fb0a5\", \"sql_unittests\": \"35a92e9304ea5dfc7da8f736e8b322830c90a6bd\", \"storage_unittests\": \"f4b17049f0bef51b44816ce5e8fb779d8590e754\", \"sync_integration_tests\": \"f4dbfe979ea0acb86b3ac3cfa089255031d2c779\", \"traffic_annotation_auditor_unittests\": \"8f4d5b9222f3e3a2c9cc6763a8bb30581e541d39\", \"ui_base_unittests\": \"d5c4b638f7ee00b38d66f5128bb90cdae6d97d03\", \"ui_touch_selection_unittests\": \"7ae4f07f39d58b8bd58c90cf7d7f22127b6a4883\", \"unit_tests\": \"2324cba1c83266d2a1c6e38fd8aae4c8c9aa1915\", \"updater_tests\": \"8b23f663a5ec271d6d7b60d07d719ef0726b34a2\", \"url_unittests\": \"6bc1437e481a5b549ef76301f9204f3d104bb177\", \"views_unittests\": \"aaf35c4f8d51af491cb6b828995aa82b4d9f9e51\", \"viz_unittests\": \"f35fa08cfe08c885cb0110523de77a76b8c34e8a\", \"vr_common_unittests\": \"6330d10f8f37c75e68eba92b5c1e122c5ccf5505\", \"vr_pixeltests\": \"0b5c502af9aab6f411c030d05f82c8d7feba94d9\", \"weblayer_browsertests\": \"45a9f6910c42d3464d7a0be64da19a2286851280\", \"weblayer_unittests\": \"395b1135c5f2768358b71ad318ddfc67d57d260b\", \"wm_unittests\": \"627d4e2bbd966c5b5184bfb9a3ed5e2539499c35\", \"wtf_unittests\": \"cd1445af803f2dc6ffddd48ad47b0673922fe95b\", \"zlib_unittests\": \"35170c277cc902a46a93a19d7a37646ec8afece0\", \"zucchini_unittests\": \"ff67a57ae7f3a5c1841dd163ac259b8cda2f5623\"}}, \"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-9-kl3f\"], \"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\": [\"5666-679c9bd\"], \"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.VerifiedCrxWithBadModelInfoFileShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610979822486831",
"status": "COMPLETED",
"status_changed_ts": "1610979822995358",
"tags": [
"build_address:luci.chromium.ci/win-asan/16873",
"builder:win-asan",
"buildset:commit/git/3572a7c461ef6056adff7c300e7fae7493fe571e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3572a7c461ef6056adff7c300e7fae7493fe571e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023189006949427776",
"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/8857758944926781056/+/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:512eb2cec32d2110",
"user_agent:luci-scheduler"
],
"updated_ts": "1610983027851170",
"url": "https://ci.chromium.org/b/8857758944926781056",
"utcnow_ts": "1610983028272632"
}
}
[15:17:08.286] Invocation finished in 53m34.085700191s with status FAILED