[02:08:35.063] New invocation is queued and will start shortly
[02:08:36.144] Starting the invocation (attempt 1)
[02:08:36.184] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:08:36.185] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:08:36.185] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023778830063658288",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0f5a6851b26e7dd35f91184f9fb93f71564c63ee\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023778830063658288",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0f5a6851b26e7dd35f91184f9fb93f71564c63ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0f5a6851b26e7dd35f91184f9fb93f71564c63ee",
"gitiles_ref:refs/heads/master"
]
}
[02:08:36.937] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610417316309815",
"id": "8858348768091720672",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0f5a6851b26e7dd35f91184f9fb93f71564c63ee\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610417316786245",
"tags": [
"build_address:luci.chromium.ci/win-asan/16767",
"builder:win-asan",
"buildset:commit/git/0f5a6851b26e7dd35f91184f9fb93f71564c63ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0f5a6851b26e7dd35f91184f9fb93f71564c63ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023778830063658288",
"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/8858348768091720672/+/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": "1610417316786388",
"url": "https://ci.chromium.org/b/8858348768091720672",
"utcnow_ts": "1610417316928272"
}
}
[02:08:36.937] Task URL: https://ci.chromium.org/b/8858348768091720672
[02:08:36.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:2:0) after 1m0s
[02:08:42.198] Received PubSub notification, asking Buildbucket for the build status
[02:08:42.413] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:37.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:2:0)
[02:09:37.186] Timer tick, asking Buildbucket for the build status
[02:09:37.270] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:37.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:4:0) after 1m0s
[02:10:37.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:4:0)
[02:10:37.400] Timer tick, asking Buildbucket for the build status
[02:10:38.347] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:38.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:5:0) after 1m0s
[02:11:38.468] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:5:0)
[02:11:38.468] Timer tick, asking Buildbucket for the build status
[02:11:38.611] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:38.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:6:0) after 1m0s
[02:12:38.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:6:0)
[02:12:38.644] Timer tick, asking Buildbucket for the build status
[02:12:38.853] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:38.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:7:0) after 1m0s
[02:13:38.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:7:0)
[02:13:38.897] Timer tick, asking Buildbucket for the build status
[02:13:39.307] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:39.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:8:0) after 1m0s
[02:14:39.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:8:0)
[02:14:39.447] Timer tick, asking Buildbucket for the build status
[02:14:39.655] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:39.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:9:0) after 1m0s
[02:15:39.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:9:0)
[02:15:39.699] Timer tick, asking Buildbucket for the build status
[02:15:39.900] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:39.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:10:0) after 1m0s
[02:16:39.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:10:0)
[02:16:39.971] Timer tick, asking Buildbucket for the build status
[02:16:40.049] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:40.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:11:0) after 1m0s
[02:17:40.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:11:0)
[02:17:40.073] Timer tick, asking Buildbucket for the build status
[02:17:40.151] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:40.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:12:0) after 1m0s
[02:18:40.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:12:0)
[02:18:40.182] Timer tick, asking Buildbucket for the build status
[02:18:40.392] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:40.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:13:0) after 1m0s
[02:19:40.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:13:0)
[02:19:40.414] Timer tick, asking Buildbucket for the build status
[02:19:40.524] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:40.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:14:0) after 1m0s
[02:20:40.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:14:0)
[02:20:40.516] Timer tick, asking Buildbucket for the build status
[02:20:41.104] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:41.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:15:0) after 1m0s
[02:21:41.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:15:0)
[02:21:41.160] Timer tick, asking Buildbucket for the build status
[02:21:41.302] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:41.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:16:0) after 1m0s
[02:22:41.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:16:0)
[02:22:41.525] Timer tick, asking Buildbucket for the build status
[02:22:41.634] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:41.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:17:0) after 1m0s
[02:23:41.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:17:0)
[02:23:41.844] Timer tick, asking Buildbucket for the build status
[02:23:42.175] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:42.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:18:0) after 1m0s
[02:24:42.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:18:0)
[02:24:42.348] Timer tick, asking Buildbucket for the build status
[02:24:42.533] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:42.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:19:0) after 1m0s
[02:25:42.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:19:0)
[02:25:42.898] Timer tick, asking Buildbucket for the build status
[02:25:42.959] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:42.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:20:0) after 1m0s
[02:26:43.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:20:0)
[02:26:43.009] Timer tick, asking Buildbucket for the build status
[02:26:43.077] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:43.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:21:0) after 1m0s
[02:27:43.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:21:0)
[02:27:43.274] Timer tick, asking Buildbucket for the build status
[02:27:43.346] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:43.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:22:0) after 1m0s
[02:28:43.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:22:0)
[02:28:43.730] Timer tick, asking Buildbucket for the build status
[02:28:43.868] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:43.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:23:0) after 1m0s
[02:29:44.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:23:0)
[02:29:44.059] Timer tick, asking Buildbucket for the build status
[02:29:44.158] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:44.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:24:0) after 1m0s
[02:30:44.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:24:0)
[02:30:44.601] Timer tick, asking Buildbucket for the build status
[02:30:44.677] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:44.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:25:0) after 1m0s
[02:31:44.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:25:0)
[02:31:44.932] Timer tick, asking Buildbucket for the build status
[02:31:44.999] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:44.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:26:0) after 1m0s
[02:32:45.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:26:0)
[02:32:45.235] Timer tick, asking Buildbucket for the build status
[02:32:45.415] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:45.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:27:0) after 1m0s
[02:33:45.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:27:0)
[02:33:45.603] Timer tick, asking Buildbucket for the build status
[02:33:45.739] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:45.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:28:0) after 1m0s
[02:34:45.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:28:0)
[02:34:45.850] Timer tick, asking Buildbucket for the build status
[02:34:46.019] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:46.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:29:0) after 1m0s
[02:35:46.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:29:0)
[02:35:46.065] Timer tick, asking Buildbucket for the build status
[02:35:46.245] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:46.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:30:0) after 1m0s
[02:36:46.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:30:0)
[02:36:46.310] Timer tick, asking Buildbucket for the build status
[02:36:46.731] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:46.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:31:0) after 1m0s
[02:37:46.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:31:0)
[02:37:46.779] Timer tick, asking Buildbucket for the build status
[02:37:46.954] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:46.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:32:0) after 1m0s
[02:38:47.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:32:0)
[02:38:47.062] Timer tick, asking Buildbucket for the build status
[02:38:47.233] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:47.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:33:0) after 1m0s
[02:39:47.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:33:0)
[02:39:47.339] Timer tick, asking Buildbucket for the build status
[02:39:47.502] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:47.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:34:0) after 1m0s
[02:40:47.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:34:0)
[02:40:47.577] Timer tick, asking Buildbucket for the build status
[02:40:47.773] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:47.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:35:0) after 1m0s
[02:41:47.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:35:0)
[02:41:47.807] Timer tick, asking Buildbucket for the build status
[02:41:47.945] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:47.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:36:0) after 1m0s
[02:42:48.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:36:0)
[02:42:48.025] Timer tick, asking Buildbucket for the build status
[02:42:48.226] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:48.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:37:0) after 1m0s
[02:43:48.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:37:0)
[02:43:48.350] Timer tick, asking Buildbucket for the build status
[02:43:48.453] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:48.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:38:0) after 1m0s
[02:44:48.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:38:0)
[02:44:48.606] Timer tick, asking Buildbucket for the build status
[02:44:48.747] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:48.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:39:0) after 1m0s
[02:45:48.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:39:0)
[02:45:48.787] Timer tick, asking Buildbucket for the build status
[02:45:48.970] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:48.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:40:0) after 1m0s
[02:46:49.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:40:0)
[02:46:49.016] Timer tick, asking Buildbucket for the build status
[02:46:49.226] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:49.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:41:0) after 1m0s
[02:47:49.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:41:0)
[02:47:49.366] Timer tick, asking Buildbucket for the build status
[02:47:49.477] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:49.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:42:0) after 1m0s
[02:48:49.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:42:0)
[02:48:49.583] Timer tick, asking Buildbucket for the build status
[02:48:49.764] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:49.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:43:0) after 1m0s
[02:49:49.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:43:0)
[02:49:49.954] Timer tick, asking Buildbucket for the build status
[02:49:50.604] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:50.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:44:0) after 1m0s
[02:50:50.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:44:0)
[02:50:50.623] Timer tick, asking Buildbucket for the build status
[02:50:50.714] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:50.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:45:0) after 1m0s
[02:51:50.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:45:0)
[02:51:50.739] Timer tick, asking Buildbucket for the build status
[02:51:50.817] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:50.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:46:0) after 1m0s
[02:52:50.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:46:0)
[02:52:50.897] Timer tick, asking Buildbucket for the build status
[02:52:51.121] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:51.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:47:0) after 1m0s
[02:53:51.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:47:0)
[02:53:51.147] Timer tick, asking Buildbucket for the build status
[02:53:51.438] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:51.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:48:0) after 1m0s
[02:54:51.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:48:0)
[02:54:51.460] Timer tick, asking Buildbucket for the build status
[02:54:51.570] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:51.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:49:0) after 1m0s
[02:55:51.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:49:0)
[02:55:51.633] Timer tick, asking Buildbucket for the build status
[02:55:51.729] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:51.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:50:0) after 1m0s
[02:56:52.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:50:0)
[02:56:52.212] Timer tick, asking Buildbucket for the build status
[02:56:52.702] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:52.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:51:0) after 1m0s
[02:57:52.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:51:0)
[02:57:52.885] Timer tick, asking Buildbucket for the build status
[02:57:53.463] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:53.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:52:0) after 1m0s
[02:58:53.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:52:0)
[02:58:53.522] Timer tick, asking Buildbucket for the build status
[02:58:54.120] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:54.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:53:0) after 1m0s
[02:59:54.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:53:0)
[02:59:54.523] Timer tick, asking Buildbucket for the build status
[02:59:54.610] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:54.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:54:0) after 1m0s
[03:00:54.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:54:0)
[03:00:54.615] Timer tick, asking Buildbucket for the build status
[03:00:54.759] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:54.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:55:0) after 1m0s
[03:01:55.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:55:0)
[03:01:55.045] Timer tick, asking Buildbucket for the build status
[03:01:55.245] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:55.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:56:0) after 1m0s
[03:02:55.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:56:0)
[03:02:55.352] Timer tick, asking Buildbucket for the build status
[03:02:55.838] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:55.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:57:0) after 1m0s
[03:03:56.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:57:0)
[03:03:56.045] Timer tick, asking Buildbucket for the build status
[03:03:56.487] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:56.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:58:0) after 1m0s
[03:04:56.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:58:0)
[03:04:56.571] Timer tick, asking Buildbucket for the build status
[03:04:56.781] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:56.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:59:0) after 1m0s
[03:05:56.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:59:0)
[03:05:56.855] Timer tick, asking Buildbucket for the build status
[03:05:56.981] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:56.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:60:0) after 1m0s
[03:06:57.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:60:0)
[03:06:57.006] Timer tick, asking Buildbucket for the build status
[03:06:57.166] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:57.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:61:0) after 1m0s
[03:07:57.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:61:0)
[03:07:57.347] Timer tick, asking Buildbucket for the build status
[03:07:57.422] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:57.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:62:0) after 1m0s
[03:08:57.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:62:0)
[03:08:57.462] Timer tick, asking Buildbucket for the build status
[03:08:57.608] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:57.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:63:0) after 1m0s
[03:09:57.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:63:0)
[03:09:57.703] Timer tick, asking Buildbucket for the build status
[03:09:57.772] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:57.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:64:0) after 1m0s
[03:10:57.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:64:0)
[03:10:57.892] Timer tick, asking Buildbucket for the build status
[03:10:57.978] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:57.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:65:0) after 1m0s
[03:11:57.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:65:0)
[03:11:57.974] Timer tick, asking Buildbucket for the build status
[03:11:58.059] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:58.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:66:0) after 1m0s
[03:12:58.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:66:0)
[03:12:58.091] Timer tick, asking Buildbucket for the build status
[03:12:58.164] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:58.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:67:0) after 1m0s
[03:13:58.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:67:0)
[03:13:58.427] Timer tick, asking Buildbucket for the build status
[03:13:58.561] Build 8858348768091720672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:58.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023778830063658288:68:0) after 1m0s
[03:14:13.567] Received PubSub notification, asking Buildbucket for the build status
[03:14:13.676] Build 8858348768091720672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:14:13.676] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610421251524211",
"created_by": "project:chromium",
"created_ts": "1610417316309815",
"id": "8858348768091720672",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0f5a6851b26e7dd35f91184f9fb93f71564c63ee\"}}",
"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\": \"0f5a6851b26e7dd35f91184f9fb93f71564c63ee\", \"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\": 16767, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"de32c3d2de006a2c8d2acfa829634fcf2d1564ab\", \"got_dawn_revision\": \"ea26a8ce553fe2efa94830f3d7326072487bde3f\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"0f5a6851b26e7dd35f91184f9fb93f71564c63ee\", \"got_revision_cp\": \"refs/heads/master@{#842336}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"43ed322b6a782285f8fcfbaadd3926c371c3a60b\", \"got_v8_revision_cp\": \"refs/heads/8.9.241@{#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\": \"0f5a6851b26e7dd35f91184f9fb93f71564c63ee\", \"swarm_hashes_refs/heads/master(at){#842336}_without_patch\": {\"absl_hardening_tests\": \"39947578cad4b4944d238c2e290f470ecd715e06\", \"accessibility_unittests\": \"447f752f3bd659c3350bee5421070fd047d6c0cc\", \"angle_unittests\": \"f5eb3d212383bffadfcc008c3362cae1e24ce389\", \"app_shell_unittests\": \"0cf3f32564efe04628d271aecb48b044a8438596\", \"aura_unittests\": \"ff17e9adb9ec1a81c152d47761e374cb9d58926d\", \"base_unittests\": \"8914a67e419804ceb90b1c7ccde6d67d294cc616\", \"base_util_unittests\": \"800ba2cc0e6a277e72c249fe37a7d999f3929c7e\", \"blink_common_unittests\": \"2f61ffcff12e0506571c7d74eb0633c9dcf9ac11\", \"blink_fuzzer_unittests\": \"e82c17a30adb0bbc4678deab16a499b4c58f2a17\", \"blink_heap_unittests\": \"f6061b3e15898f9891a9033bc0d0745eec5fad55\", \"blink_platform_unittests\": \"6e7c0395ecc57f344c57bcaf12f08c3f2e3d756b\", \"blink_unittests\": \"4b3cae258f16438037bdfab29c3a56b0fdabcff9\", \"boringssl_crypto_tests\": \"170dbbe5a69c12612507a65ff9eab6a6d9a94e6e\", \"boringssl_ssl_tests\": \"846d68e10ac80200edc8c8161b0f05e14a1cedb5\", \"browser_switcher_bho_unittests\": \"ebba3240b94c1ff09a05e3d559f51d8d76023665\", \"browser_tests\": \"7015abf04f16e285e5dd5e3021be56db7bf72fe6\", \"capture_unittests\": \"ce8e060c693ee7d301bc7e89fc0ca02b9bd33df5\", \"cast_unittests\": \"fb4c2a5bb4cbf784746a63b8c1073b252e14aea1\", \"cc_unittests\": \"65d9d258f0a4b22c1e8c06ca9b7c54663366f22d\", \"chrome_app_unittests\": \"7e72144f8d6f18c49c35265d99cccc6459e31ef6\", \"chrome_cleaner_unittests\": \"5d40b71dd99a937fbec3f03d9c7b48b266c4f308\", \"chrome_elf_unittests\": \"e3475b1ae70b78841a512480ebd0ac36b2c42648\", \"chromedriver_unittests\": \"82819e90b4981577b4b0f0ed00efdeaaa58b62c9\", \"components_browsertests\": \"3243c6f33aee787d0622cefc82fcf064c0f40a36\", \"components_unittests\": \"3ff933de3e5c12607afc2822462555947d206d91\", \"compositor_unittests\": \"a1f6f540e707dfbb00d9579a14cea44096527c05\", \"content_browsertests\": \"f3925188515940c8b4d40c5ccdf1da74e19e0c4d\", \"content_unittests\": \"95b3345121ed652cc0b79c8ef6dba6f8a2e7dd10\", \"courgette_unittests\": \"762ca72eb01e26642a15fac137d2d1da13646abd\", \"crashpad_tests\": \"ab26af0352985d1a4f58c4afb2b8ba2026f2d6ec\", \"cronet_tests\": \"c2cfd8710c687d2f39fa39f1a1f9993c8e856a57\", \"cronet_unittests\": \"5abe7357fec94fa6316c62fea2042cf22379fdaf\", \"crypto_unittests\": \"a5fca417f6faef9bc3ccaee3d292f3e62a371e55\", \"delayloads_unittests\": \"780026322d18c35e8c40c7272535aaca2adcc135\", \"device_unittests\": \"ca7ab14fbec3cca006bf9ee97b0f7f4cf4b53a51\", \"display_unittests\": \"d30cfb787b99b8490be098c058f76de62558b567\", \"elevation_service_unittests\": \"088044fade9add973316b8d7941ee83119e1c240\", \"events_unittests\": \"577fbb211347b4037a97ad108c20e542d5011b38\", \"extensions_browsertests\": \"bf759a3b8734c4a35f364f594ae20478ac45ebe6\", \"extensions_unittests\": \"d3656f1e9e4d2bd66dd97b2ffa41b8f4fc80950e\", \"filesystem_service_unittests\": \"5a74b387aa56e57a27f5b86551b67114b3e1b213\", \"gcm_unit_tests\": \"54a2373772a75c274813ce287a3e40debe5b167e\", \"gcp_unittests\": \"e9f328af423bd9f7a7c4641293cebfba715914ed\", \"gfx_unittests\": \"5357e49ff97572b69e5198907ba6159eb2916ef6\", \"gin_unittests\": \"26ffbf640a58915ed59565d5f0a01e5f55cc4392\", \"google_apis_unittests\": \"567204281efd7b4c51cc5f2782f1a52ebce7713d\", \"gpu_unittests\": \"3ad7cd3c1e5415d8de90574addec11a3f76d9aca\", \"gwp_asan_unittests\": \"b5335895f33b4abb0188adec793609987f65d04b\", \"headless_browsertests\": \"a0b9a5bd93f56068a77a264e55c1ba7896ac7fe4\", \"headless_unittests\": \"dc6eeb9cc94acf4e142923606becbcb8c91dcb0b\", \"install_static_unittests\": \"ded0e558355334df9117a47a97a9a9df15f093ac\", \"installer_util_unittests\": \"4cb16abecce8cac90faba1cd0f2f7650d12c811e\", \"interactive_ui_tests\": \"913805e2560c7d8355edcc4d6ccf545d54c81eb5\", \"ipc_tests\": \"900cecd401aa003899d153388070f6228088b8a4\", \"jingle_unittests\": \"54a3a72e833a11c9b09ac00dae49cb32a83c486e\", \"latency_unittests\": \"0647a26520dd9d918c4f17199cf41180534f23aa\", \"libjingle_xmpp_unittests\": \"52de022dc22a287bc1ed2839b2e23b879e0022ab\", \"liburlpattern_unittests\": \"75ba19a0dea304bd6eb561e6f0a96c023b8b2cce\", \"media_blink_unittests\": \"12267940ee364292128dd347e6077db17f098ed0\", \"media_unittests\": \"4a9591c03c3b3fdc87ca4af3edb643623f6d5043\", \"message_center_unittests\": \"28e3a44077c148a8ebd80a07d1c3738226cb0e66\", \"midi_unittests\": \"a0625cfd642dca46cd465b2e6a6c569c896c5a0e\", \"mojo_core_unittests\": \"fa9f3e30eb7f093b0e37f82e20b063d2c535648a\", \"mojo_unittests\": \"0f9ca793f21756e493af799220082ad976c96810\", \"nacl_loader_unittests\": \"795b17fc491b9259c44978d25fcafe84a2e89f8b\", \"native_theme_unittests\": \"53c5da26bb570949dd11e4c49e0a2a9ae29de7f9\", \"net_unittests\": \"d7262afb5142ebc1167e2927bb02d2f8bcb128ab\", \"notification_helper_unittests\": \"88bf37a90cc4486c5c08223422d5e8e5fc60d565\", \"pdf_unittests\": \"3d29920f785e0a478f2a1e4a19f9f8c2cbfec69f\", \"perfetto_unittests\": \"d742e30d9bd4c65c866853c91ac11c62feb159f3\", \"ppapi_unittests\": \"b189ddf1f3e7801f3ac9df81b0520a49a0b948bf\", \"printing_unittests\": \"2eb2416dd38183238af15165a3b6d12f9adfa79f\", \"remoting_unittests\": \"de712637ef174c8f1db9276c543e67a1c3ac18a9\", \"sbox_integration_tests\": \"af187132fcc3e20595faa3ffd11695ff956baa5a\", \"sbox_unittests\": \"fdc8c01dae39594e6d62e1ebeff3ca88215207e0\", \"sbox_validation_tests\": \"c68bb41cf5722446eaf3229b3283b967ae6e7a82\", \"service_manager_unittests\": \"51f41e41f0995d73bcf3fe7984d8a05ec08717dc\", \"services_unittests\": \"072acb196725469b615103be8edfccd8a2c36c7e\", \"setup_unittests\": \"43f943dac3dffed410c2c9eefba3405874527c8d\", \"shell_dialogs_unittests\": \"f7c7daa07732e7f65c852826e5f3802512f6a765\", \"skia_unittests\": \"93a96fcb2c86887dc3314c0e2647cafc01f4228f\", \"snapshot_unittests\": \"bbe3d5e8730e5d584dbe26273d975dc968a078c8\", \"sql_unittests\": \"574b2fae6b73b55f822c7ba09b50ee3cf35565db\", \"storage_unittests\": \"9f1a3d3326c8b0a7ff58c25ad1305fbb078b904d\", \"sync_integration_tests\": \"df256420ec232b10197f59bf1ceb58afb394c9e3\", \"traffic_annotation_auditor_unittests\": \"f6d206928df6500cbea500d67eb6e128913300c9\", \"ui_base_unittests\": \"e376a39c36dc18e41bfa38944011715af59c18e3\", \"ui_touch_selection_unittests\": \"ab7edf173be238ae789ae7b0937479a655c8aef4\", \"unit_tests\": \"8340b50c3e71477c6af5e9322d490d1dc3e44829\", \"updater_tests\": \"1f72663deaf180af05b89cdc50257697f9fea56e\", \"url_unittests\": \"78a8bf8e8534b930758da071869cf1acb65178d1\", \"views_unittests\": \"2ba7509ee99a2a6ca2225d5c03dc0dfaea246ff4\", \"viz_unittests\": \"12d50e018771c5ad93c25e6b4f77228948cb728b\", \"vr_common_unittests\": \"f92db2737015dbd1374ece03c6994260c7d46dc8\", \"vr_pixeltests\": \"e8c70da5ca58c929c184383875968061d7f36a29\", \"weblayer_browsertests\": \"7233f2a40059c317f68aa4fdf9ce0b03fb551cc9\", \"weblayer_unittests\": \"d6ae7120cf8cf264295dc73bd583707c302c20f7\", \"wm_unittests\": \"12af2d6e2ffd8f36d4b3d4831467236cdd19f1d7\", \"wtf_unittests\": \"abd269e460d8fdbb02e5584718ec4e82ecce8e40\", \"zlib_unittests\": \"d327ec5ddfe03de3724d7af6cbca38cb5bcad0de\", \"zucchini_unittests\": \"5b4a35d76a5156d73f0269d42d294a2eb211fcfd\"}}, \"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": "1610417319516589",
"status": "COMPLETED",
"status_changed_ts": "1610421251919049",
"tags": [
"build_address:luci.chromium.ci/win-asan/16767",
"builder:win-asan",
"buildset:commit/git/0f5a6851b26e7dd35f91184f9fb93f71564c63ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0f5a6851b26e7dd35f91184f9fb93f71564c63ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023778830063658288",
"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/8858348768091720672/+/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:510d2bacc9090d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610421251985238",
"url": "https://ci.chromium.org/b/8858348768091720672",
"utcnow_ts": "1610421253666465"
}
}
[03:14:13.676] Invocation finished in 1h5m38.6304669s with status SUCCEEDED