[09:31:08.742] New invocation is queued and will start shortly
[09:31:10.336] Starting the invocation (attempt 1)
[09:31:10.399] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:31:10.399] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:31:10.399] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029549192364499792",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e949148f7a856f57028a0b52a2ed9517bca5a29a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029549192364499792",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e949148f7a856f57028a0b52a2ed9517bca5a29a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e949148f7a856f57028a0b52a2ed9517bca5a29a",
"gitiles_ref:refs/heads/master"
]
}
[09:31:11.010] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604914270583449",
"id": "8864119129767826768",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e949148f7a856f57028a0b52a2ed9517bca5a29a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604914270905571",
"tags": [
"build_address:luci.chromium.ci/win-asan/15550",
"builder:win-asan",
"buildset:commit/git/e949148f7a856f57028a0b52a2ed9517bca5a29a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e949148f7a856f57028a0b52a2ed9517bca5a29a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029549192364499792",
"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/8864119129767826768/+/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": "1604914270905685",
"url": "https://ci.chromium.org/b/8864119129767826768",
"utcnow_ts": "1604914271000444"
}
}
[09:31:11.010] Task URL: https://ci.chromium.org/b/8864119129767826768
[09:31:11.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:2:0) after 1m0s
[09:31:13.147] Received PubSub notification, asking Buildbucket for the build status
[09:31:13.223] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:11.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:2:0)
[09:32:11.085] Timer tick, asking Buildbucket for the build status
[09:32:11.253] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:11.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:4:0) after 1m0s
[09:33:11.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:4:0)
[09:33:11.281] Timer tick, asking Buildbucket for the build status
[09:33:11.483] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:11.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:5:0) after 1m0s
[09:34:11.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:5:0)
[09:34:11.566] Timer tick, asking Buildbucket for the build status
[09:34:11.683] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:11.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:6:0) after 1m0s
[09:35:11.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:6:0)
[09:35:11.723] Timer tick, asking Buildbucket for the build status
[09:35:12.388] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:12.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:7:0) after 1m0s
[09:36:12.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:7:0)
[09:36:12.470] Timer tick, asking Buildbucket for the build status
[09:36:12.640] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:12.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:8:0) after 1m0s
[09:37:12.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:8:0)
[09:37:12.732] Timer tick, asking Buildbucket for the build status
[09:37:12.822] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:12.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:9:0) after 1m0s
[09:38:13.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:9:0)
[09:38:13.155] Timer tick, asking Buildbucket for the build status
[09:38:13.259] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:13.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:10:0) after 1m0s
[09:39:13.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:10:0)
[09:39:13.440] Timer tick, asking Buildbucket for the build status
[09:39:13.506] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:13.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:11:0) after 1m0s
[09:40:13.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:11:0)
[09:40:13.556] Timer tick, asking Buildbucket for the build status
[09:40:13.784] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:13.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:12:0) after 1m0s
[09:41:13.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:12:0)
[09:41:13.824] Timer tick, asking Buildbucket for the build status
[09:41:13.917] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:13.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:13:0) after 1m0s
[09:42:13.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:13:0)
[09:42:13.997] Timer tick, asking Buildbucket for the build status
[09:42:14.051] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:14.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:14:0) after 1m0s
[09:43:14.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:14:0)
[09:43:14.193] Timer tick, asking Buildbucket for the build status
[09:43:14.438] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:14.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:15:0) after 1m0s
[09:44:14.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:15:0)
[09:44:14.636] Timer tick, asking Buildbucket for the build status
[09:44:14.765] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:14.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:16:0) after 1m0s
[09:45:14.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:16:0)
[09:45:14.883] Timer tick, asking Buildbucket for the build status
[09:45:14.948] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:14.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:17:0) after 1m0s
[09:46:15.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:17:0)
[09:46:15.008] Timer tick, asking Buildbucket for the build status
[09:46:15.128] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:15.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:18:0) after 1m0s
[09:47:15.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:18:0)
[09:47:15.196] Timer tick, asking Buildbucket for the build status
[09:47:15.297] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:15.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:19:0) after 1m0s
[09:48:15.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:19:0)
[09:48:15.336] Timer tick, asking Buildbucket for the build status
[09:48:15.406] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:15.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:20:0) after 1m0s
[09:49:15.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:20:0)
[09:49:15.465] Timer tick, asking Buildbucket for the build status
[09:49:15.538] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:15.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:21:0) after 1m0s
[09:50:15.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:21:0)
[09:50:15.613] Timer tick, asking Buildbucket for the build status
[09:50:15.733] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:15.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:22:0) after 1m0s
[09:51:15.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:22:0)
[09:51:15.857] Timer tick, asking Buildbucket for the build status
[09:51:16.012] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:16.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:23:0) after 1m0s
[09:52:16.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:23:0)
[09:52:16.143] Timer tick, asking Buildbucket for the build status
[09:52:16.267] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:16.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:24:0) after 1m0s
[09:53:16.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:24:0)
[09:53:16.329] Timer tick, asking Buildbucket for the build status
[09:53:16.412] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:16.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:25:0) after 1m0s
[09:54:16.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:25:0)
[09:54:16.477] Timer tick, asking Buildbucket for the build status
[09:54:16.589] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:16.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:26:0) after 1m0s
[09:55:16.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:26:0)
[09:55:16.614] Timer tick, asking Buildbucket for the build status
[09:55:16.801] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:16.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:27:0) after 1m0s
[09:56:16.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:27:0)
[09:56:16.828] Timer tick, asking Buildbucket for the build status
[09:56:16.927] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:16.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:28:0) after 1m0s
[09:57:16.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:28:0)
[09:57:16.973] Timer tick, asking Buildbucket for the build status
[09:57:17.208] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:17.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:29:0) after 1m0s
[09:58:17.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:29:0)
[09:58:17.263] Timer tick, asking Buildbucket for the build status
[09:58:17.389] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:17.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:30:0) after 1m0s
[09:59:17.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:30:0)
[09:59:17.486] Timer tick, asking Buildbucket for the build status
[09:59:17.630] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:17.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:31:0) after 1m0s
[10:00:17.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:31:0)
[10:00:17.728] Timer tick, asking Buildbucket for the build status
[10:00:17.876] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:17.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:32:0) after 1m0s
[10:01:17.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:32:0)
[10:01:17.906] Timer tick, asking Buildbucket for the build status
[10:01:18.009] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:18.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:33:0) after 1m0s
[10:02:18.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:33:0)
[10:02:18.037] Timer tick, asking Buildbucket for the build status
[10:02:24.688] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:24.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:34:0) after 1m0s
[10:03:24.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:34:0)
[10:03:24.732] Timer tick, asking Buildbucket for the build status
[10:03:24.976] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:24.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:35:0) after 1m0s
[10:04:25.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:35:0)
[10:04:25.054] Timer tick, asking Buildbucket for the build status
[10:04:25.176] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:25.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:36:0) after 1m0s
[10:05:25.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:36:0)
[10:05:25.215] Timer tick, asking Buildbucket for the build status
[10:05:25.898] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:25.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:37:0) after 1m0s
[10:06:25.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:37:0)
[10:06:25.995] Timer tick, asking Buildbucket for the build status
[10:06:26.884] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:26.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:38:0) after 1m0s
[10:07:27.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:38:0)
[10:07:27.032] Timer tick, asking Buildbucket for the build status
[10:07:27.232] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:27.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:39:0) after 1m0s
[10:08:27.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:39:0)
[10:08:27.315] Timer tick, asking Buildbucket for the build status
[10:08:27.550] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:27.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:40:0) after 1m0s
[10:09:27.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:40:0)
[10:09:27.632] Timer tick, asking Buildbucket for the build status
[10:09:27.716] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:27.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:41:0) after 1m0s
[10:10:27.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:41:0)
[10:10:27.766] Timer tick, asking Buildbucket for the build status
[10:10:27.889] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:27.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:42:0) after 1m0s
[10:11:27.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:42:0)
[10:11:27.949] Timer tick, asking Buildbucket for the build status
[10:11:28.136] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:28.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:43:0) after 1m0s
[10:12:28.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:43:0)
[10:12:28.195] Timer tick, asking Buildbucket for the build status
[10:12:28.521] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:28.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:44:0) after 1m0s
[10:13:28.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:44:0)
[10:13:28.548] Timer tick, asking Buildbucket for the build status
[10:13:28.774] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:28.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:45:0) after 1m0s
[10:14:28.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:45:0)
[10:14:28.835] Timer tick, asking Buildbucket for the build status
[10:14:29.044] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:29.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:46:0) after 1m0s
[10:15:29.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:46:0)
[10:15:29.120] Timer tick, asking Buildbucket for the build status
[10:15:30.032] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:30.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:47:0) after 1m0s
[10:16:30.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:47:0)
[10:16:30.089] Timer tick, asking Buildbucket for the build status
[10:16:30.362] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:30.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:48:0) after 1m0s
[10:17:30.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:48:0)
[10:17:30.435] Timer tick, asking Buildbucket for the build status
[10:17:30.794] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:30.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:49:0) after 1m0s
[10:18:30.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:49:0)
[10:18:30.853] Timer tick, asking Buildbucket for the build status
[10:18:30.933] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:30.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:50:0) after 1m0s
[10:19:31.017] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:50:0)
[10:19:31.017] Timer tick, asking Buildbucket for the build status
[10:19:31.136] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:31.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:51:0) after 1m0s
[10:20:31.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:51:0)
[10:20:31.204] Timer tick, asking Buildbucket for the build status
[10:20:31.798] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:31.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:52:0) after 1m0s
[10:21:31.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:52:0)
[10:21:31.824] Timer tick, asking Buildbucket for the build status
[10:21:31.960] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:31.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:53:0) after 1m0s
[10:22:32.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:53:0)
[10:22:32.073] Timer tick, asking Buildbucket for the build status
[10:22:33.096] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:33.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:54:0) after 1m0s
[10:23:33.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:54:0)
[10:23:33.151] Timer tick, asking Buildbucket for the build status
[10:23:33.387] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:33.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:55:0) after 1m0s
[10:24:33.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:55:0)
[10:24:33.442] Timer tick, asking Buildbucket for the build status
[10:24:33.615] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:33.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:56:0) after 1m0s
[10:25:33.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:56:0)
[10:25:33.665] Timer tick, asking Buildbucket for the build status
[10:25:33.832] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:33.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:57:0) after 1m0s
[10:26:33.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:57:0)
[10:26:33.890] Timer tick, asking Buildbucket for the build status
[10:26:34.042] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:34.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:58:0) after 1m0s
[10:27:34.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:58:0)
[10:27:34.074] Timer tick, asking Buildbucket for the build status
[10:27:34.156] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:34.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:59:0) after 1m0s
[10:28:34.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:59:0)
[10:28:34.190] Timer tick, asking Buildbucket for the build status
[10:28:34.275] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:34.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:60:0) after 1m0s
[10:29:34.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:60:0)
[10:29:34.334] Timer tick, asking Buildbucket for the build status
[10:29:34.408] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:34.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:61:0) after 1m0s
[10:30:34.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:61:0)
[10:30:34.493] Timer tick, asking Buildbucket for the build status
[10:30:34.574] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:34.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:62:0) after 1m0s
[10:31:34.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:62:0)
[10:31:34.722] Timer tick, asking Buildbucket for the build status
[10:31:34.878] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:34.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:63:0) after 1m0s
[10:32:34.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:63:0)
[10:32:34.885] Timer tick, asking Buildbucket for the build status
[10:32:35.171] Build 8864119129767826768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:35.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029549192364499792:64:0) after 1m0s
[10:32:45.770] Received PubSub notification, asking Buildbucket for the build status
[10:32:45.852] Build 8864119129767826768: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:32:45.852] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604917964407728",
"created_by": "project:chromium",
"created_ts": "1604914270583449",
"failure_reason": "BUILD_FAILURE",
"id": "8864119129767826768",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e949148f7a856f57028a0b52a2ed9517bca5a29a\"}}",
"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.FrameSizeChangeVideo/0\\n\\n- MSE_ExternalClearKey/EncryptedMediaTest.ConfigChangeVideo_ClearToEncrypted/0\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"e949148f7a856f57028a0b52a2ed9517bca5a29a\", \"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-8-b9eu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15550, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"d4439824c55428a699aa543a2f397d06a00841b6\", \"got_dawn_revision\": \"3e17e33724b4d0b4c4fc1985340882e1f543e3a0\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"e949148f7a856f57028a0b52a2ed9517bca5a29a\", \"got_revision_cp\": \"refs/heads/master@{#825280}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"22679f906024d22470c263f409ad6a3ceb335006\", \"got_v8_revision_cp\": \"refs/heads/8.8.253@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"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\": \"e949148f7a856f57028a0b52a2ed9517bca5a29a\", \"swarm_hashes_refs/heads/master(at){#825280}_without_patch\": {\"absl_hardening_tests\": \"085a8ebbf62501d8413c0cb0992987121311a2e4\", \"accessibility_unittests\": \"ab37d5f359231133a32af8e397db35b83cf973a8\", \"angle_unittests\": \"ecf59aec24fd4d23de882960fd06e13d62322829\", \"app_shell_unittests\": \"dd0b5a50f19bf7be4a0eabd694adbafafc1a7b8e\", \"aura_unittests\": \"00003df23c3a731adb95914ca6effe87c653d0ec\", \"base_unittests\": \"0db71f32acb5951730d675b460fb16f4b1b04ecb\", \"base_util_unittests\": \"a1a00cf17da2ac3ca79f4d87cec20a4092390266\", \"blink_common_unittests\": \"6fe6f537b95a984ee83c9de0fa642569ed99b1a8\", \"blink_fuzzer_unittests\": \"2fc90898314344e8b33ebeb835f25f329bae3d59\", \"blink_heap_unittests\": \"9f91d3e4a6871cf3a09296d64432692221c7fb36\", \"blink_platform_unittests\": \"aad263d348fcc528b0dbf0c15b5417a2fd0cdf29\", \"blink_unittests\": \"8a7690f253597a2bacab18b73b3a72215b23b16b\", \"boringssl_crypto_tests\": \"92363c7e62f5fa9a6ee8cd7fd9af79d3f8705ef1\", \"boringssl_ssl_tests\": \"014c62ea717eef7a4d50f6e6338407defc30fe3b\", \"browser_switcher_bho_unittests\": \"b1cf73e5754d677acd762f4d8491f9ceaaaa45b1\", \"browser_tests\": \"382698fdccfc8624740e1a71f2231ee9f2fd83e2\", \"capture_unittests\": \"42529c9b892a7353b4809e7ec459ed1972220954\", \"cast_unittests\": \"5acfa2b2384e39296d864b6ec53b4df88cb24f82\", \"cc_unittests\": \"5e2d28bd9f1238303da7687f4998ed4ceb692e1b\", \"chrome_app_unittests\": \"dfe3095c09c9b2d8213183033c7d295675521abd\", \"chrome_cleaner_unittests\": \"6f67dce96076d46ccd0a0f199b3162af5596d50e\", \"chrome_elf_unittests\": \"d158ac57f1acad172db91c4a55a64a58a1e4bf16\", \"chromedriver_unittests\": \"10bb56ee540ee5a58e2c2a9f50bacb2a25163611\", \"components_browsertests\": \"a2c3bfcc9e13c5f1d4fa862c22fb1fc29aaede4d\", \"components_unittests\": \"0a654f2a861a96de88c439173893fbc441930bd9\", \"compositor_unittests\": \"b42cf9bb67ae5968244efc0a743e4b94c17cf615\", \"content_browsertests\": \"3b17574cde5f53c3f3db68b8fed2293de681fb18\", \"content_unittests\": \"ad80553769c5c381e1aaae752ad1d568602fd593\", \"courgette_unittests\": \"e07c76916fa092b8432bfd50c39e503f9531a682\", \"crashpad_tests\": \"e820f6e63d55a930b873845d480b367bfc28d668\", \"cronet_tests\": \"53b05d0739c03fac6cd21a0c9784802605721adf\", \"cronet_unittests\": \"21b61985992b98eeafc777a35ebe958e921b67b3\", \"crypto_unittests\": \"2e85593b6b1b88304bc43821bccc861055c140bd\", \"delayloads_unittests\": \"62d74bf54435f0c0f5fadac8a9e1000f14afd041\", \"device_unittests\": \"bfdb368f9502b0bc390daf66b76e1083d670df53\", \"display_unittests\": \"78ddd7ef8ac507c2a707f6680a7485b9a268e614\", \"elevation_service_unittests\": \"854c23ae4964ae24548844c88fc7728e9673b9ed\", \"events_unittests\": \"0a0fe44c1298275ab4acfb555a379731dd0697e6\", \"extensions_browsertests\": \"efc9e189babdf45ccfd05865fe592c9b4ce039fe\", \"extensions_unittests\": \"538bab0d015bffe89856a051bfa3c0acb41d751c\", \"filesystem_service_unittests\": \"787a75caef8a7fc795d497a1973b4d48668a1558\", \"gcm_unit_tests\": \"3109a8e5c3ac60cf1fa0f2b711b84705a81ecda1\", \"gcp_unittests\": \"261a18637d82e8f1dbaa1321dcf551fc1bcc29af\", \"gfx_unittests\": \"a134c442820c027c46f5621d515cb4c3c847e16a\", \"gin_unittests\": \"70adbe226a3bd144bdf669214c9111aca6c00b9a\", \"google_apis_unittests\": \"3f111cd6ccf4bda8e879a757f3a5260733235a78\", \"gpu_unittests\": \"5eeb277d02d7c7bed41e820acbb315918cf474d8\", \"gwp_asan_unittests\": \"35b01d6aabdd1872016d66b0816056ed7d14d5d1\", \"headless_browsertests\": \"9f4cfa00d8d5db6b564adc5a8b1d2ada0f68de08\", \"headless_unittests\": \"31dbc49d3ea8163c939ede7eeb06617b5c6c07bd\", \"install_static_unittests\": \"e8788f6d84228fb9fcee19e691cf5baef674a8fc\", \"installer_util_unittests\": \"ab1ea8ad76291241205babd49339f2858a3b1953\", \"interactive_ui_tests\": \"ba8bfa4e181260a066416cd864ab731ea3dd6fd1\", \"ipc_tests\": \"4f840445597f6be12899b8d7b7fb90c20291bf76\", \"jingle_unittests\": \"dbb45a367b4d92c38d89a3ec03df183abefb2e4a\", \"latency_unittests\": \"1da3735f7e6592e41443ff25db2fa377200851dc\", \"libjingle_xmpp_unittests\": \"d981bff5700b51fa83c7569ee517e565cce985ec\", \"liburlpattern_unittests\": \"d38fb55c45fc8d5505a3cd644278e43c933890a3\", \"media_blink_unittests\": \"345feaa7daf5b46a17cdc7ead5a45bf7e47b3974\", \"media_unittests\": \"5354406ba4b0250795c25865c1cb30acdd4fdea3\", \"message_center_unittests\": \"834f3e68666d4cf64c9a181b98a9c53f792ff1f3\", \"midi_unittests\": \"e7de74274e7fea919f545b2ae32690232ff26648\", \"mojo_core_unittests\": \"102c2b884e06df2c82315ca438925932a9246cdc\", \"mojo_unittests\": \"b17265d85d46b046805c792eb5ac8f7b3e1945bc\", \"nacl_loader_unittests\": \"23056ea8e33c2aa4fbfb9668311f6ff987820a50\", \"native_theme_unittests\": \"407df8510e153f5c980149f0444bc3102566fcc8\", \"net_unittests\": \"d5a3e7e5c4deba17c3fca964a89cd9ebdb948fe8\", \"notification_helper_unittests\": \"7265251095a6a2b5e5f5c0a16334429cef8f2c19\", \"pdf_unittests\": \"5f24512f192698d7c954d4c2c6c8dfa1142521bf\", \"perfetto_unittests\": \"54f53edc93116c3065e182d3a08196a3668e3135\", \"ppapi_unittests\": \"262dd9e6902474e6cd4ce2dd4ba541bf8b29fab0\", \"printing_unittests\": \"bdb6ecd147f6209a58d79cd758179f6f994321e6\", \"remoting_unittests\": \"a42c673edd384c216b2c02612a55aaf316bdb015\", \"sbox_integration_tests\": \"103022aefed52fb7566b4c5bc46ef667b74fbaef\", \"sbox_unittests\": \"d48ce8b399370b4ddf5327b240100cb7aa11ebc9\", \"sbox_validation_tests\": \"dcede3e8712148decc0c2c1f2a1e5bf3a388b60d\", \"service_manager_unittests\": \"ef8594fc5831bcbf3692fe469108f9ae7a23fe84\", \"services_unittests\": \"43a4cbac125f51a311a336a6767b9c90a5ecddfd\", \"setup_unittests\": \"00c93e12dafdd4537ecf0198ef4b4a305fbe1745\", \"shell_dialogs_unittests\": \"131f32c429fa4f4d2dd9b921ea0621316d0bdc66\", \"skia_unittests\": \"3a8fe76c07409f05bc0c52376aa49282d29cfb7b\", \"snapshot_unittests\": \"c22da2fd7de027b2294995e77972e22617dcd55e\", \"sql_unittests\": \"76904b238517b3388c9c3521b4a6df25edd4b01a\", \"storage_unittests\": \"2dfc22e5e3fb64aa066f49ce9c1ce888de9381d4\", \"sync_integration_tests\": \"0b72fa549e574f4fbf2a9e1e551fb28c237278d0\", \"traffic_annotation_auditor_unittests\": \"44b653ea50ea40b7e850c04c1ecd32538b67e746\", \"ui_base_unittests\": \"6ccd0adea0d5afab261d0dabef810e92bf3b743b\", \"ui_touch_selection_unittests\": \"4757aad4858ccf0ce5e58ff01e4819c3ddc34226\", \"unit_tests\": \"db9b666dfba8ea6b447eaea7cbec3b4951bc5a13\", \"updater_tests\": \"3fac8a983d613fd165ba7c3949b36594a3e89419\", \"url_unittests\": \"4ca760450e5c638a49718e3521002021c47ef00b\", \"views_unittests\": \"e499365eca2541231c8c3c410634ce40eb12022e\", \"viz_unittests\": \"70802d56ca931145a0776493d929e5f40039be0b\", \"vr_common_unittests\": \"999bb8642fb069b70fb39d13e2d6530f367b0eca\", \"vr_pixeltests\": \"3d036eae5e26457b958ee0b5830780b92ad6f3f2\", \"weblayer_browsertests\": \"9b050e71972c6f8835a03a511cb3799a8a16d7c2\", \"weblayer_unittests\": \"ecfee82e724bd4a13bf708a4353cd0b192895cc6\", \"wm_unittests\": \"aa2012528e5bd0046468bbe6538da833793f870a\", \"wtf_unittests\": \"b87836adddd47352f9b92adade83fad57a071094\", \"zlib_unittests\": \"0562529299328adb6d280dcc18b2b105449b1989\", \"zucchini_unittests\": \"db8ab11b9963ca93ff6c4229a3ed91fc445f955c\"}}, \"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-8-b9eu\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5626-39642e9\"], \"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.FrameSizeChangeVideo/0\\n\\n- MSE_ExternalClearKey/EncryptedMediaTest.ConfigChangeVideo_ClearToEncrypted/0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604914272272830",
"status": "COMPLETED",
"status_changed_ts": "1604914272884318",
"tags": [
"build_address:luci.chromium.ci/win-asan/15550",
"builder:win-asan",
"buildset:commit/git/e949148f7a856f57028a0b52a2ed9517bca5a29a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e949148f7a856f57028a0b52a2ed9517bca5a29a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029549192364499792",
"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/8864119129767826768/+/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:4fc529dd9f0cfd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604917965400568",
"url": "https://ci.chromium.org/b/8864119129767826768",
"utcnow_ts": "1604917965836187"
}
}
[10:32:45.852] Invocation finished in 1h1m37.128988319s with status FAILED