[20:06:36.355] New invocation is queued and will start shortly
[20:06:37.465] Starting the invocation (attempt 1)
[20:06:37.520] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:06:37.521] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:06:37.521] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019996531188522016",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"34cb6244bd4bcb36391629a9fde2044e5e4b3b47\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019996531188522016",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/34cb6244bd4bcb36391629a9fde2044e5e4b3b47",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/34cb6244bd4bcb36391629a9fde2044e5e4b3b47",
"gitiles_ref:refs/heads/master"
]
}
[20:06:40.138] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614024397658609",
"id": "8854566469160042096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"34cb6244bd4bcb36391629a9fde2044e5e4b3b47\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614024398933297",
"tags": [
"build_address:luci.chromium.ci/win-asan/17476",
"builder:win-asan",
"buildset:commit/git/34cb6244bd4bcb36391629a9fde2044e5e4b3b47",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/34cb6244bd4bcb36391629a9fde2044e5e4b3b47",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019996531188522016",
"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/8854566469160042096/+/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": "1614024398933473",
"url": "https://ci.chromium.org/b/8854566469160042096",
"utcnow_ts": "1614024400130611"
}
}
[20:06:40.138] Task URL: https://ci.chromium.org/b/8854566469160042096
[20:06:40.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:2:0) after 1m0s
[20:06:43.098] Received PubSub notification, asking Buildbucket for the build status
[20:06:43.177] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:40.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:2:0)
[20:07:40.162] Timer tick, asking Buildbucket for the build status
[20:07:40.242] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:40.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:4:0) after 1m0s
[20:08:40.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:4:0)
[20:08:40.363] Timer tick, asking Buildbucket for the build status
[20:08:40.773] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:40.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:5:0) after 1m0s
[20:09:40.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:5:0)
[20:09:40.818] Timer tick, asking Buildbucket for the build status
[20:09:40.951] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:40.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:6:0) after 1m0s
[20:10:40.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:6:0)
[20:10:40.984] Timer tick, asking Buildbucket for the build status
[20:10:41.058] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:41.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:7:0) after 1m0s
[20:11:41.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:7:0)
[20:11:41.171] Timer tick, asking Buildbucket for the build status
[20:11:41.336] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:41.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:8:0) after 1m0s
[20:12:41.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:8:0)
[20:12:41.410] Timer tick, asking Buildbucket for the build status
[20:12:41.624] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:41.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:9:0) after 1m0s
[20:13:41.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:9:0)
[20:13:41.772] Timer tick, asking Buildbucket for the build status
[20:13:41.874] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:41.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:10:0) after 1m0s
[20:14:41.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:10:0)
[20:14:41.898] Timer tick, asking Buildbucket for the build status
[20:14:41.972] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:41.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:11:0) after 1m0s
[20:15:41.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:11:0)
[20:15:41.990] Timer tick, asking Buildbucket for the build status
[20:15:42.270] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:42.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:12:0) after 1m0s
[20:16:42.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:12:0)
[20:16:42.384] Timer tick, asking Buildbucket for the build status
[20:16:42.464] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:42.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:13:0) after 1m0s
[20:17:42.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:13:0)
[20:17:42.540] Timer tick, asking Buildbucket for the build status
[20:17:42.664] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:42.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:14:0) after 1m0s
[20:18:42.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:14:0)
[20:18:42.706] Timer tick, asking Buildbucket for the build status
[20:18:42.897] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:42.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:15:0) after 1m0s
[20:19:42.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:15:0)
[20:19:42.918] Timer tick, asking Buildbucket for the build status
[20:19:42.985] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:42.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:16:0) after 1m0s
[20:20:43.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:16:0)
[20:20:43.059] Timer tick, asking Buildbucket for the build status
[20:20:43.145] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:43.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:17:0) after 1m0s
[20:21:43.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:17:0)
[20:21:43.210] Timer tick, asking Buildbucket for the build status
[20:21:43.513] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:43.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:18:0) after 1m0s
[20:22:43.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:18:0)
[20:22:43.565] Timer tick, asking Buildbucket for the build status
[20:22:43.676] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:43.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:19:0) after 1m0s
[20:23:43.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:19:0)
[20:23:43.723] Timer tick, asking Buildbucket for the build status
[20:23:43.795] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:43.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:20:0) after 1m0s
[20:24:43.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:20:0)
[20:24:43.885] Timer tick, asking Buildbucket for the build status
[20:24:43.952] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:43.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:21:0) after 1m0s
[20:25:43.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:21:0)
[20:25:43.978] Timer tick, asking Buildbucket for the build status
[20:25:44.155] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:44.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:22:0) after 1m0s
[20:26:44.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:22:0)
[20:26:44.190] Timer tick, asking Buildbucket for the build status
[20:26:44.624] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:44.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:23:0) after 1m0s
[20:27:44.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:23:0)
[20:27:44.649] Timer tick, asking Buildbucket for the build status
[20:27:44.904] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:44.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:24:0) after 1m0s
[20:28:44.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:24:0)
[20:28:44.944] Timer tick, asking Buildbucket for the build status
[20:28:45.233] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:45.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:25:0) after 1m0s
[20:29:45.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:25:0)
[20:29:45.234] Timer tick, asking Buildbucket for the build status
[20:29:45.362] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:45.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:26:0) after 1m0s
[20:30:45.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:26:0)
[20:30:45.402] Timer tick, asking Buildbucket for the build status
[20:30:45.618] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:45.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:27:0) after 1m0s
[20:31:45.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:27:0)
[20:31:45.711] Timer tick, asking Buildbucket for the build status
[20:31:45.968] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:45.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:28:0) after 1m0s
[20:32:46.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:28:0)
[20:32:46.040] Timer tick, asking Buildbucket for the build status
[20:32:46.495] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:46.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:29:0) after 1m0s
[20:33:46.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:29:0)
[20:33:46.641] Timer tick, asking Buildbucket for the build status
[20:33:46.723] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:46.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:30:0) after 1m0s
[20:34:46.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:30:0)
[20:34:46.774] Timer tick, asking Buildbucket for the build status
[20:34:47.053] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:47.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:31:0) after 1m0s
[20:35:47.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:31:0)
[20:35:47.061] Timer tick, asking Buildbucket for the build status
[20:35:47.144] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:47.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:32:0) after 1m0s
[20:36:47.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:32:0)
[20:36:47.208] Timer tick, asking Buildbucket for the build status
[20:36:47.355] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:47.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:33:0) after 1m0s
[20:37:47.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:33:0)
[20:37:47.430] Timer tick, asking Buildbucket for the build status
[20:37:47.511] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:47.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:34:0) after 1m0s
[20:38:47.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:34:0)
[20:38:47.576] Timer tick, asking Buildbucket for the build status
[20:38:47.792] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:47.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:35:0) after 1m0s
[20:39:47.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:35:0)
[20:39:47.814] Timer tick, asking Buildbucket for the build status
[20:39:48.037] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:48.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:36:0) after 1m0s
[20:40:48.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:36:0)
[20:40:48.156] Timer tick, asking Buildbucket for the build status
[20:40:48.251] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:48.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:37:0) after 1m0s
[20:41:48.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:37:0)
[20:41:48.237] Timer tick, asking Buildbucket for the build status
[20:41:48.343] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:48.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:38:0) after 1m0s
[20:42:48.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:38:0)
[20:42:48.384] Timer tick, asking Buildbucket for the build status
[20:42:48.805] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:48.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:39:0) after 1m0s
[20:43:48.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:39:0)
[20:43:48.875] Timer tick, asking Buildbucket for the build status
[20:43:49.845] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:49.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:40:0) after 1m0s
[20:44:49.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:40:0)
[20:44:49.933] Timer tick, asking Buildbucket for the build status
[20:44:50.033] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:50.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:41:0) after 1m0s
[20:45:50.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:41:0)
[20:45:50.164] Timer tick, asking Buildbucket for the build status
[20:45:50.318] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:50.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:42:0) after 1m0s
[20:46:50.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:42:0)
[20:46:50.461] Timer tick, asking Buildbucket for the build status
[20:46:51.776] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:51.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:43:0) after 1m0s
[20:47:51.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:43:0)
[20:47:51.798] Timer tick, asking Buildbucket for the build status
[20:47:51.882] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:51.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:44:0) after 1m0s
[20:48:51.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:44:0)
[20:48:51.937] Timer tick, asking Buildbucket for the build status
[20:48:52.224] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:52.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:45:0) after 1m0s
[20:49:52.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:45:0)
[20:49:52.267] Timer tick, asking Buildbucket for the build status
[20:49:52.515] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:52.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:46:0) after 1m0s
[20:50:52.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:46:0)
[20:50:52.538] Timer tick, asking Buildbucket for the build status
[20:50:52.803] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:52.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:47:0) after 1m0s
[20:51:52.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:47:0)
[20:51:52.828] Timer tick, asking Buildbucket for the build status
[20:51:52.969] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:52.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:48:0) after 1m0s
[20:52:53.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:48:0)
[20:52:53.057] Timer tick, asking Buildbucket for the build status
[20:52:53.167] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:53.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:49:0) after 1m0s
[20:53:53.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:49:0)
[20:53:53.174] Timer tick, asking Buildbucket for the build status
[20:53:53.308] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:53.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:50:0) after 1m0s
[20:54:53.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:50:0)
[20:54:53.381] Timer tick, asking Buildbucket for the build status
[20:54:53.469] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:53.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:51:0) after 1m0s
[20:55:53.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:51:0)
[20:55:53.473] Timer tick, asking Buildbucket for the build status
[20:55:53.831] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:53.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:52:0) after 1m0s
[20:56:53.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:52:0)
[20:56:53.922] Timer tick, asking Buildbucket for the build status
[20:56:54.032] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:54.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:53:0) after 1m0s
[20:57:54.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:53:0)
[20:57:54.122] Timer tick, asking Buildbucket for the build status
[20:57:57.737] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:57.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:54:0) after 1m0s
[20:58:57.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:54:0)
[20:58:57.794] Timer tick, asking Buildbucket for the build status
[20:58:57.876] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:57.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:55:0) after 1m0s
[20:59:57.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:55:0)
[20:59:57.887] Timer tick, asking Buildbucket for the build status
[20:59:58.176] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:58.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:56:0) after 1m0s
[21:00:58.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:56:0)
[21:00:58.222] Timer tick, asking Buildbucket for the build status
[21:00:58.498] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:58.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:57:0) after 1m0s
[21:01:58.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:57:0)
[21:01:58.523] Timer tick, asking Buildbucket for the build status
[21:01:58.698] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:58.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:58:0) after 1m0s
[21:02:58.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:58:0)
[21:02:58.724] Timer tick, asking Buildbucket for the build status
[21:02:59.220] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:59.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:59:0) after 1m0s
[21:03:59.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:59:0)
[21:03:59.245] Timer tick, asking Buildbucket for the build status
[21:03:59.450] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:59.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:60:0) after 1m0s
[21:04:59.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:60:0)
[21:04:59.488] Timer tick, asking Buildbucket for the build status
[21:04:59.660] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:59.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:61:0) after 1m0s
[21:05:59.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:61:0)
[21:05:59.657] Timer tick, asking Buildbucket for the build status
[21:06:00.212] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:00.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:62:0) after 1m0s
[21:07:00.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:62:0)
[21:07:00.240] Timer tick, asking Buildbucket for the build status
[21:07:00.560] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:00.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:63:0) after 1m0s
[21:08:00.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:63:0)
[21:08:00.968] Timer tick, asking Buildbucket for the build status
[21:08:01.057] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:01.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:64:0) after 1m0s
[21:09:01.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:64:0)
[21:09:01.277] Timer tick, asking Buildbucket for the build status
[21:09:01.465] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:01.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:65:0) after 1m0s
[21:10:01.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:65:0)
[21:10:01.463] Timer tick, asking Buildbucket for the build status
[21:10:01.724] Build 8854566469160042096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:01.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019996531188522016:66:0) after 1m0s
[21:10:27.795] Received PubSub notification, asking Buildbucket for the build status
[21:10:28.195] Build 8854566469160042096: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:10:28.195] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614028227156129",
"created_by": "project:chromium",
"created_ts": "1614024397658609",
"failure_reason": "BUILD_FAILURE",
"id": "8854566469160042096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"34cb6244bd4bcb36391629a9fde2044e5e4b3b47\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**compositor_unittests** failed because of:\\n\\n- LayerAnimatorTest.ObserverDeletesAnimationsOnAbort\\n\\n- LayerOwnerTestWithCompositor.RecreateRootLayerDuringAnimation\\n\\n- LayerAnimatorTest.MultiPreemptBySettingNewTarget\\n\\n- TotalAnimationThroughputReporterTest.RemoveWhileAnimating\\n\\n- ...272 more failure(s) (276 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\": \"34cb6244bd4bcb36391629a9fde2044e5e4b3b47\", \"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\": 17476, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"c624d4101ea6c01e494e5322ca0baca5fef085a6\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"34cb6244bd4bcb36391629a9fde2044e5e4b3b47\", \"got_revision_cp\": \"refs/heads/master@{#856394}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"c8019e4062f5bfc3094573fec601a3682ca791bd\", \"got_v8_revision_cp\": \"refs/heads/9.0.235@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"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\": \"34cb6244bd4bcb36391629a9fde2044e5e4b3b47\", \"swarm_hashes_refs/heads/master(at){#856394}_without_patch\": {\"absl_hardening_tests\": \"2b87268e7cda98045b64c2fdd7fc65ea978ddf97\", \"accessibility_unittests\": \"0f065b3edb62b67d63a843151d5bcab742edb5fa\", \"angle_unittests\": \"0b0e476523d50d59cd1db0f8bcfb33d055d7604a\", \"app_shell_unittests\": \"5637c5513c9ec9648a0d7cc6c92da544adc5fb6f\", \"aura_unittests\": \"bc9da05cc0d58445c0d53d6ee8ce1a60a4d3c8b5\", \"base_unittests\": \"6c5fc3edb78532a037cf3e8c7881d032568609c2\", \"base_util_unittests\": \"452c2d9bc97fed71e28a5149d876b31aea18d38b\", \"blink_common_unittests\": \"477da507ec6d83bf095b9dc91bf45527952ce217\", \"blink_fuzzer_unittests\": \"02c9e80abc9e92d5701a108f87eed1489642e278\", \"blink_heap_unittests\": \"e8ed3c81baca41b91f8dc8c8d5f6eb435cb79dd3\", \"blink_platform_unittests\": \"77bf2763e9e56b7b60f9093b92a3b5a508d3efc8\", \"blink_unittests\": \"7884caa89e10e58a3e3df2b03d8f67970ff50b14\", \"boringssl_crypto_tests\": \"e23c3080abcdbe87d0ce7439d84eed3e1c91a3dc\", \"boringssl_ssl_tests\": \"a8040d8fc802fadaac0a6b53048fd00e7e86823e\", \"browser_switcher_bho_unittests\": \"a760eaacfc06ca324486cbeae4ff8518cfee20d7\", \"browser_tests\": \"ce4b333fd9cc99a73a4915ee61edab9aaee4b010\", \"capture_unittests\": \"a6d9e8bdf030d1ad6b84362eb2135f5dd4334fe9\", \"cast_unittests\": \"c7cc474a48379fd7b18c9f6b19a9cb1bf44444f3\", \"cc_unittests\": \"21aa9b5d9a69f16a5b8b0d0210f930e706c0012a\", \"chrome_app_unittests\": \"fcba0a4f0d7657c9963ec844aec63b3ea7c80a27\", \"chrome_cleaner_unittests\": \"8647bc0d66b53b4e289152890d602c421b324f26\", \"chrome_elf_unittests\": \"1dcc1f7052fd251dc5d8e29113322ff068f4642c\", \"chromedriver_unittests\": \"a76e97c0eee6be51bad1e6c5536d2d0a24637f45\", \"components_browsertests\": \"77776e3c85863317bb613f4e44e38bcb9871fc69\", \"components_unittests\": \"e6f8de514f986de020aeaa60efd73d0d89beca37\", \"compositor_unittests\": \"a2e55d6d663b1b0b79f220b8fdf9a7c13c1320dd\", \"content_browsertests\": \"ac82ee99b96d5252b9f02b711a6a5577c457535b\", \"content_unittests\": \"df40d67588360254575ab2b9856a01307c5b4b51\", \"courgette_unittests\": \"f6d082ab9d35330f90ed4cff9b04c619fb811f6b\", \"crashpad_tests\": \"ca42246d03ef5549c4bebc0deeb3f9ae67a6c2dc\", \"cronet_tests\": \"bb8acd4b7cb1b8879625a81afe443b8121220f4f\", \"cronet_unittests\": \"4b022198d9839a1f7b0c84a4661bde6c77042287\", \"crypto_unittests\": \"7e0f41ab593a075299a992781e0a560f8bf1abc1\", \"delayloads_unittests\": \"4f3ee732dd081b773cfc5ab6b39a49644641df4f\", \"device_unittests\": \"562042259878e94e5576ba5d87a9ed61ffb47f1c\", \"display_unittests\": \"79d4f3c841a671535b856d39fc0c9593a36e5e47\", \"elevation_service_unittests\": \"c94f00e50a9a23e035744c4694ce8db7f8a99b9f\", \"events_unittests\": \"39f3fa807e38f552166281f13e35233164934fe3\", \"extensions_browsertests\": \"3fc868f8ec9273734322bf9d47642ebb2121c381\", \"extensions_unittests\": \"14ec8907e618aef754868ae6c228ade23290549f\", \"filesystem_service_unittests\": \"8aae32f5b549b3a8c2378af568be6667b0491594\", \"gcm_unit_tests\": \"089a58102ba58e6a1765e45cbd9ca5509ea0d412\", \"gcp_unittests\": \"d39c47a181c83488125f64ab209aeff5a7f67c16\", \"gfx_unittests\": \"5af9611e6a47c65e5c247a366511d2c3e0911e21\", \"gin_unittests\": \"2fef1bae4b8fad429a81ed1d595203b39ea85489\", \"google_apis_unittests\": \"f9f9f7e2c4b3bc689ec9c006e803c16f838c3cde\", \"gpu_unittests\": \"eb94b01a1c40b1abd6b7e612f20d29be490cd461\", \"gwp_asan_unittests\": \"23e629043324b7f169b18b8352407e881e8c90d6\", \"headless_browsertests\": \"3a726cad5a1217eccaa15b96e820255e4bd7be57\", \"headless_unittests\": \"4aed42a4ca485f0db5b5011e7e2907ec72578e49\", \"install_static_unittests\": \"3538a44bcccf4063a9b2dd4da053e5800db09d35\", \"installer_util_unittests\": \"3fbbe4c321d132edb464f70b3a6d1310de7f894d\", \"interactive_ui_tests\": \"e3f7caa3abdfab74fb56ed06eb889929e97ac47c\", \"ipc_tests\": \"587189a90cb5c7b110b9ea37b891d56a0259780b\", \"jingle_unittests\": \"e9a2f0f1f370c4748afe610f76d4d15f6ac5c8f8\", \"latency_unittests\": \"01b1d4195300e9a5ffeaefbfa788447742c7df9f\", \"libjingle_xmpp_unittests\": \"9a6ef37683afdda0b354705f79bc66d9d3505b51\", \"liburlpattern_unittests\": \"322f0503af16cf960153eb3905b592b4f3df7bc9\", \"media_blink_unittests\": \"026c45c19163ac323cd5f9083e56d2a824297be0\", \"media_unittests\": \"47f045438d8faa1757c4cfd2cf5c6c26eeecd70b\", \"message_center_unittests\": \"3d1ddad1f6f015ea2a8d2b167af04794371391b0\", \"midi_unittests\": \"7d88613cac3c8ad17dfa55d9d19b3ff4eb788298\", \"mojo_core_unittests\": \"982f472dd523ec4bc21708648f6ca7a1ae2726e0\", \"mojo_unittests\": \"15190424f5935faa3bd0ef76cdf4ed359cdc2676\", \"nacl_loader_unittests\": \"478120c0c6dd0f823fc296dbf5916f25d8b386b9\", \"native_theme_unittests\": \"678b58885699a0aeb4163a206af0576d58313877\", \"net_unittests\": \"8bebf110b6910ddbf5eedb4f3ac59bab85508e99\", \"notification_helper_unittests\": \"9e4e0447dea64cef3f95a049cd1d0b51285f631e\", \"pdf_unittests\": \"d5ddd84fc5bafc9cd2be6a166e880b8562a78abe\", \"perfetto_unittests\": \"14f80b4de0f0af53f82fdc20f4ef1226a73e2c11\", \"ppapi_unittests\": \"01a87d83e2307fe2c29e0778551313989abdc742\", \"printing_unittests\": \"04b1710e089d7aed09830c9ad5f96eb0f52c3f4c\", \"remoting_unittests\": \"abf31e58ab750601a3b5bf2844de217897d632c7\", \"sbox_integration_tests\": \"1b69654e7e867eaa7fa8acacbccc7fbd9a0d0de2\", \"sbox_unittests\": \"926e4674a54dd0303a6e8984b9542651bf7a17fa\", \"sbox_validation_tests\": \"7dcd5dd360a8220875c2b68356d6cc5d8f64462a\", \"service_manager_unittests\": \"7f3b03debe37e9ef1ae008fb56bfe7c9e5913d85\", \"services_unittests\": \"7825ec2b5267c365d319bdb49cc4038ef1b765d4\", \"setup_unittests\": \"4bf20cd7a64cb1ef7b604450fc509196347b4adb\", \"shell_dialogs_unittests\": \"d50331c3a524c579a194420f907eab86289fec93\", \"skia_unittests\": \"bb38a92def834529560825c332d9c2a7a01c2986\", \"snapshot_unittests\": \"f5219d16709dd4c3415076aeff68421197fa9a3f\", \"sql_unittests\": \"42aa1fc8a33d81501af70a2b8e017a771e05a240\", \"storage_unittests\": \"fd5de0ce7903845301493df95a1e8c565545a959\", \"sync_integration_tests\": \"37884667acec8c252c9a950e1a8339d9874b8699\", \"traffic_annotation_auditor_unittests\": \"457e9c81cf20d18e2b2f354c22f967d9342f641f\", \"ui_base_unittests\": \"634a7fdf6cf536e31b1768c8e246b31705200c0d\", \"ui_touch_selection_unittests\": \"4734b803e1f0435a7935f898e2f406287bddd5f0\", \"unit_tests\": \"a421d4451a4efa032b88bf81b84fc751ac534255\", \"updater_tests\": \"093e0a5fdd50f4fe527ed22730d03346aa22ef2f\", \"url_unittests\": \"51895e318bbe2070fe16252b9d4c29212dba33f2\", \"views_unittests\": \"74b9ee86dfc01d1d2ff5540d328ec48243fb709c\", \"viz_unittests\": \"61478fa885922cfafef3f38528493f451b87fae8\", \"vr_common_unittests\": \"ccbeb62bfd2ddeeef1867f30e5e0050b611671e4\", \"vr_pixeltests\": \"f15e20c485fee5aeccd9fc9bc55366feb24ecf86\", \"weblayer_browsertests\": \"a64787fd5a2e0f5c0436ad3b9282fe340fa13d12\", \"weblayer_unittests\": \"4c2186ed61ff123d16960c34deebd17224f00e1a\", \"wm_unittests\": \"c0fdd9d350db3adaa6f1f68555a2f1e248395f98\", \"wtf_unittests\": \"ea76108d7d14571af58b16580cd8b1d3452dd7ea\", \"zlib_unittests\": \"15b2c1a0d3804dd8c35897ade8932936da6c755b\", \"zucchini_unittests\": \"48fd8fe68ebf7b195a37e041410b29a3bbb828cf\"}}, \"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**compositor_unittests** failed because of:\\n\\n- LayerAnimatorTest.ObserverDeletesAnimationsOnAbort\\n\\n- LayerOwnerTestWithCompositor.RecreateRootLayerDuringAnimation\\n\\n- LayerAnimatorTest.MultiPreemptBySettingNewTarget\\n\\n- TotalAnimationThroughputReporterTest.RemoveWhileAnimating\\n\\n- ...272 more failure(s) (276 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614024402322974",
"status": "COMPLETED",
"status_changed_ts": "1614024402898056",
"tags": [
"build_address:luci.chromium.ci/win-asan/17476",
"builder:win-asan",
"buildset:commit/git/34cb6244bd4bcb36391629a9fde2044e5e4b3b47",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/34cb6244bd4bcb36391629a9fde2044e5e4b3b47",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019996531188522016",
"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/8854566469160042096/+/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:51e42b6766ef9810",
"user_agent:luci-scheduler"
],
"updated_ts": "1614028227427281",
"url": "https://ci.chromium.org/b/8854566469160042096",
"utcnow_ts": "1614028228180015"
}
}
[21:10:28.195] Invocation finished in 1h3m51.855633834s with status FAILED