[20:38:09.132] New invocation is queued and will start shortly
[20:38:10.310] Starting the invocation (attempt 1)
[20:38:10.380] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:38:10.380] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:38:10.380] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023527828157533376",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"517275ce172849780be8548ac2f8f4f095b975b6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023527828157533376",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/517275ce172849780be8548ac2f8f4f095b975b6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/517275ce172849780be8548ac2f8f4f095b975b6",
"gitiles_ref:refs/heads/master"
]
}
[20:38:11.680] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610656690517129",
"id": "8858097766043170352",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"517275ce172849780be8548ac2f8f4f095b975b6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610656691426841",
"tags": [
"build_address:luci.chromium.ci/win-asan/16823",
"builder:win-asan",
"buildset:commit/git/517275ce172849780be8548ac2f8f4f095b975b6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/517275ce172849780be8548ac2f8f4f095b975b6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023527828157533376",
"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/8858097766043170352/+/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": "1610656691427129",
"url": "https://ci.chromium.org/b/8858097766043170352",
"utcnow_ts": "1610656691662691"
}
}
[20:38:11.680] Task URL: https://ci.chromium.org/b/8858097766043170352
[20:38:11.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:2:0) after 1m0s
[20:38:27.575] Received PubSub notification, asking Buildbucket for the build status
[20:38:27.728] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:11.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:2:0)
[20:39:11.724] Timer tick, asking Buildbucket for the build status
[20:39:11.848] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:11.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:4:0) after 1m0s
[20:40:11.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:4:0)
[20:40:11.997] Timer tick, asking Buildbucket for the build status
[20:40:12.114] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:12.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:5:0) after 1m0s
[20:41:12.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:5:0)
[20:41:12.206] Timer tick, asking Buildbucket for the build status
[20:41:12.327] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:12.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:6:0) after 1m0s
[20:42:12.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:6:0)
[20:42:12.383] Timer tick, asking Buildbucket for the build status
[20:42:12.880] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:12.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:7:0) after 1m0s
[20:43:12.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:7:0)
[20:43:12.915] Timer tick, asking Buildbucket for the build status
[20:43:13.108] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:13.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:8:0) after 1m0s
[20:44:13.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:8:0)
[20:44:13.186] Timer tick, asking Buildbucket for the build status
[20:44:13.243] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:13.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:9:0) after 1m0s
[20:45:13.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:9:0)
[20:45:13.316] Timer tick, asking Buildbucket for the build status
[20:45:13.403] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:13.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:10:0) after 1m0s
[20:46:13.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:10:0)
[20:46:13.393] Timer tick, asking Buildbucket for the build status
[20:46:13.493] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:13.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:11:0) after 1m0s
[20:47:13.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:11:0)
[20:47:13.603] Timer tick, asking Buildbucket for the build status
[20:47:13.717] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:13.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:12:0) after 1m0s
[20:48:13.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:12:0)
[20:48:13.900] Timer tick, asking Buildbucket for the build status
[20:48:14.093] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:14.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:13:0) after 1m0s
[20:49:14.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:13:0)
[20:49:14.174] Timer tick, asking Buildbucket for the build status
[20:49:14.249] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:14.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:14:0) after 1m0s
[20:50:14.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:14:0)
[20:50:14.233] Timer tick, asking Buildbucket for the build status
[20:50:14.398] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:14.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:15:0) after 1m0s
[20:51:14.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:15:0)
[20:51:14.498] Timer tick, asking Buildbucket for the build status
[20:51:14.807] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:14.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:16:0) after 1m0s
[20:52:14.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:16:0)
[20:52:14.798] Timer tick, asking Buildbucket for the build status
[20:52:14.876] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:14.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:17:0) after 1m0s
[20:53:14.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:17:0)
[20:53:14.900] Timer tick, asking Buildbucket for the build status
[20:53:15.002] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:15.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:18:0) after 1m0s
[20:54:15.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:18:0)
[20:54:15.016] Timer tick, asking Buildbucket for the build status
[20:54:15.156] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:15.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:19:0) after 1m0s
[20:55:15.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:19:0)
[20:55:15.194] Timer tick, asking Buildbucket for the build status
[20:55:15.361] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:15.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:20:0) after 1m0s
[20:56:15.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:20:0)
[20:56:15.398] Timer tick, asking Buildbucket for the build status
[20:56:15.487] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:15.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:21:0) after 1m0s
[20:57:15.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:21:0)
[20:57:15.588] Timer tick, asking Buildbucket for the build status
[20:57:16.056] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:16.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:22:0) after 1m0s
[20:58:16.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:22:0)
[20:58:16.082] Timer tick, asking Buildbucket for the build status
[20:58:16.193] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:16.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:23:0) after 1m0s
[20:59:16.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:23:0)
[20:59:16.254] Timer tick, asking Buildbucket for the build status
[20:59:16.392] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:16.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:24:0) after 1m0s
[21:00:16.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:24:0)
[21:00:16.480] Timer tick, asking Buildbucket for the build status
[21:00:16.764] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:16.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:25:0) after 1m0s
[21:01:16.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:25:0)
[21:01:16.791] Timer tick, asking Buildbucket for the build status
[21:01:16.907] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:16.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:26:0) after 1m0s
[21:02:16.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:26:0)
[21:02:16.930] Timer tick, asking Buildbucket for the build status
[21:02:17.129] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:17.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:27:0) after 1m0s
[21:03:17.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:27:0)
[21:03:17.196] Timer tick, asking Buildbucket for the build status
[21:03:17.286] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:17.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:28:0) after 1m0s
[21:04:17.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:28:0)
[21:04:17.319] Timer tick, asking Buildbucket for the build status
[21:04:17.429] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:17.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:29:0) after 1m0s
[21:05:17.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:29:0)
[21:05:17.562] Timer tick, asking Buildbucket for the build status
[21:05:19.525] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:19.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:30:0) after 1m0s
[21:06:19.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:30:0)
[21:06:19.577] Timer tick, asking Buildbucket for the build status
[21:06:19.695] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:19.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:31:0) after 1m0s
[21:07:19.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:31:0)
[21:07:19.865] Timer tick, asking Buildbucket for the build status
[21:07:20.215] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:20.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:32:0) after 1m0s
[21:08:20.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:32:0)
[21:08:20.258] Timer tick, asking Buildbucket for the build status
[21:08:20.397] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:20.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:33:0) after 1m0s
[21:09:20.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:33:0)
[21:09:20.382] Timer tick, asking Buildbucket for the build status
[21:09:20.955] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:20.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:34:0) after 1m0s
[21:10:21.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:34:0)
[21:10:21.028] Timer tick, asking Buildbucket for the build status
[21:10:21.200] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:21.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:35:0) after 1m0s
[21:11:21.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:35:0)
[21:11:21.317] Timer tick, asking Buildbucket for the build status
[21:11:21.439] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:21.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:36:0) after 1m0s
[21:12:21.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:36:0)
[21:12:21.505] Timer tick, asking Buildbucket for the build status
[21:12:21.576] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:21.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:37:0) after 1m0s
[21:13:21.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:37:0)
[21:13:21.617] Timer tick, asking Buildbucket for the build status
[21:13:21.720] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:21.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:38:0) after 1m0s
[21:14:21.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:38:0)
[21:14:21.785] Timer tick, asking Buildbucket for the build status
[21:14:22.162] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:22.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:39:0) after 1m0s
[21:15:22.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:39:0)
[21:15:22.203] Timer tick, asking Buildbucket for the build status
[21:15:22.383] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:22.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:40:0) after 1m0s
[21:16:22.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:40:0)
[21:16:22.406] Timer tick, asking Buildbucket for the build status
[21:16:22.599] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:22.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:41:0) after 1m0s
[21:17:22.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:41:0)
[21:17:22.701] Timer tick, asking Buildbucket for the build status
[21:17:22.795] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:22.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:42:0) after 1m0s
[21:18:22.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:42:0)
[21:18:22.847] Timer tick, asking Buildbucket for the build status
[21:18:23.306] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:23.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:43:0) after 1m0s
[21:19:23.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:43:0)
[21:19:23.312] Timer tick, asking Buildbucket for the build status
[21:19:23.497] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:23.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:44:0) after 1m0s
[21:20:23.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:44:0)
[21:20:23.556] Timer tick, asking Buildbucket for the build status
[21:20:23.664] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:23.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:45:0) after 1m0s
[21:21:23.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:45:0)
[21:21:23.710] Timer tick, asking Buildbucket for the build status
[21:21:24.155] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:24.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:46:0) after 1m0s
[21:22:24.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:46:0)
[21:22:24.200] Timer tick, asking Buildbucket for the build status
[21:22:24.372] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:24.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:47:0) after 1m0s
[21:23:24.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:47:0)
[21:23:24.416] Timer tick, asking Buildbucket for the build status
[21:23:24.510] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:24.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:48:0) after 1m0s
[21:24:24.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:48:0)
[21:24:24.532] Timer tick, asking Buildbucket for the build status
[21:24:24.684] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:24.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:49:0) after 1m0s
[21:25:24.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:49:0)
[21:25:24.703] Timer tick, asking Buildbucket for the build status
[21:25:24.841] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:24.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:50:0) after 1m0s
[21:26:24.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:50:0)
[21:26:24.902] Timer tick, asking Buildbucket for the build status
[21:26:25.052] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:25.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:51:0) after 1m0s
[21:27:25.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:51:0)
[21:27:25.116] Timer tick, asking Buildbucket for the build status
[21:27:25.269] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:25.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:52:0) after 1m0s
[21:28:25.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:52:0)
[21:28:25.272] Timer tick, asking Buildbucket for the build status
[21:28:25.405] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:25.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:53:0) after 1m0s
[21:29:25.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:53:0)
[21:29:25.405] Timer tick, asking Buildbucket for the build status
[21:29:25.897] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:25.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:54:0) after 1m0s
[21:30:25.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:54:0)
[21:30:25.981] Timer tick, asking Buildbucket for the build status
[21:30:26.529] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:26.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:55:0) after 1m0s
[21:31:26.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:55:0)
[21:31:26.501] Timer tick, asking Buildbucket for the build status
[21:31:26.614] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:26.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:56:0) after 1m0s
[21:32:26.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:56:0)
[21:32:26.730] Timer tick, asking Buildbucket for the build status
[21:32:26.851] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:26.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:57:0) after 1m0s
[21:33:26.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:57:0)
[21:33:26.940] Timer tick, asking Buildbucket for the build status
[21:33:27.234] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:27.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:58:0) after 1m0s
[21:34:27.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:58:0)
[21:34:27.316] Timer tick, asking Buildbucket for the build status
[21:34:27.473] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:27.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:59:0) after 1m0s
[21:35:27.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:59:0)
[21:35:27.529] Timer tick, asking Buildbucket for the build status
[21:35:27.626] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:27.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:60:0) after 1m0s
[21:36:27.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:60:0)
[21:36:27.655] Timer tick, asking Buildbucket for the build status
[21:36:27.737] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:27.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:61:0) after 1m0s
[21:37:27.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:61:0)
[21:37:27.817] Timer tick, asking Buildbucket for the build status
[21:37:28.322] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:28.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:62:0) after 1m0s
[21:38:28.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:62:0)
[21:38:28.351] Timer tick, asking Buildbucket for the build status
[21:38:28.593] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:28.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:63:0) after 1m0s
[21:39:28.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:63:0)
[21:39:28.584] Timer tick, asking Buildbucket for the build status
[21:39:28.925] Build 8858097766043170352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:28.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023527828157533376:64:0) after 1m0s
[21:39:53.291] Received PubSub notification, asking Buildbucket for the build status
[21:39:53.371] Build 8858097766043170352: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:39:53.371] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610660390668643",
"created_by": "project:chromium",
"created_ts": "1610656690517129",
"id": "8858097766043170352",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"517275ce172849780be8548ac2f8f4f095b975b6\"}}",
"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\": \"517275ce172849780be8548ac2f8f4f095b975b6\", \"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-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16823, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"d392af02b74947659291ea6aee840b09845df9df\", \"got_dawn_revision\": \"d2fb128052aec0a98a696c841ab6232c7323423d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"517275ce172849780be8548ac2f8f4f095b975b6\", \"got_revision_cp\": \"refs/heads/master@{#843709}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"517275ce172849780be8548ac2f8f4f095b975b6\", \"swarm_hashes_refs/heads/master(at){#843709}_without_patch\": {\"absl_hardening_tests\": \"415a391a76af3dd028e2dc2408466b2bb138e089\", \"accessibility_unittests\": \"9f7a06502a7ddad300bd52e0706716f2d03fdf86\", \"angle_unittests\": \"aae76c26ea58a32da934c1307b22ffd06d1a54b7\", \"app_shell_unittests\": \"8ed9d31e569c2a28b0d36d4ac4ded2a6733a1d09\", \"aura_unittests\": \"c44f5730124c96123f263e3f5e98d50c4ddaec34\", \"base_unittests\": \"71394d0b1bf31186e3ef4ffa31219f6e7030aa48\", \"base_util_unittests\": \"cbd95aa6cac442e5dbc838a5f161768e9848ae7d\", \"blink_common_unittests\": \"a3ab9e5123aee223ab47c7df1d534054ec613eb1\", \"blink_fuzzer_unittests\": \"15e6ad0743833b3b3673f30d2428e6bce61542cb\", \"blink_heap_unittests\": \"8f91c71ada70ae3cb6f52f4faf575e51f1261a53\", \"blink_platform_unittests\": \"903bf703271083cc24f09aaf73ed0ad8c9388c5c\", \"blink_unittests\": \"57e3a78a5bca6269a29279481512f6483ac5adda\", \"boringssl_crypto_tests\": \"02e76a265b12b159547d24308b463ad5d10a1526\", \"boringssl_ssl_tests\": \"583e892f51f8118f5742b8322fbd69b51c32c25b\", \"browser_switcher_bho_unittests\": \"d8478eef02d4ee4ba08d0e6e25eb218639e3bdbf\", \"browser_tests\": \"6d8a13c54b0164f5957b5d17075d97d82942939b\", \"capture_unittests\": \"f59c1e57ed8f4fddc15d240261724e3710deee71\", \"cast_unittests\": \"d2ef24880c919d562fc6ca35efd332aad43c2d30\", \"cc_unittests\": \"24245db0eb0893dff4c70b6604dac35b3a96b698\", \"chrome_app_unittests\": \"308a8d87b546375341fe977d4f5e3f0016165709\", \"chrome_cleaner_unittests\": \"b0479076613db2630800cd23a0a3764a1a52deaa\", \"chrome_elf_unittests\": \"404b550527af9776dfa025b720d63f472b19ec54\", \"chromedriver_unittests\": \"41e9b826b101c11831fa8fb9f13f5110aa7d7f6e\", \"components_browsertests\": \"4af3de9e9e79dff41d9b32eca958a4d7680330e1\", \"components_unittests\": \"64d9983baf88113b3cabffda464193af1d8fe388\", \"compositor_unittests\": \"4fb55c413aca6b00ee822bb640ed9b59d698436a\", \"content_browsertests\": \"f089f6631e2696a0a346ca7c62268a1c53f9244e\", \"content_unittests\": \"f53ecbccc5ad29869f32f7dd3e400814634a6494\", \"courgette_unittests\": \"388c2955d4f1978c15efb27c1540584555e03274\", \"crashpad_tests\": \"99e82ddbeec92062d48f61fe792ed86621790703\", \"cronet_tests\": \"82b353297d08868118bef23da7ba3241fa9ade73\", \"cronet_unittests\": \"682c8b100a6293ceb72fa72c5da74dc6f74f2ad7\", \"crypto_unittests\": \"23a49c81d86ad449df25761371694ef88bee4958\", \"delayloads_unittests\": \"8b5558e5af12d9be12955bf83e91119d401cc47a\", \"device_unittests\": \"5e8caddbae8e4e250145c657ce4bd3653c55358f\", \"display_unittests\": \"3c28888695e4cfab42de81fea5d1a92849b95ac0\", \"elevation_service_unittests\": \"358567219bd59ab384c2e822c5ed5f837330a164\", \"events_unittests\": \"392225c424ae3e44efabbc8cfdd06ae1b5ea56a4\", \"extensions_browsertests\": \"41f38d14921546b3f8e9c80a15688c0694ffcfe0\", \"extensions_unittests\": \"6007f0e2c2ca24dfdbb7bd435feb2df96a5daa45\", \"filesystem_service_unittests\": \"f4bc07eae43b27b79c8dd93d46443d00d8e3d992\", \"gcm_unit_tests\": \"2a9717362ed3c76f1d92ff579aee40bbcfca6922\", \"gcp_unittests\": \"bb6b132fd3ff214d3f43cb1fda97c2e9528aabe6\", \"gfx_unittests\": \"45eb06256b34be318abe8ae12e06e3d946d741f6\", \"gin_unittests\": \"964036a35f58add51b5a252e0b740feec3c8c116\", \"google_apis_unittests\": \"b8e7804c87031ed54601465e9ac7a48e55554f65\", \"gpu_unittests\": \"1549ce428d3bda10d07913c593ed15951a7da5ce\", \"gwp_asan_unittests\": \"90ab845f4132947261e8f8e223bb8dc74800fcba\", \"headless_browsertests\": \"3168c19c5c3323ada7d524470d2e4c5a628d32c9\", \"headless_unittests\": \"05af79d67ded5c9a321ac44669d7a1c0adbb189e\", \"install_static_unittests\": \"738f7fe14abbb9174472bee4004fa4014a572b27\", \"installer_util_unittests\": \"8f3a133781b0dc4c66a4a0c554fe045920552e81\", \"interactive_ui_tests\": \"1985f9ed7c2bb182ffb48c77bb533f26c095effc\", \"ipc_tests\": \"7c5ab6d9a477659d9d49b0266557504c06ad19d9\", \"jingle_unittests\": \"978f7c47de6738b3a4c7d8534f7b526c630a75af\", \"latency_unittests\": \"c1ac2ad8db76772b7029cef4364aa324b68a718a\", \"libjingle_xmpp_unittests\": \"260da5bffda660959619bd09ec2db9c1c3be7677\", \"liburlpattern_unittests\": \"f01e11555234e69767103e28e703e399c73d71dc\", \"media_blink_unittests\": \"9b5ef139efc6f65f04da0e373747c3811482f8f7\", \"media_unittests\": \"b34b7ebc46948100550220f1ae08c5ac9fcb0ed5\", \"message_center_unittests\": \"fc5b2a968085f423ea8376fe5009c5299ec9b3a9\", \"midi_unittests\": \"e7e3d23b5a50b605d6838c7e01d3dc8f5362c2ec\", \"mojo_core_unittests\": \"96ba0aa2438d565b57272cdc99a8a88af42cae73\", \"mojo_unittests\": \"d7d0ab246a835a2f444ef09e51af9df0d246ce01\", \"nacl_loader_unittests\": \"28faffdae0deef6d04e1bf7175771d0df52f384e\", \"native_theme_unittests\": \"589ce12e04a78a31bed30e64337876ea17de2b5b\", \"net_unittests\": \"cd94be0a4d3267bfc1d33463089da6d93e2bdf35\", \"notification_helper_unittests\": \"5425f37dc3d5bd5d0b07ad8b4af1fe992db8e178\", \"pdf_unittests\": \"a14703dcb84a948b4df4d51535d6d613f7f385ae\", \"perfetto_unittests\": \"458e710197694d67bbf86dadee7cdbfa2802e243\", \"ppapi_unittests\": \"c212356aae131ce0c1c6338684c46c01da09a8dc\", \"printing_unittests\": \"6a4524ef909d1278eea02b1b936fb18b635b87ee\", \"remoting_unittests\": \"930a693457cf289d2a86e568249e37a555dbee24\", \"sbox_integration_tests\": \"45344228ed9a24f6c19e7f083b9d5aa062e7b407\", \"sbox_unittests\": \"8647d19f1930f1877d55ebaee5487fae3e4d5c27\", \"sbox_validation_tests\": \"5023cbfc92c370a0ac2106b7b4a0e347d3e3cd9e\", \"service_manager_unittests\": \"70637f8ba0beeddd6783e7ec6afff8472a8e13c9\", \"services_unittests\": \"9c5e8aabeeee3e1ea05f267170da3147413171b5\", \"setup_unittests\": \"a85ec61a221f37e508f1188d0655db5b649209e3\", \"shell_dialogs_unittests\": \"d53223846d8f7960c848a62865c32d51ae58c711\", \"skia_unittests\": \"2d108a2314c2d07b07d111db1168179bcb99c076\", \"snapshot_unittests\": \"8ff32497d61c926c80a3b6fc65a764c20dbfb49f\", \"sql_unittests\": \"03fe8f4a2dd3090071192551aa8a7f2252404fe9\", \"storage_unittests\": \"4b11baad2c80152492801ad81aa867d304ac757e\", \"sync_integration_tests\": \"eb38209cd00479b7955ae8a2b80c4ec252949da2\", \"traffic_annotation_auditor_unittests\": \"55ac0263edfafe2eee0d6b15ff9a0e298dc2d55f\", \"ui_base_unittests\": \"744eaa6bacad58d13d99bde68d4272c88b6c480d\", \"ui_touch_selection_unittests\": \"0e7307a75ec2ee22229da71085e53ef1a7abbcf2\", \"unit_tests\": \"900d303ca7d746530c0adfc93ef8d8cd3be45806\", \"updater_tests\": \"bf4236c38d7be1f4f7dffce8589006288d02ae42\", \"url_unittests\": \"881c22ce82a944147e44d532179b64c025861fa4\", \"views_unittests\": \"3f9f5a45455f2340e1701e2d7e8da5b4a3f56f78\", \"viz_unittests\": \"f971ad7caa8b57ce4128b4d07ec3b315dc5bdb03\", \"vr_common_unittests\": \"d107bd24215679a61c9caa211a9ea969b99fe758\", \"vr_pixeltests\": \"7a93ace89ec86bdc12eacbc1e3141f0c57a37f6c\", \"weblayer_browsertests\": \"66555077c1c503385bee970f5ac6b1cd27c762fe\", \"weblayer_unittests\": \"7c55c9f8b1200b3987d953bd4ad05d9fb5375490\", \"wm_unittests\": \"b5b9740cc3134ecceda8e7efb4b2f0dcc90810c4\", \"wtf_unittests\": \"39e92e01af40f4226604a71ab2e8af4d20d4b8bf\", \"zlib_unittests\": \"3b2d31e80cf6ccc6eb59c31edb828f83c1d420bd\", \"zucchini_unittests\": \"fed3d5972e06f3cdbca3526738c146434786da27\"}}, \"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-nb2x\"], \"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\": [\"5661-c6f6250\"], \"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": "1610656697914164",
"status": "COMPLETED",
"status_changed_ts": "1610660391331166",
"tags": [
"build_address:luci.chromium.ci/win-asan/16823",
"builder:win-asan",
"buildset:commit/git/517275ce172849780be8548ac2f8f4f095b975b6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/517275ce172849780be8548ac2f8f4f095b975b6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023527828157533376",
"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/8858097766043170352/+/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:511b703f3c1d8d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610660391397423",
"url": "https://ci.chromium.org/b/8858097766043170352",
"utcnow_ts": "1610660393361405"
}
}
[21:39:53.371] Invocation finished in 1h1m44.253807407s with status SUCCEEDED