[06:11:59.484] New invocation is queued and will start shortly
[06:12:02.492] Starting the invocation (attempt 1)
[06:12:02.536] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:12:02.536] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:12:02.536] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028202767571872720",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9113ed973979e4474f791e945d586cf900c5a803\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028202767571872720",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9113ed973979e4474f791e945d586cf900c5a803",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9113ed973979e4474f791e945d586cf900c5a803",
"gitiles_ref:refs/heads/master"
]
}
[06:12:03.691] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606198322627352",
"id": "8862772703611524416",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9113ed973979e4474f791e945d586cf900c5a803\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606198323505533",
"tags": [
"build_address:luci.chromium.ci/win-asan/15816",
"builder:win-asan",
"buildset:commit/git/9113ed973979e4474f791e945d586cf900c5a803",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9113ed973979e4474f791e945d586cf900c5a803",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028202767571872720",
"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/8862772703611524416/+/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": "1606198323505649",
"url": "https://ci.chromium.org/b/8862772703611524416",
"utcnow_ts": "1606198323680190"
}
}
[06:12:03.691] Task URL: https://ci.chromium.org/b/8862772703611524416
[06:12:03.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:2:0) after 1m0s
[06:12:06.646] Received PubSub notification, asking Buildbucket for the build status
[06:12:06.717] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:03.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:2:0)
[06:13:03.788] Timer tick, asking Buildbucket for the build status
[06:13:03.889] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:03.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:4:0) after 1m0s
[06:14:03.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:4:0)
[06:14:03.918] Timer tick, asking Buildbucket for the build status
[06:14:04.117] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:04.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:5:0) after 1m0s
[06:15:04.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:5:0)
[06:15:04.142] Timer tick, asking Buildbucket for the build status
[06:15:04.313] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:04.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:6:0) after 1m0s
[06:16:04.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:6:0)
[06:16:04.416] Timer tick, asking Buildbucket for the build status
[06:16:04.718] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:04.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:7:0) after 1m0s
[06:17:04.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:7:0)
[06:17:04.775] Timer tick, asking Buildbucket for the build status
[06:17:04.938] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:04.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:8:0) after 1m0s
[06:18:04.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:8:0)
[06:18:04.959] Timer tick, asking Buildbucket for the build status
[06:18:05.240] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:05.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:9:0) after 1m0s
[06:19:05.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:9:0)
[06:19:05.306] Timer tick, asking Buildbucket for the build status
[06:19:05.378] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:05.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:10:0) after 1m0s
[06:20:05.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:10:0)
[06:20:05.410] Timer tick, asking Buildbucket for the build status
[06:20:05.521] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:05.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:11:0) after 1m0s
[06:21:05.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:11:0)
[06:21:05.587] Timer tick, asking Buildbucket for the build status
[06:21:05.649] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:05.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:12:0) after 1m0s
[06:22:06.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:12:0)
[06:22:06.058] Timer tick, asking Buildbucket for the build status
[06:22:06.248] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:06.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:13:0) after 1m0s
[06:23:06.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:13:0)
[06:23:06.449] Timer tick, asking Buildbucket for the build status
[06:23:06.576] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:06.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:14:0) after 1m0s
[06:24:06.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:14:0)
[06:24:06.625] Timer tick, asking Buildbucket for the build status
[06:24:06.695] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:06.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:15:0) after 1m0s
[06:25:06.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:15:0)
[06:25:06.838] Timer tick, asking Buildbucket for the build status
[06:25:07.010] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:07.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:16:0) after 1m0s
[06:26:07.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:16:0)
[06:26:07.138] Timer tick, asking Buildbucket for the build status
[06:26:07.312] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:07.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:17:0) after 1m0s
[06:27:07.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:17:0)
[06:27:07.565] Timer tick, asking Buildbucket for the build status
[06:27:07.669] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:07.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:18:0) after 1m0s
[06:28:07.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:18:0)
[06:28:07.761] Timer tick, asking Buildbucket for the build status
[06:28:07.873] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:07.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:19:0) after 1m0s
[06:29:08.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:19:0)
[06:29:08.167] Timer tick, asking Buildbucket for the build status
[06:29:08.310] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:08.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:20:0) after 1m0s
[06:30:08.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:20:0)
[06:30:08.508] Timer tick, asking Buildbucket for the build status
[06:30:08.767] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:08.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:21:0) after 1m0s
[06:31:09.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:21:0)
[06:31:09.315] Timer tick, asking Buildbucket for the build status
[06:31:09.452] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:09.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:22:0) after 1m0s
[06:32:09.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:22:0)
[06:32:09.508] Timer tick, asking Buildbucket for the build status
[06:32:09.629] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:09.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:23:0) after 1m0s
[06:33:09.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:23:0)
[06:33:09.702] Timer tick, asking Buildbucket for the build status
[06:33:10.206] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:10.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:24:0) after 1m0s
[06:34:10.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:24:0)
[06:34:10.269] Timer tick, asking Buildbucket for the build status
[06:34:10.439] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:10.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:25:0) after 1m0s
[06:35:10.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:25:0)
[06:35:10.507] Timer tick, asking Buildbucket for the build status
[06:35:10.643] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:10.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:26:0) after 1m0s
[06:36:10.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:26:0)
[06:36:10.792] Timer tick, asking Buildbucket for the build status
[06:36:11.055] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:11.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:27:0) after 1m0s
[06:37:11.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:27:0)
[06:37:11.072] Timer tick, asking Buildbucket for the build status
[06:37:11.197] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:11.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:28:0) after 1m0s
[06:38:11.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:28:0)
[06:38:11.225] Timer tick, asking Buildbucket for the build status
[06:38:11.788] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:11.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:29:0) after 1m0s
[06:39:12.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:29:0)
[06:39:12.032] Timer tick, asking Buildbucket for the build status
[06:39:12.108] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:12.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:30:0) after 1m0s
[06:40:12.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:30:0)
[06:40:12.277] Timer tick, asking Buildbucket for the build status
[06:40:12.808] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:12.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:31:0) after 1m0s
[06:41:13.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:31:0)
[06:41:13.768] Timer tick, asking Buildbucket for the build status
[06:41:13.888] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:13.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:32:0) after 1m0s
[06:42:14.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:32:0)
[06:42:14.164] Timer tick, asking Buildbucket for the build status
[06:42:14.516] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:14.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:33:0) after 1m0s
[06:43:14.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:33:0)
[06:43:14.809] Timer tick, asking Buildbucket for the build status
[06:43:14.891] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:14.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:34:0) after 1m0s
[06:44:15.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:34:0)
[06:44:15.018] Timer tick, asking Buildbucket for the build status
[06:44:15.086] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:15.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:35:0) after 1m0s
[06:45:15.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:35:0)
[06:45:15.382] Timer tick, asking Buildbucket for the build status
[06:45:15.538] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:15.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:36:0) after 1m0s
[06:46:15.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:36:0)
[06:46:15.689] Timer tick, asking Buildbucket for the build status
[06:46:15.801] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:15.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:37:0) after 1m0s
[06:47:16.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:37:0)
[06:47:16.051] Timer tick, asking Buildbucket for the build status
[06:47:16.162] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:16.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:38:0) after 1m0s
[06:48:16.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:38:0)
[06:48:16.253] Timer tick, asking Buildbucket for the build status
[06:48:16.745] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:16.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:39:0) after 1m0s
[06:49:16.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:39:0)
[06:49:16.848] Timer tick, asking Buildbucket for the build status
[06:49:17.301] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:17.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:40:0) after 1m0s
[06:50:17.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:40:0)
[06:50:17.757] Timer tick, asking Buildbucket for the build status
[06:50:17.853] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:17.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:41:0) after 1m0s
[06:51:17.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:41:0)
[06:51:17.938] Timer tick, asking Buildbucket for the build status
[06:51:18.337] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:18.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:42:0) after 1m0s
[06:52:18.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:42:0)
[06:52:18.590] Timer tick, asking Buildbucket for the build status
[06:52:19.118] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:19.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:43:0) after 1m0s
[06:53:20.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:43:0)
[06:53:20.236] Timer tick, asking Buildbucket for the build status
[06:53:20.342] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:20.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:44:0) after 1m0s
[06:54:20.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:44:0)
[06:54:20.620] Timer tick, asking Buildbucket for the build status
[06:54:20.919] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:20.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:45:0) after 1m0s
[06:55:20.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:45:0)
[06:55:20.964] Timer tick, asking Buildbucket for the build status
[06:55:21.054] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:21.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:46:0) after 1m0s
[06:56:21.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:46:0)
[06:56:21.284] Timer tick, asking Buildbucket for the build status
[06:56:21.404] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:21.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:47:0) after 1m0s
[06:57:21.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:47:0)
[06:57:21.572] Timer tick, asking Buildbucket for the build status
[06:57:21.696] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:21.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:48:0) after 1m0s
[06:58:21.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:48:0)
[06:58:21.761] Timer tick, asking Buildbucket for the build status
[06:58:21.831] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:21.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:49:0) after 1m0s
[06:59:21.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:49:0)
[06:59:21.987] Timer tick, asking Buildbucket for the build status
[06:59:22.066] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:22.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:50:0) after 1m0s
[07:00:22.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:50:0)
[07:00:22.104] Timer tick, asking Buildbucket for the build status
[07:00:22.352] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:22.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:51:0) after 1m0s
[07:01:22.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:51:0)
[07:01:22.503] Timer tick, asking Buildbucket for the build status
[07:01:23.216] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:23.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:52:0) after 1m0s
[07:02:23.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:52:0)
[07:02:23.484] Timer tick, asking Buildbucket for the build status
[07:02:23.599] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:23.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:53:0) after 1m0s
[07:03:23.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:53:0)
[07:03:23.774] Timer tick, asking Buildbucket for the build status
[07:03:23.853] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:23.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:54:0) after 1m0s
[07:04:23.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:54:0)
[07:04:23.907] Timer tick, asking Buildbucket for the build status
[07:04:24.036] Build 8862772703611524416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:24.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028202767571872720:55:0) after 1m0s
[07:05:21.223] Received PubSub notification, asking Buildbucket for the build status
[07:05:21.311] Build 8862772703611524416: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:05:21.314] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606201520147561",
"created_by": "project:chromium",
"created_ts": "1606198322627352",
"id": "8862772703611524416",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9113ed973979e4474f791e945d586cf900c5a803\"}}",
"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\": \"9113ed973979e4474f791e945d586cf900c5a803\", \"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\": 15816, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"c2a74cbb8ee1b1076c6e28f6aae2e8f8de283f28\", \"got_dawn_revision\": \"03badec5d73ab4138f798b94221546c496177a09\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"9113ed973979e4474f791e945d586cf900c5a803\", \"got_revision_cp\": \"refs/heads/master@{#830475}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"43bb60e1fa119d80b449c6550dd2b72328b101b9\", \"got_v8_revision\": \"4ff956a829fefd66af0deeb9bb091fda4389fa21\", \"got_v8_revision_cp\": \"refs/heads/8.9.39@{#1}\", \"got_webrtc_revision\": \"19526a69c763d26f4699f14dd33681db8976a1b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32676}\", \"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\": \"9113ed973979e4474f791e945d586cf900c5a803\", \"swarm_hashes_refs/heads/master(at){#830475}_without_patch\": {\"absl_hardening_tests\": \"68220dabff8853b36faac8ed4caba006c78e3f6d\", \"accessibility_unittests\": \"1802b19df4c0fc3d2f33a85c2529fa5c1b3c4df9\", \"angle_unittests\": \"267b488e03d435a60d94a51797ce0c445225caf2\", \"app_shell_unittests\": \"70313dd8e6e41f4a026de297bef614c04bd0a8e0\", \"aura_unittests\": \"cb8108b965a040ac8e3dc22abcea5c2ee0f89afc\", \"base_unittests\": \"ac23a4e2db50dc78b25a287ed70aa6991a376421\", \"base_util_unittests\": \"ba1dad7f381d613e0ac4cf90e557cdb748431588\", \"blink_common_unittests\": \"87f0f200db16ae639a822685a0f07af92b4cd29e\", \"blink_fuzzer_unittests\": \"f0a622e6d3499a12870b18251b8d30bd19e1b469\", \"blink_heap_unittests\": \"65386254b239437c15e5a2ec994d4eb1d606a387\", \"blink_platform_unittests\": \"b1447b0eace0088caa8cd9e0f13a2f19d9989c00\", \"blink_unittests\": \"3d8d54cafbad0d44bcb0b664376436a4d98f5d3e\", \"boringssl_crypto_tests\": \"260a55559a572395d908bf3327ad4027660ba07c\", \"boringssl_ssl_tests\": \"1b2f9e3dd9249eadcbbe720f2364b0be62d49227\", \"browser_switcher_bho_unittests\": \"a56360406e5c7671d66c91f3d7e35b4ea75f2e3b\", \"browser_tests\": \"6b19d9821c51485110b79e706bf7b57d09351dfb\", \"capture_unittests\": \"99551c83bec707aacdeaed59e0c513cf4e6e5911\", \"cast_unittests\": \"283225bc365e4faf575e4c49f5326e02a8ff6344\", \"cc_unittests\": \"ec5c288aa5aa4a1d8d43fe4ec22ec4d4ee85f6af\", \"chrome_app_unittests\": \"3f42d7beb18d21d6f27782fc60605d5a843a0a0b\", \"chrome_cleaner_unittests\": \"7a4a4a10dbe228cdd5d7138301ffc1842a346f6a\", \"chrome_elf_unittests\": \"1be7d3936d5810e45aaf58ea8e786e070aa4226f\", \"chromedriver_unittests\": \"0c38c27791d1865559b428dbf914719bcc8c1862\", \"components_browsertests\": \"b7ed7c09b7b9e893e55a64c7b94c6185cd7a394f\", \"components_unittests\": \"59decba6a14882e3709a5bc89ce8010f1e4dc684\", \"compositor_unittests\": \"61395bdfd80b95b1e8fe91475ba9377502d244af\", \"content_browsertests\": \"8395f27c2b5e8dd2b3d4df8c1ebd513ec5b644e6\", \"content_unittests\": \"cfdba35d9df6d53b4ae9591dd3521dbe23cf2fac\", \"courgette_unittests\": \"de2f2040f1736932588335fe678928247f24048d\", \"crashpad_tests\": \"70f7f777f8181898298ca1237adbfeedf7a30046\", \"cronet_tests\": \"cea60dc90862e62e4fc4497a799f39c833969735\", \"cronet_unittests\": \"16f6c701e9652bccf317d3a50136007820123d0a\", \"crypto_unittests\": \"e31d0abc0a3987e007cc85bf152e0036a9400cb5\", \"delayloads_unittests\": \"b426d31d2ed8dac0886fa84407485622521432dc\", \"device_unittests\": \"87d8bebe7e0f6ee70c6b7bd55309cd3d3c9a5d49\", \"display_unittests\": \"cc6462219d3a00d44bdb29ad9160bcf56a07ee4f\", \"elevation_service_unittests\": \"57f14bb9f04f903ce9ecf34b75247ab31c981f7a\", \"events_unittests\": \"750310fba48126c968bc087b16dc7399c6910450\", \"extensions_browsertests\": \"348104a3389cdc1dc64378bd2063abeb16c32383\", \"extensions_unittests\": \"d39a64c2925c511ffcbcd36c41c9ac8ac26ddb48\", \"filesystem_service_unittests\": \"eb6a70a52f5e7a0f3a675a02262c85c26cd08ee1\", \"gcm_unit_tests\": \"f85d5f5e11e0990f13aaaa438414c726cf510370\", \"gcp_unittests\": \"8d4e6869ad6178840c3538356a56e3d7822e7758\", \"gfx_unittests\": \"b88ee8192441d7b4dea00f63af2c258c959df189\", \"gin_unittests\": \"dae39e3831708e9be08be29b8ad70e9639e87b23\", \"google_apis_unittests\": \"54ecb06f5f3d59269e6ec31e35abd2b5ae98a68d\", \"gpu_unittests\": \"af714fc32c1d2f033941cda80dab9cf61806a79c\", \"gwp_asan_unittests\": \"6a78bce1cbf6b1ce68c1dee3ee39c2953f2d8007\", \"headless_browsertests\": \"4ff1fa56123c168480fc904fd456f081bdd38ed8\", \"headless_unittests\": \"41b2f27c86a3d4ed2a961eefc4ac52b5e25eeb0b\", \"install_static_unittests\": \"7e82edd73e5f2f1bd5e9f6422ecbae768b49cc70\", \"installer_util_unittests\": \"48e0ebc6d0ca135b62077205cd268cb07b613570\", \"interactive_ui_tests\": \"456e0ec3c878867d3705ae2cc52e8591dc2cd271\", \"ipc_tests\": \"492820e137569d48fbadbc68b18ec985f618a313\", \"jingle_unittests\": \"45af441b078658f5262b81c28bfcdf5855a0319a\", \"latency_unittests\": \"ae7535fd27c22a56a1ab22d4c118a925c122d175\", \"libjingle_xmpp_unittests\": \"36b8c8230be03179430798e633367abc8dcd8629\", \"liburlpattern_unittests\": \"a272d434e16c8488d3652c7b741821a129c7771e\", \"media_blink_unittests\": \"4353d584344daa9000980a5f87b535340fafb488\", \"media_unittests\": \"b19ee9125e1d83b0e3b3c53052f362c1c4ea677f\", \"message_center_unittests\": \"2a788e6de25216848800b78a6789633ca554c9db\", \"midi_unittests\": \"78a4081a2c43208a6f746043e6ef63649c06a36f\", \"mojo_core_unittests\": \"0877f4ad1889eaa3ef4455c6db33cdd3eb1c03db\", \"mojo_unittests\": \"3c97ccc6320738eb985a754ddf651adb0f4202ed\", \"nacl_loader_unittests\": \"8cf0da25f1ce69d1b646e1b5966d6f761ef82483\", \"native_theme_unittests\": \"79887488bef24ebee98d782031d97ac5426d7a6d\", \"net_unittests\": \"dcdbe6f307cb446bc755fe20934db358512c3c4b\", \"notification_helper_unittests\": \"28e96cd9396a1a070310b303ca46e383bef54491\", \"pdf_unittests\": \"50e20456e95797ca796e6582abfba699b26b2f5e\", \"perfetto_unittests\": \"4caf43e0d66feb6eadd61ae5397ecdd3cb71cc9c\", \"ppapi_unittests\": \"d3ecd4bd43c521c98c7180446f61d5482337d11a\", \"printing_unittests\": \"19eed250a8f0a7befababaf0f584896aabdc9d58\", \"remoting_unittests\": \"894c530a95fb3d98de6d8803d9d79b5ab7f3b46e\", \"sbox_integration_tests\": \"b6589497dd13559b24858cc45822699d3e529766\", \"sbox_unittests\": \"12b44e12208fe6006a8a5b936e0fed822d0fd65f\", \"sbox_validation_tests\": \"6e33e0fa59b56a7c6e1724f5ef0309dd8041caac\", \"service_manager_unittests\": \"361426a1b5c8e89b1ceed1fb16174c4a5f4bb53e\", \"services_unittests\": \"198d8773d929cb09e47ccb72d1c13ee23dfffa84\", \"setup_unittests\": \"3fab9bce820bb80c9a957fb87b0d27b0cfa10ab5\", \"shell_dialogs_unittests\": \"51891ab8245e9ab26cbf78249c1669babdc067cd\", \"skia_unittests\": \"aa7786f3284db84c2cce6a20f1a323552a4981e6\", \"snapshot_unittests\": \"3f2c764474f7db16ad69c2f74e1f625b01140e67\", \"sql_unittests\": \"0565fa3ca9c81d97f4a3047cf8ee10c902369f00\", \"storage_unittests\": \"6bf9a1b8d61279e15d474eaca02e138af2a06589\", \"sync_integration_tests\": \"c5dc013cef66e43274e23cd916fbc73187df216f\", \"traffic_annotation_auditor_unittests\": \"a6ac45f446971e86e2cbbdc0c7e2fc728d86f2ec\", \"ui_base_unittests\": \"bbf7bdd1384e26c40787669eaa4c99451af60673\", \"ui_touch_selection_unittests\": \"def2946c9da40f0064fe377b399ddbcdbddf8add\", \"unit_tests\": \"6fed99c9440f81dce4cdc654a821e60c68d880a5\", \"updater_tests\": \"c9599c184e5e12b024e452cf1bceab4fe0738df1\", \"url_unittests\": \"152728aac0e08fa0b8d80488d80a95d41a1fde4c\", \"views_unittests\": \"7fea0c4035487a2fa0fa6a77db968789d3241880\", \"viz_unittests\": \"c8290e6c80f674facca5da1b167e5fb5c4714d75\", \"vr_common_unittests\": \"5d79829cae2df3dcdbf10017d9f00fb2cf12b150\", \"vr_pixeltests\": \"b7b1f7841461b8e444e26230997164dbe1373cf9\", \"weblayer_browsertests\": \"59471492c6f625a2e954540b17fcec81e52d5ded\", \"weblayer_unittests\": \"eed6bf9eb6b4aa858cffa3775ac4efbf78db210f\", \"wm_unittests\": \"c136a1ee726d48bde9a2f56ca9f956d0f89d8aac\", \"wtf_unittests\": \"79ea024ea9b471e14973acb458cd1bd984533cfd\", \"zlib_unittests\": \"da7b44661fadd43e95ee3bfb8484caa2b48a2224\", \"zucchini_unittests\": \"22acaf2e0ac14cc75d36693ca3691755fac99d91\"}}, \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606198325106804",
"status": "COMPLETED",
"status_changed_ts": "1606201520560078",
"tags": [
"build_address:luci.chromium.ci/win-asan/15816",
"builder:win-asan",
"buildset:commit/git/9113ed973979e4474f791e945d586cf900c5a803",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9113ed973979e4474f791e945d586cf900c5a803",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028202767571872720",
"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/8862772703611524416/+/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:5011b2f35758a010",
"user_agent:luci-scheduler"
],
"updated_ts": "1606201520567982",
"url": "https://ci.chromium.org/b/8862772703611524416",
"utcnow_ts": "1606201521287352"
}
}
[07:05:21.314] Invocation finished in 53m21.847369699s with status SUCCEEDED