[13:03:00.865] New invocation is queued and will start shortly
[13:03:03.386] Starting the invocation (attempt 1)
[13:03:03.442] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:03:03.447] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:03:03.447] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023556462951118240",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"97adba472d4bf3f97675b5d9eade34526ebf730c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023556462951118240",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/97adba472d4bf3f97675b5d9eade34526ebf730c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/97adba472d4bf3f97675b5d9eade34526ebf730c",
"gitiles_ref:refs/heads/master"
]
}
[13:03:04.857] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1610629383577416",
"id": "8858126399444748096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"97adba472d4bf3f97675b5d9eade34526ebf730c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610629384621056",
"tags": [
"build_address:luci.chromium.ci/win-asan/16816",
"builder:win-asan",
"buildset:commit/git/97adba472d4bf3f97675b5d9eade34526ebf730c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/97adba472d4bf3f97675b5d9eade34526ebf730c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023556462951118240",
"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/8858126399444748096/+/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": "1610629384621163",
"url": "https://ci.chromium.org/b/8858126399444748096",
"utcnow_ts": "1610629384815301"
}
}
[13:03:04.857] Task URL: https://ci.chromium.org/b/8858126399444748096
[13:03:04.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:2:0) after 1m0s
[13:03:09.832] Received PubSub notification, asking Buildbucket for the build status
[13:03:10.177] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:04.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:2:0)
[13:04:04.877] Timer tick, asking Buildbucket for the build status
[13:04:05.099] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:05.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:4:0) after 1m0s
[13:05:05.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:4:0)
[13:05:05.107] Timer tick, asking Buildbucket for the build status
[13:05:05.642] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:05.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:5:0) after 1m0s
[13:06:05.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:5:0)
[13:06:05.662] Timer tick, asking Buildbucket for the build status
[13:06:06.014] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:06.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:6:0) after 1m0s
[13:07:06.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:6:0)
[13:07:06.026] Timer tick, asking Buildbucket for the build status
[13:07:06.434] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:06.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:7:0) after 1m0s
[13:08:06.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:7:0)
[13:08:06.511] Timer tick, asking Buildbucket for the build status
[13:08:07.410] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:07.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:8:0) after 1m0s
[13:09:07.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:8:0)
[13:09:07.419] Timer tick, asking Buildbucket for the build status
[13:09:08.112] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:08.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:9:0) after 1m0s
[13:10:08.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:9:0)
[13:10:08.180] Timer tick, asking Buildbucket for the build status
[13:10:08.402] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:08.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:10:0) after 1m0s
[13:11:08.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:10:0)
[13:11:08.427] Timer tick, asking Buildbucket for the build status
[13:11:08.686] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:08.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:11:0) after 1m0s
[13:12:08.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:11:0)
[13:12:08.720] Timer tick, asking Buildbucket for the build status
[13:12:08.914] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:08.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:12:0) after 1m0s
[13:13:08.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:12:0)
[13:13:08.909] Timer tick, asking Buildbucket for the build status
[13:13:09.142] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:09.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:13:0) after 1m0s
[13:14:09.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:13:0)
[13:14:09.212] Timer tick, asking Buildbucket for the build status
[13:14:09.331] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:09.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:14:0) after 1m0s
[13:15:09.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:14:0)
[13:15:09.413] Timer tick, asking Buildbucket for the build status
[13:15:09.813] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:09.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:15:0) after 1m0s
[13:16:09.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:15:0)
[13:16:09.864] Timer tick, asking Buildbucket for the build status
[13:16:10.303] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:10.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:16:0) after 1m0s
[13:17:10.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:16:0)
[13:17:10.330] Timer tick, asking Buildbucket for the build status
[13:17:10.815] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:10.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:17:0) after 1m0s
[13:18:10.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:17:0)
[13:18:10.840] Timer tick, asking Buildbucket for the build status
[13:18:10.948] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:10.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:18:0) after 1m0s
[13:19:10.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:18:0)
[13:19:10.978] Timer tick, asking Buildbucket for the build status
[13:19:13.848] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:13.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:19:0) after 1m0s
[13:20:13.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:19:0)
[13:20:13.885] Timer tick, asking Buildbucket for the build status
[13:20:14.019] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:14.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:20:0) after 1m0s
[13:21:14.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:20:0)
[13:21:14.079] Timer tick, asking Buildbucket for the build status
[13:21:14.650] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:14.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:21:0) after 1m0s
[13:22:14.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:21:0)
[13:22:14.772] Timer tick, asking Buildbucket for the build status
[13:22:14.952] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:14.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:22:0) after 1m0s
[13:23:15.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:22:0)
[13:23:15.054] Timer tick, asking Buildbucket for the build status
[13:23:15.611] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:15.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:23:0) after 1m0s
[13:24:15.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:23:0)
[13:24:15.669] Timer tick, asking Buildbucket for the build status
[13:24:15.828] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:15.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:24:0) after 1m0s
[13:25:15.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:24:0)
[13:25:15.929] Timer tick, asking Buildbucket for the build status
[13:25:16.047] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:16.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:25:0) after 1m0s
[13:26:16.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:25:0)
[13:26:16.102] Timer tick, asking Buildbucket for the build status
[13:26:16.504] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:16.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:26:0) after 1m0s
[13:27:16.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:26:0)
[13:27:16.562] Timer tick, asking Buildbucket for the build status
[13:27:17.005] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:17.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:27:0) after 1m0s
[13:28:17.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:27:0)
[13:28:17.063] Timer tick, asking Buildbucket for the build status
[13:28:17.409] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:17.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:28:0) after 1m0s
[13:29:17.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:28:0)
[13:29:17.471] Timer tick, asking Buildbucket for the build status
[13:29:17.553] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:17.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:29:0) after 1m0s
[13:30:17.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:29:0)
[13:30:17.574] Timer tick, asking Buildbucket for the build status
[13:30:17.653] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:17.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:30:0) after 1m0s
[13:31:17.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:30:0)
[13:31:17.671] Timer tick, asking Buildbucket for the build status
[13:31:18.063] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:18.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:31:0) after 1m0s
[13:32:18.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:31:0)
[13:32:18.095] Timer tick, asking Buildbucket for the build status
[13:32:18.237] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:18.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:32:0) after 1m0s
[13:33:18.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:32:0)
[13:33:18.292] Timer tick, asking Buildbucket for the build status
[13:33:18.397] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:18.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:33:0) after 1m0s
[13:34:18.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:33:0)
[13:34:18.420] Timer tick, asking Buildbucket for the build status
[13:34:18.539] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:18.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:34:0) after 1m0s
[13:35:18.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:34:0)
[13:35:18.561] Timer tick, asking Buildbucket for the build status
[13:35:19.000] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:19.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:35:0) after 1m0s
[13:36:19.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:35:0)
[13:36:19.026] Timer tick, asking Buildbucket for the build status
[13:36:19.521] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:19.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:36:0) after 1m0s
[13:37:19.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:36:0)
[13:37:19.528] Timer tick, asking Buildbucket for the build status
[13:37:19.599] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:19.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:37:0) after 1m0s
[13:38:19.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:37:0)
[13:38:19.577] Timer tick, asking Buildbucket for the build status
[13:38:19.713] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:19.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:38:0) after 1m0s
[13:39:19.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:38:0)
[13:39:19.782] Timer tick, asking Buildbucket for the build status
[13:39:20.102] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:20.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:39:0) after 1m0s
[13:40:20.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:39:0)
[13:40:20.141] Timer tick, asking Buildbucket for the build status
[13:40:20.277] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:20.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:40:0) after 1m0s
[13:41:20.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:40:0)
[13:41:20.304] Timer tick, asking Buildbucket for the build status
[13:41:20.418] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:20.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:41:0) after 1m0s
[13:42:20.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:41:0)
[13:42:20.462] Timer tick, asking Buildbucket for the build status
[13:42:20.589] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:20.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:42:0) after 1m0s
[13:43:20.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:42:0)
[13:43:20.636] Timer tick, asking Buildbucket for the build status
[13:43:21.236] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:21.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:43:0) after 1m0s
[13:44:21.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:43:0)
[13:44:21.257] Timer tick, asking Buildbucket for the build status
[13:44:21.384] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:21.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:44:0) after 1m0s
[13:45:21.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:44:0)
[13:45:21.433] Timer tick, asking Buildbucket for the build status
[13:45:21.584] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:21.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:45:0) after 1m0s
[13:46:21.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:45:0)
[13:46:21.605] Timer tick, asking Buildbucket for the build status
[13:46:21.912] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:21.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:46:0) after 1m0s
[13:47:21.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:46:0)
[13:47:21.937] Timer tick, asking Buildbucket for the build status
[13:47:22.252] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:22.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:47:0) after 1m0s
[13:48:22.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:47:0)
[13:48:22.251] Timer tick, asking Buildbucket for the build status
[13:48:22.355] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:22.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:48:0) after 1m0s
[13:49:22.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:48:0)
[13:49:22.351] Timer tick, asking Buildbucket for the build status
[13:49:22.521] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:22.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:49:0) after 1m0s
[13:50:22.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:49:0)
[13:50:22.586] Timer tick, asking Buildbucket for the build status
[13:50:22.716] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:22.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:50:0) after 1m0s
[13:51:22.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:50:0)
[13:51:22.741] Timer tick, asking Buildbucket for the build status
[13:51:22.815] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:22.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:51:0) after 1m0s
[13:52:22.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:51:0)
[13:52:22.931] Timer tick, asking Buildbucket for the build status
[13:52:23.199] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:23.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:52:0) after 1m0s
[13:53:23.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:52:0)
[13:53:23.271] Timer tick, asking Buildbucket for the build status
[13:53:23.774] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:23.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:53:0) after 1m0s
[13:54:23.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:53:0)
[13:54:23.847] Timer tick, asking Buildbucket for the build status
[13:54:23.930] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:23.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:54:0) after 1m0s
[13:55:23.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:54:0)
[13:55:23.969] Timer tick, asking Buildbucket for the build status
[13:55:24.133] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:24.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:55:0) after 1m0s
[13:56:24.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:55:0)
[13:56:24.301] Timer tick, asking Buildbucket for the build status
[13:56:24.844] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:24.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:56:0) after 1m0s
[13:57:24.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:56:0)
[13:57:24.874] Timer tick, asking Buildbucket for the build status
[13:57:25.017] Build 8858126399444748096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:25.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023556462951118240:57:0) after 1m0s
[13:57:58.861] Received PubSub notification, asking Buildbucket for the build status
[13:57:59.320] Build 8858126399444748096: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:57:59.321] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1610632677351359",
"created_by": "project:chromium",
"created_ts": "1610629383577416",
"id": "8858126399444748096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"97adba472d4bf3f97675b5d9eade34526ebf730c\"}}",
"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\": \"97adba472d4bf3f97675b5d9eade34526ebf730c\", \"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\": 16816, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"b421774ae9c3a78ca833a506ce1ea3baa01c3bbe\", \"got_dawn_revision\": \"247f511f198f901ce3eb0344f355f53bf9ffe59d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"97adba472d4bf3f97675b5d9eade34526ebf730c\", \"got_revision_cp\": \"refs/heads/master@{#843505}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"c80a50a3c4de747d61e044cea6f94223016110d2\", \"got_v8_revision_cp\": \"refs/heads/8.9.273@{#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\": \"97adba472d4bf3f97675b5d9eade34526ebf730c\", \"swarm_hashes_refs/heads/master(at){#843505}_without_patch\": {\"absl_hardening_tests\": \"e5d200530756ee63183975eb05cece6b271f0d95\", \"accessibility_unittests\": \"b438b08af2e5659edddd4edc297d9a7774b6d219\", \"angle_unittests\": \"fa9cee359bb98e6df1e537fe9b48a8295a3f30af\", \"app_shell_unittests\": \"d65a3972a2ade3c07e4a3ca4e89916b784cdd879\", \"aura_unittests\": \"3b2af61501be05a3825a0ddc52fccb736d5c2c6a\", \"base_unittests\": \"5fddea7a7e1a242fa75378539c8f11faee9f8c80\", \"base_util_unittests\": \"262151d1d7b6f11e1333516b147d64315a589055\", \"blink_common_unittests\": \"4234a5e21825af41f4925f946fbacb9fec957fea\", \"blink_fuzzer_unittests\": \"56389be62b17ffd8db1f06fbb43aa3d99149929a\", \"blink_heap_unittests\": \"30f46a010bf77a9cd62c5d0e0888c21eac166e56\", \"blink_platform_unittests\": \"91ee7f60329d7e27ed719b389ce6d8f704422c63\", \"blink_unittests\": \"2d9d2d7ec65479c5845c778dde31deb3e0fa6a81\", \"boringssl_crypto_tests\": \"4d55d3433385cd277560b7b757b8856f144c9052\", \"boringssl_ssl_tests\": \"f4a74d0719257cd8a75f0fc3203113baa17c1708\", \"browser_switcher_bho_unittests\": \"2140e8c5d7e45c625b12f9c245eda90eb093d4e9\", \"browser_tests\": \"5695dcaca08dbf19b7e3af2cb755b9559f9f6e00\", \"capture_unittests\": \"105f58d162bfab4f745b4849ae622416438bfe10\", \"cast_unittests\": \"c3a235835a89a40c2c173816aab0ff845fa3bd34\", \"cc_unittests\": \"f485e36ae4bb60a7db2c49a5ebbd7a8c61892a4b\", \"chrome_app_unittests\": \"c1e1350f8aaa6da7e3eaccc48c2fb7c6b82af1cc\", \"chrome_cleaner_unittests\": \"92cf8b0122e7b319c0eb41e5a782fedb88af5223\", \"chrome_elf_unittests\": \"176000bcb00efa4cf5ed04d4042e917a3ba6f7e7\", \"chromedriver_unittests\": \"11b2a09800d646399ace9a3de133d181f1c0b270\", \"components_browsertests\": \"8b32630ff2e4d48fc2794c05af931b409bab3dce\", \"components_unittests\": \"206ca796c756c08d2a11933904ca0e332e258e37\", \"compositor_unittests\": \"e5833570d32937a36076acb48603cde58182fbd4\", \"content_browsertests\": \"69655970dc0d9956c9867c8fac9a6e440c2bded7\", \"content_unittests\": \"4c4b41756e4e49e3f5343a9f6cdbdf5a1e774bf8\", \"courgette_unittests\": \"5b36c05fbf6b6a0daaeb02ba86575ec3a7f6186e\", \"crashpad_tests\": \"274aaa96e55b178c72ba7f6ea60b2494a08f27b7\", \"cronet_tests\": \"3d72c0300c84e8036ae3b3c75f7151a2b94bd4f7\", \"cronet_unittests\": \"5a5cc0454f2cd62813f22fad29baa50e4c30f6bc\", \"crypto_unittests\": \"13e3b845582a26f4f7e63923560370dc444e7ff9\", \"delayloads_unittests\": \"549cbdc97a1c56e70c32c789d8c18b1312102f62\", \"device_unittests\": \"1d7383b9edc7b26d765da61d1b165d5a3c8cfd36\", \"display_unittests\": \"d2624e2e698c932aaacad6e7b86e0ee666b9a0d2\", \"elevation_service_unittests\": \"1423ec6a7df1bba14117bfd1292ac8838c8dbb4d\", \"events_unittests\": \"f6b1870036a06ebdcce5e73a26f825916bd81e07\", \"extensions_browsertests\": \"0ae07575cd3bb6016178cc00618abc0fb91ef0ca\", \"extensions_unittests\": \"fb5685dc7a8a5c06b61442f4d62eab65376f3f87\", \"filesystem_service_unittests\": \"519f37d0a276c7c759a96edb1d035087db6e484f\", \"gcm_unit_tests\": \"9ba89fc161466703b3dad2eb16d8109a74ec2603\", \"gcp_unittests\": \"8e85ff4306f2ebfc46c8d2593e17edbf98e489e6\", \"gfx_unittests\": \"55b51df9af7717bc75c38c7bfc24e2af51e6c65a\", \"gin_unittests\": \"db00921617e1fd9e2ec4a2b8cca1d56f3c9cdb2b\", \"google_apis_unittests\": \"6c40703f8ecb3401b0becae81aa3b94d257946d6\", \"gpu_unittests\": \"bb9f3460bd6d1006ea5509519bcd6a634b506552\", \"gwp_asan_unittests\": \"0edb5e62ab3bf0fd55d1879597645b04f8f920c5\", \"headless_browsertests\": \"fd0bdb8ac0ef2fdc346582a3f17826cd07efc199\", \"headless_unittests\": \"50c75265103d275c6bd475f53701a501f401c72b\", \"install_static_unittests\": \"b34655fa879dd19a7c852488e9794b5945dff31c\", \"installer_util_unittests\": \"dbc97384cbe8a0daf029e14558f52c1438b2567b\", \"interactive_ui_tests\": \"108953bc833c252d145905d1d72331d2b85a469f\", \"ipc_tests\": \"b60cd4e6dbfbc7ff6d3f338130a4696741c18712\", \"jingle_unittests\": \"46bc3dcb2a6c2d6e1a484b130408e82a2812272b\", \"latency_unittests\": \"94faecce237c0c379aaeccef36b09a1d19434f5b\", \"libjingle_xmpp_unittests\": \"28d9f75b640c56f0722f94da46fdeda8bac30f37\", \"liburlpattern_unittests\": \"6d7aa038f5e23f9350f241bfca4beaaea063e019\", \"media_blink_unittests\": \"81e00162ab6c8a88e3163b24b468a5efc5052a4a\", \"media_unittests\": \"d8d45966c325c2f3fae646807e82ed3323e460ad\", \"message_center_unittests\": \"4676e06ca5ab11598f2c244b104ec0c7d41794bf\", \"midi_unittests\": \"92cb49e04ad7f2c8f57717ca582fe552b8040360\", \"mojo_core_unittests\": \"cc2d1f8753fe339755fda5bd8ca0c0aa3b658051\", \"mojo_unittests\": \"062abf9e8f26edc580865f660d7baa74bd3d0c16\", \"nacl_loader_unittests\": \"d681e14472cd5961256c533f91231150d07964fd\", \"native_theme_unittests\": \"3c92077c59581b98c2dcd4e622130e91a61200e2\", \"net_unittests\": \"e090bf6e981640cd7c47d3d4bf75fd4df0035a50\", \"notification_helper_unittests\": \"bf76ac810929438e5c6dbca8162c518cc9780954\", \"pdf_unittests\": \"0c233cc924eba0e432bb20642593baaf7f640376\", \"perfetto_unittests\": \"d232aaa00814b97103f49b55419728a7ea8b169f\", \"ppapi_unittests\": \"33009a2b6baf269d6baa3c045c489adfc9be3327\", \"printing_unittests\": \"f8389c7c38a4c22710a603145ddcc65b6956b2bd\", \"remoting_unittests\": \"c75b021c8458dba2a88282f64d92e4570e4d5500\", \"sbox_integration_tests\": \"918c44c0dba0a7476ac0403e32c1ab20a0c3b4fa\", \"sbox_unittests\": \"3e90ce37a98cfc8214fa7bf36f691328e5fe1340\", \"sbox_validation_tests\": \"8e927bdb66d094882b16bd9dd2885175f1d53b0e\", \"service_manager_unittests\": \"8873e33e7f606fe5f5acdeb63312c214c0853480\", \"services_unittests\": \"4f28af323fab70e6b8e4b8220cc6ba5737cb9b46\", \"setup_unittests\": \"87f9af6f06a3d98bb66a4f7c15cdccbc53c18a20\", \"shell_dialogs_unittests\": \"21286863a59c0daf5d129c16be69325587d6e1bc\", \"skia_unittests\": \"cd4170b304af356fd7bc7050a281610073383fff\", \"snapshot_unittests\": \"d38a3d17cb534ee753f183bbcd75c885c6cd7dea\", \"sql_unittests\": \"47c132da9e8b679a7f214ffce49b52ec01323bda\", \"storage_unittests\": \"b5eafa6c8423ef028085709a5632048e45891319\", \"sync_integration_tests\": \"55925396c2dce3ef440917de5a1d8ea5647c6ede\", \"traffic_annotation_auditor_unittests\": \"2f4a7eed60d8f9bc58273b19706aca6df832f51d\", \"ui_base_unittests\": \"c83049c04da0001c0020632421c0e338a6747b80\", \"ui_touch_selection_unittests\": \"0b09bce87c20fab73088126b22e38a53144d0d08\", \"unit_tests\": \"a8c0040c4ae4e5bf1082407200ff5ec75958d952\", \"updater_tests\": \"7b83294d3ad66135c70a3d67f87b57a1e0427d76\", \"url_unittests\": \"2cd8594fcc2595a70a3651cad0e65e288c3ad289\", \"views_unittests\": \"184392c17dfc00030ed9303841fc8a2335dadad6\", \"viz_unittests\": \"1f60df319261626480de69f254c910ee85db5d6c\", \"vr_common_unittests\": \"bc5b2eb0ec708b7bcecdc851f9d2aae6ea9fe8d4\", \"vr_pixeltests\": \"7999fd19d74e679082ffbc33b64b98dabd71183d\", \"weblayer_browsertests\": \"6d4d9f5025df0ca30b3efd45503316abd5953590\", \"weblayer_unittests\": \"1bd30d3084e4251b0c22ba98506ca3a976bf9ba0\", \"wm_unittests\": \"c009890b12e75f68cbbf588928780a41136cb0b9\", \"wtf_unittests\": \"31a2f047a9b7ac15e9ccfe66a9e0b43072dce9b3\", \"zlib_unittests\": \"a210b2dc73ef9bd9735550fd6e75ca91eed09365\", \"zucchini_unittests\": \"b306dc77361d37da49650fb7266fa492967c7b40\"}}, \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610629388824388",
"status": "COMPLETED",
"status_changed_ts": "1610632678121887",
"tags": [
"build_address:luci.chromium.ci/win-asan/16816",
"builder:win-asan",
"buildset:commit/git/97adba472d4bf3f97675b5d9eade34526ebf730c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/97adba472d4bf3f97675b5d9eade34526ebf730c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023556462951118240",
"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/8858126399444748096/+/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:5119cf92c4e8bb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610632678135013",
"url": "https://ci.chromium.org/b/8858126399444748096",
"utcnow_ts": "1610632679290074"
}
}
[13:57:59.323] Invocation finished in 54m58.473626366s with status SUCCEEDED