[09:46:00.646] New invocation is queued and will start shortly
[09:46:01.848] Starting the invocation (attempt 1)
[09:46:01.927] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:46:01.927] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:46:01.928] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028551690512489408",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c84d163362be6d17993278bd0a267c384218d47e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028551690512489408",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c84d163362be6d17993278bd0a267c384218d47e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c84d163362be6d17993278bd0a267c384218d47e",
"gitiles_ref:refs/heads/master"
]
}
[09:46:02.729] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605865562081953",
"id": "8863121628333657472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c84d163362be6d17993278bd0a267c384218d47e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605865562444824",
"tags": [
"build_address:luci.chromium.ci/win-asan/15737",
"builder:win-asan",
"buildset:commit/git/c84d163362be6d17993278bd0a267c384218d47e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c84d163362be6d17993278bd0a267c384218d47e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028551690512489408",
"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/8863121628333657472/+/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": "1605865562444954",
"url": "https://ci.chromium.org/b/8863121628333657472",
"utcnow_ts": "1605865562715511"
}
}
[09:46:02.729] Task URL: https://ci.chromium.org/b/8863121628333657472
[09:46:02.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:2:0) after 1m0s
[09:47:02.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:2:0)
[09:47:02.796] Timer tick, asking Buildbucket for the build status
[09:47:02.916] Build 8863121628333657472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:02.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:3:0) after 1m0s
[09:48:02.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:3:0)
[09:48:02.971] Timer tick, asking Buildbucket for the build status
[09:48:03.054] Build 8863121628333657472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:03.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:4:0) after 1m0s
[09:48:20.099] Received PubSub notification, asking Buildbucket for the build status
[09:48:20.180] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:03.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:4:0)
[09:49:03.184] Timer tick, asking Buildbucket for the build status
[09:49:04.007] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:04.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:6:0) after 1m0s
[09:50:04.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:6:0)
[09:50:04.077] Timer tick, asking Buildbucket for the build status
[09:50:04.253] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:04.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:7:0) after 1m0s
[09:51:04.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:7:0)
[09:51:04.350] Timer tick, asking Buildbucket for the build status
[09:51:04.470] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:04.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:8:0) after 1m0s
[09:52:04.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:8:0)
[09:52:04.617] Timer tick, asking Buildbucket for the build status
[09:52:04.691] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:04.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:9:0) after 1m0s
[09:53:04.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:9:0)
[09:53:04.776] Timer tick, asking Buildbucket for the build status
[09:53:04.846] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:04.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:10:0) after 1m0s
[09:54:05.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:10:0)
[09:54:05.020] Timer tick, asking Buildbucket for the build status
[09:54:05.101] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:05.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:11:0) after 1m0s
[09:55:05.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:11:0)
[09:55:05.142] Timer tick, asking Buildbucket for the build status
[09:55:05.671] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:05.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:12:0) after 1m0s
[09:56:05.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:12:0)
[09:56:05.695] Timer tick, asking Buildbucket for the build status
[09:56:06.037] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:06.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:13:0) after 1m0s
[09:57:06.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:13:0)
[09:57:06.070] Timer tick, asking Buildbucket for the build status
[09:57:06.211] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:06.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:14:0) after 1m0s
[09:58:06.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:14:0)
[09:58:06.305] Timer tick, asking Buildbucket for the build status
[09:58:06.423] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:06.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:15:0) after 1m0s
[09:59:06.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:15:0)
[09:59:06.488] Timer tick, asking Buildbucket for the build status
[09:59:06.802] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:06.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:16:0) after 1m0s
[10:00:07.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:16:0)
[10:00:07.377] Timer tick, asking Buildbucket for the build status
[10:00:07.497] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:07.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:17:0) after 1m0s
[10:01:08.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:17:0)
[10:01:08.332] Timer tick, asking Buildbucket for the build status
[10:01:08.795] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:08.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:18:0) after 1m0s
[10:02:08.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:18:0)
[10:02:08.980] Timer tick, asking Buildbucket for the build status
[10:02:09.110] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:09.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:19:0) after 1m0s
[10:03:09.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:19:0)
[10:03:09.177] Timer tick, asking Buildbucket for the build status
[10:03:09.320] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:09.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:20:0) after 1m0s
[10:04:09.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:20:0)
[10:04:09.344] Timer tick, asking Buildbucket for the build status
[10:04:09.404] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:09.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:21:0) after 1m0s
[10:05:09.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:21:0)
[10:05:09.460] Timer tick, asking Buildbucket for the build status
[10:05:09.740] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:09.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:22:0) after 1m0s
[10:06:09.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:22:0)
[10:06:09.789] Timer tick, asking Buildbucket for the build status
[10:06:09.918] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:09.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:23:0) after 1m0s
[10:07:09.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:23:0)
[10:07:09.962] Timer tick, asking Buildbucket for the build status
[10:07:10.041] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:10.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:24:0) after 1m0s
[10:08:10.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:24:0)
[10:08:10.136] Timer tick, asking Buildbucket for the build status
[10:08:10.210] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:10.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:25:0) after 1m0s
[10:09:10.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:25:0)
[10:09:10.307] Timer tick, asking Buildbucket for the build status
[10:09:10.596] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:10.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:26:0) after 1m0s
[10:10:10.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:26:0)
[10:10:10.650] Timer tick, asking Buildbucket for the build status
[10:10:10.835] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:10.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:27:0) after 1m0s
[10:11:10.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:27:0)
[10:11:10.813] Timer tick, asking Buildbucket for the build status
[10:11:11.225] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:11.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:28:0) after 1m0s
[10:12:11.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:28:0)
[10:12:11.297] Timer tick, asking Buildbucket for the build status
[10:12:11.392] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:11.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:29:0) after 1m0s
[10:13:11.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:29:0)
[10:13:11.419] Timer tick, asking Buildbucket for the build status
[10:13:11.567] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:11.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:30:0) after 1m0s
[10:14:11.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:30:0)
[10:14:11.626] Timer tick, asking Buildbucket for the build status
[10:14:11.692] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:11.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:31:0) after 1m0s
[10:15:11.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:31:0)
[10:15:11.712] Timer tick, asking Buildbucket for the build status
[10:15:11.855] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:11.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:32:0) after 1m0s
[10:16:11.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:32:0)
[10:16:11.922] Timer tick, asking Buildbucket for the build status
[10:16:12.418] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:12.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:33:0) after 1m0s
[10:17:12.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:33:0)
[10:17:12.428] Timer tick, asking Buildbucket for the build status
[10:17:12.495] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:12.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:34:0) after 1m0s
[10:18:12.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:34:0)
[10:18:12.555] Timer tick, asking Buildbucket for the build status
[10:18:12.639] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:12.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:35:0) after 1m0s
[10:19:12.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:35:0)
[10:19:12.663] Timer tick, asking Buildbucket for the build status
[10:19:12.833] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:12.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:36:0) after 1m0s
[10:20:12.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:36:0)
[10:20:12.910] Timer tick, asking Buildbucket for the build status
[10:20:12.980] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:12.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:37:0) after 1m0s
[10:21:13.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:37:0)
[10:21:13.015] Timer tick, asking Buildbucket for the build status
[10:21:13.148] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:13.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:38:0) after 1m0s
[10:22:13.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:38:0)
[10:22:13.222] Timer tick, asking Buildbucket for the build status
[10:22:13.325] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:13.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:39:0) after 1m0s
[10:23:13.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:39:0)
[10:23:13.544] Timer tick, asking Buildbucket for the build status
[10:23:13.611] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:13.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:40:0) after 1m0s
[10:24:13.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:40:0)
[10:24:13.732] Timer tick, asking Buildbucket for the build status
[10:24:14.072] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:14.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:41:0) after 1m0s
[10:25:14.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:41:0)
[10:25:14.471] Timer tick, asking Buildbucket for the build status
[10:25:14.652] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:14.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:42:0) after 1m0s
[10:26:14.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:42:0)
[10:26:14.772] Timer tick, asking Buildbucket for the build status
[10:26:14.882] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:14.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:43:0) after 1m0s
[10:27:14.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:43:0)
[10:27:14.905] Timer tick, asking Buildbucket for the build status
[10:27:14.970] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:14.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:44:0) after 1m0s
[10:28:15.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:44:0)
[10:28:15.015] Timer tick, asking Buildbucket for the build status
[10:28:15.081] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:15.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:45:0) after 1m0s
[10:29:15.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:45:0)
[10:29:15.359] Timer tick, asking Buildbucket for the build status
[10:29:15.883] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:15.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:46:0) after 1m0s
[10:30:15.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:46:0)
[10:30:15.960] Timer tick, asking Buildbucket for the build status
[10:30:16.356] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:16.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:47:0) after 1m0s
[10:31:16.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:47:0)
[10:31:16.441] Timer tick, asking Buildbucket for the build status
[10:31:16.662] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:16.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:48:0) after 1m0s
[10:32:16.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:48:0)
[10:32:16.708] Timer tick, asking Buildbucket for the build status
[10:32:16.840] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:16.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:49:0) after 1m0s
[10:33:16.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:49:0)
[10:33:16.894] Timer tick, asking Buildbucket for the build status
[10:33:16.960] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:16.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:50:0) after 1m0s
[10:34:17.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:50:0)
[10:34:17.111] Timer tick, asking Buildbucket for the build status
[10:34:17.209] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:17.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:51:0) after 1m0s
[10:35:17.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:51:0)
[10:35:17.294] Timer tick, asking Buildbucket for the build status
[10:35:17.425] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:17.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:52:0) after 1m0s
[10:36:17.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:52:0)
[10:36:17.454] Timer tick, asking Buildbucket for the build status
[10:36:18.104] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:18.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:53:0) after 1m0s
[10:37:18.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:53:0)
[10:37:18.198] Timer tick, asking Buildbucket for the build status
[10:37:18.569] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:18.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:54:0) after 1m0s
[10:38:18.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:54:0)
[10:38:18.677] Timer tick, asking Buildbucket for the build status
[10:38:19.714] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:19.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:55:0) after 1m0s
[10:39:19.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:55:0)
[10:39:19.866] Timer tick, asking Buildbucket for the build status
[10:39:19.995] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:19.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:56:0) after 1m0s
[10:40:20.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:56:0)
[10:40:20.103] Timer tick, asking Buildbucket for the build status
[10:40:20.467] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:20.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:57:0) after 1m0s
[10:41:20.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:57:0)
[10:41:20.504] Timer tick, asking Buildbucket for the build status
[10:41:20.667] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:20.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:58:0) after 1m0s
[10:42:20.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:58:0)
[10:42:20.693] Timer tick, asking Buildbucket for the build status
[10:42:21.313] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:21.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:59:0) after 1m0s
[10:43:21.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:59:0)
[10:43:21.408] Timer tick, asking Buildbucket for the build status
[10:43:21.669] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:21.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:60:0) after 1m0s
[10:44:21.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:60:0)
[10:44:21.694] Timer tick, asking Buildbucket for the build status
[10:44:21.767] Build 8863121628333657472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:21.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028551690512489408:61:0) after 1m0s
[10:44:59.138] Received PubSub notification, asking Buildbucket for the build status
[10:44:59.217] Build 8863121628333657472: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[10:44:59.217] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605869098325386",
"created_by": "project:chromium",
"created_ts": "1605865562081953",
"failure_reason": "INFRA_FAILURE",
"id": "8863121628333657472",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c84d163362be6d17993278bd0a267c384218d47e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"recipe infra failure: Infra Failure: Step('test_pre_run.[trigger] browser_tests on Windows-10-15063 (36)') (retcode: 1)\"}, \"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\": \"c84d163362be6d17993278bd0a267c384218d47e\", \"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-xoby\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15737, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"777749aafaac268b3468b551050f5bf4b28cf9b6\", \"got_dawn_revision\": \"1a14e7e0244114fe737caf46e3a2690355aceafe\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"c84d163362be6d17993278bd0a267c384218d47e\", \"got_revision_cp\": \"refs/heads/master@{#829598}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"de9e649438ed3cc2ecf5c30ebf08f033d01270e6\", \"got_v8_revision\": \"94b2c943f7f37e0450e7c5d029fbd22a9d7f6946\", \"got_v8_revision_cp\": \"refs/heads/8.9.14@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"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\": \"c84d163362be6d17993278bd0a267c384218d47e\", \"swarm_hashes_refs/heads/master(at){#829598}_without_patch\": {\"absl_hardening_tests\": \"f06b8e182f91d96c374e3bd1b5a8dd24a0c457bf\", \"accessibility_unittests\": \"99c99dda96ccb6a11b063cb94736a2c7ccb12dbc\", \"angle_unittests\": \"b1a9151f77f8378c1e42d70225198be184b79db5\", \"app_shell_unittests\": \"21dcf60a599480bd749da56de3dc3f64341e636a\", \"aura_unittests\": \"8bb51ebe41602ce5973002b77b9cec5f9d6f89bf\", \"base_unittests\": \"7cdcd88aa1710daf68cde809757ab6f2ed4d709f\", \"base_util_unittests\": \"d73d41d68eafcbb3f43c03c5d0342e893c08f580\", \"blink_common_unittests\": \"17d85f08e7f1bb76403656d1e02a0394d8cc18ea\", \"blink_fuzzer_unittests\": \"7da6c556a8cd62c5746c7e8bd8564ab6f815307b\", \"blink_heap_unittests\": \"a38b53fbb059e5b537c9230f27f48e6692a32b27\", \"blink_platform_unittests\": \"8da88b02955f9f62b0170c7e2152d6e91ffbf4fc\", \"blink_unittests\": \"81286138edb11cd5609c9265d190f05b73f0918c\", \"boringssl_crypto_tests\": \"8e4bcc4f5953c9f5b5158e19e6264b9b7cb27058\", \"boringssl_ssl_tests\": \"677a8f73ff635b8eb4519d731e438b303065d3f3\", \"browser_switcher_bho_unittests\": \"ae6de0200892e1a957cbc8f1723658c00c36c86b\", \"browser_tests\": \"4592879868d69d68efe7b70877fab612efb8e577\", \"capture_unittests\": \"211e8881d6f6d768ac5a045480ce676513b14c37\", \"cast_unittests\": \"cdf952f9a499184cb946d0f336609bc3f2bfb187\", \"cc_unittests\": \"2fc5328f70a140748b53f779d837991b4f36aca0\", \"chrome_app_unittests\": \"1ebd1593e06f7932bee11e790852fc7b9a405a40\", \"chrome_cleaner_unittests\": \"c04c54e328936095dee4737a012dbee1b6df4841\", \"chrome_elf_unittests\": \"78819d6d36019a0f4e37a6084409d18bf41652fe\", \"chromedriver_unittests\": \"0ee931b67d335a88db99299d6cf7333d8fd90751\", \"components_browsertests\": \"fdc29ec1ef4212d5589eaaa1bc3390bd4d9893d6\", \"components_unittests\": \"3a235da6468f184ac31bf6a6ca16cb132d610559\", \"compositor_unittests\": \"ee0199878aa62ec244b80129631df4fcb04be03e\", \"content_browsertests\": \"4b899b865af7bf940d09abbdba0d4342e232d65b\", \"content_unittests\": \"f0d1d612a30ad3dab7ccdb45d1461c20b946f149\", \"courgette_unittests\": \"fa19ef6d7ebf6ec93ad130f7913803e81b6f20fe\", \"crashpad_tests\": \"15e00eeb7ed95f8bc5bd165c521a53d1f5af3b95\", \"cronet_tests\": \"abdac313abe8848ea7b696943ad1fe537951044b\", \"cronet_unittests\": \"d748b06c0390a0d0539fb58b88349ca50fa0b895\", \"crypto_unittests\": \"938653690544de6ce0eabf6d3d0cbf246c1aee87\", \"delayloads_unittests\": \"186ce610ccc7403a13c134dbfcaaceffd7d9d01d\", \"device_unittests\": \"dfa7adfe35b29ed8802708747632af555a594b51\", \"display_unittests\": \"5af957c381fecc7b79e8bf2f95900d12240ecf52\", \"elevation_service_unittests\": \"84ae5bdf807c7323c9244a8fb3fce60c22fb22c0\", \"events_unittests\": \"8fae3fc934a91b6aea907357ca7ae1a9f45b0976\", \"extensions_browsertests\": \"46238bc63416e0518c8b4631181930d7a219cf73\", \"extensions_unittests\": \"a98629ad4d2ec7335087092d4e84cb8b7f59909e\", \"filesystem_service_unittests\": \"eed3a7573de05ea8b31260fb398b6e3f52f8f429\", \"gcm_unit_tests\": \"49a1b077a13d5e9ba5c9835bf96c124185e68fa2\", \"gcp_unittests\": \"49cc5a9f659206689da64bc36ea3847d5f2521b5\", \"gfx_unittests\": \"e4fd0eec378316bf5c7633ec0e62685563ade406\", \"gin_unittests\": \"8e758b2c076f2200b7008d76337a91fff8c6d53a\", \"google_apis_unittests\": \"17337b318a1d5ac345f666c0909209fe8cbeeac4\", \"gpu_unittests\": \"a9c72ac62ee0b4a8c3805a48620d9a30fdb56b4f\", \"gwp_asan_unittests\": \"01843b85ea0b88ad478fd17011a60db2adec67cf\", \"headless_browsertests\": \"31b5d438db7715a0d7dfc09339359f813b90135a\", \"headless_unittests\": \"e1bbee968871b71e016d4f803d7ab7c36de80069\", \"install_static_unittests\": \"de3d0d3c6ea6efd84556f5d66fb190f2e005882a\", \"installer_util_unittests\": \"7d2ac490d160ca7f8f7c902c66ea58df675d1429\", \"interactive_ui_tests\": \"0aff6c6b112c3720e6b18e27febe011cc7d320fd\", \"ipc_tests\": \"39691bf30edb323a735d76b2f57a6a8c433d4eef\", \"jingle_unittests\": \"24bf7381dd302b34d17490a6cbee995fa2bb5dfe\", \"latency_unittests\": \"538d35e94dff0cbba8d7fd37ebf6c97334981bb2\", \"libjingle_xmpp_unittests\": \"f84d57c462a95e79756bb66484a848cb74645c94\", \"liburlpattern_unittests\": \"6e575255f6fb1ede93a071fb0f21de50fbf88a0b\", \"media_blink_unittests\": \"a5d2c2572e3d8cd55d13405d6fe4f82aaae18893\", \"media_unittests\": \"b5bc371d6bf205d91db37ed4c9947f9c3d402245\", \"message_center_unittests\": \"6f00d012b8e0dac90ccfd8885f4732d7bf65a435\", \"midi_unittests\": \"4e4a09465b554f4d8fe397e2781c30b40c2d2f73\", \"mojo_core_unittests\": \"3701867261c5349b5e73169b8b4c5652c9cf50f3\", \"mojo_unittests\": \"897883c4249614fed4121bc9c1b171cdeb0af920\", \"nacl_loader_unittests\": \"b244694a5b0f3cfbfc345b6e7b91f960409d965d\", \"native_theme_unittests\": \"65ec44d7753fd3353eccab6f88ea73280ceffabb\", \"net_unittests\": \"9a5cb0c263ebb089b84090b24a0f11506a04b3a3\", \"notification_helper_unittests\": \"480e428b23a3f3cae9d477fa1fc540cf38055bc2\", \"pdf_unittests\": \"99bd32095a3d0c24f63bba515e5e68f3fab9929b\", \"perfetto_unittests\": \"7387bc504a826fa5f29206c79b5e5ccd03a9f8cc\", \"ppapi_unittests\": \"9310ae6b03ac57ec5692cc48947b7c237c7726ea\", \"printing_unittests\": \"ac5bf486948e4d680341f7867f27d9be6b5c2462\", \"remoting_unittests\": \"3bf32c2d36bb59b68046660143685790729d1e01\", \"sbox_integration_tests\": \"849204802376b773aee17daa2645adc83c4d6ccd\", \"sbox_unittests\": \"201a7c606e6e7c4c5ad33e1c2a3e2cf39bcad4a2\", \"sbox_validation_tests\": \"eaa3301c9fcaf90cfa392f43759b3107f14cf32b\", \"service_manager_unittests\": \"6b29ba754dc338427647cdf41c019d3031003506\", \"services_unittests\": \"19a6e09919ded56361a65d0a92bcf77e00be004d\", \"setup_unittests\": \"bbed46904a2e6a9c9a57d77ae71e70c007719021\", \"shell_dialogs_unittests\": \"f70d8eccca6cd92ebfb60ba6b92123af100015f9\", \"skia_unittests\": \"291d189b5d2446e6261b4df074f53cea51476ac1\", \"snapshot_unittests\": \"832ff283fa8035934c1b0ccd4bb01ef7221949fa\", \"sql_unittests\": \"c63938f50f1f58bde2e21f6766f9bf9869aa9b64\", \"storage_unittests\": \"833bfd0f2212d7c73eebb40c8fc9d9b740a04880\", \"sync_integration_tests\": \"63444e248527c4ce138594f78000b5bacf6b58b1\", \"traffic_annotation_auditor_unittests\": \"e23d1178082c6f773ea653c67279199b78e9e4b1\", \"ui_base_unittests\": \"026c2932ecd97082d6d5d4983eba319cc46af4cc\", \"ui_touch_selection_unittests\": \"73c629c4927c954d47f94a0e907361da1da3e227\", \"unit_tests\": \"a4c8a2fff978fa07a405d38b2edca09c9d7cd9ba\", \"updater_tests\": \"0ddc4dcdbff4b4bbb31171a2cf2f35d0d7a93e46\", \"url_unittests\": \"d97ae4d3dd1f2c13e544ceb546f01d8415e77050\", \"views_unittests\": \"b91a194e19f78533d8f847c5992b67260fd4c1db\", \"viz_unittests\": \"066351fa89a34157ec21cbdf3f92a73fa84849b2\", \"vr_common_unittests\": \"d90fc9bad01d58fcf7e205dcbf3775d45f239faa\", \"vr_pixeltests\": \"02f1346f79168c5fcffadd09ce70b442d219257c\", \"weblayer_browsertests\": \"6a24d75fe452963bdff61032452477b953971f2d\", \"weblayer_unittests\": \"9f59966a999dc79077b785faf41858ee3dcaaaf3\", \"wm_unittests\": \"db60bf2119b5f1e843d33ef5be58b8b37bc8b018\", \"wtf_unittests\": \"42e7a06f3b62b4cc9c22a2d1ff945b6136a83e6d\", \"zlib_unittests\": \"5080115b9399fe5445165daf863d6aa3d42eb15a\", \"zucchini_unittests\": \"64f0dc7b9f2e9aed2fb7eef771fba318df698d9f\"}}, \"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-xoby\"], \"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\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"recipe infra failure: Infra Failure: Step('test_pre_run.[trigger] browser_tests on Windows-10-15063 (36)') (retcode: 1)\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605865698611636",
"status": "COMPLETED",
"status_changed_ts": "1605865699662763",
"tags": [
"build_address:luci.chromium.ci/win-asan/15737",
"builder:win-asan",
"buildset:commit/git/c84d163362be6d17993278bd0a267c384218d47e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c84d163362be6d17993278bd0a267c384218d47e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028551690512489408",
"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/8863121628333657472/+/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:4ffddd6b15834f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605869098793995",
"url": "https://ci.chromium.org/b/8863121628333657472",
"utcnow_ts": "1605869099208800"
}
}
[10:44:59.217] Invocation finished in 58m58.596962592s with status FAILED