[09:11:00.697] New invocation is queued and will start shortly
[09:11:02.288] Starting the invocation (attempt 1)
[09:11:02.349] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:11:02.349] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:11:02.350] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020490762493175344",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a87e456ca7851cf11779ea1426583d8fd92dcd08\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020490762493175344",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a87e456ca7851cf11779ea1426583d8fd92dcd08",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a87e456ca7851cf11779ea1426583d8fd92dcd08",
"gitiles_ref:refs/heads/master"
]
}
[09:11:03.047] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613553062448634",
"id": "8855060699949251088",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a87e456ca7851cf11779ea1426583d8fd92dcd08\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613553062904972",
"tags": [
"build_address:luci.chromium.ci/win-asan/17389",
"builder:win-asan",
"buildset:commit/git/a87e456ca7851cf11779ea1426583d8fd92dcd08",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a87e456ca7851cf11779ea1426583d8fd92dcd08",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020490762493175344",
"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/8855060699949251088/+/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": "1613553062905112",
"url": "https://ci.chromium.org/b/8855060699949251088",
"utcnow_ts": "1613553063030161"
}
}
[09:11:03.047] Task URL: https://ci.chromium.org/b/8855060699949251088
[09:11:03.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:2:0) after 1m0s
[09:12:03.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:2:0)
[09:12:03.129] Timer tick, asking Buildbucket for the build status
[09:12:03.363] Build 8855060699949251088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:12:03.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:3:0) after 1m0s
[09:12:18.098] Received PubSub notification, asking Buildbucket for the build status
[09:12:18.188] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:03.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:3:0)
[09:13:03.451] Timer tick, asking Buildbucket for the build status
[09:13:03.634] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:03.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:5:0) after 1m0s
[09:14:03.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:5:0)
[09:14:03.692] Timer tick, asking Buildbucket for the build status
[09:14:03.815] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:03.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:6:0) after 1m0s
[09:15:03.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:6:0)
[09:15:03.993] Timer tick, asking Buildbucket for the build status
[09:15:04.107] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:04.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:7:0) after 1m0s
[09:16:04.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:7:0)
[09:16:04.200] Timer tick, asking Buildbucket for the build status
[09:16:04.287] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:04.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:8:0) after 1m0s
[09:17:04.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:8:0)
[09:17:04.289] Timer tick, asking Buildbucket for the build status
[09:17:04.660] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:04.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:9:0) after 1m0s
[09:18:04.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:9:0)
[09:18:04.693] Timer tick, asking Buildbucket for the build status
[09:18:04.905] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:04.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:10:0) after 1m0s
[09:19:04.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:10:0)
[09:19:04.985] Timer tick, asking Buildbucket for the build status
[09:19:05.107] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:05.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:11:0) after 1m0s
[09:20:05.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:11:0)
[09:20:05.126] Timer tick, asking Buildbucket for the build status
[09:20:05.201] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:05.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:12:0) after 1m0s
[09:21:05.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:12:0)
[09:21:05.246] Timer tick, asking Buildbucket for the build status
[09:21:05.313] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:05.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:13:0) after 1m0s
[09:22:05.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:13:0)
[09:22:05.409] Timer tick, asking Buildbucket for the build status
[09:22:05.532] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:05.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:14:0) after 1m0s
[09:23:05.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:14:0)
[09:23:05.617] Timer tick, asking Buildbucket for the build status
[09:23:06.357] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:06.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:15:0) after 1m0s
[09:24:06.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:15:0)
[09:24:06.383] Timer tick, asking Buildbucket for the build status
[09:24:06.490] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:06.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:16:0) after 1m0s
[09:25:06.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:16:0)
[09:25:06.578] Timer tick, asking Buildbucket for the build status
[09:25:06.725] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:06.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:17:0) after 1m0s
[09:26:06.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:17:0)
[09:26:06.788] Timer tick, asking Buildbucket for the build status
[09:26:07.346] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:07.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:18:0) after 1m0s
[09:27:07.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:18:0)
[09:27:07.437] Timer tick, asking Buildbucket for the build status
[09:27:07.543] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:07.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:19:0) after 1m0s
[09:28:07.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:19:0)
[09:28:07.545] Timer tick, asking Buildbucket for the build status
[09:28:07.734] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:07.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:20:0) after 1m0s
[09:29:07.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:20:0)
[09:29:07.751] Timer tick, asking Buildbucket for the build status
[09:29:07.987] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:07.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:21:0) after 1m0s
[09:30:08.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:21:0)
[09:30:08.065] Timer tick, asking Buildbucket for the build status
[09:30:08.149] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:08.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:22:0) after 1m0s
[09:31:08.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:22:0)
[09:31:08.223] Timer tick, asking Buildbucket for the build status
[09:31:08.650] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:08.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:23:0) after 1m0s
[09:32:08.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:23:0)
[09:32:08.681] Timer tick, asking Buildbucket for the build status
[09:32:08.927] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:08.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:24:0) after 1m0s
[09:33:08.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:24:0)
[09:33:08.969] Timer tick, asking Buildbucket for the build status
[09:33:09.285] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:09.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:25:0) after 1m0s
[09:34:09.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:25:0)
[09:34:09.378] Timer tick, asking Buildbucket for the build status
[09:34:09.923] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:09.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:26:0) after 1m0s
[09:35:10.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:26:0)
[09:35:10.007] Timer tick, asking Buildbucket for the build status
[09:35:10.211] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:10.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:27:0) after 1m0s
[09:36:10.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:27:0)
[09:36:10.243] Timer tick, asking Buildbucket for the build status
[09:36:10.372] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:10.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:28:0) after 1m0s
[09:37:10.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:28:0)
[09:37:10.376] Timer tick, asking Buildbucket for the build status
[09:37:10.743] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:10.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:29:0) after 1m0s
[09:38:10.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:29:0)
[09:38:10.810] Timer tick, asking Buildbucket for the build status
[09:38:10.946] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:10.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:30:0) after 1m0s
[09:39:10.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:30:0)
[09:39:10.976] Timer tick, asking Buildbucket for the build status
[09:39:11.073] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:11.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:31:0) after 1m0s
[09:40:11.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:31:0)
[09:40:11.145] Timer tick, asking Buildbucket for the build status
[09:40:11.323] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:11.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:32:0) after 1m0s
[09:41:11.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:32:0)
[09:41:11.383] Timer tick, asking Buildbucket for the build status
[09:41:11.467] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:11.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:33:0) after 1m0s
[09:42:11.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:33:0)
[09:42:11.479] Timer tick, asking Buildbucket for the build status
[09:42:11.819] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:11.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:34:0) after 1m0s
[09:43:11.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:34:0)
[09:43:11.845] Timer tick, asking Buildbucket for the build status
[09:43:11.994] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:11.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:35:0) after 1m0s
[09:44:12.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:35:0)
[09:44:12.064] Timer tick, asking Buildbucket for the build status
[09:44:12.202] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:12.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:36:0) after 1m0s
[09:45:12.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:36:0)
[09:45:12.173] Timer tick, asking Buildbucket for the build status
[09:45:12.371] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:12.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:37:0) after 1m0s
[09:46:12.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:37:0)
[09:46:12.390] Timer tick, asking Buildbucket for the build status
[09:46:12.479] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:12.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:38:0) after 1m0s
[09:47:12.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:38:0)
[09:47:12.571] Timer tick, asking Buildbucket for the build status
[09:47:12.731] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:12.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:39:0) after 1m0s
[09:48:12.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:39:0)
[09:48:12.777] Timer tick, asking Buildbucket for the build status
[09:48:12.936] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:12.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:40:0) after 1m0s
[09:49:12.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:40:0)
[09:49:12.954] Timer tick, asking Buildbucket for the build status
[09:49:13.078] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:13.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:41:0) after 1m0s
[09:50:13.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:41:0)
[09:50:13.116] Timer tick, asking Buildbucket for the build status
[09:50:13.249] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:13.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:42:0) after 1m0s
[09:51:13.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:42:0)
[09:51:13.496] Timer tick, asking Buildbucket for the build status
[09:51:13.708] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:13.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:43:0) after 1m0s
[09:52:13.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:43:0)
[09:52:13.793] Timer tick, asking Buildbucket for the build status
[09:52:13.924] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:13.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:44:0) after 1m0s
[09:53:14.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:44:0)
[09:53:14.095] Timer tick, asking Buildbucket for the build status
[09:53:14.202] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:14.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:45:0) after 1m0s
[09:54:14.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:45:0)
[09:54:14.274] Timer tick, asking Buildbucket for the build status
[09:54:14.404] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:14.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:46:0) after 1m0s
[09:55:14.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:46:0)
[09:55:14.556] Timer tick, asking Buildbucket for the build status
[09:55:14.862] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:14.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:47:0) after 1m0s
[09:56:14.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:47:0)
[09:56:14.914] Timer tick, asking Buildbucket for the build status
[09:56:15.067] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:15.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:48:0) after 1m0s
[09:57:15.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:48:0)
[09:57:15.087] Timer tick, asking Buildbucket for the build status
[09:57:15.225] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:15.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:49:0) after 1m0s
[09:58:15.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:49:0)
[09:58:15.224] Timer tick, asking Buildbucket for the build status
[09:58:15.371] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:15.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:50:0) after 1m0s
[09:59:15.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:50:0)
[09:59:15.391] Timer tick, asking Buildbucket for the build status
[09:59:15.493] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:15.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:51:0) after 1m0s
[10:00:15.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:51:0)
[10:00:15.513] Timer tick, asking Buildbucket for the build status
[10:00:15.943] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:15.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:52:0) after 1m0s
[10:01:15.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:52:0)
[10:01:15.963] Timer tick, asking Buildbucket for the build status
[10:01:16.067] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:16.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:53:0) after 1m0s
[10:02:16.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:53:0)
[10:02:16.068] Timer tick, asking Buildbucket for the build status
[10:02:16.690] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:16.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:54:0) after 1m0s
[10:03:16.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:54:0)
[10:03:16.696] Timer tick, asking Buildbucket for the build status
[10:03:16.863] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:16.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:55:0) after 1m0s
[10:04:16.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:55:0)
[10:04:16.956] Timer tick, asking Buildbucket for the build status
[10:04:17.133] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:17.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:56:0) after 1m0s
[10:05:17.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:56:0)
[10:05:17.198] Timer tick, asking Buildbucket for the build status
[10:05:17.331] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:17.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:57:0) after 1m0s
[10:06:17.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:57:0)
[10:06:17.366] Timer tick, asking Buildbucket for the build status
[10:06:17.470] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:17.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:58:0) after 1m0s
[10:07:17.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:58:0)
[10:07:17.490] Timer tick, asking Buildbucket for the build status
[10:07:17.604] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:17.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:59:0) after 1m0s
[10:08:17.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:59:0)
[10:08:17.797] Timer tick, asking Buildbucket for the build status
[10:08:17.900] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:17.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:60:0) after 1m0s
[10:09:18.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:60:0)
[10:09:18.011] Timer tick, asking Buildbucket for the build status
[10:09:18.123] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:18.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:61:0) after 1m0s
[10:10:18.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:61:0)
[10:10:18.231] Timer tick, asking Buildbucket for the build status
[10:10:18.347] Build 8855060699949251088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:18.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020490762493175344:62:0) after 1m0s
[10:10:43.676] Received PubSub notification, asking Buildbucket for the build status
[10:10:43.984] Build 8855060699949251088: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:10:43.984] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613556642735070",
"created_by": "project:chromium",
"created_ts": "1613553062448634",
"id": "8855060699949251088",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a87e456ca7851cf11779ea1426583d8fd92dcd08\"}}",
"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\": \"a87e456ca7851cf11779ea1426583d8fd92dcd08\", \"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-0-6l07\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17389, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"3c28b2a0b4813f94730b2e8ca29d490bfb5d4c41\", \"got_dawn_revision\": \"a5ba2827f5810b7b1cd6ee046e8df6e109bb02b6\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"a87e456ca7851cf11779ea1426583d8fd92dcd08\", \"got_revision_cp\": \"refs/heads/master@{#854682}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"bb04803b8daf08e186b327d18e14c78378f44e37\", \"got_v8_revision\": \"972f21b5ed71a418e8ed86f3b39bb7c2b7f175ac\", \"got_v8_revision_cp\": \"refs/heads/9.0.197@{#1}\", \"got_webrtc_revision\": \"8408c9938cb543a7cd5e7127927f3b4d0834ad59\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33272}\", \"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\": \"a87e456ca7851cf11779ea1426583d8fd92dcd08\", \"swarm_hashes_refs/heads/master(at){#854682}_without_patch\": {\"absl_hardening_tests\": \"0a628f8bbc5e4d80f255e4b53544929b307f2682\", \"accessibility_unittests\": \"6c982cb569a223b4dad62d4cd4d7b2cb545befe0\", \"angle_unittests\": \"051363136f5adad8eed838bc06103cf458df1100\", \"app_shell_unittests\": \"a3a87e6706ead541821ada9b014a1b783e0a92f3\", \"aura_unittests\": \"0f75dec020be08988dd3ce62f010ea343b35146b\", \"base_unittests\": \"4a45a35674d0311aa6f4807523b26e137a3faa18\", \"base_util_unittests\": \"8d1377081e5594a083a4c064236793ec057d0cbb\", \"blink_common_unittests\": \"f9d064cebaec26e1c8389d46c53a8f1ade997340\", \"blink_fuzzer_unittests\": \"07d9cabdea8caa232a600489b383738767315516\", \"blink_heap_unittests\": \"3e2a2774811e9a5f724416d12f728649bbe7b21b\", \"blink_platform_unittests\": \"ac19daae04e7b81718c89d3ecfc65b20c031a499\", \"blink_unittests\": \"883c2f1d36694cdba1ec704c655a59dbe0daf6d8\", \"boringssl_crypto_tests\": \"f4e41508c5b4b4e159cc09a8c56e71372db9a544\", \"boringssl_ssl_tests\": \"fc9d455abec4391f041c03a65d40e501c2abfb53\", \"browser_switcher_bho_unittests\": \"6c41b36760843f6e9337a3bea68312661d7f8fbe\", \"browser_tests\": \"d22ae4f56820846d0c1bd16ae1bf157c89be98d9\", \"capture_unittests\": \"36c8d57f0714e9484546702d540551a201b41b3f\", \"cast_unittests\": \"0c38ab873a633a1e6a3e5c9237fa5c3770eeb7f1\", \"cc_unittests\": \"b552792cbcf7c8f66d7511ab8dbb318628ae3b26\", \"chrome_app_unittests\": \"7c5870f1648580406293b8b11487e1cdbead7ccb\", \"chrome_cleaner_unittests\": \"bd5968f850b3f19c2b31f5f9d26b67801afac8ab\", \"chrome_elf_unittests\": \"f0cfee8b81839cab13c3b51394d6581cf8b5dd0b\", \"chromedriver_unittests\": \"77093aa9481661553c31980df6db02652649f53b\", \"components_browsertests\": \"448167606b9352edc231196b864f3c54cea81092\", \"components_unittests\": \"b225d54de178802d501c3a9033dcc72717876323\", \"compositor_unittests\": \"293f0454f3c36460cbfac310d4a4a66e9375de18\", \"content_browsertests\": \"3b502cbdd7bce0dc52a1eedc879fac6150653b99\", \"content_unittests\": \"d5ea231d1d30bd24eaa7536454420facacc0d5e3\", \"courgette_unittests\": \"cf7afa69021ceb9060662627790f9685e9f43757\", \"crashpad_tests\": \"20526affcd66c48b9d6c0bd3a632054bec6eed91\", \"cronet_tests\": \"2a520bbaf290099e65eece6adde67c9b19cf0954\", \"cronet_unittests\": \"2ed31bb4c6495b411d1b8b6bde4072b102e0f0ce\", \"crypto_unittests\": \"ae9bf65d511dfa13a835f94a231594f6bb6ae2a2\", \"delayloads_unittests\": \"7b4f436b595106eace5822a0b307ad82c85dc9c4\", \"device_unittests\": \"4fc02f22c6f56ba68bffb17f996f67dceeff1ed7\", \"display_unittests\": \"6a2af6c8de95c755524ea783177be58f78c1ce1c\", \"elevation_service_unittests\": \"100114c7928b928784019cc9f0525c6f7a3ef796\", \"events_unittests\": \"aa9bab7701484ae89e7f7371ff5fa30aa32d81d8\", \"extensions_browsertests\": \"9d1ce7c913edac2e20e219d7a3fa1b207839b1fc\", \"extensions_unittests\": \"3724c56b1dea53fac83ff7057afd89711d2fe3b0\", \"filesystem_service_unittests\": \"cce1d229aeb4457f5aa939c89a5b4fc0fe938aa0\", \"gcm_unit_tests\": \"f1769bdc74ecfb0c156d08f039b559b625542b6d\", \"gcp_unittests\": \"8a934ea203e0ba9c9cdc033a2433ec71ac372db6\", \"gfx_unittests\": \"52224f91419a3450d8630bd3d2f0c0c69b2fa640\", \"gin_unittests\": \"a9c74a2b2d1be267792e2401968d08563954f70a\", \"google_apis_unittests\": \"bc264833f9c967b81ce99231845bb42f99eb7ab3\", \"gpu_unittests\": \"085f95764faa15775703dccfdf8e67e82679510c\", \"gwp_asan_unittests\": \"5776f4c095658ba92eab1357fd7a1b97acda422a\", \"headless_browsertests\": \"a4a13c5e83bdc27481002011590b730c9cb624c7\", \"headless_unittests\": \"c2b32b450f031c3eff12e99605659e9115b2e0c7\", \"install_static_unittests\": \"836c0f877f0f444396a0a0bb2d3a5fe141e6b940\", \"installer_util_unittests\": \"e925ac33401978b566f3281c334039b177a83ee9\", \"interactive_ui_tests\": \"52e57169ce4cc68b53b0e01fe0ad078407332307\", \"ipc_tests\": \"4af2abbebcf5a66b7c58d0fea3555c95f9e5427d\", \"jingle_unittests\": \"a84c6550829c0b3ea9ec226d05fbdfc012aafe91\", \"latency_unittests\": \"5ffbae5311ce93946ff3fb01884acd7ccbb79e56\", \"libjingle_xmpp_unittests\": \"d1b7d1bb989320ba7658d0dc8c9662136928cbf1\", \"liburlpattern_unittests\": \"4d1cf9472165a27ded000a3f45fb596c3e44596f\", \"media_blink_unittests\": \"72fdf659e5032133f75fa96bf3370908ea20f057\", \"media_unittests\": \"f461231617b310d2e93a1883a4e8df5b4a8c06fa\", \"message_center_unittests\": \"19ee72f99d42f0bd3199a766a3a682fb987ff2bf\", \"midi_unittests\": \"373b263c33c6bb08f4da125eb1739c83d148e853\", \"mojo_core_unittests\": \"52565d1b7baf58b23bd3f10aa90813026809a3fb\", \"mojo_unittests\": \"d2575a3e7fdd5942324ef07ae438b5a201e3ecc8\", \"nacl_loader_unittests\": \"86a15e3975819ec0d53fd758707acd865634ecff\", \"native_theme_unittests\": \"ef4848d2465932568eeb104012e2c003d30919f6\", \"net_unittests\": \"e3e19ac3b7f185aa820091ccf7e65bac8c658ff3\", \"notification_helper_unittests\": \"07381129d6faefab25ff609c15891548a8eb4534\", \"pdf_unittests\": \"38043066862e5a229da9d5887625360d75b8cb6c\", \"perfetto_unittests\": \"d30e3394cc0c61ea61deae2a317a5b8e05f0a6fd\", \"ppapi_unittests\": \"d563aac87c8817abab0421d138e637b236c8c80b\", \"printing_unittests\": \"6a17e40e82d07aa406ae778fea088b4fe11c74d5\", \"remoting_unittests\": \"2f645028f9651ef8cd5e5e364600cf1dc9b5d329\", \"sbox_integration_tests\": \"5a23210139717e79f01db286912ae2d9819827e4\", \"sbox_unittests\": \"d4c3b85299859e07fd1387d1f7d4d163bb5ff2ca\", \"sbox_validation_tests\": \"6d208b36d5669b4e0ffaf82d7e0208ab4f97860b\", \"service_manager_unittests\": \"58eb58d5f0a3406bc145c390b6eef80f7e1d6bdb\", \"services_unittests\": \"ae78314a2e098b4713deccd0faa2f38371e8411c\", \"setup_unittests\": \"fc1312828492705f335b0eb75d99cf3813170697\", \"shell_dialogs_unittests\": \"0af662c2a34ddcfadea534642f5c9df821c6b563\", \"skia_unittests\": \"3e7ed48140c56ced2b8fd086affa74f5b96234ad\", \"snapshot_unittests\": \"f959d96831a5f37be30ddf75fdfe4b60061044ba\", \"sql_unittests\": \"adea4f26b56617edb368126b44c8a967b850badb\", \"storage_unittests\": \"c109e287a49785a2f47e87d15b25f7a0da28dc4a\", \"sync_integration_tests\": \"8ae7507346231b514f886539257a1998f1ec10a5\", \"traffic_annotation_auditor_unittests\": \"922f468a27edad5513a5ea9e0d6c3ddefe20858b\", \"ui_base_unittests\": \"9dd4f9b540cd87714e418fd85081d5b65538e8aa\", \"ui_touch_selection_unittests\": \"065d24dd8560bd31faee608f3528e4ff852fd936\", \"unit_tests\": \"8040ff7d551ef7b59b8d9873894de7b3a32d623f\", \"updater_tests\": \"cc538e5958be362221a8cbff99d26a5bdd385bc3\", \"url_unittests\": \"eaddb19706c7edc82c624cab7565a13f711cd874\", \"views_unittests\": \"ecaa96fb53beffdf8fe9c2ca498737f83c548d05\", \"viz_unittests\": \"d9c92e25ef34b429b3e034d6d2624d50db3b1c5b\", \"vr_common_unittests\": \"2184a92159a8cb2d6a5c39aabb09c94ef7550bd6\", \"vr_pixeltests\": \"d7563604efd61b89c89a5e51256eb6b25659c9ae\", \"weblayer_browsertests\": \"75a6e9739ff5a59c55bc9eb23dd2bcfd3aca4a88\", \"weblayer_unittests\": \"c53b3fef5adb81dcaa9c26e153cbdebd011bf2ed\", \"wm_unittests\": \"2b3bfe2fc74ed976b072c40bc0af165cba8cd2c4\", \"wtf_unittests\": \"a547bd54a7632119fee1361bbe62a30f08e80f2b\", \"zlib_unittests\": \"47f71713589c4515d03f067008a3f9efa926bcd6\", \"zucchini_unittests\": \"0fdb3c21c5a490318e0c404ccedb5abd30e7168c\"}}, \"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-0-6l07\"], \"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\": [\"5700-b36f18b\"], \"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": "1613553137347759",
"status": "COMPLETED",
"status_changed_ts": "1613556643138851",
"tags": [
"build_address:luci.chromium.ci/win-asan/17389",
"builder:win-asan",
"buildset:commit/git/a87e456ca7851cf11779ea1426583d8fd92dcd08",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a87e456ca7851cf11779ea1426583d8fd92dcd08",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020490762493175344",
"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/8855060699949251088/+/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:51c8142e3d025d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613556643144509",
"url": "https://ci.chromium.org/b/8855060699949251088",
"utcnow_ts": "1613556643975155"
}
}
[10:10:43.984] Invocation finished in 59m43.345627765s with status SUCCEEDED