[00:36:56.572] New invocation is queued and will start shortly
[00:36:57.974] Starting the invocation (attempt 1)
[00:36:58.066] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:36:58.066] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:36:58.066] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023693998683585744",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c82c4b381e9e10fda7c235e80280518a5aa6c540\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023693998683585744",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c82c4b381e9e10fda7c235e80280518a5aa6c540",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c82c4b381e9e10fda7c235e80280518a5aa6c540",
"gitiles_ref:refs/heads/master"
]
}
[00:36:59.321] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610498218199646",
"id": "8858263936312300576",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c82c4b381e9e10fda7c235e80280518a5aa6c540\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610498219148910",
"tags": [
"build_address:luci.chromium.ci/win-asan/16786",
"builder:win-asan",
"buildset:commit/git/c82c4b381e9e10fda7c235e80280518a5aa6c540",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c82c4b381e9e10fda7c235e80280518a5aa6c540",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023693998683585744",
"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/8858263936312300576/+/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": "1610498219149020",
"url": "https://ci.chromium.org/b/8858263936312300576",
"utcnow_ts": "1610498219309876"
}
}
[00:36:59.321] Task URL: https://ci.chromium.org/b/8858263936312300576
[00:36:59.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:2:0) after 1m0s
[00:37:05.913] Received PubSub notification, asking Buildbucket for the build status
[00:37:06.406] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:59.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:2:0)
[00:37:59.332] Timer tick, asking Buildbucket for the build status
[00:37:59.451] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:59.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:4:0) after 1m0s
[00:38:59.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:4:0)
[00:38:59.554] Timer tick, asking Buildbucket for the build status
[00:38:59.669] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:59.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:5:0) after 1m0s
[00:39:59.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:5:0)
[00:39:59.734] Timer tick, asking Buildbucket for the build status
[00:39:59.862] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:59.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:6:0) after 1m0s
[00:40:59.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:6:0)
[00:40:59.890] Timer tick, asking Buildbucket for the build status
[00:41:00.208] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:00.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:7:0) after 1m0s
[00:42:00.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:7:0)
[00:42:00.355] Timer tick, asking Buildbucket for the build status
[00:42:00.564] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:00.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:8:0) after 1m0s
[00:43:00.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:8:0)
[00:43:00.572] Timer tick, asking Buildbucket for the build status
[00:43:00.697] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:00.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:9:0) after 1m0s
[00:44:00.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:9:0)
[00:44:00.870] Timer tick, asking Buildbucket for the build status
[00:44:01.042] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:01.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:10:0) after 1m0s
[00:45:01.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:10:0)
[00:45:01.957] Timer tick, asking Buildbucket for the build status
[00:45:02.064] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:02.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:11:0) after 1m0s
[00:46:02.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:11:0)
[00:46:02.114] Timer tick, asking Buildbucket for the build status
[00:46:02.977] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:02.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:12:0) after 1m0s
[00:47:03.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:12:0)
[00:47:03.016] Timer tick, asking Buildbucket for the build status
[00:47:03.179] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:03.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:13:0) after 1m0s
[00:48:03.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:13:0)
[00:48:03.281] Timer tick, asking Buildbucket for the build status
[00:48:04.009] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:04.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:14:0) after 1m0s
[00:49:04.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:14:0)
[00:49:04.171] Timer tick, asking Buildbucket for the build status
[00:49:04.388] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:04.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:15:0) after 1m0s
[00:50:04.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:15:0)
[00:50:04.409] Timer tick, asking Buildbucket for the build status
[00:50:04.686] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:04.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:16:0) after 1m0s
[00:51:04.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:16:0)
[00:51:04.709] Timer tick, asking Buildbucket for the build status
[00:51:04.984] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:04.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:17:0) after 1m0s
[00:52:05.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:17:0)
[00:52:05.042] Timer tick, asking Buildbucket for the build status
[00:52:05.524] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:05.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:18:0) after 1m0s
[00:53:05.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:18:0)
[00:53:05.545] Timer tick, asking Buildbucket for the build status
[00:53:05.725] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:05.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:19:0) after 1m0s
[00:54:05.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:19:0)
[00:54:05.846] Timer tick, asking Buildbucket for the build status
[00:54:06.012] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:06.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:20:0) after 1m0s
[00:55:06.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:20:0)
[00:55:06.033] Timer tick, asking Buildbucket for the build status
[00:55:06.288] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:06.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:21:0) after 1m0s
[00:56:06.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:21:0)
[00:56:06.329] Timer tick, asking Buildbucket for the build status
[00:56:06.500] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:06.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:22:0) after 1m0s
[00:57:06.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:22:0)
[00:57:06.509] Timer tick, asking Buildbucket for the build status
[00:57:06.723] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:06.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:23:0) after 1m0s
[00:58:06.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:23:0)
[00:58:06.831] Timer tick, asking Buildbucket for the build status
[00:58:07.084] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:07.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:24:0) after 1m0s
[00:59:07.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:24:0)
[00:59:07.309] Timer tick, asking Buildbucket for the build status
[00:59:10.400] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:10.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:25:0) after 1m0s
[01:00:10.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:25:0)
[01:00:10.524] Timer tick, asking Buildbucket for the build status
[01:00:11.998] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:11.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:26:0) after 1m0s
[01:01:12.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:26:0)
[01:01:12.049] Timer tick, asking Buildbucket for the build status
[01:01:12.348] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:12.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:27:0) after 1m0s
[01:02:12.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:27:0)
[01:02:12.369] Timer tick, asking Buildbucket for the build status
[01:02:12.611] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:12.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:28:0) after 1m0s
[01:03:12.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:28:0)
[01:03:12.670] Timer tick, asking Buildbucket for the build status
[01:03:12.758] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:12.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:29:0) after 1m0s
[01:04:12.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:29:0)
[01:04:12.774] Timer tick, asking Buildbucket for the build status
[01:04:12.877] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:12.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:30:0) after 1m0s
[01:05:12.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:30:0)
[01:05:12.903] Timer tick, asking Buildbucket for the build status
[01:05:13.024] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:13.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:31:0) after 1m0s
[01:06:13.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:31:0)
[01:06:13.025] Timer tick, asking Buildbucket for the build status
[01:06:13.311] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:13.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:32:0) after 1m0s
[01:07:13.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:32:0)
[01:07:13.333] Timer tick, asking Buildbucket for the build status
[01:07:13.489] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:13.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:33:0) after 1m0s
[01:08:13.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:33:0)
[01:08:13.517] Timer tick, asking Buildbucket for the build status
[01:08:13.697] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:13.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:34:0) after 1m0s
[01:09:13.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:34:0)
[01:09:13.720] Timer tick, asking Buildbucket for the build status
[01:09:13.988] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:13.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:35:0) after 1m0s
[01:10:13.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:35:0)
[01:10:13.976] Timer tick, asking Buildbucket for the build status
[01:10:14.073] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:14.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:36:0) after 1m0s
[01:11:14.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:36:0)
[01:11:14.118] Timer tick, asking Buildbucket for the build status
[01:11:14.401] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:14.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:37:0) after 1m0s
[01:12:14.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:37:0)
[01:12:14.432] Timer tick, asking Buildbucket for the build status
[01:12:14.581] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:14.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:38:0) after 1m0s
[01:13:14.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:38:0)
[01:13:14.622] Timer tick, asking Buildbucket for the build status
[01:13:14.743] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:14.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:39:0) after 1m0s
[01:14:14.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:39:0)
[01:14:14.813] Timer tick, asking Buildbucket for the build status
[01:14:15.613] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:15.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:40:0) after 1m0s
[01:15:15.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:40:0)
[01:15:15.678] Timer tick, asking Buildbucket for the build status
[01:15:15.990] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:15.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:41:0) after 1m0s
[01:16:16.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:41:0)
[01:16:16.012] Timer tick, asking Buildbucket for the build status
[01:16:16.294] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:16.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:42:0) after 1m0s
[01:17:16.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:42:0)
[01:17:16.378] Timer tick, asking Buildbucket for the build status
[01:17:16.547] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:16.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:43:0) after 1m0s
[01:18:16.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:43:0)
[01:18:16.576] Timer tick, asking Buildbucket for the build status
[01:18:16.981] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:16.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:44:0) after 1m0s
[01:19:17.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:44:0)
[01:19:17.029] Timer tick, asking Buildbucket for the build status
[01:19:17.126] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:17.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:45:0) after 1m0s
[01:20:17.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:45:0)
[01:20:17.602] Timer tick, asking Buildbucket for the build status
[01:20:17.778] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:17.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:46:0) after 1m0s
[01:21:17.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:46:0)
[01:21:17.764] Timer tick, asking Buildbucket for the build status
[01:21:17.849] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:17.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:47:0) after 1m0s
[01:22:17.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:47:0)
[01:22:17.925] Timer tick, asking Buildbucket for the build status
[01:22:18.149] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:18.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:48:0) after 1m0s
[01:23:18.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:48:0)
[01:23:18.224] Timer tick, asking Buildbucket for the build status
[01:23:18.385] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:18.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:49:0) after 1m0s
[01:24:18.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:49:0)
[01:24:18.450] Timer tick, asking Buildbucket for the build status
[01:24:18.573] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:18.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:50:0) after 1m0s
[01:25:18.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:50:0)
[01:25:18.593] Timer tick, asking Buildbucket for the build status
[01:25:18.728] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:18.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:51:0) after 1m0s
[01:26:18.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:51:0)
[01:26:18.769] Timer tick, asking Buildbucket for the build status
[01:26:18.862] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:18.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:52:0) after 1m0s
[01:27:18.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:52:0)
[01:27:18.915] Timer tick, asking Buildbucket for the build status
[01:27:19.030] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:19.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:53:0) after 1m0s
[01:28:19.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:53:0)
[01:28:19.065] Timer tick, asking Buildbucket for the build status
[01:28:19.200] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:19.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:54:0) after 1m0s
[01:29:19.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:54:0)
[01:29:19.290] Timer tick, asking Buildbucket for the build status
[01:29:19.869] Build 8858263936312300576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:19.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023693998683585744:55:0) after 1m0s
[01:29:26.672] Received PubSub notification, asking Buildbucket for the build status
[01:29:26.920] Build 8858263936312300576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:29:26.920] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610501365625077",
"created_by": "project:chromium",
"created_ts": "1610498218199646",
"id": "8858263936312300576",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c82c4b381e9e10fda7c235e80280518a5aa6c540\"}}",
"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\": \"c82c4b381e9e10fda7c235e80280518a5aa6c540\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-8-wwgq\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16786, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"d38b9f86ff8c57378ed2b301ad1f1b9ba80837ed\", \"got_dawn_revision\": \"22eff1eb8c2ff44e1928121d93559fcf0b81f834\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"c82c4b381e9e10fda7c235e80280518a5aa6c540\", \"got_revision_cp\": \"refs/heads/master@{#842796}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"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\": \"c82c4b381e9e10fda7c235e80280518a5aa6c540\", \"swarm_hashes_refs/heads/master(at){#842796}_without_patch\": {\"absl_hardening_tests\": \"f44134fd267763252c2aa1800d639ec3933c7358\", \"accessibility_unittests\": \"c9c4a468f7180190d95fc55d7f43b6b395abec6f\", \"angle_unittests\": \"31bce9376dbdb0b74a44f1e5593b10c6adbbe3a2\", \"app_shell_unittests\": \"8bee64978b4c02add74850db22f41bc539538000\", \"aura_unittests\": \"12f2eaeedac18e5af94d474ab75d7a7a6468fabe\", \"base_unittests\": \"a2e8fec0f4a409fee139ed0fdee42d190cfb68a5\", \"base_util_unittests\": \"ab7d1dd41299889213048c3c5af245cc174b0ddc\", \"blink_common_unittests\": \"f5cfdfb5db436d2e5d02ca96f0dc3c2bc8976db6\", \"blink_fuzzer_unittests\": \"4250abcb76cb5b2351c7d2dea7ebeaa107bb1593\", \"blink_heap_unittests\": \"e7c5cec6c771d0c20ae7e8cc0be32f8e4ea7123c\", \"blink_platform_unittests\": \"79c04b73de11526babe07499d8742bb0ba3be6be\", \"blink_unittests\": \"00121b132591aa411db0e5d92de2e34b24a107cd\", \"boringssl_crypto_tests\": \"ae76154daa61d7496b3e7821bc65c997466779ac\", \"boringssl_ssl_tests\": \"ce581e139aeeca3c001ed9380438ba1eb7c9531a\", \"browser_switcher_bho_unittests\": \"edb73f0ea1395bd84324f6212f4f40e16efc0972\", \"browser_tests\": \"51c85562360ba26d518259262684020876f670b1\", \"capture_unittests\": \"d286b803ffd45c208669b96c265ab5018c064364\", \"cast_unittests\": \"cdd2716f713d52c0bf068bd74c55f9edb1a13797\", \"cc_unittests\": \"a6182071f2cb11fef4ee5c93345ec4f612d80fa3\", \"chrome_app_unittests\": \"31edd713a7290ec4e6dbeb5d267b61689158a4b0\", \"chrome_cleaner_unittests\": \"4eb52af597ad81801bf4eb1355227f9e7fc27682\", \"chrome_elf_unittests\": \"a658e6bc7cd9d03ac464976f7b5a4d73701a83d1\", \"chromedriver_unittests\": \"b23ea4c4bb2c8bf7e2b2e1d8d98f734ad0718ac8\", \"components_browsertests\": \"bac54cf9a3f088fae0b164a647de060b216590d6\", \"components_unittests\": \"bbd30eabeb7914ec8868e2ee175623a7d8249336\", \"compositor_unittests\": \"750d51f60ccebd025cf39686c1dc2744419e4683\", \"content_browsertests\": \"58586b6e52851a58986a3b916aab72f6d8c370aa\", \"content_unittests\": \"2142ff1801391269ef3524d76fce60ac3bbf7a28\", \"courgette_unittests\": \"b3066bc56e4e6cd3510ea23eb12f3f4344e32811\", \"crashpad_tests\": \"dacaf435a6a93aa2a0bcf4371dfef588053faf08\", \"cronet_tests\": \"797d86d0f385fa37a3f60f90a5365265594bb7e6\", \"cronet_unittests\": \"da5eb6a768c84a35c89d568d37f6736720ba3d1c\", \"crypto_unittests\": \"122ec5ef6bad63468b2689c451f50a9fa83988f8\", \"delayloads_unittests\": \"b2d285378b69b6a8eaa6b8ba3324e646b6acbaab\", \"device_unittests\": \"5d2390a96a14faace1a18cb29d17848161e6c7e8\", \"display_unittests\": \"f1ad8c442e277a18599f571d582f06e19fa3c6a8\", \"elevation_service_unittests\": \"c2846cac58faf1a61175d514e5c2cb405defac8e\", \"events_unittests\": \"b4e68f5453e87f6f4f840ef60a430ea9ec502874\", \"extensions_browsertests\": \"3b39271c1ad5e8bdc6cbd1bff169e403ad1d8c78\", \"extensions_unittests\": \"ed29a56f02d74ad66d4f403f6ae072f4cf1514a9\", \"filesystem_service_unittests\": \"c9cf5e8eb8860729130f5c8255902d20e9817a39\", \"gcm_unit_tests\": \"5eab134fb42f96418c9b63c7e3024d21ca79c40b\", \"gcp_unittests\": \"09809b3c5e229078b1e3ebe6612e9fdf92249938\", \"gfx_unittests\": \"d3404bb2c2b3bac73a43048022eba5667761089a\", \"gin_unittests\": \"e6a3ef7c816dfc49a6edc3188213f0640b534406\", \"google_apis_unittests\": \"1f1c592630a44cf04ec4ae2b4c59b2e3c3acd02f\", \"gpu_unittests\": \"f334a3c7f17abaa2c94d90ea88249f3cf8049610\", \"gwp_asan_unittests\": \"cf3803011549042a6e2d2472f33ce802a5d5b959\", \"headless_browsertests\": \"752ec77f1f5b4ba3ed9bfb1bc3eaf122ac271d08\", \"headless_unittests\": \"554fb1eb10e5d84492a14bc551e13a3f64cf8b18\", \"install_static_unittests\": \"b52113a3de66d97ef97c844016269565b1ee4cb9\", \"installer_util_unittests\": \"3576d84e72c2d9b1d4b713260e8e59e2e9b743bd\", \"interactive_ui_tests\": \"06e7675cb0c8a4f2c2520e3a693f94894a0667c3\", \"ipc_tests\": \"8d5a073d86c598d8d37fe2daeeff648369feb67c\", \"jingle_unittests\": \"3b22282d324c7e286cea523ae80c7ae7864c9709\", \"latency_unittests\": \"a8c8b64ee4371d160cd7ce0db2fe6b8d8deb12f6\", \"libjingle_xmpp_unittests\": \"835ca1898498b600cee14a2b5cadc8dd02b61d36\", \"liburlpattern_unittests\": \"51e409297cb4d63d546369015756607e8de3d576\", \"media_blink_unittests\": \"a9c380c042eed1410dfef0ddc7f24c57012ad98d\", \"media_unittests\": \"6a8c0f463109423c558983422cb77fe86a78fba8\", \"message_center_unittests\": \"b96cd1930838517763d1300f845467c6bfef7332\", \"midi_unittests\": \"c2b7631e407c626e2e24dafbba861cd80ed8cb19\", \"mojo_core_unittests\": \"7fe2069be9a5a5bf0b20daa3615b0b67e0bf3071\", \"mojo_unittests\": \"b8ce891ba3f680904fdcac1729fdada677314dc5\", \"nacl_loader_unittests\": \"09caf6724a5629db46bfc7ab0f66c2b97e2c54b0\", \"native_theme_unittests\": \"0c42e1f6848975b2b0e34e1d24b5e4a3219874bd\", \"net_unittests\": \"7a26d4d35310f5cec3aedac079a8e439b933ed0a\", \"notification_helper_unittests\": \"30202f1617eda9a165eb8168844c8cc83f85c5db\", \"pdf_unittests\": \"d80e41afdf2c45d484c04fde77731df9527a8551\", \"perfetto_unittests\": \"a58fa43c376099ec76a71ce223bedc55fedcabd4\", \"ppapi_unittests\": \"b3cf1dbd9680bc21a4e390f41e5b3555ed75ca92\", \"printing_unittests\": \"dfb3f6fd3ceb1e753bce339f68da92dc5635be40\", \"remoting_unittests\": \"59d80f5067f28f1358b62642259c524879f0fcce\", \"sbox_integration_tests\": \"4f288c475856792e9e1c98b21410e10cf0ce5ed9\", \"sbox_unittests\": \"fbece9dcb038c83466452c7b5bb8e53f40cf636f\", \"sbox_validation_tests\": \"36f0c9356a49d7a980205371e0bff68238f4078f\", \"service_manager_unittests\": \"124087e6e3a5864e28e1494f76f791f0a7324be0\", \"services_unittests\": \"282b20cee4c2ab55e0ca5778089b65924e993ae0\", \"setup_unittests\": \"bf3046ea5e87bbeaf38bbaebca9a4d0903764c3a\", \"shell_dialogs_unittests\": \"5e07199d83a95bb6851a3d26cb0230a6f12fe852\", \"skia_unittests\": \"1e552c06616e393844d8d4205fac5b6bb70fe770\", \"snapshot_unittests\": \"4bb277070362f9081234d9f318270a7548d93d8a\", \"sql_unittests\": \"a1334dc22d7218b776ed1e937e2f20b80ae489ba\", \"storage_unittests\": \"deb23f854bab05150f5479e7c5e86d8c5323a930\", \"sync_integration_tests\": \"b0578835bf39eb8f154fc794add4b3e7f35aac53\", \"traffic_annotation_auditor_unittests\": \"7a5f55ef413d2e7d4fa0b4368ea25f1872587cd8\", \"ui_base_unittests\": \"3b224cb95914c6db3a8158887a06289253ab0619\", \"ui_touch_selection_unittests\": \"47ca3a16f5d1e96a55738dc3e65a04cdf201a090\", \"unit_tests\": \"cb30692555055cf3e47c86ee6525e1c6a287b413\", \"updater_tests\": \"91bbd4f7674dd639ac10264a032f9b67ff87f3b7\", \"url_unittests\": \"2a1eac3ad88e2082885e7929ec2e453663902f6f\", \"views_unittests\": \"806950e22f7b87c3f7367c740464233d80620af8\", \"viz_unittests\": \"b2d90fcb5f4da631f34c24dca277af95e1230fbd\", \"vr_common_unittests\": \"8a0d0bb0e19e6fcfeba4f1d0f0c1d52d6b414620\", \"vr_pixeltests\": \"936b6207d16a03864c0a1cde8cf333503cb259e6\", \"weblayer_browsertests\": \"660279a85ca906eb85f54e90c9934ebf49677778\", \"weblayer_unittests\": \"34b3169ff5bcbc496d7c463ae16e1df34ee62546\", \"wm_unittests\": \"e4e3dc55abdbb8f6876f472633bc25f76a63c1db\", \"wtf_unittests\": \"b94c5d0eb7c63299aaeb1fe3f1fd8b8666cd90a7\", \"zlib_unittests\": \"5d56b6c3b2cd4de5ed1edceb69c977954570d885\", \"zucchini_unittests\": \"fbd3dc9881e048b90f93aa8dfc888290d654ae40\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-8-wwgq\"], \"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\": [\"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": "1610498222742427",
"status": "COMPLETED",
"status_changed_ts": "1610501366043772",
"tags": [
"build_address:luci.chromium.ci/win-asan/16786",
"builder:win-asan",
"buildset:commit/git/c82c4b381e9e10fda7c235e80280518a5aa6c540",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c82c4b381e9e10fda7c235e80280518a5aa6c540",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023693998683585744",
"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/8858263936312300576/+/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:5111fe25ace61810",
"user_agent:luci-scheduler"
],
"updated_ts": "1610501366061051",
"url": "https://ci.chromium.org/b/8858263936312300576",
"utcnow_ts": "1610501366906541"
}
}
[01:29:26.920] Invocation finished in 52m30.365366202s with status SUCCEEDED