[09:45:02.458] New invocation is queued and will start shortly
[09:45:03.724] Starting the invocation (attempt 1)
[09:45:03.772] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:45:03.772] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:45:03.772] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021666382069093104",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1c52e2f80775ec51d66adad95dfdac9ea5db7267\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021666382069093104",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1c52e2f80775ec51d66adad95dfdac9ea5db7267",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c52e2f80775ec51d66adad95dfdac9ea5db7267",
"gitiles_ref:refs/heads/master"
]
}
[09:45:04.507] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612431903888603",
"id": "8856236319907259072",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c52e2f80775ec51d66adad95dfdac9ea5db7267\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612431904309643",
"tags": [
"build_address:luci.chromium.ci/win-asan/17163",
"builder:win-asan",
"buildset:commit/git/1c52e2f80775ec51d66adad95dfdac9ea5db7267",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c52e2f80775ec51d66adad95dfdac9ea5db7267",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021666382069093104",
"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/8856236319907259072/+/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": "1612431904309748",
"url": "https://ci.chromium.org/b/8856236319907259072",
"utcnow_ts": "1612431904487889"
}
}
[09:45:04.507] Task URL: https://ci.chromium.org/b/8856236319907259072
[09:45:04.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:2:0) after 1m0s
[09:45:07.767] Received PubSub notification, asking Buildbucket for the build status
[09:45:07.839] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:04.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:2:0)
[09:46:04.595] Timer tick, asking Buildbucket for the build status
[09:46:04.690] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:04.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:4:0) after 1m0s
[09:47:04.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:4:0)
[09:47:04.714] Timer tick, asking Buildbucket for the build status
[09:47:04.946] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:04.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:5:0) after 1m0s
[09:48:04.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:5:0)
[09:48:04.991] Timer tick, asking Buildbucket for the build status
[09:48:05.102] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:05.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:6:0) after 1m0s
[09:49:05.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:6:0)
[09:49:05.120] Timer tick, asking Buildbucket for the build status
[09:49:05.405] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:05.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:7:0) after 1m0s
[09:50:05.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:7:0)
[09:50:05.481] Timer tick, asking Buildbucket for the build status
[09:50:06.032] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:06.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:8:0) after 1m0s
[09:51:06.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:8:0)
[09:51:06.059] Timer tick, asking Buildbucket for the build status
[09:51:06.210] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:06.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:9:0) after 1m0s
[09:52:06.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:9:0)
[09:52:06.213] Timer tick, asking Buildbucket for the build status
[09:52:06.266] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:06.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:10:0) after 1m0s
[09:53:06.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:10:0)
[09:53:06.264] Timer tick, asking Buildbucket for the build status
[09:53:06.342] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:06.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:11:0) after 1m0s
[09:54:06.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:11:0)
[09:54:06.392] Timer tick, asking Buildbucket for the build status
[09:54:06.504] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:06.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:12:0) after 1m0s
[09:55:06.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:12:0)
[09:55:06.570] Timer tick, asking Buildbucket for the build status
[09:55:07.854] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:07.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:13:0) after 1m0s
[09:56:07.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:13:0)
[09:56:07.855] Timer tick, asking Buildbucket for the build status
[09:56:07.942] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:07.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:14:0) after 1m0s
[09:57:07.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:14:0)
[09:57:07.991] Timer tick, asking Buildbucket for the build status
[09:57:08.407] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:08.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:15:0) after 1m0s
[09:58:08.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:15:0)
[09:58:08.479] Timer tick, asking Buildbucket for the build status
[09:58:08.673] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:08.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:16:0) after 1m0s
[09:59:08.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:16:0)
[09:59:08.689] Timer tick, asking Buildbucket for the build status
[09:59:09.331] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:09.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:17:0) after 1m0s
[10:00:09.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:17:0)
[10:00:09.351] Timer tick, asking Buildbucket for the build status
[10:00:10.069] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:10.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:18:0) after 1m0s
[10:01:10.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:18:0)
[10:01:10.125] Timer tick, asking Buildbucket for the build status
[10:01:10.907] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:10.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:19:0) after 1m0s
[10:02:11.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:19:0)
[10:02:11.014] Timer tick, asking Buildbucket for the build status
[10:02:11.293] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:11.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:20:0) after 1m0s
[10:03:11.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:20:0)
[10:03:11.428] Timer tick, asking Buildbucket for the build status
[10:03:11.494] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:11.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:21:0) after 1m0s
[10:04:11.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:21:0)
[10:04:11.605] Timer tick, asking Buildbucket for the build status
[10:04:12.926] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:12.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:22:0) after 1m0s
[10:05:12.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:22:0)
[10:05:12.970] Timer tick, asking Buildbucket for the build status
[10:05:13.340] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:13.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:23:0) after 1m0s
[10:06:13.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:23:0)
[10:06:13.457] Timer tick, asking Buildbucket for the build status
[10:06:13.531] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:13.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:24:0) after 1m0s
[10:07:13.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:24:0)
[10:07:13.528] Timer tick, asking Buildbucket for the build status
[10:07:13.846] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:13.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:25:0) after 1m0s
[10:08:13.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:25:0)
[10:08:13.929] Timer tick, asking Buildbucket for the build status
[10:08:14.050] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:14.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:26:0) after 1m0s
[10:09:14.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:26:0)
[10:09:14.095] Timer tick, asking Buildbucket for the build status
[10:09:14.197] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:14.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:27:0) after 1m0s
[10:10:14.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:27:0)
[10:10:14.262] Timer tick, asking Buildbucket for the build status
[10:10:14.399] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:14.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:28:0) after 1m0s
[10:11:14.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:28:0)
[10:11:14.504] Timer tick, asking Buildbucket for the build status
[10:11:14.705] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:14.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:29:0) after 1m0s
[10:12:14.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:29:0)
[10:12:14.853] Timer tick, asking Buildbucket for the build status
[10:12:15.053] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:15.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:30:0) after 1m0s
[10:13:15.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:30:0)
[10:13:15.244] Timer tick, asking Buildbucket for the build status
[10:13:15.971] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:15.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:31:0) after 1m0s
[10:14:16.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:31:0)
[10:14:16.059] Timer tick, asking Buildbucket for the build status
[10:14:16.640] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:16.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:32:0) after 1m0s
[10:15:16.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:32:0)
[10:15:16.683] Timer tick, asking Buildbucket for the build status
[10:15:16.911] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:16.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:33:0) after 1m0s
[10:16:16.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:33:0)
[10:16:16.930] Timer tick, asking Buildbucket for the build status
[10:16:17.035] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:17.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:34:0) after 1m0s
[10:17:17.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:34:0)
[10:17:17.085] Timer tick, asking Buildbucket for the build status
[10:17:17.152] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:17.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:35:0) after 1m0s
[10:18:17.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:35:0)
[10:18:17.196] Timer tick, asking Buildbucket for the build status
[10:18:17.637] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:17.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:36:0) after 1m0s
[10:19:17.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:36:0)
[10:19:17.713] Timer tick, asking Buildbucket for the build status
[10:19:17.774] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:17.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:37:0) after 1m0s
[10:20:17.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:37:0)
[10:20:17.831] Timer tick, asking Buildbucket for the build status
[10:20:19.638] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:19.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:38:0) after 1m0s
[10:21:19.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:38:0)
[10:21:19.660] Timer tick, asking Buildbucket for the build status
[10:21:19.818] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:19.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:39:0) after 1m0s
[10:22:19.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:39:0)
[10:22:19.864] Timer tick, asking Buildbucket for the build status
[10:22:19.961] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:19.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:40:0) after 1m0s
[10:23:19.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:40:0)
[10:23:19.956] Timer tick, asking Buildbucket for the build status
[10:23:20.395] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:20.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:41:0) after 1m0s
[10:24:20.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:41:0)
[10:24:20.479] Timer tick, asking Buildbucket for the build status
[10:24:20.577] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:20.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:42:0) after 1m0s
[10:25:20.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:42:0)
[10:25:20.611] Timer tick, asking Buildbucket for the build status
[10:25:20.720] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:20.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:43:0) after 1m0s
[10:26:20.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:43:0)
[10:26:20.745] Timer tick, asking Buildbucket for the build status
[10:26:20.857] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:20.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:44:0) after 1m0s
[10:27:20.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:44:0)
[10:27:20.855] Timer tick, asking Buildbucket for the build status
[10:27:20.956] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:20.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:45:0) after 1m0s
[10:28:20.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:45:0)
[10:28:20.983] Timer tick, asking Buildbucket for the build status
[10:28:21.163] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:21.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:46:0) after 1m0s
[10:29:21.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:46:0)
[10:29:21.184] Timer tick, asking Buildbucket for the build status
[10:29:21.512] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:21.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:47:0) after 1m0s
[10:30:21.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:47:0)
[10:30:21.528] Timer tick, asking Buildbucket for the build status
[10:30:21.627] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:21.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:48:0) after 1m0s
[10:31:21.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:48:0)
[10:31:21.639] Timer tick, asking Buildbucket for the build status
[10:31:21.715] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:21.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:49:0) after 1m0s
[10:32:21.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:49:0)
[10:32:21.785] Timer tick, asking Buildbucket for the build status
[10:32:22.291] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:22.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:50:0) after 1m0s
[10:33:22.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:50:0)
[10:33:22.334] Timer tick, asking Buildbucket for the build status
[10:33:22.474] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:22.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:51:0) after 1m0s
[10:34:22.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:51:0)
[10:34:22.489] Timer tick, asking Buildbucket for the build status
[10:34:22.700] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:22.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:52:0) after 1m0s
[10:35:22.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:52:0)
[10:35:22.758] Timer tick, asking Buildbucket for the build status
[10:35:22.900] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:22.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:53:0) after 1m0s
[10:36:22.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:53:0)
[10:36:22.918] Timer tick, asking Buildbucket for the build status
[10:36:23.298] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:23.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:54:0) after 1m0s
[10:37:23.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:54:0)
[10:37:23.354] Timer tick, asking Buildbucket for the build status
[10:37:23.445] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:23.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:55:0) after 1m0s
[10:38:23.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:55:0)
[10:38:23.511] Timer tick, asking Buildbucket for the build status
[10:38:23.595] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:23.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:56:0) after 1m0s
[10:39:23.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:56:0)
[10:39:23.622] Timer tick, asking Buildbucket for the build status
[10:39:23.816] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:23.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:57:0) after 1m0s
[10:40:23.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:57:0)
[10:40:23.838] Timer tick, asking Buildbucket for the build status
[10:40:24.003] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:24.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:58:0) after 1m0s
[10:41:24.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:58:0)
[10:41:24.030] Timer tick, asking Buildbucket for the build status
[10:41:24.155] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:24.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:59:0) after 1m0s
[10:42:24.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:59:0)
[10:42:24.245] Timer tick, asking Buildbucket for the build status
[10:42:24.315] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:24.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:60:0) after 1m0s
[10:43:24.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:60:0)
[10:43:24.442] Timer tick, asking Buildbucket for the build status
[10:43:24.520] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:24.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:61:0) after 1m0s
[10:44:24.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:61:0)
[10:44:24.737] Timer tick, asking Buildbucket for the build status
[10:44:24.817] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:24.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:62:0) after 1m0s
[10:45:25.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:62:0)
[10:45:25.038] Timer tick, asking Buildbucket for the build status
[10:45:25.152] Build 8856236319907259072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:25.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021666382069093104:63:0) after 1m0s
[10:45:58.879] Received PubSub notification, asking Buildbucket for the build status
[10:45:59.266] Build 8856236319907259072: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:45:59.266] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612435558055619",
"created_by": "project:chromium",
"created_ts": "1612431903888603",
"id": "8856236319907259072",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c52e2f80775ec51d66adad95dfdac9ea5db7267\"}}",
"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\": \"1c52e2f80775ec51d66adad95dfdac9ea5db7267\", \"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-2-30ob\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17163, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"ad985fcdd806a26343b9b5f8408e551b515493e5\", \"got_dawn_revision\": \"d5a0728b67c8328edaed3d9cfee7ee44fcb893e8\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"1c52e2f80775ec51d66adad95dfdac9ea5db7267\", \"got_revision_cp\": \"refs/heads/master@{#850513}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"b8307ba2ab92cba515b38d20aa32b36d4bade80c\", \"got_v8_revision_cp\": \"refs/heads/9.0.127@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"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\": \"1c52e2f80775ec51d66adad95dfdac9ea5db7267\", \"swarm_hashes_refs/heads/master(at){#850513}_without_patch\": {\"absl_hardening_tests\": \"95e5bf2564a4906b7039e84e9415d1b6f46eebf6\", \"accessibility_unittests\": \"0b24243323ce40af0c4d9a387ba603a89d54c5ce\", \"angle_unittests\": \"56066148d2654757f9685227f3f7969d65be21c5\", \"app_shell_unittests\": \"111464e7cede378ae798344d30d915c657c5c598\", \"aura_unittests\": \"54c3a49cd31857f1272ba77b08d49b9ddd70015c\", \"base_unittests\": \"802a2199014f7d3c7ff5d19e0897a041fff4514b\", \"base_util_unittests\": \"6b347d3ca8afc1852cf53a7b205163557a14627b\", \"blink_common_unittests\": \"b6889f324ddcc2dbc9e969533b76bb3b17183610\", \"blink_fuzzer_unittests\": \"bfbe5b33f201c0236c6671ffd27146aba11bc05e\", \"blink_heap_unittests\": \"4b86886579d34e095f246f1cef0239f913a92998\", \"blink_platform_unittests\": \"40852981308b5afadefcfca2cbd3777a0b857d2a\", \"blink_unittests\": \"41c6067648eba64d0c90aa952909dd5e77032008\", \"boringssl_crypto_tests\": \"9ea933882d8af52bf61bf9844e306663f5634c68\", \"boringssl_ssl_tests\": \"5f630df9ff7a0bb5e20d7b2046125b9babcbd5b8\", \"browser_switcher_bho_unittests\": \"83971e477ae90a31d681c3c82a337a93d79da935\", \"browser_tests\": \"e1f9327b5fffeba4720c90bc4d5efc29ceed2434\", \"capture_unittests\": \"70ec780ebade803289747a0257d832fae96fa237\", \"cast_unittests\": \"fcc72a6f9f9d27aa8ac5a0685fc3974a404dd4dd\", \"cc_unittests\": \"89a4542da110b704b028a028e3aded0cd4b4c2f8\", \"chrome_app_unittests\": \"4452718617fbf11efb5d4e208a3627a1cfcaf922\", \"chrome_cleaner_unittests\": \"587836c9e97267149685e04a0aa1fbb3cd416d9d\", \"chrome_elf_unittests\": \"7fca79cf90aebc84ed10b0dd8730d5381f3afe44\", \"chromedriver_unittests\": \"1d418f4b51946aa94afda487a73fdbf3aa33a551\", \"components_browsertests\": \"a768b039c6b27ee6f6bf87b82d1fe5b96faead5a\", \"components_unittests\": \"d77b141b85a15d1215a27a463933b1453034bb2e\", \"compositor_unittests\": \"e33d826f339fb8a6065fca6eacd293f9a28cb020\", \"content_browsertests\": \"c18ad31748fdc99f90595a58a37665e567f27d9d\", \"content_unittests\": \"c20b2fe4ac8e6316487c2114efede4a3bf678f35\", \"courgette_unittests\": \"6cf384d45de0b6fa81b8df5dcf5f447ac30b942d\", \"crashpad_tests\": \"3fa2c31d01984c3034f7e220df6f5b851ee63dfd\", \"cronet_tests\": \"d0daeda266334e50b38e331f87bea10d5a3f5156\", \"cronet_unittests\": \"f96cb3f96cb33225eb5fbc1776f4922ce63e0bc1\", \"crypto_unittests\": \"0b833cc1978decfd5b076f35ff24d8ce7e374135\", \"delayloads_unittests\": \"cd0ac6e3a4a2dfb0f0eeb1f57ab3113a4de71087\", \"device_unittests\": \"dcdad00b39271c6e653133b47e4c89eddaeb1dd6\", \"display_unittests\": \"ef8bfac730ed44d875a503c554bb1dd6f3c516d8\", \"elevation_service_unittests\": \"d32e6818804c5aa71add669a0ac26b9f6c64a59e\", \"events_unittests\": \"5888254d3500c61c8b564fe4b47f3d2903d4c330\", \"extensions_browsertests\": \"bb597aa09c290c88239910c0844c804b90566f5e\", \"extensions_unittests\": \"98ec70fe19370d39aec54d2916c4dadacd89c499\", \"filesystem_service_unittests\": \"72582afc248505eaad3c7a4008fd393c02ce5fc3\", \"gcm_unit_tests\": \"f8836a3bb2d828ef24426d55a7761e625603598d\", \"gcp_unittests\": \"ca99c52f479cc69117bff290106a8e70e5fa6e86\", \"gfx_unittests\": \"968ddf6c206dc69488692f51a516780f0259cb84\", \"gin_unittests\": \"639b45e68b393dbdce529e0b1f757a12a1b03a21\", \"google_apis_unittests\": \"6fccd4e408af16d15c492569cfdba8d11971d43a\", \"gpu_unittests\": \"af1b3079851d378c8ac298ae2e6376669d695cf0\", \"gwp_asan_unittests\": \"4f1d274945b15dffc53aac3dfae84c124ae6de17\", \"headless_browsertests\": \"0ee273904a2295c126a30894e56e6f8ac0f05988\", \"headless_unittests\": \"624a280464ec37cb175a389b8e81efc337eea109\", \"install_static_unittests\": \"564c03f84d328751e25385611f2870accebc9fac\", \"installer_util_unittests\": \"19cff9417e94cccfe9b8f0c8df2fb7482c15d84f\", \"interactive_ui_tests\": \"44c712649b65b46ff348cd06f4592fb37a005a2e\", \"ipc_tests\": \"912a0c93d7d3e13f9306c931cb9ff00e755f36da\", \"jingle_unittests\": \"75b7f8b86d4524d68f42aeeeaf71a684c59f5baf\", \"latency_unittests\": \"f664c375ef5bc72a2a78af77b75daba0093e5eaf\", \"libjingle_xmpp_unittests\": \"aa34bdb105a7e5be76bbc4d5aa082a2ea6d59199\", \"liburlpattern_unittests\": \"29a6b45f18aabac4d78355ce39cf22128291e1c1\", \"media_blink_unittests\": \"a0500f54f7536da210d50f4298c3443800d3651d\", \"media_unittests\": \"1204a2150d66ffbb3645e655d65abef44aa5ca31\", \"message_center_unittests\": \"16952f560e97eb9cf4f07e25da37445ea516535e\", \"midi_unittests\": \"7b88be8733e03670fbd0213a5fb0a85bab87aaba\", \"mojo_core_unittests\": \"5f663ba9b92202272fe4a8b5e67afac5eccbeeca\", \"mojo_unittests\": \"9b1100e04a8f3c6f064a75696b5f4d4aee9b58c0\", \"nacl_loader_unittests\": \"6fbb90b2d8d2d14b28b636bfcc99735d4f4610df\", \"native_theme_unittests\": \"698e483ac84e9795d7d5968b949891a43172179e\", \"net_unittests\": \"52494d11090372e6c8a22cfe995f6a02dd908757\", \"notification_helper_unittests\": \"4f348b4aa7240f42fb8c6e19851c1d2cee9937a4\", \"pdf_unittests\": \"4af3a60098dddab599bfebab30e1c85403dd0b02\", \"perfetto_unittests\": \"ed05e867855a40d377c06a465c1633b2e90ae388\", \"ppapi_unittests\": \"26ffc15779f5f03ec5900d41fe609326db15aeed\", \"printing_unittests\": \"fe9bc2ed47e5a76a816732675d873da2e907b2d7\", \"remoting_unittests\": \"08180cdf0539aca9c147d71ba61c508277d48745\", \"sbox_integration_tests\": \"c89f841914416a605ccd239089575f6adcbd0db0\", \"sbox_unittests\": \"054d8c2e7fcc77b8981dd583251052afa1653266\", \"sbox_validation_tests\": \"a2d44d714251a93736a90da398f9470f7d9ef530\", \"service_manager_unittests\": \"ca381adf0844ef75640de11a8578651692c2beb5\", \"services_unittests\": \"06c8ee8c761c53a48285edbced83b80c7437b032\", \"setup_unittests\": \"7fada3e1541a1bcf02086f06456b65741a0d555c\", \"shell_dialogs_unittests\": \"e1d055a6f536bf8d162b5029a151f9b9a85375c1\", \"skia_unittests\": \"b4d665c4cdcebb462a0878fc9cc2fd02c222510c\", \"snapshot_unittests\": \"ac66c7a17cc755b6de04f991e9347f92030753b8\", \"sql_unittests\": \"7a6ba238c1ef7aabf7234e417013082eceff166d\", \"storage_unittests\": \"0c8886d900d0547c5da2b6334f1a85303b107c0b\", \"sync_integration_tests\": \"0529e39ce9dd65d4b9c9e1932aac8e14c4f634a9\", \"traffic_annotation_auditor_unittests\": \"29f29f481265c5413b318494ba5f8eff1c6dc645\", \"ui_base_unittests\": \"facb2977231b5986bd219e8f4cc60ce652f8e107\", \"ui_touch_selection_unittests\": \"7e4c47ff670ea30a29e6be5b77b1414dff62550c\", \"unit_tests\": \"9fb18ba031d42ebc196f5f8c0a36d99a374a0f63\", \"updater_tests\": \"dc979bc85c62aa99c95264ece6daac634116300f\", \"url_unittests\": \"237f2e1cf37d190de3f5596038b580eef5052558\", \"views_unittests\": \"8d8eceb5e4d4a3f6744d5767cfef273a24dbf26c\", \"viz_unittests\": \"af58d5e8b14f78de524ed9931df5c235cd379e08\", \"vr_common_unittests\": \"df6d9fd54884e492cb78939f03456d3b655d0c12\", \"vr_pixeltests\": \"12e0afbf00eb58a7b9e38d9858662366e0e50fe3\", \"weblayer_browsertests\": \"464ad6320a15e3936676edd59c4798c666e6998e\", \"weblayer_unittests\": \"61dd2b868f422df2ec29af623b65ee8e4e14ebcf\", \"wm_unittests\": \"2e80167497636896642093567939ded0ac9c65ba\", \"wtf_unittests\": \"d9c59d4b1e088cb89cb65792e59246c93d1fa508\", \"zlib_unittests\": \"230c98021daa0f5155374bd3fb0299e2eccc429a\", \"zucchini_unittests\": \"d3e4dd18bd84e7b9602c89e6b99c7619ba26f3f8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_v2\", \"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-2-30ob\"], \"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\": [\"5676-c76598c\"], \"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": "1612431906286185",
"status": "COMPLETED",
"status_changed_ts": "1612435558334703",
"tags": [
"build_address:luci.chromium.ci/win-asan/17163",
"builder:win-asan",
"buildset:commit/git/1c52e2f80775ec51d66adad95dfdac9ea5db7267",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c52e2f80775ec51d66adad95dfdac9ea5db7267",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021666382069093104",
"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/8856236319907259072/+/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:51853fda7dfeff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612435558339598",
"url": "https://ci.chromium.org/b/8856236319907259072",
"utcnow_ts": "1612435559248659"
}
}
[10:45:59.267] Invocation finished in 1h0m56.823426685s with status SUCCEEDED