[20:22:36.268] New invocation is queued and will start shortly
[20:22:37.350] Starting the invocation (attempt 1)
[20:22:37.401] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:22:37.401] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:22:37.401] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019904927681224048",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a80cf5fdad31d9898817e65c35cd907268194e60\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019904927681224048",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a80cf5fdad31d9898817e65c35cd907268194e60",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a80cf5fdad31d9898817e65c35cd907268194e60",
"gitiles_ref:refs/heads/master"
]
}
[20:22:38.812] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614111757493223",
"id": "8854474865733401632",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a80cf5fdad31d9898817e65c35cd907268194e60\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614111758293324",
"tags": [
"build_address:luci.chromium.ci/win-asan/17496",
"builder:win-asan",
"buildset:commit/git/a80cf5fdad31d9898817e65c35cd907268194e60",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a80cf5fdad31d9898817e65c35cd907268194e60",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019904927681224048",
"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/8854474865733401632/+/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": "1614111758293441",
"url": "https://ci.chromium.org/b/8854474865733401632",
"utcnow_ts": "1614111758804080"
}
}
[20:22:38.812] Task URL: https://ci.chromium.org/b/8854474865733401632
[20:22:38.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:2:0) after 1m0s
[20:22:50.828] Received PubSub notification, asking Buildbucket for the build status
[20:22:50.901] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:38.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:2:0)
[20:23:38.883] Timer tick, asking Buildbucket for the build status
[20:23:38.972] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:38.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:4:0) after 1m0s
[20:24:39.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:4:0)
[20:24:39.199] Timer tick, asking Buildbucket for the build status
[20:24:39.765] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:39.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:5:0) after 1m0s
[20:25:39.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:5:0)
[20:25:39.858] Timer tick, asking Buildbucket for the build status
[20:25:40.008] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:40.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:6:0) after 1m0s
[20:26:40.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:6:0)
[20:26:40.214] Timer tick, asking Buildbucket for the build status
[20:26:40.305] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:40.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:7:0) after 1m0s
[20:27:40.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:7:0)
[20:27:40.424] Timer tick, asking Buildbucket for the build status
[20:27:40.540] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:40.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:8:0) after 1m0s
[20:28:40.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:8:0)
[20:28:40.609] Timer tick, asking Buildbucket for the build status
[20:28:40.741] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:40.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:9:0) after 1m0s
[20:29:40.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:9:0)
[20:29:40.876] Timer tick, asking Buildbucket for the build status
[20:29:41.113] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:41.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:10:0) after 1m0s
[20:30:41.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:10:0)
[20:30:41.159] Timer tick, asking Buildbucket for the build status
[20:30:41.241] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:41.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:11:0) after 1m0s
[20:31:41.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:11:0)
[20:31:41.393] Timer tick, asking Buildbucket for the build status
[20:31:41.461] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:41.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:12:0) after 1m0s
[20:32:41.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:12:0)
[20:32:41.533] Timer tick, asking Buildbucket for the build status
[20:32:41.649] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:41.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:13:0) after 1m0s
[20:33:41.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:13:0)
[20:33:41.675] Timer tick, asking Buildbucket for the build status
[20:33:41.758] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:41.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:14:0) after 1m0s
[20:34:41.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:14:0)
[20:34:41.778] Timer tick, asking Buildbucket for the build status
[20:34:41.853] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:41.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:15:0) after 1m0s
[20:35:41.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:15:0)
[20:35:42.000] Timer tick, asking Buildbucket for the build status
[20:35:42.094] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:42.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:16:0) after 1m0s
[20:36:42.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:16:0)
[20:36:42.138] Timer tick, asking Buildbucket for the build status
[20:36:42.263] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:42.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:17:0) after 1m0s
[20:37:42.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:17:0)
[20:37:42.413] Timer tick, asking Buildbucket for the build status
[20:37:42.533] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:42.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:18:0) after 1m0s
[20:38:42.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:18:0)
[20:38:42.625] Timer tick, asking Buildbucket for the build status
[20:38:42.706] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:42.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:19:0) after 1m0s
[20:39:42.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:19:0)
[20:39:42.802] Timer tick, asking Buildbucket for the build status
[20:39:42.954] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:42.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:20:0) after 1m0s
[20:40:43.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:20:0)
[20:40:43.011] Timer tick, asking Buildbucket for the build status
[20:40:43.286] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:43.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:21:0) after 1m0s
[20:41:43.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:21:0)
[20:41:43.362] Timer tick, asking Buildbucket for the build status
[20:41:43.435] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:43.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:22:0) after 1m0s
[20:42:43.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:22:0)
[20:42:43.511] Timer tick, asking Buildbucket for the build status
[20:42:43.685] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:43.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:23:0) after 1m0s
[20:43:43.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:23:0)
[20:43:43.826] Timer tick, asking Buildbucket for the build status
[20:43:43.907] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:43.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:24:0) after 1m0s
[20:44:43.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:24:0)
[20:44:43.979] Timer tick, asking Buildbucket for the build status
[20:44:44.490] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:44.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:25:0) after 1m0s
[20:45:44.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:25:0)
[20:45:44.542] Timer tick, asking Buildbucket for the build status
[20:45:44.708] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:44.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:26:0) after 1m0s
[20:46:44.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:26:0)
[20:46:44.708] Timer tick, asking Buildbucket for the build status
[20:46:45.018] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:45.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:27:0) after 1m0s
[20:47:45.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:27:0)
[20:47:45.081] Timer tick, asking Buildbucket for the build status
[20:47:45.421] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:45.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:28:0) after 1m0s
[20:48:45.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:28:0)
[20:48:45.580] Timer tick, asking Buildbucket for the build status
[20:48:45.703] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:45.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:29:0) after 1m0s
[20:49:45.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:29:0)
[20:49:45.845] Timer tick, asking Buildbucket for the build status
[20:49:46.011] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:46.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:30:0) after 1m0s
[20:50:46.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:30:0)
[20:50:46.021] Timer tick, asking Buildbucket for the build status
[20:50:46.110] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:46.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:31:0) after 1m0s
[20:51:46.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:31:0)
[20:51:46.160] Timer tick, asking Buildbucket for the build status
[20:51:46.247] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:46.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:32:0) after 1m0s
[20:52:46.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:32:0)
[20:52:46.303] Timer tick, asking Buildbucket for the build status
[20:52:46.795] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:46.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:33:0) after 1m0s
[20:53:46.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:33:0)
[20:53:46.840] Timer tick, asking Buildbucket for the build status
[20:53:46.988] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:46.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:34:0) after 1m0s
[20:54:47.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:34:0)
[20:54:47.034] Timer tick, asking Buildbucket for the build status
[20:54:47.485] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:47.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:35:0) after 1m0s
[20:55:47.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:35:0)
[20:55:47.465] Timer tick, asking Buildbucket for the build status
[20:55:47.590] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:47.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:36:0) after 1m0s
[20:56:47.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:36:0)
[20:56:47.611] Timer tick, asking Buildbucket for the build status
[20:56:47.878] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:47.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:37:0) after 1m0s
[20:57:47.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:37:0)
[20:57:47.975] Timer tick, asking Buildbucket for the build status
[20:57:48.066] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:48.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:38:0) after 1m0s
[20:58:48.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:38:0)
[20:58:48.144] Timer tick, asking Buildbucket for the build status
[20:58:48.232] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:48.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:39:0) after 1m0s
[20:59:48.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:39:0)
[20:59:48.250] Timer tick, asking Buildbucket for the build status
[20:59:48.360] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:48.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:40:0) after 1m0s
[21:00:48.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:40:0)
[21:00:48.393] Timer tick, asking Buildbucket for the build status
[21:00:48.485] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:48.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:41:0) after 1m0s
[21:01:48.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:41:0)
[21:01:48.507] Timer tick, asking Buildbucket for the build status
[21:01:48.820] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:48.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:42:0) after 1m0s
[21:02:48.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:42:0)
[21:02:48.873] Timer tick, asking Buildbucket for the build status
[21:02:48.964] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:48.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:43:0) after 1m0s
[21:03:49.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:43:0)
[21:03:49.035] Timer tick, asking Buildbucket for the build status
[21:03:49.199] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:49.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:44:0) after 1m0s
[21:04:49.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:44:0)
[21:04:49.245] Timer tick, asking Buildbucket for the build status
[21:04:49.338] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:49.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:45:0) after 1m0s
[21:05:49.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:45:0)
[21:05:49.402] Timer tick, asking Buildbucket for the build status
[21:05:49.486] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:49.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:46:0) after 1m0s
[21:06:49.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:46:0)
[21:06:49.536] Timer tick, asking Buildbucket for the build status
[21:06:49.730] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:49.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:47:0) after 1m0s
[21:07:49.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:47:0)
[21:07:49.749] Timer tick, asking Buildbucket for the build status
[21:07:49.904] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:49.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:48:0) after 1m0s
[21:08:50.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:48:0)
[21:08:50.550] Timer tick, asking Buildbucket for the build status
[21:08:50.643] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:50.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:49:0) after 1m0s
[21:09:50.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:49:0)
[21:09:50.662] Timer tick, asking Buildbucket for the build status
[21:09:50.754] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:50.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:50:0) after 1m0s
[21:10:50.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:50:0)
[21:10:50.786] Timer tick, asking Buildbucket for the build status
[21:10:51.201] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:51.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:51:0) after 1m0s
[21:11:51.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:51:0)
[21:11:51.221] Timer tick, asking Buildbucket for the build status
[21:11:51.301] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:51.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:52:0) after 1m0s
[21:12:51.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:52:0)
[21:12:51.320] Timer tick, asking Buildbucket for the build status
[21:12:51.438] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:51.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:53:0) after 1m0s
[21:13:51.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:53:0)
[21:13:51.462] Timer tick, asking Buildbucket for the build status
[21:13:51.543] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:51.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:54:0) after 1m0s
[21:14:51.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:54:0)
[21:14:51.603] Timer tick, asking Buildbucket for the build status
[21:14:52.181] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:52.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:55:0) after 1m0s
[21:15:52.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:55:0)
[21:15:52.178] Timer tick, asking Buildbucket for the build status
[21:15:52.372] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:52.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:56:0) after 1m0s
[21:16:52.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:56:0)
[21:16:52.404] Timer tick, asking Buildbucket for the build status
[21:16:52.738] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:52.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:57:0) after 1m0s
[21:17:52.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:57:0)
[21:17:52.769] Timer tick, asking Buildbucket for the build status
[21:17:52.918] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:52.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:58:0) after 1m0s
[21:18:52.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:58:0)
[21:18:52.946] Timer tick, asking Buildbucket for the build status
[21:18:53.029] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:53.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:59:0) after 1m0s
[21:19:53.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:59:0)
[21:19:53.032] Timer tick, asking Buildbucket for the build status
[21:19:53.287] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:53.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:60:0) after 1m0s
[21:20:53.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:60:0)
[21:20:53.344] Timer tick, asking Buildbucket for the build status
[21:20:53.546] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:53.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:61:0) after 1m0s
[21:21:53.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:61:0)
[21:21:53.725] Timer tick, asking Buildbucket for the build status
[21:21:53.901] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:53.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:62:0) after 1m0s
[21:22:54.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:62:0)
[21:22:54.105] Timer tick, asking Buildbucket for the build status
[21:22:54.597] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:54.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:63:0) after 1m0s
[21:23:54.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:63:0)
[21:23:54.972] Timer tick, asking Buildbucket for the build status
[21:23:55.076] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:55.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:64:0) after 1m0s
[21:24:55.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:64:0)
[21:24:55.314] Timer tick, asking Buildbucket for the build status
[21:24:55.696] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:55.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:65:0) after 1m0s
[21:25:55.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:65:0)
[21:25:55.768] Timer tick, asking Buildbucket for the build status
[21:25:55.896] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:55.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:66:0) after 1m0s
[21:26:56.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:66:0)
[21:26:56.255] Timer tick, asking Buildbucket for the build status
[21:26:56.338] Build 8854474865733401632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:56.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019904927681224048:67:0) after 1m0s
[21:27:17.056] Received PubSub notification, asking Buildbucket for the build status
[21:27:17.257] Build 8854474865733401632: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:27:17.257] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614115636485294",
"created_by": "project:chromium",
"created_ts": "1614111757493223",
"failure_reason": "BUILD_FAILURE",
"id": "8854474865733401632",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a80cf5fdad31d9898817e65c35cd907268194e60\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SRC_ExternalClearKey/EncryptedMediaTest.Playback_VideoClearAudio_WebM_Opus/0\\n\\n- ChromeRenderFrameObserverNoTranslateNorPhishingTest.CapturePageTextNotCalled\\n\\n- FormAutofillTest.FindFormForInputElement\\n\\n- PasswordAutofillAgentTest.PromptForAJAXSubmitAfterDeletingParentElement\\n\\n- ...6 more failure(s) (10 total)...\"}, \"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\": \"a80cf5fdad31d9898817e65c35cd907268194e60\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17496, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"f19f319a59c83d9ccdce08a3b41024cfc997fd8d\", \"got_dawn_revision\": \"232f4d183ce8ab93909b30af814e320c0f0db57c\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"a80cf5fdad31d9898817e65c35cd907268194e60\", \"got_revision_cp\": \"refs/heads/master@{#856826}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"8bc6184274d97b9b57d5389e423f6f3f7c092918\", \"got_v8_revision_cp\": \"refs/heads/9.0.239@{#1}\", \"got_webrtc_revision\": \"d0844a80deaab28d7df8e5dff9f71e6ab6919025\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33323}\", \"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\": \"a80cf5fdad31d9898817e65c35cd907268194e60\", \"swarm_hashes_refs/heads/master(at){#856826}_without_patch\": {\"absl_hardening_tests\": \"c09b678be0ed806b6f0edbcdb13730c9d2b429e2\", \"accessibility_unittests\": \"685299daa6de6e8658b55d3265514bf9f7cc9ca4\", \"angle_unittests\": \"9a33f2e51748be2af06bd7a07675f97ad4b9644a\", \"app_shell_unittests\": \"9de43cabc7adcfb7f50cf39c9569563d559bb847\", \"aura_unittests\": \"25e0695a9b5bd7930c9e594966401bd5cdce4599\", \"base_unittests\": \"602e17284bcae693ee29ec8e4087a2b8b0ab6cb0\", \"base_util_unittests\": \"a941540828cdc9294a8f290e1136da7297fe21f9\", \"blink_common_unittests\": \"19374d38938ac3eda5949e112cea4861717c5582\", \"blink_fuzzer_unittests\": \"05d5332cfe7fc3846578d7518e8d2029ec323711\", \"blink_heap_unittests\": \"8befcee9dad02c622ddd50ce2534a7f465d9079f\", \"blink_platform_unittests\": \"baa6c33e46cad8826e97e22e075e242eca83955a\", \"blink_unittests\": \"907beda6e41fbf2327daadfa0de26208490ba157\", \"boringssl_crypto_tests\": \"eaffa5d12adf692bdb7e8fa939898fe908aba020\", \"boringssl_ssl_tests\": \"b90a5dcfcf288c06f1c7b510298b340cccdb39bf\", \"browser_switcher_bho_unittests\": \"502055f7ba822262a52281d357953ba709b5880c\", \"browser_tests\": \"083805f3fd1475cdafd0fc8f484f2694da63f804\", \"capture_unittests\": \"4ca5331dd8f0e8763f34a4a51b00fa8dda7d43ff\", \"cast_unittests\": \"c5c1ffb72dea5d996c44cd48708dfabc13944f51\", \"cc_unittests\": \"6289499c938358bd905bd67565cf17ba8880192c\", \"chrome_app_unittests\": \"bbb271e8d52f4b373acea80c04988927df622ad2\", \"chrome_cleaner_unittests\": \"c4d36fd340f9b860c0da23b01d6cd5c450221373\", \"chrome_elf_unittests\": \"8bfc4c8a118aa5f133f0dbd89cab82faa2804c52\", \"chromedriver_unittests\": \"b10b86a570bc663df3061293ab902001a4979a57\", \"components_browsertests\": \"0fefa3743299d61b7cfa0a8601161f14fa397d9c\", \"components_unittests\": \"940c294de219492b22495ee408c73d63aa9fed7e\", \"compositor_unittests\": \"2bb632c5d76fc648f655e491bc812bd734c4a463\", \"content_browsertests\": \"a4a3c06b5f87ae49c77c9aa53b751c837b8776c0\", \"content_unittests\": \"478c11831af277acea7dd7840ef73c7b15ff4f9d\", \"courgette_unittests\": \"5e5f411c1842bcda1df7c4881aeac1fe9c2d6db5\", \"crashpad_tests\": \"02299e5d3d0577ee61f53495d06d069e4d20744e\", \"cronet_tests\": \"a4635c763890af63ad7a70a7749dd18b6e2ebdc3\", \"cronet_unittests\": \"290eef0c0b064ed963fe5551db73e82792fef963\", \"crypto_unittests\": \"77790055e53a525dc18193822ac154df6e0e7705\", \"delayloads_unittests\": \"29c25ea6efa92e834c697d7cd0783a0705d20928\", \"device_unittests\": \"4b5e407efb5dbb1466dd97aa70b733afe95691f1\", \"display_unittests\": \"e0edad0b916d7d55a91635285633660e546dc305\", \"elevation_service_unittests\": \"c82cb658f85aded5cd007e0d8890f154324dede0\", \"events_unittests\": \"519f3b0048f1b3ef98b0850fe52af94dc5a395a0\", \"extensions_browsertests\": \"83e0477e1ef24f95c3a5e829086d509f79c673a3\", \"extensions_unittests\": \"6eec67d940635c23bdebd523c38d314888a3c304\", \"filesystem_service_unittests\": \"20f491b912ef899c049bd23207fba6e0bc4c21b0\", \"gcm_unit_tests\": \"06cc7b49bc4deea7a102c33e7271ed1385919493\", \"gcp_unittests\": \"8dfdb3d632d4437f5deec299210d94ea323a8436\", \"gfx_unittests\": \"66e92d40a80b7061e5d4223c629af51472196774\", \"gin_unittests\": \"d345b2601993cd4560556c90d7b42c97ee4ba8b0\", \"google_apis_unittests\": \"6ae98065dc7bd24a39d55ea13fa4646735d72602\", \"gpu_unittests\": \"8e33f72a2784c71c63c213d88825276fdcae536a\", \"gwp_asan_unittests\": \"93e7dedd71ad06ca4e9731928c85687ae8303eb8\", \"headless_browsertests\": \"1271e85f4faabf7cbeaa1fb28ccdae6f38a53312\", \"headless_unittests\": \"96e56a4eaa89627f1a6c4bf7b159255c711e6979\", \"install_static_unittests\": \"85020ea57cbd0ce57d0dd855b51c7daf9719d4a6\", \"installer_util_unittests\": \"262071faf2dad1b4774ca6c2325138917dee3699\", \"interactive_ui_tests\": \"8601e8636054b3981bdbd6d56351760750e5e174\", \"ipc_tests\": \"8305f256237bbeb0959ce8765701336eb54f4b05\", \"jingle_unittests\": \"c75bf1c2bd38605c1906f4e8a9d6b72505ccf014\", \"latency_unittests\": \"69fd292c47db4ff9da633c4114295d81b8325afa\", \"libjingle_xmpp_unittests\": \"e6239c02e2291c91229ce649daa90d9f723d95a5\", \"liburlpattern_unittests\": \"e1d060681d119bdfdecd08e6d7df8f4a11125da2\", \"media_blink_unittests\": \"916ea62d951c7bf5c5595645b27ee9067d665e52\", \"media_unittests\": \"2f7909a6acc1d3b43038ecb917b3fb102332e2a4\", \"message_center_unittests\": \"b8986a32348adebff932d831e0ebb4b531142342\", \"midi_unittests\": \"d04f8ba7d6ab9003b099c469305791ef5e9857b9\", \"mojo_core_unittests\": \"d89a4400c4185bf9aad33000eee2b4b2eeb3fe06\", \"mojo_unittests\": \"6eb7be141c0727e71d11d7efa758a360c4511b52\", \"nacl_loader_unittests\": \"31398faffaf75c9e1851f538a37298e3087e1a32\", \"native_theme_unittests\": \"f5b93f99091a8b337e6b4b8595ff2d1916554b34\", \"net_unittests\": \"291e7c9ffb0e6f324af719447f7e8db2435d6163\", \"notification_helper_unittests\": \"a6106afba61fc224767e8b269eba5b53ebae7ba0\", \"pdf_unittests\": \"5c2203fd7417c1042fe13b2c5d102215454b53f2\", \"perfetto_unittests\": \"69e39dd837ab596b3cd4b1694963355a1f38279b\", \"ppapi_unittests\": \"63ea2528018cceb9aa1ad800b39e691b93db8064\", \"printing_unittests\": \"dfdf414079e8eef7f9a5ae232fd6445bae1922e9\", \"remoting_unittests\": \"e27be7a622d96ac747cfe841d95649128c13b83c\", \"sbox_integration_tests\": \"7f7d39e2bac7ce89ef60f1e0a4bd9250756285dc\", \"sbox_unittests\": \"cdb819388ceb08f4e441401c98816ca204167a9b\", \"sbox_validation_tests\": \"1c33e4b9e8aad7866111db259bf54c3a86256e77\", \"service_manager_unittests\": \"5135fc6cbc117d0c4e5b47cd8aa20f6c55b73dc1\", \"services_unittests\": \"e0a883ea0d9869e5b23bdfac134a5008c36baf5c\", \"setup_unittests\": \"beade080469c64101fb36ede1298ad1024ea81e1\", \"shell_dialogs_unittests\": \"135abff62a564f9fe023b665975a5c396aed633f\", \"skia_unittests\": \"f06e8962c376fedb2fe3e126a96d4e031206f6c9\", \"snapshot_unittests\": \"0f80a8cc23d78f0a9b1afc90beff1c83e5e52498\", \"sql_unittests\": \"6dc93b26201fd4dc78f4db2274331a1b2a559899\", \"storage_unittests\": \"ee9a2b083937c8c82933dff79cd126d33715a543\", \"sync_integration_tests\": \"04d618bfb943b6078ab73a85e044c178978b41e4\", \"traffic_annotation_auditor_unittests\": \"349e9c74ae1c6ec1243b4ff926301acfc05f1985\", \"ui_base_unittests\": \"0a595db2c523bc4446c44a7ebee1e838787e07da\", \"ui_touch_selection_unittests\": \"6f9374822d24b07b556f980465226b36f260e8b3\", \"unit_tests\": \"3c31a86170091a0d088cb1c754ed5572bf9708cb\", \"updater_tests\": \"3843c9e25b68e281d1c8d678c07a483e2c62c229\", \"url_unittests\": \"0a2697a04bc7438c2f43f7e2cd8451029093f106\", \"views_unittests\": \"0d85fc6045975568a4a589aa3c68e8fa7711c9de\", \"viz_unittests\": \"c2b4a97dbc40637224b9616d985139fbf5676281\", \"vr_common_unittests\": \"f308fc67b0e1de8bc9b8d950db3af10658764405\", \"vr_pixeltests\": \"cd62800ee2e5810aa6ef0e3322053e0b0c20ea0c\", \"weblayer_browsertests\": \"74fbb888a55a8d2f9ae92a2110075a13fcff12c0\", \"weblayer_unittests\": \"3e8a278f3b1d3f15bd449c86a062e288a2e38032\", \"wm_unittests\": \"f430bb1b46ed31bc870259b9ba2780f9efb1ec0f\", \"wtf_unittests\": \"c1b43da916dbb0c1520d564499522fede2804f38\", \"zlib_unittests\": \"a890387f5c9a88dc3667795f0feb5e093dc32e52\", \"zucchini_unittests\": \"bb881c14deea9e67d74c03ecccda6383108c6dfa\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-xpf4\"], \"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\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- SRC_ExternalClearKey/EncryptedMediaTest.Playback_VideoClearAudio_WebM_Opus/0\\n\\n- ChromeRenderFrameObserverNoTranslateNorPhishingTest.CapturePageTextNotCalled\\n\\n- FormAutofillTest.FindFormForInputElement\\n\\n- PasswordAutofillAgentTest.PromptForAJAXSubmitAfterDeletingParentElement\\n\\n- ...6 more failure(s) (10 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614111768441048",
"status": "COMPLETED",
"status_changed_ts": "1614111770309207",
"tags": [
"build_address:luci.chromium.ci/win-asan/17496",
"builder:win-asan",
"buildset:commit/git/a80cf5fdad31d9898817e65c35cd907268194e60",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a80cf5fdad31d9898817e65c35cd907268194e60",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019904927681224048",
"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/8854474865733401632/+/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:51e96062ab41a410",
"user_agent:luci-scheduler"
],
"updated_ts": "1614115636485586",
"url": "https://ci.chromium.org/b/8854474865733401632",
"utcnow_ts": "1614115637200608"
}
}
[21:27:17.257] Invocation finished in 1h4m41.005366409s with status FAILED