[01:19:42.199] New invocation is queued and will start shortly
[01:19:43.392] Starting the invocation (attempt 1)
[01:19:43.448] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:19:43.448] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:19:43.448] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023872502359081184",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2336b6557478c544a81650258aab4b0e7af49c78\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023872502359081184",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2336b6557478c544a81650258aab4b0e7af49c78",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2336b6557478c544a81650258aab4b0e7af49c78",
"gitiles_ref:refs/heads/master"
]
}
[01:19:44.084] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610327983611000",
"id": "8858442440215327584",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2336b6557478c544a81650258aab4b0e7af49c78\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610327983957164",
"tags": [
"build_address:luci.chromium.ci/win-asan/16747",
"builder:win-asan",
"buildset:commit/git/2336b6557478c544a81650258aab4b0e7af49c78",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2336b6557478c544a81650258aab4b0e7af49c78",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023872502359081184",
"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/8858442440215327584/+/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": "1610327983957273",
"url": "https://ci.chromium.org/b/8858442440215327584",
"utcnow_ts": "1610327984077124"
}
}
[01:19:44.084] Task URL: https://ci.chromium.org/b/8858442440215327584
[01:19:44.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:2:0) after 1m0s
[01:19:46.063] Received PubSub notification, asking Buildbucket for the build status
[01:19:46.132] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:44.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:2:0)
[01:20:44.185] Timer tick, asking Buildbucket for the build status
[01:20:44.323] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:44.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:4:0) after 1m0s
[01:21:44.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:4:0)
[01:21:44.396] Timer tick, asking Buildbucket for the build status
[01:21:44.485] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:44.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:5:0) after 1m0s
[01:22:44.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:5:0)
[01:22:44.514] Timer tick, asking Buildbucket for the build status
[01:22:44.989] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:44.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:6:0) after 1m0s
[01:23:45.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:6:0)
[01:23:45.012] Timer tick, asking Buildbucket for the build status
[01:23:45.329] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:45.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:7:0) after 1m0s
[01:24:45.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:7:0)
[01:24:45.348] Timer tick, asking Buildbucket for the build status
[01:24:45.512] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:45.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:8:0) after 1m0s
[01:25:45.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:8:0)
[01:25:45.559] Timer tick, asking Buildbucket for the build status
[01:25:45.628] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:45.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:9:0) after 1m0s
[01:26:45.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:9:0)
[01:26:45.689] Timer tick, asking Buildbucket for the build status
[01:26:45.810] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:45.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:10:0) after 1m0s
[01:27:45.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:10:0)
[01:27:45.832] Timer tick, asking Buildbucket for the build status
[01:27:45.893] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:45.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:11:0) after 1m0s
[01:28:45.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:11:0)
[01:28:45.911] Timer tick, asking Buildbucket for the build status
[01:28:45.975] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:45.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:12:0) after 1m0s
[01:29:46.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:12:0)
[01:29:46.084] Timer tick, asking Buildbucket for the build status
[01:29:46.209] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:46.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:13:0) after 1m0s
[01:30:46.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:13:0)
[01:30:46.329] Timer tick, asking Buildbucket for the build status
[01:30:46.775] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:46.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:14:0) after 1m0s
[01:31:46.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:14:0)
[01:31:46.809] Timer tick, asking Buildbucket for the build status
[01:31:46.917] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:46.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:15:0) after 1m0s
[01:32:49.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:15:0)
[01:32:49.660] Timer tick, asking Buildbucket for the build status
[01:32:49.766] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:49.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:16:0) after 1m0s
[01:33:49.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:16:0)
[01:33:49.819] Timer tick, asking Buildbucket for the build status
[01:33:49.878] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:49.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:17:0) after 1m0s
[01:34:49.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:17:0)
[01:34:49.976] Timer tick, asking Buildbucket for the build status
[01:34:50.147] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:50.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:18:0) after 1m0s
[01:35:50.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:18:0)
[01:35:50.258] Timer tick, asking Buildbucket for the build status
[01:35:51.033] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:51.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:19:0) after 1m0s
[01:36:51.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:19:0)
[01:36:51.053] Timer tick, asking Buildbucket for the build status
[01:36:51.181] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:51.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:20:0) after 1m0s
[01:37:51.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:20:0)
[01:37:51.440] Timer tick, asking Buildbucket for the build status
[01:37:51.570] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:51.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:21:0) after 1m0s
[01:38:51.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:21:0)
[01:38:51.629] Timer tick, asking Buildbucket for the build status
[01:38:51.763] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:51.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:22:0) after 1m0s
[01:39:51.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:22:0)
[01:39:51.884] Timer tick, asking Buildbucket for the build status
[01:39:52.125] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:52.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:23:0) after 1m0s
[01:40:52.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:23:0)
[01:40:52.146] Timer tick, asking Buildbucket for the build status
[01:40:52.261] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:52.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:24:0) after 1m0s
[01:41:52.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:24:0)
[01:41:52.280] Timer tick, asking Buildbucket for the build status
[01:41:52.921] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:52.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:25:0) after 1m0s
[01:42:52.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:25:0)
[01:42:52.918] Timer tick, asking Buildbucket for the build status
[01:42:53.506] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:53.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:26:0) after 1m0s
[01:43:53.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:26:0)
[01:43:53.665] Timer tick, asking Buildbucket for the build status
[01:43:53.781] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:53.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:27:0) after 1m0s
[01:44:53.858] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:27:0)
[01:44:53.858] Timer tick, asking Buildbucket for the build status
[01:44:53.967] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:53.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:28:0) after 1m0s
[01:45:54.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:28:0)
[01:45:54.094] Timer tick, asking Buildbucket for the build status
[01:45:54.210] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:54.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:29:0) after 1m0s
[01:46:54.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:29:0)
[01:46:54.663] Timer tick, asking Buildbucket for the build status
[01:46:55.760] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:55.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:30:0) after 1m0s
[01:47:55.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:30:0)
[01:47:55.824] Timer tick, asking Buildbucket for the build status
[01:47:55.898] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:55.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:31:0) after 1m0s
[01:48:55.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:31:0)
[01:48:55.984] Timer tick, asking Buildbucket for the build status
[01:48:56.060] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:56.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:32:0) after 1m0s
[01:49:56.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:32:0)
[01:49:56.215] Timer tick, asking Buildbucket for the build status
[01:49:56.375] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:56.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:33:0) after 1m0s
[01:50:56.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:33:0)
[01:50:56.459] Timer tick, asking Buildbucket for the build status
[01:50:56.796] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:56.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:34:0) after 1m0s
[01:51:56.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:34:0)
[01:51:56.954] Timer tick, asking Buildbucket for the build status
[01:51:57.291] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:57.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:35:0) after 1m0s
[01:52:57.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:35:0)
[01:52:57.310] Timer tick, asking Buildbucket for the build status
[01:52:57.398] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:57.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:36:0) after 1m0s
[01:53:57.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:36:0)
[01:53:57.447] Timer tick, asking Buildbucket for the build status
[01:53:57.706] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:57.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:37:0) after 1m0s
[01:54:57.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:37:0)
[01:54:57.716] Timer tick, asking Buildbucket for the build status
[01:54:58.055] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:58.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:38:0) after 1m0s
[01:55:58.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:38:0)
[01:55:58.079] Timer tick, asking Buildbucket for the build status
[01:55:58.151] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:58.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:39:0) after 1m0s
[01:56:58.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:39:0)
[01:56:58.248] Timer tick, asking Buildbucket for the build status
[01:56:58.307] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:58.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:40:0) after 1m0s
[01:57:58.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:40:0)
[01:57:58.382] Timer tick, asking Buildbucket for the build status
[01:57:58.464] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:58.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:41:0) after 1m0s
[01:58:58.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:41:0)
[01:58:58.471] Timer tick, asking Buildbucket for the build status
[01:58:58.923] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:58.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:42:0) after 1m0s
[01:59:58.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:42:0)
[01:59:58.951] Timer tick, asking Buildbucket for the build status
[01:59:59.228] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:59.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:43:0) after 1m0s
[02:00:59.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:43:0)
[02:00:59.246] Timer tick, asking Buildbucket for the build status
[02:00:59.336] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:59.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:44:0) after 1m0s
[02:01:59.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:44:0)
[02:01:59.338] Timer tick, asking Buildbucket for the build status
[02:01:59.401] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:59.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:45:0) after 1m0s
[02:02:59.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:45:0)
[02:02:59.470] Timer tick, asking Buildbucket for the build status
[02:03:00.685] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:00.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:46:0) after 1m0s
[02:04:01.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:46:0)
[02:04:01.258] Timer tick, asking Buildbucket for the build status
[02:04:01.483] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:01.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:47:0) after 1m0s
[02:05:01.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:47:0)
[02:05:01.539] Timer tick, asking Buildbucket for the build status
[02:05:01.650] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:01.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:48:0) after 1m0s
[02:06:02.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:48:0)
[02:06:02.615] Timer tick, asking Buildbucket for the build status
[02:06:02.750] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:02.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:49:0) after 1m0s
[02:07:02.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:49:0)
[02:07:02.801] Timer tick, asking Buildbucket for the build status
[02:07:03.207] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:03.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:50:0) after 1m0s
[02:08:03.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:50:0)
[02:08:03.229] Timer tick, asking Buildbucket for the build status
[02:08:03.309] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:03.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:51:0) after 1m0s
[02:09:03.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:51:0)
[02:09:03.384] Timer tick, asking Buildbucket for the build status
[02:09:03.565] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:03.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:52:0) after 1m0s
[02:10:03.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:52:0)
[02:10:03.670] Timer tick, asking Buildbucket for the build status
[02:10:04.052] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:04.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:53:0) after 1m0s
[02:11:04.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:53:0)
[02:11:04.093] Timer tick, asking Buildbucket for the build status
[02:11:04.230] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:04.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:54:0) after 1m0s
[02:12:04.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:54:0)
[02:12:04.308] Timer tick, asking Buildbucket for the build status
[02:12:04.397] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:04.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:55:0) after 1m0s
[02:13:04.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:55:0)
[02:13:04.451] Timer tick, asking Buildbucket for the build status
[02:13:05.022] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:05.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:56:0) after 1m0s
[02:14:05.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:56:0)
[02:14:05.068] Timer tick, asking Buildbucket for the build status
[02:14:05.194] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:05.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:57:0) after 1m0s
[02:15:05.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:57:0)
[02:15:05.242] Timer tick, asking Buildbucket for the build status
[02:15:05.652] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:05.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:58:0) after 1m0s
[02:16:05.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:58:0)
[02:16:05.753] Timer tick, asking Buildbucket for the build status
[02:16:06.281] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:06.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:59:0) after 1m0s
[02:17:06.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:59:0)
[02:17:06.401] Timer tick, asking Buildbucket for the build status
[02:17:06.619] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:06.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:60:0) after 1m0s
[02:18:06.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:60:0)
[02:18:06.659] Timer tick, asking Buildbucket for the build status
[02:18:06.854] Build 8858442440215327584: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:06.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023872502359081184:61:0) after 1m0s
[02:18:07.471] Received PubSub notification, asking Buildbucket for the build status
[02:18:07.573] Build 8858442440215327584: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:18:07.573] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610331484719738",
"created_by": "project:chromium",
"created_ts": "1610327983611000",
"id": "8858442440215327584",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2336b6557478c544a81650258aab4b0e7af49c78\"}}",
"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\": \"2336b6557478c544a81650258aab4b0e7af49c78\", \"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-us-central1-b-32-1-ervs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16747, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"0b90eef09f607336330f318bc8e65fa981634160\", \"got_dawn_revision\": \"3a0405597374c9ae12fb93e33aac35826455a958\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"2336b6557478c544a81650258aab4b0e7af49c78\", \"got_revision_cp\": \"refs/heads/master@{#841911}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"348ba200aa29d5b3a36d696d48d8f619e055219f\", \"got_v8_revision\": \"5d60a181de0863d8d00ae42ec0ee3521f51b501a\", \"got_v8_revision_cp\": \"refs/heads/8.9.229@{#1}\", \"got_webrtc_revision\": \"42082f9045e74e65b9e3a66b51093e0dc2f9375a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32929}\", \"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\": \"2336b6557478c544a81650258aab4b0e7af49c78\", \"swarm_hashes_refs/heads/master(at){#841911}_without_patch\": {\"absl_hardening_tests\": \"263a2bb1f83e7e3466c0b288cc1d2659da464fa3\", \"accessibility_unittests\": \"22e63dbc06b80829b08339357908a88b47d8b2bc\", \"angle_unittests\": \"915f84af2f71a9f12f648dfed609c440ebc1b97d\", \"app_shell_unittests\": \"0e4bec1c17d78b2e34420184e6f0f0cb2896c9ed\", \"aura_unittests\": \"e7c657ed2f822c94759f3f3220989f08a37e7b14\", \"base_unittests\": \"918d47eab7f5258d7f810806a622148e4af87a6f\", \"base_util_unittests\": \"cf51485a34fa795fc8724b63d7ab7d89d13eb817\", \"blink_common_unittests\": \"9f5af52a4332fe8c5ae4aaaf50b260f503ef2c34\", \"blink_fuzzer_unittests\": \"4d8e38bce18eff602181e555343cb275798fab78\", \"blink_heap_unittests\": \"8b1c6479e24c9f637651e5fd20bddbaa93d41ec7\", \"blink_platform_unittests\": \"21680ec78474624a52c55f3e3f49a93eb7363d7b\", \"blink_unittests\": \"452b7290feec1329f57bde43d6339ef6ca237f01\", \"boringssl_crypto_tests\": \"7ce3b570ffdbdf77e800d94b7e4eb4f2fc2e6dce\", \"boringssl_ssl_tests\": \"b7fa7f4d2c184bb8778f54111768d535187d006b\", \"browser_switcher_bho_unittests\": \"13203f7ff96092eaa32a638298cc9cc849de9e64\", \"browser_tests\": \"850e35288a5459d04ae250e3be693ca9f036738d\", \"capture_unittests\": \"78f645a379f0a02b20573485c2a686724e46905d\", \"cast_unittests\": \"a30e8f3633bd6d5445285429447dfaa3aea8013a\", \"cc_unittests\": \"b31b94bae5c62bb629793f6080d79d6974b5f5f2\", \"chrome_app_unittests\": \"da139e0fb224a8fcb8a752bad471209209190074\", \"chrome_cleaner_unittests\": \"832091c5fcc578f40cb1a2540b843107d410e264\", \"chrome_elf_unittests\": \"bd7004e73e31bf954e610c9e4ada76ce9515177f\", \"chromedriver_unittests\": \"fdd26e14bedfc27ae8f34ccd53e00c8a4263400a\", \"components_browsertests\": \"c859f8dca187f3a9eb916ebdf80b8b2a644f6d5c\", \"components_unittests\": \"76cc5aa27f247a131d5d3efbe5e85174a0bb0180\", \"compositor_unittests\": \"a1e3a5a6f886d42471ea08751d5d18bf65b2197f\", \"content_browsertests\": \"b127b5b3d17d3f2fb4948ed8031d86a33b904cdb\", \"content_unittests\": \"d67376474aa1b8770e8c09150b404497ebdafbdd\", \"courgette_unittests\": \"4187f2d13b5f861ae1d1b51514badda04ade0ed8\", \"crashpad_tests\": \"d502bcec8b6a598327c5385e6bf6e118c8f779ef\", \"cronet_tests\": \"7123ae3277f1a37c864790af33dbf24eb59c08ff\", \"cronet_unittests\": \"ab57de9555d3e19d003406e7ebd8f41135b19331\", \"crypto_unittests\": \"57026856aad1fbb82ab97a70a6fb43468277bc09\", \"delayloads_unittests\": \"52da00baa6fda9f6c7dd3fda4c49ba401cf8c200\", \"device_unittests\": \"08f9bfb4c48c1c4f5db24a0da5ae6b240fb30792\", \"display_unittests\": \"dd7e5dd916b3ef27809f0746165e0592f55716c4\", \"elevation_service_unittests\": \"ea7cc2f83fce07eecdcc85e871406a5178f3ac53\", \"events_unittests\": \"b78b3a7d3588cf785bde3f545e923b3f1364725b\", \"extensions_browsertests\": \"5fb3f1065f40961626c7f5cea5bbe0583ac98c03\", \"extensions_unittests\": \"24b09b30f8d70fd85d8c9cbaf23d9a36bd302dde\", \"filesystem_service_unittests\": \"1ca0ea8d55c20beb3ae6b4e48ab09f3ec1e2a651\", \"gcm_unit_tests\": \"ec31712a1150833dc85a33b1b421f0f1dcce43bb\", \"gcp_unittests\": \"b11a38815d27426ac8e628c2118c2d89d23a9829\", \"gfx_unittests\": \"c06da246fde7da2022dd44d204724b7946bbc048\", \"gin_unittests\": \"3b7aebbd9e419f002a6b10290f3aa8332baf4c19\", \"google_apis_unittests\": \"495b26c9f7e69e5dbb4206f198e2615ac6c04e20\", \"gpu_unittests\": \"90349a780ca4fad596a8539a90be6fb289f4f1d3\", \"gwp_asan_unittests\": \"c532943201f95285ffff105b15098c14aba5a9a0\", \"headless_browsertests\": \"f194274b6510c2920cd21aae34d2ed216a2850c7\", \"headless_unittests\": \"06c79e0134179d1f61691f982439d8df527a8a5a\", \"install_static_unittests\": \"665d59413c0859a7b535b75b6a70438b5a201644\", \"installer_util_unittests\": \"acf0a30eb418f8cfabfd09e5c2545cd7f722e442\", \"interactive_ui_tests\": \"f38c891ec84f624603d478d2f8c8228a06d13fd1\", \"ipc_tests\": \"23db75503de726520d1edf35673649aacf8bda5d\", \"jingle_unittests\": \"9b67b87b48b80c270f59397b0c0ed48920c5d4e8\", \"latency_unittests\": \"9e3c872a9769cab94a1285954e55c1b5cd3715f7\", \"libjingle_xmpp_unittests\": \"951a8696c0ce92c1a9bfffb15adccd6391095199\", \"liburlpattern_unittests\": \"c873b6028142a0327d2a6b995de111159dbf54df\", \"media_blink_unittests\": \"de01f7b2490b5c2445682bf79a4ea0bdf7e1179a\", \"media_unittests\": \"e87b4bf19578bbb70ce45dba081b7b5d898c107f\", \"message_center_unittests\": \"0c9e033e8077e7a89778f3383d075d68704744cb\", \"midi_unittests\": \"f63929d9edcc17808621281e80048a4248ec1008\", \"mojo_core_unittests\": \"61fdd7110655a1bd4e8e3a7b4d4a9238db7eb6bc\", \"mojo_unittests\": \"8744c043471ab521b3f923714e4dbc1db231f7d3\", \"nacl_loader_unittests\": \"b156848f81d989e7b22fae51705045e55b05eb34\", \"native_theme_unittests\": \"2fc380bcfc887940a07369c0a0c73b93d909b739\", \"net_unittests\": \"22d1bfd47a1b93d0fb3a120a133c4485f1380572\", \"notification_helper_unittests\": \"2d10663c0ca6b30bdcc99f5e5148e2d26b29933e\", \"pdf_unittests\": \"8beebbc03de566701163d0c948cb21a0a336df0d\", \"perfetto_unittests\": \"d956693d0cab28655fdd0ccff1cd2d952921a9bb\", \"ppapi_unittests\": \"c38c67b9caed5536fc22f751aa029afe80b1a8bc\", \"printing_unittests\": \"56500350115fbfde2decb89bb9b7ad90d6ea0b73\", \"remoting_unittests\": \"d8aa8c34d12d96785a9f0dae0110050e6333b88d\", \"sbox_integration_tests\": \"1638ef3ea4e52bbafba3ba62b0e5fc8c708a9cfa\", \"sbox_unittests\": \"327163edbf124562c39110e07862fa88299ac951\", \"sbox_validation_tests\": \"7af29d15a8af9a0045e2da0349b0d275ba013016\", \"service_manager_unittests\": \"aaa77c679c7c70aee9d8de2aad75fa7302a1ad72\", \"services_unittests\": \"1496499a6bd056dcafa7f149cd6039b2e7d01265\", \"setup_unittests\": \"70410d503fd5341ca3adb137b1ca847fe8807ca0\", \"shell_dialogs_unittests\": \"3827543ad809aa901c2b18b7317260e3e9c7c289\", \"skia_unittests\": \"49c8b39eb161cd8d97e6a6093f4ad8bfb7f1da36\", \"snapshot_unittests\": \"64307f82b740f93dcde314f6f2c273b0643b6390\", \"sql_unittests\": \"b5c57c6691b922b73882152601541fcd9b1a411d\", \"storage_unittests\": \"401152b0bc791f131ee94f984ebdd9ded1ed80fd\", \"sync_integration_tests\": \"c6fd97d356273da4d304102d81eb50a7fa48d1da\", \"traffic_annotation_auditor_unittests\": \"e5fe4906589f5ffed060838cb5fdca5f283d8abc\", \"ui_base_unittests\": \"d95d2242c117b7a33f8bc3574138d0156b8a7dd1\", \"ui_touch_selection_unittests\": \"edb2f367eb0a8b630d74fe0b5d1dba6ef036a43d\", \"unit_tests\": \"cc5645a1e20d96d8293d7af766d81ecb75510fcd\", \"updater_tests\": \"3c0b9a7d98ba1c9630813d6a4f54f3eb68d001e2\", \"url_unittests\": \"7b7cadd7054df84738e2d3945782ccccdb27787f\", \"views_unittests\": \"db5a536e938b7dec01a048d5ca29e09c5aa813da\", \"viz_unittests\": \"2b0ad241aac4443ec5fcaf82decc0b2e34f432c6\", \"vr_common_unittests\": \"5dc033a6f290f7b65ca177cc721fc018a424d1bd\", \"vr_pixeltests\": \"c89ac4c0eb3699c508c46d7163ac51f942fa6c06\", \"weblayer_browsertests\": \"fd360bd9d2a8b58e5e7b996bf35bd562efa7249f\", \"weblayer_unittests\": \"0dbaf7a7155097dfb4dcea7bc5aaaa3a266c1f61\", \"wm_unittests\": \"d25eb458a82f2652ec6ab3dc7235787cd4b5e122\", \"wtf_unittests\": \"8c570e4471cf120905789ab40b1f50fc8172e0a1\", \"zlib_unittests\": \"830d0834bbb812f50e90c5b7c59d5ad437289cf9\", \"zucchini_unittests\": \"00ebe21a752a5d428de2a77cdb979ead2f61ca14\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-ervs\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610327985249935",
"status": "COMPLETED",
"status_changed_ts": "1610331485224257",
"tags": [
"build_address:luci.chromium.ci/win-asan/16747",
"builder:win-asan",
"buildset:commit/git/2336b6557478c544a81650258aab4b0e7af49c78",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2336b6557478c544a81650258aab4b0e7af49c78",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023872502359081184",
"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/8858442440215327584/+/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:5107d89116775110",
"user_agent:luci-scheduler"
],
"updated_ts": "1610331485259216",
"url": "https://ci.chromium.org/b/8858442440215327584",
"utcnow_ts": "1610331487565514"
}
}
[02:18:07.573] Invocation finished in 58m25.390086868s with status SUCCEEDED