[01:23:28.584] New invocation is queued and will start shortly
[01:23:29.690] Starting the invocation (attempt 1)
[01:23:29.740] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:23:29.741] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:23:29.741] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021426146883173760",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f976d83ee578757909e5a5c0ad187b47bc6e5e33\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021426146883173760",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f976d83ee578757909e5a5c0ad187b47bc6e5e33",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f976d83ee578757909e5a5c0ad187b47bc6e5e33",
"gitiles_ref:refs/heads/master"
]
}
[01:23:31.335] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612661009825702",
"id": "8855996084920261072",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f976d83ee578757909e5a5c0ad187b47bc6e5e33\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612661010893278",
"tags": [
"build_address:luci.chromium.ci/win-asan/17208",
"builder:win-asan",
"buildset:commit/git/f976d83ee578757909e5a5c0ad187b47bc6e5e33",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f976d83ee578757909e5a5c0ad187b47bc6e5e33",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021426146883173760",
"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/8855996084920261072/+/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": "1612661010893886",
"url": "https://ci.chromium.org/b/8855996084920261072",
"utcnow_ts": "1612661011319369"
}
}
[01:23:31.335] Task URL: https://ci.chromium.org/b/8855996084920261072
[01:23:31.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:2:0) after 1m0s
[01:23:48.329] Received PubSub notification, asking Buildbucket for the build status
[01:23:48.499] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:32.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:2:0)
[01:24:32.450] Timer tick, asking Buildbucket for the build status
[01:24:32.574] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:32.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:4:0) after 1m0s
[01:25:32.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:4:0)
[01:25:32.596] Timer tick, asking Buildbucket for the build status
[01:25:33.016] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:33.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:5:0) after 1m0s
[01:26:33.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:5:0)
[01:26:33.084] Timer tick, asking Buildbucket for the build status
[01:26:33.249] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:33.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:6:0) after 1m0s
[01:27:33.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:6:0)
[01:27:33.311] Timer tick, asking Buildbucket for the build status
[01:27:33.934] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:33.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:7:0) after 1m0s
[01:28:33.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:7:0)
[01:28:33.986] Timer tick, asking Buildbucket for the build status
[01:28:34.198] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:34.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:8:0) after 1m0s
[01:29:34.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:8:0)
[01:29:34.300] Timer tick, asking Buildbucket for the build status
[01:29:35.419] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:35.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:9:0) after 1m0s
[01:30:35.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:9:0)
[01:30:35.533] Timer tick, asking Buildbucket for the build status
[01:30:35.943] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:35.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:10:0) after 1m0s
[01:31:35.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:10:0)
[01:31:35.985] Timer tick, asking Buildbucket for the build status
[01:31:36.072] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:36.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:11:0) after 1m0s
[01:32:36.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:11:0)
[01:32:36.189] Timer tick, asking Buildbucket for the build status
[01:32:36.491] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:36.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:12:0) after 1m0s
[01:33:36.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:12:0)
[01:33:36.629] Timer tick, asking Buildbucket for the build status
[01:33:36.844] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:36.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:13:0) after 1m0s
[01:34:36.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:13:0)
[01:34:36.927] Timer tick, asking Buildbucket for the build status
[01:34:37.028] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:37.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:14:0) after 1m0s
[01:35:37.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:14:0)
[01:35:37.061] Timer tick, asking Buildbucket for the build status
[01:35:37.235] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:37.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:15:0) after 1m0s
[01:36:37.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:15:0)
[01:36:37.286] Timer tick, asking Buildbucket for the build status
[01:36:37.434] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:37.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:16:0) after 1m0s
[01:37:37.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:16:0)
[01:37:37.494] Timer tick, asking Buildbucket for the build status
[01:37:37.578] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:37.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:17:0) after 1m0s
[01:38:37.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:17:0)
[01:38:37.687] Timer tick, asking Buildbucket for the build status
[01:38:37.783] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:37.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:18:0) after 1m0s
[01:39:37.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:18:0)
[01:39:37.859] Timer tick, asking Buildbucket for the build status
[01:39:38.317] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:38.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:19:0) after 1m0s
[01:40:38.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:19:0)
[01:40:38.469] Timer tick, asking Buildbucket for the build status
[01:40:38.582] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:38.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:20:0) after 1m0s
[01:41:38.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:20:0)
[01:41:38.703] Timer tick, asking Buildbucket for the build status
[01:41:38.844] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:38.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:21:0) after 1m0s
[01:42:38.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:21:0)
[01:42:38.926] Timer tick, asking Buildbucket for the build status
[01:42:39.799] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:39.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:22:0) after 1m0s
[01:43:39.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:22:0)
[01:43:39.904] Timer tick, asking Buildbucket for the build status
[01:43:40.482] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:40.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:23:0) after 1m0s
[01:44:40.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:23:0)
[01:44:40.531] Timer tick, asking Buildbucket for the build status
[01:44:40.656] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:40.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:24:0) after 1m0s
[01:45:40.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:24:0)
[01:45:40.673] Timer tick, asking Buildbucket for the build status
[01:45:40.945] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:40.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:25:0) after 1m0s
[01:46:40.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:25:0)
[01:46:40.980] Timer tick, asking Buildbucket for the build status
[01:46:41.415] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:41.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:26:0) after 1m0s
[01:47:41.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:26:0)
[01:47:41.442] Timer tick, asking Buildbucket for the build status
[01:47:41.748] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:41.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:27:0) after 1m0s
[01:48:41.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:27:0)
[01:48:41.766] Timer tick, asking Buildbucket for the build status
[01:48:41.885] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:41.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:28:0) after 1m0s
[01:49:41.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:28:0)
[01:49:41.925] Timer tick, asking Buildbucket for the build status
[01:49:42.029] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:42.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:29:0) after 1m0s
[01:50:42.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:29:0)
[01:50:42.091] Timer tick, asking Buildbucket for the build status
[01:50:42.238] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:42.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:30:0) after 1m0s
[01:51:42.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:30:0)
[01:51:42.267] Timer tick, asking Buildbucket for the build status
[01:51:42.430] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:42.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:31:0) after 1m0s
[01:52:42.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:31:0)
[01:52:42.463] Timer tick, asking Buildbucket for the build status
[01:52:42.630] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:42.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:32:0) after 1m0s
[01:53:42.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:32:0)
[01:53:42.652] Timer tick, asking Buildbucket for the build status
[01:53:42.749] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:42.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:33:0) after 1m0s
[01:54:42.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:33:0)
[01:54:42.779] Timer tick, asking Buildbucket for the build status
[01:54:42.850] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:42.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:34:0) after 1m0s
[01:55:42.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:34:0)
[01:55:42.940] Timer tick, asking Buildbucket for the build status
[01:55:43.007] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:43.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:35:0) after 1m0s
[01:56:43.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:35:0)
[01:56:43.039] Timer tick, asking Buildbucket for the build status
[01:56:43.140] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:43.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:36:0) after 1m0s
[01:57:43.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:36:0)
[01:57:43.243] Timer tick, asking Buildbucket for the build status
[01:57:43.346] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:43.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:37:0) after 1m0s
[01:58:43.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:37:0)
[01:58:43.359] Timer tick, asking Buildbucket for the build status
[01:58:43.467] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:43.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:38:0) after 1m0s
[01:59:43.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:38:0)
[01:59:43.614] Timer tick, asking Buildbucket for the build status
[01:59:43.697] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:43.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:39:0) after 1m0s
[02:00:43.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:39:0)
[02:00:43.713] Timer tick, asking Buildbucket for the build status
[02:00:43.827] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:43.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:40:0) after 1m0s
[02:01:43.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:40:0)
[02:01:43.867] Timer tick, asking Buildbucket for the build status
[02:01:44.326] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:44.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:41:0) after 1m0s
[02:02:44.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:41:0)
[02:02:44.522] Timer tick, asking Buildbucket for the build status
[02:02:44.950] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:44.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:42:0) after 1m0s
[02:03:45.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:42:0)
[02:03:45.003] Timer tick, asking Buildbucket for the build status
[02:03:45.159] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:45.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:43:0) after 1m0s
[02:04:45.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:43:0)
[02:04:45.210] Timer tick, asking Buildbucket for the build status
[02:04:46.332] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:46.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:44:0) after 1m0s
[02:05:46.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:44:0)
[02:05:46.357] Timer tick, asking Buildbucket for the build status
[02:05:46.715] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:46.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:45:0) after 1m0s
[02:06:46.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:45:0)
[02:06:46.767] Timer tick, asking Buildbucket for the build status
[02:06:47.169] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:47.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:46:0) after 1m0s
[02:07:47.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:46:0)
[02:07:47.229] Timer tick, asking Buildbucket for the build status
[02:07:47.330] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:47.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:47:0) after 1m0s
[02:08:47.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:47:0)
[02:08:47.353] Timer tick, asking Buildbucket for the build status
[02:08:47.456] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:47.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:48:0) after 1m0s
[02:09:47.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:48:0)
[02:09:47.574] Timer tick, asking Buildbucket for the build status
[02:09:47.752] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:47.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:49:0) after 1m0s
[02:10:47.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:49:0)
[02:10:47.824] Timer tick, asking Buildbucket for the build status
[02:10:47.941] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:47.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:50:0) after 1m0s
[02:11:48.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:50:0)
[02:11:48.058] Timer tick, asking Buildbucket for the build status
[02:11:48.129] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:48.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:51:0) after 1m0s
[02:12:48.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:51:0)
[02:12:48.189] Timer tick, asking Buildbucket for the build status
[02:12:48.318] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:48.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:52:0) after 1m0s
[02:13:48.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:52:0)
[02:13:48.371] Timer tick, asking Buildbucket for the build status
[02:13:48.554] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:48.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:53:0) after 1m0s
[02:14:48.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:53:0)
[02:14:48.572] Timer tick, asking Buildbucket for the build status
[02:14:48.686] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:48.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:54:0) after 1m0s
[02:15:48.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:54:0)
[02:15:48.714] Timer tick, asking Buildbucket for the build status
[02:15:48.862] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:48.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:55:0) after 1m0s
[02:16:48.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:55:0)
[02:16:48.950] Timer tick, asking Buildbucket for the build status
[02:16:49.103] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:49.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:56:0) after 1m0s
[02:17:49.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:56:0)
[02:17:49.160] Timer tick, asking Buildbucket for the build status
[02:17:49.557] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:49.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:57:0) after 1m0s
[02:18:49.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:57:0)
[02:18:49.624] Timer tick, asking Buildbucket for the build status
[02:18:49.737] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:49.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:58:0) after 1m0s
[02:19:49.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:58:0)
[02:19:49.765] Timer tick, asking Buildbucket for the build status
[02:19:50.015] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:50.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:59:0) after 1m0s
[02:20:50.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:59:0)
[02:20:50.071] Timer tick, asking Buildbucket for the build status
[02:20:50.139] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:50.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:60:0) after 1m0s
[02:21:50.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:60:0)
[02:21:50.167] Timer tick, asking Buildbucket for the build status
[02:21:50.521] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:50.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:61:0) after 1m0s
[02:22:50.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:61:0)
[02:22:50.571] Timer tick, asking Buildbucket for the build status
[02:22:50.715] Build 8855996084920261072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:50.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021426146883173760:62:0) after 1m0s
[02:23:12.277] Received PubSub notification, asking Buildbucket for the build status
[02:23:12.530] Build 8855996084920261072: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:23:12.530] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612664590206791",
"created_by": "project:chromium",
"created_ts": "1612661009825702",
"id": "8855996084920261072",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f976d83ee578757909e5a5c0ad187b47bc6e5e33\"}}",
"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\": \"f976d83ee578757909e5a5c0ad187b47bc6e5e33\", \"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-7-1hzs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17208, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"d820ec7b3e5f9412d1ceb55d31b3f7dc09e3d16d\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"f976d83ee578757909e5a5c0ad187b47bc6e5e33\", \"got_revision_cp\": \"refs/heads/master@{#851510}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"d42413a4b4636422ba5ea66ba40e7d24fb64f9b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33183}\", \"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\": \"f976d83ee578757909e5a5c0ad187b47bc6e5e33\", \"swarm_hashes_refs/heads/master(at){#851510}_without_patch\": {\"absl_hardening_tests\": \"2f8399d60fdc164c12d7640d5833167a68be85d7\", \"accessibility_unittests\": \"b1694b0f3e01eab84cec48bacf0976ea4568486c\", \"angle_unittests\": \"ce3d4b7e826cd59bba78a9bc206d42a22908f7e4\", \"app_shell_unittests\": \"ba75d4009320c86cdb00a9916adc1cd695ac7bdb\", \"aura_unittests\": \"e965e7b4fb722b837101d5c2b771c6008e78ee4f\", \"base_unittests\": \"8e82ec02a9d4aabad00e8be3645409eabe7db316\", \"base_util_unittests\": \"e0fbe8139789c061712ada98ff7094b73e10a86d\", \"blink_common_unittests\": \"75c700fc2a7fcf36ced1cef0a01c99f0a618189e\", \"blink_fuzzer_unittests\": \"a2d87a708cf5939034e8bdfa2577c3803d78a32c\", \"blink_heap_unittests\": \"1c9c01442c7a71548a0d775402d3bf2eec6c622e\", \"blink_platform_unittests\": \"dbdb09e8f38e8268288752d8cf7f815fc39e0644\", \"blink_unittests\": \"744b718894baa071670dfe463bbe362a854a631e\", \"boringssl_crypto_tests\": \"abe1fdbd3803ac5318a41c3b61587dcc0b91a829\", \"boringssl_ssl_tests\": \"41911704fdab1339822244a682545cedbda02b57\", \"browser_switcher_bho_unittests\": \"08c334ea0f693f1f44fd7a5ba71331f0b05ecf1f\", \"browser_tests\": \"eacb240f3ceb92ed602fdec58329ccfcef494b27\", \"capture_unittests\": \"2bc4e3c7cb4cecf44d58488aa19cc833bff01ebc\", \"cast_unittests\": \"ba1101b6cc4553b24961bc2915c55371b0442b79\", \"cc_unittests\": \"145512cfc08424dd1faba01bc22184914ac69fc3\", \"chrome_app_unittests\": \"de32e1a72e7d71f1df9ad68a82f2a0939daf61a9\", \"chrome_cleaner_unittests\": \"dfc3251538796e8c2b755b6ec7a28ff9e988d350\", \"chrome_elf_unittests\": \"31877323beab72d25d77908903ba74909e129373\", \"chromedriver_unittests\": \"88a068ab9471c22928f014a88e9ecc42767a5917\", \"components_browsertests\": \"fed415453ec7dd61fc73b5a452f2241be6df02b6\", \"components_unittests\": \"12f3cd43899d60805ca6829b2d374506afd17012\", \"compositor_unittests\": \"f73843211760ab45e5fd7d2dde55d4fc2262678e\", \"content_browsertests\": \"9a4854d74e1a46f32332f44a8e98b739295223b4\", \"content_unittests\": \"fd6c661d855e8d533afd729245dde29788252bf5\", \"courgette_unittests\": \"5cbf4c11f974c2a14dff1e27e304fbd037541db9\", \"crashpad_tests\": \"1f04e0ef58248a7277e2621011e85ecffe49732e\", \"cronet_tests\": \"00b408d49f6e5b1a8dcbd5e69c3ec7bb9df651ab\", \"cronet_unittests\": \"a306a9efde1d8b5e6a25c5397f9abbc766ed1e7c\", \"crypto_unittests\": \"0cabc2b7759f1a711040a7fbe728e53082f5100f\", \"delayloads_unittests\": \"d51efed9a56589d0a1ebe19b93959bcbe40a0113\", \"device_unittests\": \"ba741f546fbc14712fb78bdf5967ec9c7ad69da6\", \"display_unittests\": \"eb1de063c3d590ad1dd99822760d5d7ae19bb332\", \"elevation_service_unittests\": \"5f389cbfa59a56a0ede017d7e9a42237c176f9e6\", \"events_unittests\": \"dfc23139d3b50c2de3b7b89a50a9e0b08ddeb84f\", \"extensions_browsertests\": \"a6051ec970f0a92fe84f30e09981d4ae28d8359c\", \"extensions_unittests\": \"b8408e684bb2f41aeb9b0654774af47165ab4c57\", \"filesystem_service_unittests\": \"c734b957c4a7b0583d855a52c63a243fd85efa1f\", \"gcm_unit_tests\": \"91c2d06a906fb2d910ad337041f1cef921aef42a\", \"gcp_unittests\": \"ff8cf16eb7241d86151cbcf3af21e1672b051ab9\", \"gfx_unittests\": \"2baf04d339e21506b1724d184d9c8f2e73870ac4\", \"gin_unittests\": \"d3222e9e3cbf85fcb1ecee854b80429620d9aa34\", \"google_apis_unittests\": \"e1bf04286238af6fa00024a6f6297dd0047eed74\", \"gpu_unittests\": \"7b0893c92c951fc62642c36de23611f1a3661355\", \"gwp_asan_unittests\": \"1c6843dbd12f9ea743e42676a716a314bcc3f1a3\", \"headless_browsertests\": \"14f1f0253b2cb9bbdfae80133d80f364db5edc09\", \"headless_unittests\": \"6d0a47489ad8481e3437051e394aa60333a69513\", \"install_static_unittests\": \"273e174245e9efbab9032122f9138fa8a0611dc8\", \"installer_util_unittests\": \"30ae3c9f0d6b3ef10653907895324fb20a625a92\", \"interactive_ui_tests\": \"e5101e68570292d9e63e9aa75e81dd9469cdf08f\", \"ipc_tests\": \"f89634e4ff50f4a050f05c82bdaec58320a230bb\", \"jingle_unittests\": \"dd3ad89ab2f8a537274d8231d2629a034e9b5b43\", \"latency_unittests\": \"cec8b8919db267411708f8cd70ecf1cf3a388be5\", \"libjingle_xmpp_unittests\": \"3259afdf4f7a2ec5c69eb058cca51ca4068ef8e9\", \"liburlpattern_unittests\": \"9a69247609a880e7d03a21a30d6a9fe66f836dd3\", \"media_blink_unittests\": \"6561b816582e88420d43f241f9fcba459a4ee2e0\", \"media_unittests\": \"25094a7d248c659bef67bf1bf7fe5d178cbcfdfc\", \"message_center_unittests\": \"da82ad17f4f16a86817de8edda3f4d778070b9d5\", \"midi_unittests\": \"fe6e1e1b285b4ecfa919e72b24f0b0a40146dd8c\", \"mojo_core_unittests\": \"3f35e23b9ed881628f2ffce8aedadbc5d93325dd\", \"mojo_unittests\": \"e9f7f28338b64eb4604b0ff07e1e39ff7dc0599f\", \"nacl_loader_unittests\": \"8eb18313659fbd751c3f4222aabf01c6bb8c0bbc\", \"native_theme_unittests\": \"14aa7e979409ea8e5c6cf150e854ff014f8de487\", \"net_unittests\": \"d33eebfe8a7a35f586a5521498f78196fdcbcc33\", \"notification_helper_unittests\": \"e5c115c95a41da321425afca7294f2d67335f07d\", \"pdf_unittests\": \"4360b3bf3e4867068c8e50123b153e7a6337f2e6\", \"perfetto_unittests\": \"d8947bc4f932eb3d6065393d99973f91ffebde32\", \"ppapi_unittests\": \"1fd9ce5f4ba09ce522518905888e974750d7afa3\", \"printing_unittests\": \"afd59ef9d70cfba5fa6dac917faba0454df3bb99\", \"remoting_unittests\": \"fa9016af05ae2be6e639fd42b28a6da02737faf9\", \"sbox_integration_tests\": \"c2a579e91fe7b634ee8bdfb5d5cf41651be8f2a2\", \"sbox_unittests\": \"9d6712a91892336b6b88637176803ecdba66c737\", \"sbox_validation_tests\": \"ea03ef90178524e5aef4235567d963a2e54503cd\", \"service_manager_unittests\": \"f06400dfade8cc43e0a5d8e963347df7c8a09d94\", \"services_unittests\": \"7982be3da4b8b6ac7e40c81071d610a7a01fa58c\", \"setup_unittests\": \"413c7f576cd7d2babf78e5bc9418a14d217318aa\", \"shell_dialogs_unittests\": \"455c9d009e7f253ee5aca5f7e313511c503a8b21\", \"skia_unittests\": \"06912d46b79e0e667343a1b92c9d9fbff617a869\", \"snapshot_unittests\": \"2ae1d85c13fe43a0545c51e8222bc7a4ef34bda6\", \"sql_unittests\": \"e84560be6ed2f4fbb313723472ef8b7eb8e75e31\", \"storage_unittests\": \"f9f9c60aa77fb483d5f54e732260560ea98b4f3e\", \"sync_integration_tests\": \"096e677602c0b2f7643652c02c8510db0da0da27\", \"traffic_annotation_auditor_unittests\": \"f5e7d5512cc10e7fccb3f235c9035dd5b435bea2\", \"ui_base_unittests\": \"938f6315c72ffb142a3de7df87a6c27ee05b8972\", \"ui_touch_selection_unittests\": \"232eac659c7fdf2d8a8c189aace1c77379a20e65\", \"unit_tests\": \"34ae413d1dc08d795f6d20c3229adf4ff834cf40\", \"updater_tests\": \"72510657c0a50aacbf5350c42a543386c9df8dcd\", \"url_unittests\": \"ce149c257912cd28ccecca7d90cca1cde2b94129\", \"views_unittests\": \"3a135316cfdce25cd370e285e646c36efef867ef\", \"viz_unittests\": \"f147a7823cf7a6ba03836900a673ee21054c1c0b\", \"vr_common_unittests\": \"0e33c662584bae0f3e2ef0738f8dc60408c2b662\", \"vr_pixeltests\": \"c2808ea12f6bcd8a7e89299ec1539576ca64106e\", \"weblayer_browsertests\": \"673a71726ddc5d3a5be0fd9dc9ab34ee85375110\", \"weblayer_unittests\": \"5c37b8772f086788a0500533f7bd165031ce97ca\", \"wm_unittests\": \"0bb5af7715ede09c370019eeaf1df51f7d48daff\", \"wtf_unittests\": \"2435b6169a52e97db09573c4b3575b50c8d687b3\", \"zlib_unittests\": \"d2c329ce0d677743671bda2c0943bcc8d9fcfd1f\", \"zucchini_unittests\": \"7fb8b1dc004c006cb3e82ab353f8492a5d0a09a3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_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-7-1hzs\"], \"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": "1612661027661073",
"status": "COMPLETED",
"status_changed_ts": "1612664590960247",
"tags": [
"build_address:luci.chromium.ci/win-asan/17208",
"builder:win-asan",
"buildset:commit/git/f976d83ee578757909e5a5c0ad187b47bc6e5e33",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f976d83ee578757909e5a5c0ad187b47bc6e5e33",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021426146883173760",
"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/8855996084920261072/+/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:5192e7c7ee342910",
"user_agent:luci-scheduler"
],
"updated_ts": "1612664590964232",
"url": "https://ci.chromium.org/b/8855996084920261072",
"utcnow_ts": "1612664592513373"
}
}
[02:23:12.530] Invocation finished in 59m43.960981361s with status SUCCEEDED