[20:05:33.511] New invocation is queued and will start shortly
[20:05:34.574] Starting the invocation (attempt 1)
[20:05:34.624] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:05:34.624] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:05:34.624] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026881966533444768",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4a5d5cdc895b6e27a9842b7416f948180276a25e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026881966533444768",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4a5d5cdc895b6e27a9842b7416f948180276a25e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4a5d5cdc895b6e27a9842b7416f948180276a25e",
"gitiles_ref:refs/heads/master"
]
}
[20:05:35.417] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607457934725073",
"id": "8861451904595816848",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4a5d5cdc895b6e27a9842b7416f948180276a25e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607457935275315",
"tags": [
"build_address:luci.chromium.ci/win-asan/16105",
"builder:win-asan",
"buildset:commit/git/4a5d5cdc895b6e27a9842b7416f948180276a25e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4a5d5cdc895b6e27a9842b7416f948180276a25e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026881966533444768",
"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/8861451904595816848/+/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": "1607457935275434",
"url": "https://ci.chromium.org/b/8861451904595816848",
"utcnow_ts": "1607457935407811"
}
}
[20:05:35.417] Task URL: https://ci.chromium.org/b/8861451904595816848
[20:05:35.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:2:0) after 1m0s
[20:05:39.192] Received PubSub notification, asking Buildbucket for the build status
[20:05:39.255] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:35.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:2:0)
[20:06:35.482] Timer tick, asking Buildbucket for the build status
[20:06:35.654] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:35.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:4:0) after 1m0s
[20:07:35.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:4:0)
[20:07:35.677] Timer tick, asking Buildbucket for the build status
[20:07:35.804] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:35.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:5:0) after 1m0s
[20:08:35.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:5:0)
[20:08:35.869] Timer tick, asking Buildbucket for the build status
[20:08:35.999] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:35.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:6:0) after 1m0s
[20:09:36.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:6:0)
[20:09:36.080] Timer tick, asking Buildbucket for the build status
[20:09:36.382] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:36.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:7:0) after 1m0s
[20:10:36.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:7:0)
[20:10:36.411] Timer tick, asking Buildbucket for the build status
[20:10:36.527] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:36.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:8:0) after 1m0s
[20:11:36.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:8:0)
[20:11:36.576] Timer tick, asking Buildbucket for the build status
[20:11:36.691] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:36.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:9:0) after 1m0s
[20:12:36.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:9:0)
[20:12:36.704] Timer tick, asking Buildbucket for the build status
[20:12:37.262] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:37.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:10:0) after 1m0s
[20:13:37.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:10:0)
[20:13:37.248] Timer tick, asking Buildbucket for the build status
[20:13:38.361] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:38.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:11:0) after 1m0s
[20:14:38.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:11:0)
[20:14:38.442] Timer tick, asking Buildbucket for the build status
[20:14:38.524] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:38.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:12:0) after 1m0s
[20:15:38.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:12:0)
[20:15:38.602] Timer tick, asking Buildbucket for the build status
[20:15:39.147] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:39.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:13:0) after 1m0s
[20:16:39.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:13:0)
[20:16:39.204] Timer tick, asking Buildbucket for the build status
[20:16:39.277] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:39.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:14:0) after 1m0s
[20:17:39.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:14:0)
[20:17:39.411] Timer tick, asking Buildbucket for the build status
[20:17:39.839] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:39.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:15:0) after 1m0s
[20:18:39.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:15:0)
[20:18:39.907] Timer tick, asking Buildbucket for the build status
[20:18:39.967] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:39.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:16:0) after 1m0s
[20:19:39.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:16:0)
[20:19:39.938] Timer tick, asking Buildbucket for the build status
[20:19:40.068] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:40.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:17:0) after 1m0s
[20:20:40.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:17:0)
[20:20:40.102] Timer tick, asking Buildbucket for the build status
[20:20:40.409] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:40.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:18:0) after 1m0s
[20:21:40.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:18:0)
[20:21:40.512] Timer tick, asking Buildbucket for the build status
[20:21:40.579] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:40.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:19:0) after 1m0s
[20:22:40.596] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:19:0)
[20:22:40.596] Timer tick, asking Buildbucket for the build status
[20:22:40.747] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:40.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:20:0) after 1m0s
[20:23:40.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:20:0)
[20:23:40.807] Timer tick, asking Buildbucket for the build status
[20:23:40.900] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:40.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:21:0) after 1m0s
[20:24:40.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:21:0)
[20:24:40.922] Timer tick, asking Buildbucket for the build status
[20:24:41.045] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:41.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:22:0) after 1m0s
[20:25:41.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:22:0)
[20:25:41.078] Timer tick, asking Buildbucket for the build status
[20:25:41.205] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:41.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:23:0) after 1m0s
[20:26:41.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:23:0)
[20:26:41.305] Timer tick, asking Buildbucket for the build status
[20:26:41.427] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:41.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:24:0) after 1m0s
[20:27:41.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:24:0)
[20:27:41.447] Timer tick, asking Buildbucket for the build status
[20:27:41.559] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:41.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:25:0) after 1m0s
[20:28:41.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:25:0)
[20:28:41.658] Timer tick, asking Buildbucket for the build status
[20:28:41.779] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:41.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:26:0) after 1m0s
[20:29:41.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:26:0)
[20:29:41.828] Timer tick, asking Buildbucket for the build status
[20:29:42.248] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:42.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:27:0) after 1m0s
[20:30:42.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:27:0)
[20:30:42.298] Timer tick, asking Buildbucket for the build status
[20:30:42.499] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:42.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:28:0) after 1m0s
[20:31:42.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:28:0)
[20:31:42.571] Timer tick, asking Buildbucket for the build status
[20:31:42.700] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:42.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:29:0) after 1m0s
[20:32:42.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:29:0)
[20:32:42.752] Timer tick, asking Buildbucket for the build status
[20:32:42.855] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:42.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:30:0) after 1m0s
[20:33:42.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:30:0)
[20:33:42.901] Timer tick, asking Buildbucket for the build status
[20:33:43.057] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:43.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:31:0) after 1m0s
[20:34:43.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:31:0)
[20:34:43.105] Timer tick, asking Buildbucket for the build status
[20:34:43.214] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:43.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:32:0) after 1m0s
[20:35:43.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:32:0)
[20:35:43.240] Timer tick, asking Buildbucket for the build status
[20:35:43.626] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:43.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:33:0) after 1m0s
[20:36:43.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:33:0)
[20:36:43.679] Timer tick, asking Buildbucket for the build status
[20:36:43.838] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:43.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:34:0) after 1m0s
[20:37:43.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:34:0)
[20:37:43.864] Timer tick, asking Buildbucket for the build status
[20:37:43.956] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:43.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:35:0) after 1m0s
[20:38:43.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:35:0)
[20:38:43.991] Timer tick, asking Buildbucket for the build status
[20:38:44.519] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:44.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:36:0) after 1m0s
[20:39:44.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:36:0)
[20:39:44.531] Timer tick, asking Buildbucket for the build status
[20:39:44.949] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:44.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:37:0) after 1m0s
[20:40:45.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:37:0)
[20:40:45.159] Timer tick, asking Buildbucket for the build status
[20:40:45.290] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:45.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:38:0) after 1m0s
[20:41:45.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:38:0)
[20:41:45.405] Timer tick, asking Buildbucket for the build status
[20:41:46.140] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:46.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:39:0) after 1m0s
[20:42:46.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:39:0)
[20:42:46.229] Timer tick, asking Buildbucket for the build status
[20:42:46.408] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:46.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:40:0) after 1m0s
[20:43:46.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:40:0)
[20:43:46.504] Timer tick, asking Buildbucket for the build status
[20:43:46.865] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:46.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:41:0) after 1m0s
[20:44:46.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:41:0)
[20:44:46.903] Timer tick, asking Buildbucket for the build status
[20:44:47.309] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:47.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:42:0) after 1m0s
[20:45:47.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:42:0)
[20:45:47.386] Timer tick, asking Buildbucket for the build status
[20:45:47.573] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:47.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:43:0) after 1m0s
[20:46:47.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:43:0)
[20:46:47.598] Timer tick, asking Buildbucket for the build status
[20:46:47.705] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:47.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:44:0) after 1m0s
[20:47:47.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:44:0)
[20:47:47.727] Timer tick, asking Buildbucket for the build status
[20:47:47.839] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:47.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:45:0) after 1m0s
[20:48:47.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:45:0)
[20:48:47.937] Timer tick, asking Buildbucket for the build status
[20:48:48.499] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:48.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:46:0) after 1m0s
[20:49:48.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:46:0)
[20:49:48.481] Timer tick, asking Buildbucket for the build status
[20:49:48.553] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:48.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:47:0) after 1m0s
[20:50:48.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:47:0)
[20:50:48.604] Timer tick, asking Buildbucket for the build status
[20:50:48.722] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:48.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:48:0) after 1m0s
[20:51:48.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:48:0)
[20:51:48.775] Timer tick, asking Buildbucket for the build status
[20:51:48.908] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:48.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:49:0) after 1m0s
[20:52:49.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:49:0)
[20:52:49.028] Timer tick, asking Buildbucket for the build status
[20:52:49.187] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:49.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:50:0) after 1m0s
[20:53:49.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:50:0)
[20:53:49.261] Timer tick, asking Buildbucket for the build status
[20:53:49.774] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:49.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:51:0) after 1m0s
[20:54:49.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:51:0)
[20:54:49.833] Timer tick, asking Buildbucket for the build status
[20:54:50.012] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:50.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:52:0) after 1m0s
[20:55:50.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:52:0)
[20:55:50.104] Timer tick, asking Buildbucket for the build status
[20:55:50.283] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:50.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:53:0) after 1m0s
[20:56:50.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:53:0)
[20:56:50.334] Timer tick, asking Buildbucket for the build status
[20:56:50.395] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:50.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:54:0) after 1m0s
[20:57:50.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:54:0)
[20:57:50.451] Timer tick, asking Buildbucket for the build status
[20:57:50.540] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:50.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:55:0) after 1m0s
[20:58:50.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:55:0)
[20:58:50.576] Timer tick, asking Buildbucket for the build status
[20:58:50.663] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:50.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:56:0) after 1m0s
[20:59:50.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:56:0)
[20:59:50.740] Timer tick, asking Buildbucket for the build status
[20:59:50.809] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:50.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:57:0) after 1m0s
[21:00:50.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:57:0)
[21:00:50.846] Timer tick, asking Buildbucket for the build status
[21:00:51.513] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:51.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:58:0) after 1m0s
[21:01:51.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:58:0)
[21:01:51.583] Timer tick, asking Buildbucket for the build status
[21:01:51.670] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:51.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:59:0) after 1m0s
[21:02:51.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:59:0)
[21:02:51.697] Timer tick, asking Buildbucket for the build status
[21:02:51.789] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:51.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:60:0) after 1m0s
[21:03:51.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:60:0)
[21:03:51.824] Timer tick, asking Buildbucket for the build status
[21:03:52.319] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:52.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:61:0) after 1m0s
[21:04:52.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:61:0)
[21:04:52.400] Timer tick, asking Buildbucket for the build status
[21:04:52.568] Build 8861451904595816848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:52.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026881966533444768:62:0) after 1m0s
[21:05:35.927] Received PubSub notification, asking Buildbucket for the build status
[21:05:35.994] Build 8861451904595816848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:05:35.994] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607461534254287",
"created_by": "project:chromium",
"created_ts": "1607457934725073",
"id": "8861451904595816848",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4a5d5cdc895b6e27a9842b7416f948180276a25e\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"4a5d5cdc895b6e27a9842b7416f948180276a25e\", \"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-z6mr\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16105, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"bcdd4e58fe54c3f0271fd7da582a61ade79dd98d\", \"got_dawn_revision\": \"61355d416d9c568bcfbed6518516fbe5ef219e42\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"4a5d5cdc895b6e27a9842b7416f948180276a25e\", \"got_revision_cp\": \"refs/heads/master@{#834815}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"84b9bb6621c7eae8da034d31aceab780d8b1abdc\", \"got_v8_revision\": \"4670e2c2930e89462938e85ba131caeb7c0d0419\", \"got_v8_revision_cp\": \"refs/heads/8.9.121@{#1}\", \"got_webrtc_revision\": \"bb1a28de3cf17476142e9de8ee12fe6c567d06c1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32789}\", \"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\": \"4a5d5cdc895b6e27a9842b7416f948180276a25e\", \"swarm_hashes_refs/heads/master(at){#834815}_without_patch\": {\"absl_hardening_tests\": \"2f48598522621c87886edf03118514e4833559d2\", \"accessibility_unittests\": \"11476b0f7b56d84ec21fc00afd30d26b4057c220\", \"angle_unittests\": \"b629383673df4f1bb4374f25ed079523c37d3e19\", \"app_shell_unittests\": \"2c364030378e313f80e2088fd567a1cb3fa587e5\", \"aura_unittests\": \"bedbda78f2ff3e299b71a0b5c00d8ef5c0433766\", \"base_unittests\": \"a441c31e5e92e9c978a9c487544ff7557d74fc8c\", \"base_util_unittests\": \"589452762fb420728f1a0dc850842c8ea8d45bdb\", \"blink_common_unittests\": \"ae64ed0716c763ff412ba225a97e404ac89bbb91\", \"blink_fuzzer_unittests\": \"231c28959125085c2f7bab7ee307d98d2a34aa61\", \"blink_heap_unittests\": \"28d485cb484caa18c86ace8008b77af27663bb9a\", \"blink_platform_unittests\": \"a5b2472379ac46c3bb1141783b30ed9ef38d12d6\", \"blink_unittests\": \"95a241eb52edbf242c3f4b7799fc71ffa13b846c\", \"boringssl_crypto_tests\": \"93c1021797e45dea907be7f47ed420353fcf0278\", \"boringssl_ssl_tests\": \"dc08da3eca46433ab1978383a04323505c2f71a1\", \"browser_switcher_bho_unittests\": \"a75b90f2c894b66e990c63c7ca627e03f9de3017\", \"browser_tests\": \"bbc2e4db55b4b8807ff53be06b907b2d7a761ea6\", \"capture_unittests\": \"f83b8a2033ff341206d87e3215e67f0ee54939be\", \"cast_unittests\": \"9ec5765f3108739e78a8f90c8c6504a99119401d\", \"cc_unittests\": \"87221f60c18390a2bb210f1c76c8d88e01606158\", \"chrome_app_unittests\": \"326454fee4eff7f90e361d3972956a3797daa28e\", \"chrome_cleaner_unittests\": \"519f1505a7cf72fd5835c2baffd840a0183f2a2e\", \"chrome_elf_unittests\": \"36b99ca61680105a08e756ab259af2efa5c9c011\", \"chromedriver_unittests\": \"a4efb5a8d95771c4035750e1cde4dcebfe3114ea\", \"components_browsertests\": \"b4e999ac8f42b503befe09465439b7382b0d6be1\", \"components_unittests\": \"0b61871bf3197a0558de05ff315d51ddbb902db3\", \"compositor_unittests\": \"2c7ae790ab19e779db5144c0cc3a1cb916c3d954\", \"content_browsertests\": \"5e6d4a1c366088afd39246b6c0c0f614906195e4\", \"content_unittests\": \"9ecb7cb45a805aafd9cf2cedb5140e41d53cf2d2\", \"courgette_unittests\": \"8f418bbd71cdbf2baf42c46962004f68dc715c37\", \"crashpad_tests\": \"568e1be747b46173c575cfc7a1260eee60873604\", \"cronet_tests\": \"7a8f161e6566689d7227f0220fff7ca92ca18c62\", \"cronet_unittests\": \"43126e7220b82a1ab0e184f04696b1f246dd326e\", \"crypto_unittests\": \"e71ce40856d68627c7ddfc6b8b1f759ea43965f0\", \"delayloads_unittests\": \"fb2c5d121aaa79b7b55d3dc2f19290f83836a2c6\", \"device_unittests\": \"5d54875bebf13e4b9f7883fe6c662ebf47f650d7\", \"display_unittests\": \"1b1d93b319c3bd004bf4d0a1d684d303aca35f27\", \"elevation_service_unittests\": \"6009d5018826ecdbae868098350c065132d53bd4\", \"events_unittests\": \"b0b2c1324ce33185a792ba996253f36d634946b6\", \"extensions_browsertests\": \"9e3f4845f2d3307c34df6f5b0fd9d9f3c002e137\", \"extensions_unittests\": \"4e9c8dd61b23d1a6aaf2f0307e9bae70aff437ed\", \"filesystem_service_unittests\": \"7fd836c823dd7c3edc06cb98997cb9b591f3511f\", \"gcm_unit_tests\": \"4768e64bbc7bcec654bcd825953d02b5463cbc3b\", \"gcp_unittests\": \"1d4f73ce4691db491b6f83c50c429b613819000b\", \"gfx_unittests\": \"b295e80a2754126d92b234e540813450fb427d3a\", \"gin_unittests\": \"d98d2b3537a57325b393b92fe40622f905e26176\", \"google_apis_unittests\": \"57f1312245d8479677ddc1eb786409768fa691ac\", \"gpu_unittests\": \"257c33cd8e3f1173812837e2827fb92caf0401b6\", \"gwp_asan_unittests\": \"227915b6f90ee81dcbf226dbcc48ed0e77b39873\", \"headless_browsertests\": \"2b4658832bd069a12ffa8792f3ddaaee68c41595\", \"headless_unittests\": \"1998b4cc7311c2c2200b4a3b3cff661fe1e5475f\", \"install_static_unittests\": \"8bb8d0aae532c6d5dde1e4213a05016774f54db6\", \"installer_util_unittests\": \"e442f634293868bd6d0265bfcce6eac61db87f47\", \"interactive_ui_tests\": \"a28c5fb4047e1c2bdacf1d770ab4cc4f7ec9f82c\", \"ipc_tests\": \"e5ebe5f7419aeb9dbacfb748a368fd6f4a38cd43\", \"jingle_unittests\": \"05476c0d000842efd075ac8774244987470d7b95\", \"latency_unittests\": \"0b0eb1bb1ec00fbba1e2456acbd1005ca3a216f2\", \"libjingle_xmpp_unittests\": \"b6d5c4fac8e406364dfc867be6ca29ab8a30e7c5\", \"liburlpattern_unittests\": \"fbf454f9656925a9226679522f056b020b5e7a53\", \"media_blink_unittests\": \"6c86165ab487996fa2fcfec12b5ce79cc63bab4c\", \"media_unittests\": \"a9f5e22989d6356c70739b5a349de106d32c944e\", \"message_center_unittests\": \"f8525925b975fabf9f10e12a148e0008743b0334\", \"midi_unittests\": \"09194330081ba93fde78e53317b9dc4da276defb\", \"mojo_core_unittests\": \"b295b3e25393b6bec1df1931d997b19a19c6d1e6\", \"mojo_unittests\": \"7120af8c7281af77f4729638616945752da862b2\", \"nacl_loader_unittests\": \"6c77e6a2c87e61656409f0c2b79f3380ec08339e\", \"native_theme_unittests\": \"b8e0bc611fd4b6dbe9407be270a607fce87ad05b\", \"net_unittests\": \"80213f5e9dee5b05d256a09b8d8f485b458315de\", \"notification_helper_unittests\": \"acd201d4dc183b8831e5d58178b54a160b7c599d\", \"pdf_unittests\": \"10a0e0cd7d291a793eb9d34db929c381fd4c4474\", \"perfetto_unittests\": \"7941ae818e497d76045506ac9a84d62406a5b7b3\", \"ppapi_unittests\": \"8a8f489d911a789e0dfb18bc9b2e971740884352\", \"printing_unittests\": \"ab44fba10ca4c0c6ceca68f9abc73f1cd327ccb7\", \"remoting_unittests\": \"5f097f2a3af2978474912d8ad192c34e152934cf\", \"sbox_integration_tests\": \"a9dcd9a93055c4386a50fffc87820e3ef1b008f0\", \"sbox_unittests\": \"59e6429be4189b82253dde5139efbfb1daca8cf1\", \"sbox_validation_tests\": \"81255c0458bab267d8335512835a16e9eccdebd2\", \"service_manager_unittests\": \"44a4e6f01c1aa9027d27ea272ed2be54f19a452c\", \"services_unittests\": \"f607b2d4f4d16700f43d780ed4a7e3a820597705\", \"setup_unittests\": \"f710b6bb1ac936c2737c03547fa3ecbe442c2bf4\", \"shell_dialogs_unittests\": \"c21797f1787139aa070314c76cd107577fae73c2\", \"skia_unittests\": \"e7b028ca09ae8abc2b860dfd7979f693804bd9c5\", \"snapshot_unittests\": \"4161ec7f4a60ef26182188433b15c550fb7f8ddb\", \"sql_unittests\": \"70a62189b6cddbfebb5466d28a649707c65d9a2b\", \"storage_unittests\": \"fccfda69066224b38af7f3e4ef6e07bbca639677\", \"sync_integration_tests\": \"08290ad6bc67933763299017426b4cbc2d0a8636\", \"traffic_annotation_auditor_unittests\": \"4f44dd6d6a32a9a2f276223857614bce475bfda5\", \"ui_base_unittests\": \"78488e3ec196a9cb48799f2c482d8f260155ef2b\", \"ui_touch_selection_unittests\": \"39f5969f224567da04d5fb5b5a585c067c59e6e5\", \"unit_tests\": \"1e381310f072c8c16db44c461db7c5f8f456394a\", \"updater_tests\": \"f6ff5acc437e89750786e7fbd2df123088612a85\", \"url_unittests\": \"b612bb8cfefe3f0290287dd5f85c340bb6fd5bf2\", \"views_unittests\": \"c1fbc587995b8abf69df4ff0076258ce77b3e39f\", \"viz_unittests\": \"d0c35ca18f687b096a593c528ace3a61007087b0\", \"vr_common_unittests\": \"2de13d8089af6b9a659ca42dedd27eb6cd5c0d17\", \"vr_pixeltests\": \"6ed430cba967bdee4beb8fa79128e106c8a8955a\", \"weblayer_browsertests\": \"dedb56b1cc0d804688f619ec7d9e7f6a8b987c0c\", \"weblayer_unittests\": \"b87283a83e01b946e0851ca3bd934ead3b98c49d\", \"wm_unittests\": \"7b8d356615193cd6d5e1d3365892181e94f0a500\", \"wtf_unittests\": \"15730c362378d62f13f642f6b082eeaa76dc8817\", \"zlib_unittests\": \"2b1a6aa0dd79a978368cfbb9c045d29615bb49c9\", \"zucchini_unittests\": \"637e98e80f9e718a18d0435875e94b20a681d5e8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-z6mr\"], \"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\": [\"5640-d0eb544\"], \"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": "1607457938269554",
"status": "COMPLETED",
"status_changed_ts": "1607461534944258",
"tags": [
"build_address:luci.chromium.ci/win-asan/16105",
"builder:win-asan",
"buildset:commit/git/4a5d5cdc895b6e27a9842b7416f948180276a25e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4a5d5cdc895b6e27a9842b7416f948180276a25e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026881966533444768",
"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/8861451904595816848/+/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:505cc718806aa210",
"user_agent:luci-scheduler"
],
"updated_ts": "1607461534993981",
"url": "https://ci.chromium.org/b/8861451904595816848",
"utcnow_ts": "1607461535984965"
}
}
[21:05:35.994] Invocation finished in 1h0m2.499849925s with status SUCCEEDED