[16:07:31.399] New invocation is queued and will start shortly
[16:07:32.528] Starting the invocation (attempt 1)
[16:07:32.565] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:07:32.565] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:07:32.565] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023635451623798624",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fd1e18fe4970f92cede558b68e3b84a18116a1f6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023635451623798624",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/fd1e18fe4970f92cede558b68e3b84a18116a1f6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd1e18fe4970f92cede558b68e3b84a18116a1f6",
"gitiles_ref:refs/heads/master"
]
}
[16:07:33.922] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610554052647133",
"id": "8858205389650247072",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fd1e18fe4970f92cede558b68e3b84a18116a1f6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610554053512599",
"tags": [
"build_address:luci.chromium.ci/win-asan/16799",
"builder:win-asan",
"buildset:commit/git/fd1e18fe4970f92cede558b68e3b84a18116a1f6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd1e18fe4970f92cede558b68e3b84a18116a1f6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023635451623798624",
"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/8858205389650247072/+/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": "1610554053512835",
"url": "https://ci.chromium.org/b/8858205389650247072",
"utcnow_ts": "1610554053882246"
}
}
[16:07:33.922] Task URL: https://ci.chromium.org/b/8858205389650247072
[16:07:33.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:2:0) after 1m0s
[16:07:36.205] Received PubSub notification, asking Buildbucket for the build status
[16:07:36.312] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:33.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:2:0)
[16:08:33.959] Timer tick, asking Buildbucket for the build status
[16:08:34.177] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:34.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:4:0) after 1m0s
[16:09:34.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:4:0)
[16:09:34.226] Timer tick, asking Buildbucket for the build status
[16:09:34.419] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:34.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:5:0) after 1m0s
[16:10:34.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:5:0)
[16:10:34.467] Timer tick, asking Buildbucket for the build status
[16:10:34.551] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:34.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:6:0) after 1m0s
[16:11:34.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:6:0)
[16:11:34.581] Timer tick, asking Buildbucket for the build status
[16:11:34.779] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:34.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:7:0) after 1m0s
[16:12:34.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:7:0)
[16:12:34.811] Timer tick, asking Buildbucket for the build status
[16:12:35.423] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:35.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:8:0) after 1m0s
[16:13:35.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:8:0)
[16:13:35.485] Timer tick, asking Buildbucket for the build status
[16:13:35.579] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:35.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:9:0) after 1m0s
[16:14:35.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:9:0)
[16:14:35.610] Timer tick, asking Buildbucket for the build status
[16:14:35.688] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:35.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:10:0) after 1m0s
[16:15:35.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:10:0)
[16:15:35.755] Timer tick, asking Buildbucket for the build status
[16:15:36.112] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:36.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:11:0) after 1m0s
[16:16:36.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:11:0)
[16:16:36.283] Timer tick, asking Buildbucket for the build status
[16:16:36.357] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:36.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:12:0) after 1m0s
[16:17:36.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:12:0)
[16:17:36.458] Timer tick, asking Buildbucket for the build status
[16:17:36.572] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:36.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:13:0) after 1m0s
[16:18:36.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:13:0)
[16:18:36.593] Timer tick, asking Buildbucket for the build status
[16:18:36.799] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:36.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:14:0) after 1m0s
[16:19:36.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:14:0)
[16:19:36.866] Timer tick, asking Buildbucket for the build status
[16:19:36.931] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:36.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:15:0) after 1m0s
[16:20:36.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:15:0)
[16:20:36.991] Timer tick, asking Buildbucket for the build status
[16:20:37.054] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:37.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:16:0) after 1m0s
[16:21:37.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:16:0)
[16:21:37.073] Timer tick, asking Buildbucket for the build status
[16:21:37.313] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:37.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:17:0) after 1m0s
[16:22:37.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:17:0)
[16:22:37.321] Timer tick, asking Buildbucket for the build status
[16:22:37.431] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:37.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:18:0) after 1m0s
[16:23:37.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:18:0)
[16:23:37.478] Timer tick, asking Buildbucket for the build status
[16:23:37.557] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:37.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:19:0) after 1m0s
[16:24:37.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:19:0)
[16:24:37.578] Timer tick, asking Buildbucket for the build status
[16:24:37.644] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:37.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:20:0) after 1m0s
[16:25:37.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:20:0)
[16:25:37.683] Timer tick, asking Buildbucket for the build status
[16:25:37.742] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:37.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:21:0) after 1m0s
[16:26:37.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:21:0)
[16:26:37.804] Timer tick, asking Buildbucket for the build status
[16:26:37.914] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:37.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:22:0) after 1m0s
[16:27:37.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:22:0)
[16:27:37.903] Timer tick, asking Buildbucket for the build status
[16:27:38.145] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:38.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:23:0) after 1m0s
[16:28:38.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:23:0)
[16:28:38.250] Timer tick, asking Buildbucket for the build status
[16:28:38.321] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:38.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:24:0) after 1m0s
[16:29:38.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:24:0)
[16:29:38.338] Timer tick, asking Buildbucket for the build status
[16:29:38.585] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:38.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:25:0) after 1m0s
[16:30:38.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:25:0)
[16:30:38.643] Timer tick, asking Buildbucket for the build status
[16:30:39.019] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:39.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:26:0) after 1m0s
[16:31:39.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:26:0)
[16:31:39.116] Timer tick, asking Buildbucket for the build status
[16:31:39.276] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:39.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:27:0) after 1m0s
[16:32:39.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:27:0)
[16:32:39.339] Timer tick, asking Buildbucket for the build status
[16:32:39.729] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:39.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:28:0) after 1m0s
[16:33:39.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:28:0)
[16:33:39.753] Timer tick, asking Buildbucket for the build status
[16:33:39.901] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:39.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:29:0) after 1m0s
[16:34:40.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:29:0)
[16:34:40.013] Timer tick, asking Buildbucket for the build status
[16:34:40.143] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:40.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:30:0) after 1m0s
[16:35:40.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:30:0)
[16:35:40.194] Timer tick, asking Buildbucket for the build status
[16:35:40.362] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:40.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:31:0) after 1m0s
[16:36:40.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:31:0)
[16:36:40.364] Timer tick, asking Buildbucket for the build status
[16:36:40.485] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:40.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:32:0) after 1m0s
[16:37:40.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:32:0)
[16:37:40.555] Timer tick, asking Buildbucket for the build status
[16:37:41.197] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:41.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:33:0) after 1m0s
[16:38:41.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:33:0)
[16:38:41.219] Timer tick, asking Buildbucket for the build status
[16:38:41.337] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:41.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:34:0) after 1m0s
[16:39:41.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:34:0)
[16:39:41.365] Timer tick, asking Buildbucket for the build status
[16:39:41.892] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:41.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:35:0) after 1m0s
[16:40:41.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:35:0)
[16:40:41.955] Timer tick, asking Buildbucket for the build status
[16:40:42.261] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:42.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:36:0) after 1m0s
[16:41:42.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:36:0)
[16:41:42.375] Timer tick, asking Buildbucket for the build status
[16:41:42.507] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:42.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:37:0) after 1m0s
[16:42:42.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:37:0)
[16:42:42.585] Timer tick, asking Buildbucket for the build status
[16:42:42.701] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:42.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:38:0) after 1m0s
[16:43:42.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:38:0)
[16:43:42.745] Timer tick, asking Buildbucket for the build status
[16:43:42.893] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:42.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:39:0) after 1m0s
[16:44:42.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:39:0)
[16:44:42.956] Timer tick, asking Buildbucket for the build status
[16:44:43.076] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:43.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:40:0) after 1m0s
[16:45:43.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:40:0)
[16:45:43.171] Timer tick, asking Buildbucket for the build status
[16:45:43.495] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:43.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:41:0) after 1m0s
[16:46:43.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:41:0)
[16:46:43.514] Timer tick, asking Buildbucket for the build status
[16:46:43.587] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:43.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:42:0) after 1m0s
[16:47:43.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:42:0)
[16:47:43.614] Timer tick, asking Buildbucket for the build status
[16:47:43.930] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:43.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:43:0) after 1m0s
[16:48:43.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:43:0)
[16:48:43.950] Timer tick, asking Buildbucket for the build status
[16:48:44.360] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:44.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:44:0) after 1m0s
[16:49:44.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:44:0)
[16:49:44.459] Timer tick, asking Buildbucket for the build status
[16:49:44.561] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:44.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:45:0) after 1m0s
[16:50:44.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:45:0)
[16:50:44.895] Timer tick, asking Buildbucket for the build status
[16:50:44.980] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:44.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:46:0) after 1m0s
[16:51:45.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:46:0)
[16:51:45.475] Timer tick, asking Buildbucket for the build status
[16:51:45.900] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:45.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:47:0) after 1m0s
[16:52:46.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:47:0)
[16:52:46.074] Timer tick, asking Buildbucket for the build status
[16:52:46.231] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:46.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:48:0) after 1m0s
[16:53:46.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:48:0)
[16:53:46.582] Timer tick, asking Buildbucket for the build status
[16:53:46.924] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:46.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:49:0) after 1m0s
[16:54:47.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:49:0)
[16:54:47.499] Timer tick, asking Buildbucket for the build status
[16:54:47.670] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:47.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:50:0) after 1m0s
[16:55:47.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:50:0)
[16:55:47.877] Timer tick, asking Buildbucket for the build status
[16:55:48.025] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:48.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:51:0) after 1m0s
[16:56:48.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:51:0)
[16:56:48.250] Timer tick, asking Buildbucket for the build status
[16:56:48.789] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:48.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:52:0) after 1m0s
[16:57:48.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:52:0)
[16:57:48.956] Timer tick, asking Buildbucket for the build status
[16:57:49.288] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:49.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:53:0) after 1m0s
[16:58:49.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:53:0)
[16:58:49.337] Timer tick, asking Buildbucket for the build status
[16:58:49.529] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:49.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:54:0) after 1m0s
[16:59:49.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:54:0)
[16:59:49.808] Timer tick, asking Buildbucket for the build status
[16:59:50.029] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:50.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:55:0) after 1m0s
[17:00:50.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:55:0)
[17:00:50.121] Timer tick, asking Buildbucket for the build status
[17:00:50.488] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:50.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:56:0) after 1m0s
[17:01:50.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:56:0)
[17:01:50.521] Timer tick, asking Buildbucket for the build status
[17:01:50.690] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:50.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:57:0) after 1m0s
[17:02:50.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:57:0)
[17:02:50.863] Timer tick, asking Buildbucket for the build status
[17:02:51.555] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:51.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:58:0) after 1m0s
[17:03:51.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:58:0)
[17:03:51.898] Timer tick, asking Buildbucket for the build status
[17:03:52.009] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:52.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:59:0) after 1m0s
[17:04:52.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:59:0)
[17:04:52.154] Timer tick, asking Buildbucket for the build status
[17:04:52.285] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:52.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:60:0) after 1m0s
[17:05:52.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:60:0)
[17:05:52.325] Timer tick, asking Buildbucket for the build status
[17:05:52.807] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:52.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:61:0) after 1m0s
[17:06:52.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:61:0)
[17:06:52.808] Timer tick, asking Buildbucket for the build status
[17:06:52.930] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:52.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:62:0) after 1m0s
[17:07:52.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:62:0)
[17:07:52.937] Timer tick, asking Buildbucket for the build status
[17:07:53.394] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:53.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:63:0) after 1m0s
[17:08:53.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:63:0)
[17:08:53.463] Timer tick, asking Buildbucket for the build status
[17:08:53.553] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:53.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:64:0) after 1m0s
[17:09:53.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:64:0)
[17:09:53.673] Timer tick, asking Buildbucket for the build status
[17:09:53.757] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:53.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:65:0) after 1m0s
[17:10:53.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:65:0)
[17:10:53.939] Timer tick, asking Buildbucket for the build status
[17:10:54.009] Build 8858205389650247072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:54.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023635451623798624:66:0) after 1m0s
[17:11:34.932] Received PubSub notification, asking Buildbucket for the build status
[17:11:35.026] Build 8858205389650247072: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:11:35.026] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610557894436092",
"created_by": "project:chromium",
"created_ts": "1610554052647133",
"failure_reason": "BUILD_FAILURE",
"id": "8858205389650247072",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fd1e18fe4970f92cede558b68e3b84a18116a1f6\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed.\"}, \"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\": \"fd1e18fe4970f92cede558b68e3b84a18116a1f6\", \"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-3-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16799, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"62b8364eafc717bfa9c35af5304b7ad5959e7134\", \"got_dawn_revision\": \"111ba65a5e3dbd23a58dd63991e92e9300f44d13\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"fd1e18fe4970f92cede558b68e3b84a18116a1f6\", \"got_revision_cp\": \"refs/heads/master@{#843030}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"6cbcce5b4a6305bfc281c520ca0d24fa6d0c9a39\", \"got_v8_revision_cp\": \"refs/heads/8.9.262@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fd1e18fe4970f92cede558b68e3b84a18116a1f6\", \"swarm_hashes_refs/heads/master(at){#843030}_without_patch\": {\"absl_hardening_tests\": \"1ef04d65afd764da8e896aeba26aada62e6909f3\", \"accessibility_unittests\": \"3ae2cd70762d46783eae8688abbb00b556f4797c\", \"angle_unittests\": \"03a910c9f080f8a8054c4a2a1a7c5507c77adc3b\", \"app_shell_unittests\": \"c09db2ad2d52a61af54adb1d46b85b23c6b6a9e6\", \"aura_unittests\": \"0379549ca19458226ecab6caa543e318d1b6d274\", \"base_unittests\": \"2e4493b4b82f90af04c7db7bbe5b305cb1b360e8\", \"base_util_unittests\": \"3737961d0c8907433d49c3c79757dfa42e8e044e\", \"blink_common_unittests\": \"d5c70f78526ed8cd1c7d9c3c2110e8e72f2ba3c8\", \"blink_fuzzer_unittests\": \"40885c28c9f16f6ec6de9c694422b7fb2cb03e5f\", \"blink_heap_unittests\": \"84302e10d80cc4a722bf4ff09f9a6051104dd247\", \"blink_platform_unittests\": \"3aef25cb9ce72ccd2f6e2cdf4aad7771f14f5aad\", \"blink_unittests\": \"f51b97132372e61fba029fb01f8f2a1fafc01c47\", \"boringssl_crypto_tests\": \"5ee30f498c0f5848954b33a14a1e19c67cec788d\", \"boringssl_ssl_tests\": \"ee8da5a346cba968462f7108f0783dfd697f9e1a\", \"browser_switcher_bho_unittests\": \"7b8bc711ffb6bead9b12b397a47bae67cb5f6cb3\", \"browser_tests\": \"57a91f716024bc985452431268ba0114864e01b9\", \"capture_unittests\": \"3700c57edbdd0a3965a8ae345c4d3d429071ed69\", \"cast_unittests\": \"4d589155e84a4af36ae08a9f9dbad5c155469f2e\", \"cc_unittests\": \"5b5aa99eaaaf72b69682914b2d475200b40f52f1\", \"chrome_app_unittests\": \"d15392f0dca3119885a98e467021817dc4e6998b\", \"chrome_cleaner_unittests\": \"801ef50364993839bd0b3176eadc1376abc73bf4\", \"chrome_elf_unittests\": \"136ef0caefe0d139f26d78da0a90ffd54568c465\", \"chromedriver_unittests\": \"6349c4a25ead29d3a00c65bf5d0189cb32fea5d1\", \"components_browsertests\": \"18c00c99c7c32a1aa72d30ffd7be1cdca3bd6e3a\", \"components_unittests\": \"e5b62b813e87db7c2cbd4ec14e4896d1e39f19cd\", \"compositor_unittests\": \"8362fd988e19dc5fb428777154a546e269fc186a\", \"content_browsertests\": \"ffd78ea56eb2b18223f2b45040dd4382c72d196a\", \"content_unittests\": \"f55a8bedf26dfab885e11d4afcf079cac3ad6fe1\", \"courgette_unittests\": \"098be4bf12255bab4f09da15b9478f91f5aa7040\", \"crashpad_tests\": \"361cc0081fa38be3532c6c97962a28b6e246ccea\", \"cronet_tests\": \"2af9f228982562b738ebf4931f706d742be94f46\", \"cronet_unittests\": \"5f8aff20506ac747bead3b3a494a0a76f6587dec\", \"crypto_unittests\": \"adb7ab3f67626e0925d8d0be0410ea6582422342\", \"delayloads_unittests\": \"f81b07341d86923cc25c4af97dca812112b81d35\", \"device_unittests\": \"012183c20c9767625033eb151b853a5c49886bd9\", \"display_unittests\": \"164715ba3d5fbf5e2c5caa20725f7c4b3f440ed3\", \"elevation_service_unittests\": \"2cae4564b2558014ef6432ee355c8122ea2f55a2\", \"events_unittests\": \"771d332b3a9758c03443798f6c38bb3051ef1218\", \"extensions_browsertests\": \"d05fc5f4fa380dd39bc30d4f7365fd8b9821f1a3\", \"extensions_unittests\": \"8fc186d9169da5ae44408fb95b578bd8cc9f61f1\", \"filesystem_service_unittests\": \"e520f364def20584f971b93ea66a5b33ec923c37\", \"gcm_unit_tests\": \"82e692eb640b0c28b7607622b1d9a69afadf9a8d\", \"gcp_unittests\": \"1cd6ef45a21185e99214d81f209636124333967d\", \"gfx_unittests\": \"36a6127cde454bb70a5fd7e6b1d0831f8c4fc770\", \"gin_unittests\": \"41d834fe779ee642c73c8c71d5ac422fd79abb08\", \"google_apis_unittests\": \"1aa5b85caef151d401ed588431537ef31779593c\", \"gpu_unittests\": \"929c497ac62e9040758a44b13c6bb00e2cbf1c1a\", \"gwp_asan_unittests\": \"15a1f0574f29e720be5b43fa47ee06d9e0434f18\", \"headless_browsertests\": \"c98183ca283fc5ea5903cab8a0c0afff1b4a3e10\", \"headless_unittests\": \"ebbb3c04b648bb186bebb7919dba288d49b5b3a0\", \"install_static_unittests\": \"665d747ee0c6b437221113486535f95d2f306097\", \"installer_util_unittests\": \"899218d2de04df7265b58d2c6cce03944cc38643\", \"interactive_ui_tests\": \"2e8c67cb00b95a5e2f2abb65ad332222149b644d\", \"ipc_tests\": \"e623ee383a4e525ebf2d8d9c6f6225f71841bfa1\", \"jingle_unittests\": \"2fdcb32a6f726acbbd1ef3ec3d61bc76cf6317d2\", \"latency_unittests\": \"ff777efbd0537e66753682fab6dff25c5e2cd181\", \"libjingle_xmpp_unittests\": \"698f7c06795b79cf08f7e1273e24c803d0f21a7c\", \"liburlpattern_unittests\": \"62e961b1330c1644b0d3affbb61d654378d011af\", \"media_blink_unittests\": \"0e7dda82a92478a466774321ed2583c615186a9c\", \"media_unittests\": \"3401dd3f821145cf7483eec41ef678f71e8347e9\", \"message_center_unittests\": \"4cff648d30caff1af5ed1d6d9b424fde9c420083\", \"midi_unittests\": \"5b17e444f83f7d2aadeacf9f3f870e20c7cf736c\", \"mojo_core_unittests\": \"a00b4b89d31be2ef0e6e41c059f16e5c5d3bc3ac\", \"mojo_unittests\": \"6b2e4576ed54f0257bbe2bc904ced9d51e23001d\", \"nacl_loader_unittests\": \"56952781ab8414dda8e25445562aa2050b316378\", \"native_theme_unittests\": \"72780e901b0ef586708600de1ad33763e72cbb38\", \"net_unittests\": \"769f27a5627fedd679d947b731d5464711e3cc8e\", \"notification_helper_unittests\": \"db108ddf017ee42fc5351ac49b81cb80744a9dd6\", \"pdf_unittests\": \"e638c24174de071930a737b7938ae56f5a565546\", \"perfetto_unittests\": \"390f48362389f5bdccdb0cfb1480c57c5f2cab07\", \"ppapi_unittests\": \"ea878f69a8434eb88540b6fc72af73f2f6e6a280\", \"printing_unittests\": \"7ebba46c0741f556753309bdf1b0a55d6d9411c1\", \"remoting_unittests\": \"af839e212a8366a0bb9010b473f8c2f46f2d420e\", \"sbox_integration_tests\": \"63f3f07972685db3e67474c11c9144490ba3d089\", \"sbox_unittests\": \"013a8f0f93a07a08be956ee47c608e8d190523b1\", \"sbox_validation_tests\": \"80bb28f3ae967686eab469f9cfb08f4c0a6ac8dc\", \"service_manager_unittests\": \"9169f05ffdd77a514ad6815cff60c54998324c19\", \"services_unittests\": \"1b998e53a8ab7b079f40b88ecfa130280dbb7d9e\", \"setup_unittests\": \"46fe3d958feda72eb1e895bf1fb832907d1c5ca1\", \"shell_dialogs_unittests\": \"cec21ebda5b2252836b7d1bf04cdced96e2dceb3\", \"skia_unittests\": \"79eb9af0ad7a3d9fc01fa52d306ecf1ae4bb8886\", \"snapshot_unittests\": \"cb24b9d95397e8a7ed644cd674e18351b79cae2b\", \"sql_unittests\": \"6385b5245ef97c28b769d16b2019b293e544e5ff\", \"storage_unittests\": \"60a2ee36f0e4cb0277cee51cb41d0f48a89d7052\", \"sync_integration_tests\": \"147bc6f08ad346da11ed5257840fb214ea5e5f8a\", \"traffic_annotation_auditor_unittests\": \"627fa665b562c32cd1eb45d7cb6ce2dd50852826\", \"ui_base_unittests\": \"c8d7d656e128eb13c1369ffa9496e730b0108059\", \"ui_touch_selection_unittests\": \"638e1022e0c78b3f2d4ab8b2745c9628e98851c4\", \"unit_tests\": \"6fe3bb1fa0295f958a0583ffb88febc05fe85b29\", \"updater_tests\": \"8e4650b4ed26f203cb7d11d4e341f9c0cfa6941f\", \"url_unittests\": \"9d1001babf56d179435c7af8624eb9a15bf9a6e1\", \"views_unittests\": \"b975c95e702877a0bdf5dbd9f0e72c8d6c994c39\", \"viz_unittests\": \"75d257d71dd072bd666b27774f240bb1c4e89579\", \"vr_common_unittests\": \"19c292942d555c014c031779c87195dd1d6fd0fc\", \"vr_pixeltests\": \"6d4137c32e06bd7a411deea1336142aa02d7d363\", \"weblayer_browsertests\": \"776259c9039eaf76335df0b9373fde2fe4f1b2d4\", \"weblayer_unittests\": \"5f180daaf43ff58d2e2885e5d11bea6d2eab89b8\", \"wm_unittests\": \"68a033b0661ccb247477c0a6d142ac28eea21172\", \"wtf_unittests\": \"cd6eeacad6abf67ae80b713876ff893fdd41ae0b\", \"zlib_unittests\": \"eac41890e5ef08112a0c18acda29c480652e0f6d\", \"zucchini_unittests\": \"feb6cc47956e67508e476dd2ec0bd9e27a1b3704\"}}, \"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-3-nb2x\"], \"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\": [\"5661-c6f6250\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610554055489201",
"status": "COMPLETED",
"status_changed_ts": "1610554055878550",
"tags": [
"build_address:luci.chromium.ci/win-asan/16799",
"builder:win-asan",
"buildset:commit/git/fd1e18fe4970f92cede558b68e3b84a18116a1f6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd1e18fe4970f92cede558b68e3b84a18116a1f6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023635451623798624",
"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/8858205389650247072/+/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:5115521d0fc74610",
"user_agent:luci-scheduler"
],
"updated_ts": "1610557894672441",
"url": "https://ci.chromium.org/b/8858205389650247072",
"utcnow_ts": "1610557895011027"
}
}
[17:11:35.026] Invocation finished in 1h4m3.645401794s with status FAILED