[03:11:08.619] New invocation is queued and will start shortly
[03:11:09.695] Starting the invocation (attempt 1)
[03:11:09.749] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:11:09.749] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:11:09.749] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020332209449328160",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c3b3a253da38cdad5255b66f1f49081c82cb05b8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020332209449328160",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c3b3a253da38cdad5255b66f1f49081c82cb05b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b3a253da38cdad5255b66f1f49081c82cb05b8",
"gitiles_ref:refs/heads/master"
]
}
[03:11:10.636] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613704269839245",
"id": "8854902147507221760",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c3b3a253da38cdad5255b66f1f49081c82cb05b8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613704270338560",
"tags": [
"build_address:luci.chromium.ci/win-asan/17419",
"builder:win-asan",
"buildset:commit/git/c3b3a253da38cdad5255b66f1f49081c82cb05b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b3a253da38cdad5255b66f1f49081c82cb05b8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020332209449328160",
"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/8854902147507221760/+/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": "1613704270338727",
"url": "https://ci.chromium.org/b/8854902147507221760",
"utcnow_ts": "1613704270623832"
}
}
[03:11:10.636] Task URL: https://ci.chromium.org/b/8854902147507221760
[03:11:10.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:2:0) after 1m0s
[03:11:13.853] Received PubSub notification, asking Buildbucket for the build status
[03:11:13.967] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:10.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:2:0)
[03:12:10.766] Timer tick, asking Buildbucket for the build status
[03:12:10.840] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:10.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:4:0) after 1m0s
[03:13:10.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:4:0)
[03:13:10.987] Timer tick, asking Buildbucket for the build status
[03:13:11.080] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:11.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:5:0) after 1m0s
[03:14:11.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:5:0)
[03:14:11.149] Timer tick, asking Buildbucket for the build status
[03:14:11.416] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:11.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:6:0) after 1m0s
[03:15:11.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:6:0)
[03:15:11.494] Timer tick, asking Buildbucket for the build status
[03:15:11.610] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:11.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:7:0) after 1m0s
[03:16:11.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:7:0)
[03:16:11.843] Timer tick, asking Buildbucket for the build status
[03:16:11.932] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:11.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:8:0) after 1m0s
[03:17:12.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:8:0)
[03:17:12.025] Timer tick, asking Buildbucket for the build status
[03:17:12.219] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:12.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:9:0) after 1m0s
[03:18:12.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:9:0)
[03:18:12.271] Timer tick, asking Buildbucket for the build status
[03:18:12.363] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:12.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:10:0) after 1m0s
[03:19:12.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:10:0)
[03:19:12.390] Timer tick, asking Buildbucket for the build status
[03:19:12.477] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:12.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:11:0) after 1m0s
[03:20:12.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:11:0)
[03:20:12.551] Timer tick, asking Buildbucket for the build status
[03:20:12.630] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:12.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:12:0) after 1m0s
[03:21:12.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:12:0)
[03:21:12.651] Timer tick, asking Buildbucket for the build status
[03:21:12.750] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:12.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:13:0) after 1m0s
[03:22:12.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:13:0)
[03:22:12.785] Timer tick, asking Buildbucket for the build status
[03:22:12.939] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:12.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:14:0) after 1m0s
[03:23:12.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:14:0)
[03:23:12.983] Timer tick, asking Buildbucket for the build status
[03:23:13.304] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:13.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:15:0) after 1m0s
[03:24:13.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:15:0)
[03:24:13.353] Timer tick, asking Buildbucket for the build status
[03:24:13.906] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:13.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:16:0) after 1m0s
[03:25:13.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:16:0)
[03:25:13.910] Timer tick, asking Buildbucket for the build status
[03:25:14.318] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:14.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:17:0) after 1m0s
[03:26:14.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:17:0)
[03:26:14.383] Timer tick, asking Buildbucket for the build status
[03:26:14.501] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:14.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:18:0) after 1m0s
[03:27:14.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:18:0)
[03:27:14.576] Timer tick, asking Buildbucket for the build status
[03:27:14.641] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:14.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:19:0) after 1m0s
[03:28:14.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:19:0)
[03:28:14.698] Timer tick, asking Buildbucket for the build status
[03:28:15.103] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:15.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:20:0) after 1m0s
[03:29:15.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:20:0)
[03:29:15.281] Timer tick, asking Buildbucket for the build status
[03:29:15.935] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:15.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:21:0) after 1m0s
[03:30:17.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:21:0)
[03:30:17.450] Timer tick, asking Buildbucket for the build status
[03:30:17.642] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:17.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:22:0) after 1m0s
[03:31:17.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:22:0)
[03:31:17.722] Timer tick, asking Buildbucket for the build status
[03:31:17.846] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:17.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:23:0) after 1m0s
[03:32:17.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:23:0)
[03:32:17.916] Timer tick, asking Buildbucket for the build status
[03:32:17.995] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:17.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:24:0) after 1m0s
[03:33:18.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:24:0)
[03:33:18.182] Timer tick, asking Buildbucket for the build status
[03:33:18.284] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:18.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:25:0) after 1m0s
[03:34:18.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:25:0)
[03:34:18.387] Timer tick, asking Buildbucket for the build status
[03:34:18.875] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:18.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:26:0) after 1m0s
[03:35:18.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:26:0)
[03:35:18.901] Timer tick, asking Buildbucket for the build status
[03:35:19.076] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:19.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:27:0) after 1m0s
[03:36:19.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:27:0)
[03:36:19.121] Timer tick, asking Buildbucket for the build status
[03:36:19.359] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:19.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:28:0) after 1m0s
[03:37:19.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:28:0)
[03:37:19.447] Timer tick, asking Buildbucket for the build status
[03:37:19.519] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:19.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:29:0) after 1m0s
[03:38:19.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:29:0)
[03:38:19.568] Timer tick, asking Buildbucket for the build status
[03:38:19.668] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:19.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:30:0) after 1m0s
[03:39:19.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:30:0)
[03:39:19.724] Timer tick, asking Buildbucket for the build status
[03:39:19.831] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:19.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:31:0) after 1m0s
[03:40:19.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:31:0)
[03:40:19.940] Timer tick, asking Buildbucket for the build status
[03:40:20.522] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:20.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:32:0) after 1m0s
[03:41:20.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:32:0)
[03:41:20.589] Timer tick, asking Buildbucket for the build status
[03:41:20.914] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:20.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:33:0) after 1m0s
[03:42:20.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:33:0)
[03:42:20.936] Timer tick, asking Buildbucket for the build status
[03:42:21.210] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:21.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:34:0) after 1m0s
[03:43:21.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:34:0)
[03:43:21.254] Timer tick, asking Buildbucket for the build status
[03:43:21.348] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:21.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:35:0) after 1m0s
[03:44:21.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:35:0)
[03:44:21.355] Timer tick, asking Buildbucket for the build status
[03:44:21.436] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:21.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:36:0) after 1m0s
[03:45:21.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:36:0)
[03:45:21.492] Timer tick, asking Buildbucket for the build status
[03:45:21.588] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:21.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:37:0) after 1m0s
[03:46:21.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:37:0)
[03:46:21.697] Timer tick, asking Buildbucket for the build status
[03:46:21.800] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:21.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:38:0) after 1m0s
[03:47:21.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:38:0)
[03:47:21.852] Timer tick, asking Buildbucket for the build status
[03:47:21.992] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:21.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:39:0) after 1m0s
[03:48:22.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:39:0)
[03:48:22.039] Timer tick, asking Buildbucket for the build status
[03:48:22.608] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:22.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:40:0) after 1m0s
[03:49:22.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:40:0)
[03:49:22.633] Timer tick, asking Buildbucket for the build status
[03:49:22.731] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:22.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:41:0) after 1m0s
[03:50:22.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:41:0)
[03:50:22.775] Timer tick, asking Buildbucket for the build status
[03:50:22.896] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:22.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:42:0) after 1m0s
[03:51:22.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:42:0)
[03:51:22.907] Timer tick, asking Buildbucket for the build status
[03:51:23.038] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:23.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:43:0) after 1m0s
[03:52:23.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:43:0)
[03:52:23.149] Timer tick, asking Buildbucket for the build status
[03:52:23.290] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:23.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:44:0) after 1m0s
[03:53:23.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:44:0)
[03:53:23.382] Timer tick, asking Buildbucket for the build status
[03:53:23.479] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:23.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:45:0) after 1m0s
[03:54:23.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:45:0)
[03:54:23.650] Timer tick, asking Buildbucket for the build status
[03:54:23.916] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:23.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:46:0) after 1m0s
[03:55:24.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:46:0)
[03:55:24.015] Timer tick, asking Buildbucket for the build status
[03:55:24.147] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:24.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:47:0) after 1m0s
[03:56:24.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:47:0)
[03:56:24.280] Timer tick, asking Buildbucket for the build status
[03:56:24.395] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:24.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:48:0) after 1m0s
[03:57:24.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:48:0)
[03:57:24.603] Timer tick, asking Buildbucket for the build status
[03:57:24.699] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:24.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:49:0) after 1m0s
[03:58:24.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:49:0)
[03:58:24.777] Timer tick, asking Buildbucket for the build status
[03:58:24.966] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:24.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:50:0) after 1m0s
[03:59:25.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:50:0)
[03:59:25.151] Timer tick, asking Buildbucket for the build status
[03:59:25.404] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:25.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:51:0) after 1m0s
[04:00:25.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:51:0)
[04:00:25.463] Timer tick, asking Buildbucket for the build status
[04:00:25.571] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:25.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:52:0) after 1m0s
[04:01:25.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:52:0)
[04:01:25.761] Timer tick, asking Buildbucket for the build status
[04:01:26.218] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:26.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:53:0) after 1m0s
[04:02:26.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:53:0)
[04:02:26.308] Timer tick, asking Buildbucket for the build status
[04:02:26.511] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:26.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:54:0) after 1m0s
[04:03:26.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:54:0)
[04:03:26.554] Timer tick, asking Buildbucket for the build status
[04:03:26.635] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:26.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:55:0) after 1m0s
[04:04:26.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:55:0)
[04:04:26.728] Timer tick, asking Buildbucket for the build status
[04:04:26.824] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:26.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:56:0) after 1m0s
[04:05:26.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:56:0)
[04:05:26.995] Timer tick, asking Buildbucket for the build status
[04:05:27.104] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:27.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:57:0) after 1m0s
[04:06:27.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:57:0)
[04:06:27.197] Timer tick, asking Buildbucket for the build status
[04:06:27.608] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:27.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:58:0) after 1m0s
[04:07:27.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:58:0)
[04:07:27.651] Timer tick, asking Buildbucket for the build status
[04:07:27.733] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:27.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:59:0) after 1m0s
[04:08:27.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:59:0)
[04:08:27.863] Timer tick, asking Buildbucket for the build status
[04:08:27.954] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:27.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:60:0) after 1m0s
[04:09:28.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:60:0)
[04:09:28.011] Timer tick, asking Buildbucket for the build status
[04:09:28.115] Build 8854902147507221760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:28.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020332209449328160:61:0) after 1m0s
[04:10:07.810] Received PubSub notification, asking Buildbucket for the build status
[04:10:07.889] Build 8854902147507221760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:10:07.889] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613707806403485",
"created_by": "project:chromium",
"created_ts": "1613704269839245",
"id": "8854902147507221760",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c3b3a253da38cdad5255b66f1f49081c82cb05b8\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"c3b3a253da38cdad5255b66f1f49081c82cb05b8\", \"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-1-2dz0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17419, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"fd132e4e7a9c84333b4fb4730f79367fe75f53e7\", \"got_dawn_revision\": \"1024d6e1873cd12280b6d43a78f779a108d57320\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"c3b3a253da38cdad5255b66f1f49081c82cb05b8\", \"got_revision_cp\": \"refs/heads/master@{#855604}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"8603b126b3d599f0ab8781163729fd226a93cf17\", \"got_v8_revision\": \"aaf128851c4dd86978eaf9d41ce297c5af31597f\", \"got_v8_revision_cp\": \"refs/heads/9.0.217@{#1}\", \"got_webrtc_revision\": \"735e33fae0a06ae05a9c0be6f5d8b06a6ad820a4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33297}\", \"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\": \"c3b3a253da38cdad5255b66f1f49081c82cb05b8\", \"swarm_hashes_refs/heads/master(at){#855604}_without_patch\": {\"absl_hardening_tests\": \"b231ad6f804c99449e572785decd16d66df2d68c\", \"accessibility_unittests\": \"9ab69c219cc1beb0d73cf9f7253a34847c9f02bc\", \"angle_unittests\": \"e854788d32ad155afec15ba551e90392dccb8a65\", \"app_shell_unittests\": \"c5766494881b876bc5f6d6e2c904b47035c5679a\", \"aura_unittests\": \"a446ed7baa4174b68e7cf42dad85cd7d55c60747\", \"base_unittests\": \"c1c188d3456a8743c5e16c6606033a0d8b673498\", \"base_util_unittests\": \"19207a7d873cf0f74de523e8cd5a556204c5ee74\", \"blink_common_unittests\": \"d723a0f978323ac0f598627bab87eb9feb9e1b35\", \"blink_fuzzer_unittests\": \"7181575cb996c6a5f8e335edd34dd25ed639ce9c\", \"blink_heap_unittests\": \"12cdb6ee87d42bd81e41b00f393ccf5fe7548728\", \"blink_platform_unittests\": \"f9027e946113b75af19eb4913a381c38f0585636\", \"blink_unittests\": \"ebbbbe1c88721ab02060ce3770e4a7ddd2b0ce1a\", \"boringssl_crypto_tests\": \"4bcfc204003ee0e1467ac45995efc3a9652eec61\", \"boringssl_ssl_tests\": \"8aae27632d89125332a043ddd0d48febedaddd5e\", \"browser_switcher_bho_unittests\": \"f6a103fc946fd1c603fc899d4d0d0fb2ed4b96a8\", \"browser_tests\": \"2652f0f891da99b5de0093f128155e06c4240cea\", \"capture_unittests\": \"616d7b81a828580ccbda00768a6ddbbd28309927\", \"cast_unittests\": \"439bae040ca3772a12136aa465e9d29bc6d28c8d\", \"cc_unittests\": \"1549302f87d18402e0c0a8ce46e6a57f040caf9b\", \"chrome_app_unittests\": \"caccf8ede3b2c8625cbdbe07e7ca7d17e0b08366\", \"chrome_cleaner_unittests\": \"c06d8fc5cd6f6e2d6cb4aefa9ad99c31165e28ca\", \"chrome_elf_unittests\": \"06570e4f7fe114650b81108f03c35a1eb7406e63\", \"chromedriver_unittests\": \"f99c1a0f3a27b39042024eb5a7da7d5a32979650\", \"components_browsertests\": \"a1ea08f418cd36cd42028d80886d6109c06c9402\", \"components_unittests\": \"9b12cdb0fc29c6974332caedf3e0eaccb8edc375\", \"compositor_unittests\": \"5bef3aadd24e39c24ba0d1cd08c21b60276d4eba\", \"content_browsertests\": \"077f7c4e91a7ed87dd38870ef3bb33e15fbaf217\", \"content_unittests\": \"280f1825ca469a2e7a603f412eb959ee2284bbc1\", \"courgette_unittests\": \"e44102befa4bab606cbae51659bf377b36db1e8d\", \"crashpad_tests\": \"e5c69e187df836a9fa9b104c2f0e7aa6b34eae89\", \"cronet_tests\": \"abdae9817e1ab14bb17a2164bbed7e702a55f7df\", \"cronet_unittests\": \"caa5da148d6c144b4108e56469586da70aad731d\", \"crypto_unittests\": \"d0a13231fc91f98c9e0a053733860b17e0b02af3\", \"delayloads_unittests\": \"99801e8d36fa643b100b9eba69ea9983c4f38595\", \"device_unittests\": \"71ee3d01b76cd9a97ba7374a0d13fdadd6f958e8\", \"display_unittests\": \"cd213695caee0a90b935da5eda80c446a67ff6e8\", \"elevation_service_unittests\": \"90fdc0677fa66d1c35e2094479cc51c8f5bfbca3\", \"events_unittests\": \"410b232f4b12d30a76e83c72ac4177ac358dc6d8\", \"extensions_browsertests\": \"af536da429795ff9f1cca6e2bc69d46e216d27ea\", \"extensions_unittests\": \"d73f523cf76baebd27bac8ec80b8f3e9cbd96319\", \"filesystem_service_unittests\": \"47139a4e7156f1c1bc5b6c31d4335c52e47f86b3\", \"gcm_unit_tests\": \"5b479c83bdc257fe10298f8d9ccc7afb7c0284f1\", \"gcp_unittests\": \"6b1ced1cd78848d331de6c6b277e2ddf0ad782a0\", \"gfx_unittests\": \"1a56f4fd7748a182aea128af960b754928838aa5\", \"gin_unittests\": \"9492d96750c3332161492f40d1ed294bed96a597\", \"google_apis_unittests\": \"83c942294b75c2ffd643bf8968856438e7ae02ff\", \"gpu_unittests\": \"9df0625ffab54109672480c0cd848e5ae756ba2b\", \"gwp_asan_unittests\": \"21e4f89432cce3fe1060ad131aeb9fc3b8e5b7f6\", \"headless_browsertests\": \"47c9fc0c6ed6fa2c5d2e40cc3ac11ae5171e4352\", \"headless_unittests\": \"1881273093da2525bc3a92b8a470a1a9d97e9852\", \"install_static_unittests\": \"eafea178b272fd38622a0e2f2cf35287c52d6101\", \"installer_util_unittests\": \"84ce1417072511cbd6759231ad43f36a4282e247\", \"interactive_ui_tests\": \"92a067296956c709e54866f05096c751c298ce7e\", \"ipc_tests\": \"f53e8cfe0ac48b3b87b2b6fbad83b9d294bf26b2\", \"jingle_unittests\": \"3db56cf34ede57302b6979ea81ae9b285fe66548\", \"latency_unittests\": \"41ff6ba3d0d70a31e7bca29940fa11693e03364c\", \"libjingle_xmpp_unittests\": \"6abdb074197364f6ff7f11360f1841d055afc895\", \"liburlpattern_unittests\": \"83ff19686cb07515adecfeaa805763ef51060df3\", \"media_blink_unittests\": \"d5d224a1c46e3821fb7fa185cbc47e5fe849086d\", \"media_unittests\": \"8043ccca035a6203d43d1d0edda03ddd2c75681f\", \"message_center_unittests\": \"f36d560b90ac5eeae5eae7dbcdc7aae75f25bbb1\", \"midi_unittests\": \"b8e6804ba55ef7f0b36b0bd4fbd665cdd9991aea\", \"mojo_core_unittests\": \"305929c8e3c17ca950ca253195275beda4336171\", \"mojo_unittests\": \"d248e9b48c14e2f7339a087f90f11d416476fb7e\", \"nacl_loader_unittests\": \"0e7843781a56848220fe33793e360893054f9628\", \"native_theme_unittests\": \"c961ce153756debdec04da57e7b17e05eea1e65a\", \"net_unittests\": \"8a7f45d3c5758464e0ecd41bfb7266b3f371cfa4\", \"notification_helper_unittests\": \"17712e56d3d3b083caf8e90a1908e8b920290272\", \"pdf_unittests\": \"40c596b29fea04056adfd0204f923e00b1c9ef9a\", \"perfetto_unittests\": \"ff92accf5f425b56454b40b8d05ff79911020cb3\", \"ppapi_unittests\": \"cb42f884d01780d701e65e5f5128bdff2dff54de\", \"printing_unittests\": \"8c30412fa94029a69a1856c1727cb1bd6e648270\", \"remoting_unittests\": \"b24d3a7100d390056a4e4e8de474ccb2c9f8b297\", \"sbox_integration_tests\": \"aebba90b7ba05f01b9163dbd486f80c38aa9a94d\", \"sbox_unittests\": \"f7ed35f5f8e7f1dd002fa973356dbe8f29c74224\", \"sbox_validation_tests\": \"4dd6c159dd46e04ef8790e1203e883d362831a22\", \"service_manager_unittests\": \"35b6eab81cf3427b8d8235e7d822664f18d15ab2\", \"services_unittests\": \"f110ae32f2e64507cbed6f5b1ec78d07b2e582b7\", \"setup_unittests\": \"cfbb1a341cebdd20af54716157abef051a484b4a\", \"shell_dialogs_unittests\": \"a11d147e1b500306ecaa7a1d8195c6081be811b1\", \"skia_unittests\": \"f019605ae0c9f101da3147709585c487621e1625\", \"snapshot_unittests\": \"7f300e2e39eb4442f1482a374ad43176ef20bb21\", \"sql_unittests\": \"ccf13c0f4206481ec4144cf824c180e075d541ba\", \"storage_unittests\": \"c58dbfbde361053b8daa653af276beaa3b4bfb6f\", \"sync_integration_tests\": \"92c4c6bd3599e7e07f8fb2efa1142714bb986a7a\", \"traffic_annotation_auditor_unittests\": \"148abd817e0b49b779e06bd5a59e29eaf6d6bc31\", \"ui_base_unittests\": \"c7b1eef081ec9ea8cf32f53971d198812754b65f\", \"ui_touch_selection_unittests\": \"8edae41102955710d90a1cc7b695eadc57e2f7bf\", \"unit_tests\": \"5b21bd77ca31fc98cdae089d80cec21790449074\", \"updater_tests\": \"f68129c92ab262d3bd2c3ceec26d7bd1a2140f62\", \"url_unittests\": \"95e5471ce15210d81b94f4f79f6c83ea9d5f662f\", \"views_unittests\": \"ef787c302817b981b20faa4e43429a736e2385ca\", \"viz_unittests\": \"1f12ef21354f6b31ea1375ade9475e4fa9432ae4\", \"vr_common_unittests\": \"d68574a06d03f65365cd1af06761242f3c808575\", \"vr_pixeltests\": \"c4cb8059fef522a17351e7bea639b0adcec90d22\", \"weblayer_browsertests\": \"be1cc005c0268db1b688509df11472a4c6628fe8\", \"weblayer_unittests\": \"913a68065a92ae1bc0602117bb7b41ac54a067b9\", \"wm_unittests\": \"c2d6496a38f7f9e6ef742eb47bcaa5220e7dc39b\", \"wtf_unittests\": \"27a366127e304e29303e5df221e01ae9b475b33e\", \"zlib_unittests\": \"298551ea8aa008082d2a2f8f8d10070d44829dca\", \"zucchini_unittests\": \"f6e6bdc7f3d659771453f73b51d233eeb98095a7\"}}, \"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-1-2dz0\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613704272806273",
"status": "COMPLETED",
"status_changed_ts": "1613707806949675",
"tags": [
"build_address:luci.chromium.ci/win-asan/17419",
"builder:win-asan",
"buildset:commit/git/c3b3a253da38cdad5255b66f1f49081c82cb05b8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b3a253da38cdad5255b66f1f49081c82cb05b8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020332209449328160",
"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/8854902147507221760/+/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:51d1169b89c17510",
"user_agent:luci-scheduler"
],
"updated_ts": "1613707806954556",
"url": "https://ci.chromium.org/b/8854902147507221760",
"utcnow_ts": "1613707807880481"
}
}
[04:10:07.889] Invocation finished in 58m59.285197166s with status SUCCEEDED