[15:08:00.900] New invocation is queued and will start shortly
[15:08:02.384] Starting the invocation (attempt 1)
[15:08:02.450] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:08:02.450] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:08:02.450] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020377704768359184",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a516967152367ba73fea80f783557fd48e0eb75f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020377704768359184",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a516967152367ba73fea80f783557fd48e0eb75f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a516967152367ba73fea80f783557fd48e0eb75f",
"gitiles_ref:refs/heads/master"
]
}
[15:08:04.247] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613660882688067",
"id": "8854947642232605856",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a516967152367ba73fea80f783557fd48e0eb75f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613660883507842",
"tags": [
"build_address:luci.chromium.ci/win-asan/17410",
"builder:win-asan",
"buildset:commit/git/a516967152367ba73fea80f783557fd48e0eb75f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a516967152367ba73fea80f783557fd48e0eb75f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020377704768359184",
"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/8854947642232605856/+/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": "1613660883507997",
"url": "https://ci.chromium.org/b/8854947642232605856",
"utcnow_ts": "1613660884226188"
}
}
[15:08:04.247] Task URL: https://ci.chromium.org/b/8854947642232605856
[15:08:04.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:2:0) after 1m0s
[15:08:07.579] Received PubSub notification, asking Buildbucket for the build status
[15:08:07.806] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:04.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:2:0)
[15:09:04.324] Timer tick, asking Buildbucket for the build status
[15:09:04.433] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:04.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:4:0) after 1m0s
[15:10:04.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:4:0)
[15:10:04.523] Timer tick, asking Buildbucket for the build status
[15:10:04.634] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:04.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:5:0) after 1m0s
[15:11:04.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:5:0)
[15:11:04.787] Timer tick, asking Buildbucket for the build status
[15:11:05.140] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:05.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:6:0) after 1m0s
[15:12:05.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:6:0)
[15:12:05.283] Timer tick, asking Buildbucket for the build status
[15:12:05.562] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:05.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:7:0) after 1m0s
[15:13:05.616] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:7:0)
[15:13:05.616] Timer tick, asking Buildbucket for the build status
[15:13:05.741] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:05.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:8:0) after 1m0s
[15:14:05.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:8:0)
[15:14:05.929] Timer tick, asking Buildbucket for the build status
[15:14:06.019] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:06.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:9:0) after 1m0s
[15:15:06.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:9:0)
[15:15:06.126] Timer tick, asking Buildbucket for the build status
[15:15:06.199] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:06.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:10:0) after 1m0s
[15:16:06.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:10:0)
[15:16:06.233] Timer tick, asking Buildbucket for the build status
[15:16:06.304] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:06.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:11:0) after 1m0s
[15:17:06.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:11:0)
[15:17:06.327] Timer tick, asking Buildbucket for the build status
[15:17:06.411] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:06.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:12:0) after 1m0s
[15:18:06.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:12:0)
[15:18:06.450] Timer tick, asking Buildbucket for the build status
[15:18:06.596] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:06.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:13:0) after 1m0s
[15:19:06.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:13:0)
[15:19:06.627] Timer tick, asking Buildbucket for the build status
[15:19:06.730] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:06.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:14:0) after 1m0s
[15:20:06.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:14:0)
[15:20:06.759] Timer tick, asking Buildbucket for the build status
[15:20:06.836] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:06.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:15:0) after 1m0s
[15:21:06.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:15:0)
[15:21:06.864] Timer tick, asking Buildbucket for the build status
[15:21:06.931] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:06.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:16:0) after 1m0s
[15:22:06.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:16:0)
[15:22:06.966] Timer tick, asking Buildbucket for the build status
[15:22:07.039] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:07.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:17:0) after 1m0s
[15:23:07.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:17:0)
[15:23:07.202] Timer tick, asking Buildbucket for the build status
[15:23:07.299] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:07.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:18:0) after 1m0s
[15:24:07.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:18:0)
[15:24:07.386] Timer tick, asking Buildbucket for the build status
[15:24:07.470] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:07.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:19:0) after 1m0s
[15:25:07.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:19:0)
[15:25:07.552] Timer tick, asking Buildbucket for the build status
[15:25:07.633] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:07.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:20:0) after 1m0s
[15:26:07.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:20:0)
[15:26:07.661] Timer tick, asking Buildbucket for the build status
[15:26:07.736] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:07.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:21:0) after 1m0s
[15:27:07.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:21:0)
[15:27:07.835] Timer tick, asking Buildbucket for the build status
[15:27:07.925] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:07.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:22:0) after 1m0s
[15:28:07.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:22:0)
[15:28:07.962] Timer tick, asking Buildbucket for the build status
[15:28:08.439] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:08.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:23:0) after 1m0s
[15:29:08.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:23:0)
[15:29:08.518] Timer tick, asking Buildbucket for the build status
[15:29:08.613] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:08.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:24:0) after 1m0s
[15:30:08.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:24:0)
[15:30:08.665] Timer tick, asking Buildbucket for the build status
[15:30:08.780] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:08.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:25:0) after 1m0s
[15:31:08.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:25:0)
[15:31:08.835] Timer tick, asking Buildbucket for the build status
[15:31:09.211] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:09.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:26:0) after 1m0s
[15:32:09.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:26:0)
[15:32:09.204] Timer tick, asking Buildbucket for the build status
[15:32:09.281] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:09.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:27:0) after 1m0s
[15:33:09.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:27:0)
[15:33:09.384] Timer tick, asking Buildbucket for the build status
[15:33:09.612] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:09.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:28:0) after 1m0s
[15:34:09.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:28:0)
[15:34:09.671] Timer tick, asking Buildbucket for the build status
[15:34:09.773] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:09.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:29:0) after 1m0s
[15:35:09.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:29:0)
[15:35:09.790] Timer tick, asking Buildbucket for the build status
[15:35:10.316] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:10.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:30:0) after 1m0s
[15:36:10.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:30:0)
[15:36:10.339] Timer tick, asking Buildbucket for the build status
[15:36:10.700] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:10.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:31:0) after 1m0s
[15:37:10.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:31:0)
[15:37:10.687] Timer tick, asking Buildbucket for the build status
[15:37:10.770] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:10.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:32:0) after 1m0s
[15:38:10.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:32:0)
[15:38:10.814] Timer tick, asking Buildbucket for the build status
[15:38:10.939] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:10.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:33:0) after 1m0s
[15:39:11.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:33:0)
[15:39:11.042] Timer tick, asking Buildbucket for the build status
[15:39:11.124] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:11.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:34:0) after 1m0s
[15:40:11.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:34:0)
[15:40:11.185] Timer tick, asking Buildbucket for the build status
[15:40:11.275] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:11.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:35:0) after 1m0s
[15:41:11.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:35:0)
[15:41:11.390] Timer tick, asking Buildbucket for the build status
[15:41:11.507] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:11.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:36:0) after 1m0s
[15:42:11.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:36:0)
[15:42:11.562] Timer tick, asking Buildbucket for the build status
[15:42:11.728] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:11.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:37:0) after 1m0s
[15:43:11.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:37:0)
[15:43:11.757] Timer tick, asking Buildbucket for the build status
[15:43:11.950] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:11.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:38:0) after 1m0s
[15:44:12.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:38:0)
[15:44:12.029] Timer tick, asking Buildbucket for the build status
[15:44:12.141] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:12.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:39:0) after 1m0s
[15:45:12.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:39:0)
[15:45:12.261] Timer tick, asking Buildbucket for the build status
[15:45:12.335] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:12.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:40:0) after 1m0s
[15:46:12.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:40:0)
[15:46:12.308] Timer tick, asking Buildbucket for the build status
[15:46:12.394] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:12.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:41:0) after 1m0s
[15:47:12.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:41:0)
[15:47:12.452] Timer tick, asking Buildbucket for the build status
[15:47:12.905] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:12.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:42:0) after 1m0s
[15:48:12.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:42:0)
[15:48:12.982] Timer tick, asking Buildbucket for the build status
[15:48:13.075] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:13.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:43:0) after 1m0s
[15:49:13.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:43:0)
[15:49:13.094] Timer tick, asking Buildbucket for the build status
[15:49:13.183] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:13.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:44:0) after 1m0s
[15:50:13.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:44:0)
[15:50:13.237] Timer tick, asking Buildbucket for the build status
[15:50:13.318] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:13.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:45:0) after 1m0s
[15:51:13.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:45:0)
[15:51:13.359] Timer tick, asking Buildbucket for the build status
[15:51:13.467] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:13.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:46:0) after 1m0s
[15:52:13.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:46:0)
[15:52:13.566] Timer tick, asking Buildbucket for the build status
[15:52:13.643] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:13.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:47:0) after 1m0s
[15:53:13.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:47:0)
[15:53:13.692] Timer tick, asking Buildbucket for the build status
[15:53:13.992] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:13.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:48:0) after 1m0s
[15:54:14.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:48:0)
[15:54:14.015] Timer tick, asking Buildbucket for the build status
[15:54:14.093] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:14.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:49:0) after 1m0s
[15:55:14.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:49:0)
[15:55:14.120] Timer tick, asking Buildbucket for the build status
[15:55:14.254] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:14.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:50:0) after 1m0s
[15:56:14.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:50:0)
[15:56:14.282] Timer tick, asking Buildbucket for the build status
[15:56:14.365] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:14.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:51:0) after 1m0s
[15:57:14.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:51:0)
[15:57:14.533] Timer tick, asking Buildbucket for the build status
[15:57:14.656] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:14.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:52:0) after 1m0s
[15:58:14.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:52:0)
[15:58:14.783] Timer tick, asking Buildbucket for the build status
[15:58:14.957] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:14.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:53:0) after 1m0s
[15:59:15.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:53:0)
[15:59:15.072] Timer tick, asking Buildbucket for the build status
[15:59:15.432] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:15.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:54:0) after 1m0s
[16:00:15.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:54:0)
[16:00:15.577] Timer tick, asking Buildbucket for the build status
[16:00:15.666] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:15.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:55:0) after 1m0s
[16:01:15.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:55:0)
[16:01:15.872] Timer tick, asking Buildbucket for the build status
[16:01:15.965] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:15.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:56:0) after 1m0s
[16:02:16.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:56:0)
[16:02:16.101] Timer tick, asking Buildbucket for the build status
[16:02:16.207] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:16.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:57:0) after 1m0s
[16:03:16.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:57:0)
[16:03:16.297] Timer tick, asking Buildbucket for the build status
[16:03:16.446] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:16.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:58:0) after 1m0s
[16:04:16.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:58:0)
[16:04:16.572] Timer tick, asking Buildbucket for the build status
[16:04:16.845] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:16.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:59:0) after 1m0s
[16:05:16.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:59:0)
[16:05:16.923] Timer tick, asking Buildbucket for the build status
[16:05:17.191] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:17.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:60:0) after 1m0s
[16:06:17.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:60:0)
[16:06:17.223] Timer tick, asking Buildbucket for the build status
[16:06:17.308] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:17.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:61:0) after 1m0s
[16:07:17.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:61:0)
[16:07:17.341] Timer tick, asking Buildbucket for the build status
[16:07:17.428] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:17.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:62:0) after 1m0s
[16:08:17.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:62:0)
[16:08:17.479] Timer tick, asking Buildbucket for the build status
[16:08:17.579] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:17.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:63:0) after 1m0s
[16:09:17.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:63:0)
[16:09:17.603] Timer tick, asking Buildbucket for the build status
[16:09:17.678] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:17.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:64:0) after 1m0s
[16:10:17.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:64:0)
[16:10:17.718] Timer tick, asking Buildbucket for the build status
[16:10:17.803] Build 8854947642232605856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:17.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020377704768359184:65:0) after 1m0s
[16:10:32.784] Received PubSub notification, asking Buildbucket for the build status
[16:10:32.875] Build 8854947642232605856: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:10:32.875] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613664630959886",
"created_by": "project:chromium",
"created_ts": "1613660882688067",
"id": "8854947642232605856",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a516967152367ba73fea80f783557fd48e0eb75f\"}}",
"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\": \"a516967152367ba73fea80f783557fd48e0eb75f\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-1-2dz0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17410, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"2d3402eba7a8c8115619256aa83bace2749f7870\", \"got_dawn_revision\": \"0b17eb8e19c886680b12b73ba28de8d30adccda9\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"a516967152367ba73fea80f783557fd48e0eb75f\", \"got_revision_cp\": \"refs/heads/master@{#855254}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"b3177eb449cae97d92b18ffafe20dfcfce23683e\", \"got_v8_revision\": \"d2a5f73860288d12da9e40b77824cf3bd152b9ca\", \"got_v8_revision_cp\": \"refs/heads/9.0.212@{#1}\", \"got_webrtc_revision\": \"e11b4aef3fce51b0fd6bff7c64ee8a6dc00dd02e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33293}\", \"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\": \"a516967152367ba73fea80f783557fd48e0eb75f\", \"swarm_hashes_refs/heads/master(at){#855254}_without_patch\": {\"absl_hardening_tests\": \"496e32fa7b50f50d2e9966a2d602cfd94d8e09fb\", \"accessibility_unittests\": \"40e8e5719f0ddcb8ee8b25d957e1160a3dc22d93\", \"angle_unittests\": \"67686e6f6f281d0b86bae9492e04ab181e9f79cb\", \"app_shell_unittests\": \"c4b298f59e0aa093581d304d29a742cf5c7bf17d\", \"aura_unittests\": \"5acc14073372adec337b2362d7124e302ceba148\", \"base_unittests\": \"65691dc282b89f4e6b8070b2baab5c626089e4bd\", \"base_util_unittests\": \"c2234e4dabb6ee07ceb72da08dbae1d90da07c99\", \"blink_common_unittests\": \"478abc7cb8c5dada707f88a8cd9ab8283fa30aa8\", \"blink_fuzzer_unittests\": \"fbecee080a2077eca9ae9680cdd56b96bf047d39\", \"blink_heap_unittests\": \"d287153c88304b744c09ab1730f8430149b47cdd\", \"blink_platform_unittests\": \"4b3fd153db56e4c96a9a583babc3c03e294c0f99\", \"blink_unittests\": \"80d92a93ae38dc721e8d730b8ec0e30d1d0f232c\", \"boringssl_crypto_tests\": \"3c90af0c2cb1f11a21790e355a71422e3aa1bfa1\", \"boringssl_ssl_tests\": \"214c8845d8101e83924a04d33512407f623d4940\", \"browser_switcher_bho_unittests\": \"181beb0c1e8f084483ba84adec6aac38f361e056\", \"browser_tests\": \"7b84bd271584512e6c19fc30bf73f6a3e3314be5\", \"capture_unittests\": \"e0836659128e6ccaa5b641de0368b8e090639074\", \"cast_unittests\": \"3a1105acf4c718366b0732f82971aca5b0389261\", \"cc_unittests\": \"efa6dbac6decf362b912ba8156c77d1e5882c8ae\", \"chrome_app_unittests\": \"70ebb6ad17a374a14762bd84aa6962b862cbfbbd\", \"chrome_cleaner_unittests\": \"40da7985c133a8b59b18c6f36686d8524d07bf45\", \"chrome_elf_unittests\": \"b444e3c1d3f0c466316195407b53a087d5758915\", \"chromedriver_unittests\": \"c10b89dea5011aad63a75205297c3e1586a548b7\", \"components_browsertests\": \"947eba099f6c0045f71efb80801d63d753c80ea5\", \"components_unittests\": \"b43a12be84c2f341bf6e7447d94daa2d0ebe9109\", \"compositor_unittests\": \"b80cd1d8b314d2197541a3567b260ed77876108b\", \"content_browsertests\": \"ea6d99b4135c0795a2da60bfe054359608f8281a\", \"content_unittests\": \"4728f80e16266fde8bd2d3590718e4480bcbdb7b\", \"courgette_unittests\": \"f38c7da6c39e3d5b36f255b279633f76788c4bef\", \"crashpad_tests\": \"26d143aafdd0f9112fc47806168fdefe8686ce53\", \"cronet_tests\": \"ba5e89f30c1c413550b810d369f7d7cc2b51cc32\", \"cronet_unittests\": \"384863c4f1ca83c02261932cc004cb5cc85b0f43\", \"crypto_unittests\": \"d972b054c494c64a928fb740c131849d8bbecc2e\", \"delayloads_unittests\": \"d5c38f24a35200d317cb2bace8414cd28556a4fd\", \"device_unittests\": \"ddab333c87396e4f99cbaad1a8e8f71d363f7859\", \"display_unittests\": \"9af286a1cec5c8d44181bf068bcee7cfbce81b25\", \"elevation_service_unittests\": \"bd5eaedfe55d0ed2716f777cba67655214493561\", \"events_unittests\": \"784ad7ec5f32ef8071395af2c0d79fbf4b3cc941\", \"extensions_browsertests\": \"d910eaa6906aa74633f6861c89175c29a2a9d389\", \"extensions_unittests\": \"bbffa3006d1e765620ea9a05abfb47fdd587cf7a\", \"filesystem_service_unittests\": \"d434f71b411d605e2ca9d51e0070a5829c171f83\", \"gcm_unit_tests\": \"b870d161ee54351f77792aeda063ff78a438fe00\", \"gcp_unittests\": \"9d1c1c24b50f06ccbbad09d32f08316cbd852938\", \"gfx_unittests\": \"e764b8fc36771821d43d4cec563254e881fb8002\", \"gin_unittests\": \"9be2ef5860e54f36bec9aeca5113f83249663873\", \"google_apis_unittests\": \"bf5a35f9943315ac909b2a793d8e30ffa83f3838\", \"gpu_unittests\": \"75ef911a0baa93170fe1b55d35d753afe8404c5a\", \"gwp_asan_unittests\": \"0b0998552f6671f5e20c0f38ffc6da8a9474c4e3\", \"headless_browsertests\": \"310af9e7d74ff8d7a17aa816cacc56f9453a571d\", \"headless_unittests\": \"7d9238ddd00da78c2bbed9dbea813d12bc0c5b8a\", \"install_static_unittests\": \"c425be176efe82d2bdb4a3159fa1ec82c6b05d23\", \"installer_util_unittests\": \"7a886bb28e3653223118eee33a9ff57a9a18e695\", \"interactive_ui_tests\": \"cda9945db489d8c3c1f631b9652f6d108bab7d02\", \"ipc_tests\": \"ec27f6736a674db2a12ebfe26dbbb2dd69ef21a0\", \"jingle_unittests\": \"621f27c370a0a38a10f000e9a8da5f648396449a\", \"latency_unittests\": \"5732e8eadcc0400d5861174200ca6c74898eb1d3\", \"libjingle_xmpp_unittests\": \"252b5a9c880740199da776375433e8a93059cf89\", \"liburlpattern_unittests\": \"4baa8fca7c04c84b4a45c3d51d6a8573b88088c0\", \"media_blink_unittests\": \"fc3da005e99370739d7b83a410c76029d46ec6a8\", \"media_unittests\": \"395c5fc03b7070580d94da9df74ef36053b022b5\", \"message_center_unittests\": \"60d8d6e20a3d275fafbd9356bf712afaaf762183\", \"midi_unittests\": \"57006ed05451c79e8e70c7008db68424f10e456e\", \"mojo_core_unittests\": \"e0086efd2a61ea9a7927a4ef9abdb9ea4f17b3c3\", \"mojo_unittests\": \"3c1149a605183f034f2a6ecdf5bf37bdfdb86da3\", \"nacl_loader_unittests\": \"f31146b750360a0860e78b29a6e5d0519439d7c3\", \"native_theme_unittests\": \"e81c8369fbc12b1f77b28e09e61e15b65f990fbd\", \"net_unittests\": \"1f801e5efde9763d81d4f86fe86a20773a55b5be\", \"notification_helper_unittests\": \"dc97d2722a04f1be61b7009f02f8b100138b1f2a\", \"pdf_unittests\": \"f95519901cae03864cf5920d33fb314b92abba5c\", \"perfetto_unittests\": \"9dda6f5e1a9643596b06e4e3f8fb294c601541e6\", \"ppapi_unittests\": \"922bb5e97507ad94693a3ccbb6d8bbb533137d4c\", \"printing_unittests\": \"3327a6cfa72bbbd406b284e57a0058bce9e1e3eb\", \"remoting_unittests\": \"f990d51ce011a9635c1003773ae72621e79ca4e5\", \"sbox_integration_tests\": \"f766198a633ff596c9902cb59ea45e84406a8415\", \"sbox_unittests\": \"f30ebf1078cfcc6d2ed719e538478a41f80bded6\", \"sbox_validation_tests\": \"fbad783b31f7ff3b0d44662c438884ace6696127\", \"service_manager_unittests\": \"faeaaf25262182c39caf048eeed0ee57f57784de\", \"services_unittests\": \"449ed5a06f93731515f89861b7a844e56c058d67\", \"setup_unittests\": \"22bfde4a4bbef7d971c2e26ddb6a38e345bdfd77\", \"shell_dialogs_unittests\": \"69222e4da022ee90d095d34c9f49e149af5751c3\", \"skia_unittests\": \"86c8ee6f5e716ac0732502918905d5d07049f670\", \"snapshot_unittests\": \"f591a66761bdaa3103dda8abce54ac7d34dc4486\", \"sql_unittests\": \"2fb8d404715cd1ec2dcb0a3099eace81b4397b60\", \"storage_unittests\": \"a9b1487a813088f44467fc1922673d7c2d612c9f\", \"sync_integration_tests\": \"68531e71cced349ade5a898b254fed13ffb413e2\", \"traffic_annotation_auditor_unittests\": \"e92f0ede6007538e2a79dd0f0a9d5dbf6f2e2a3d\", \"ui_base_unittests\": \"b95a4069709d2845c3eaa511724f6550d07131da\", \"ui_touch_selection_unittests\": \"e73724df8bb52d7fa476f6ba8b95139e028c7c38\", \"unit_tests\": \"eeb17c3566cbe8cbfd4ebfd3d5464119531f837d\", \"updater_tests\": \"d6b451a624e0d16a07e0b033387b9830cd0cb5a9\", \"url_unittests\": \"dc47d0f080364935e718c603c631a615822731da\", \"views_unittests\": \"e3ebf29f0aabe529dad8bfccf749696c520a25d7\", \"viz_unittests\": \"3b604f605ad276e748cc17743515b334bb4d30e9\", \"vr_common_unittests\": \"8a23186be81748fb15f767a75b62f1c1e298a04c\", \"vr_pixeltests\": \"198e79506ce5dba1f7f60ac37514abca5e1a2626\", \"weblayer_browsertests\": \"c62b23fcf4f30d4207b9baf36879c58e0b647a42\", \"weblayer_unittests\": \"d111879f7f30be86d6a7ef65e5db6ce66f7e4912\", \"wm_unittests\": \"77bf49ad33c562ce797bc9d3b0e5527733fbc730\", \"wtf_unittests\": \"ad851171bfbb06703fe17cdf3e2cfe9a1edd9166\", \"zlib_unittests\": \"20862c2cc2b2e0534e719e08205f25592e580cde\", \"zucchini_unittests\": \"64b44f0568057919192abe059bfb27f0340faf62\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-1-2dz0\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5700-b36f18b\"], \"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": "1613660886487121",
"status": "COMPLETED",
"status_changed_ts": "1613664631254790",
"tags": [
"build_address:luci.chromium.ci/win-asan/17410",
"builder:win-asan",
"buildset:commit/git/a516967152367ba73fea80f783557fd48e0eb75f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a516967152367ba73fea80f783557fd48e0eb75f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020377704768359184",
"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/8854947642232605856/+/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:51ce8095840f5010",
"user_agent:luci-scheduler"
],
"updated_ts": "1613664631259004",
"url": "https://ci.chromium.org/b/8854947642232605856",
"utcnow_ts": "1613664632866489"
}
}
[16:10:32.876] Invocation finished in 1h2m31.989983292s with status SUCCEEDED