[06:22:10.141] New invocation is queued and will start shortly
[06:22:11.246] Starting the invocation (attempt 1)
[06:22:11.299] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:22:11.300] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:22:11.300] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028836306017389312",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"32d9aec64baf77c82b80e10447f256299cacbded\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028836306017389312",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/32d9aec64baf77c82b80e10447f256299cacbded",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/32d9aec64baf77c82b80e10447f256299cacbded",
"gitiles_ref:refs/heads/master"
]
}
[06:22:11.891] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605594131417955",
"id": "8863406244013458896",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"32d9aec64baf77c82b80e10447f256299cacbded\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605594131776831",
"tags": [
"build_address:luci.chromium.ci/win-asan/15681",
"builder:win-asan",
"buildset:commit/git/32d9aec64baf77c82b80e10447f256299cacbded",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/32d9aec64baf77c82b80e10447f256299cacbded",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028836306017389312",
"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/8863406244013458896/+/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": "1605594131776942",
"url": "https://ci.chromium.org/b/8863406244013458896",
"utcnow_ts": "1605594131884305"
}
}
[06:22:11.891] Task URL: https://ci.chromium.org/b/8863406244013458896
[06:22:11.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:2:0) after 1m0s
[06:22:15.856] Received PubSub notification, asking Buildbucket for the build status
[06:22:16.012] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:12.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:2:0)
[06:23:12.047] Timer tick, asking Buildbucket for the build status
[06:23:12.132] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:12.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:4:0) after 1m0s
[06:24:12.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:4:0)
[06:24:12.161] Timer tick, asking Buildbucket for the build status
[06:24:12.295] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:12.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:5:0) after 1m0s
[06:25:12.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:5:0)
[06:25:12.366] Timer tick, asking Buildbucket for the build status
[06:25:12.558] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:12.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:6:0) after 1m0s
[06:26:12.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:6:0)
[06:26:12.880] Timer tick, asking Buildbucket for the build status
[06:26:13.004] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:13.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:7:0) after 1m0s
[06:27:13.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:7:0)
[06:27:13.181] Timer tick, asking Buildbucket for the build status
[06:27:13.249] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:13.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:8:0) after 1m0s
[06:28:13.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:8:0)
[06:28:13.286] Timer tick, asking Buildbucket for the build status
[06:28:13.425] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:13.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:9:0) after 1m0s
[06:29:13.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:9:0)
[06:29:13.493] Timer tick, asking Buildbucket for the build status
[06:29:13.556] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:13.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:10:0) after 1m0s
[06:30:13.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:10:0)
[06:30:13.835] Timer tick, asking Buildbucket for the build status
[06:30:13.899] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:13.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:11:0) after 1m0s
[06:31:14.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:11:0)
[06:31:14.035] Timer tick, asking Buildbucket for the build status
[06:31:14.313] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:14.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:12:0) after 1m0s
[06:32:14.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:12:0)
[06:32:14.398] Timer tick, asking Buildbucket for the build status
[06:32:14.803] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:14.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:13:0) after 1m0s
[06:33:14.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:13:0)
[06:33:14.897] Timer tick, asking Buildbucket for the build status
[06:33:15.102] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:15.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:14:0) after 1m0s
[06:34:15.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:14:0)
[06:34:15.133] Timer tick, asking Buildbucket for the build status
[06:34:15.207] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:15.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:15:0) after 1m0s
[06:35:15.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:15:0)
[06:35:15.296] Timer tick, asking Buildbucket for the build status
[06:35:15.369] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:15.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:16:0) after 1m0s
[06:36:15.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:16:0)
[06:36:15.395] Timer tick, asking Buildbucket for the build status
[06:36:15.484] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:15.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:17:0) after 1m0s
[06:37:15.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:17:0)
[06:37:15.550] Timer tick, asking Buildbucket for the build status
[06:37:15.697] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:15.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:18:0) after 1m0s
[06:38:15.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:18:0)
[06:38:15.777] Timer tick, asking Buildbucket for the build status
[06:38:15.851] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:15.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:19:0) after 1m0s
[06:39:15.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:19:0)
[06:39:15.867] Timer tick, asking Buildbucket for the build status
[06:39:15.985] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:15.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:20:0) after 1m0s
[06:40:16.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:20:0)
[06:40:16.029] Timer tick, asking Buildbucket for the build status
[06:40:16.430] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:16.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:21:0) after 1m0s
[06:41:16.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:21:0)
[06:41:16.442] Timer tick, asking Buildbucket for the build status
[06:41:16.499] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:16.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:22:0) after 1m0s
[06:42:16.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:22:0)
[06:42:16.556] Timer tick, asking Buildbucket for the build status
[06:42:16.685] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:16.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:23:0) after 1m0s
[06:43:16.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:23:0)
[06:43:16.725] Timer tick, asking Buildbucket for the build status
[06:43:16.912] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:16.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:24:0) after 1m0s
[06:44:16.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:24:0)
[06:44:16.960] Timer tick, asking Buildbucket for the build status
[06:44:17.102] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:17.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:25:0) after 1m0s
[06:45:17.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:25:0)
[06:45:17.130] Timer tick, asking Buildbucket for the build status
[06:45:17.333] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:17.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:26:0) after 1m0s
[06:46:17.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:26:0)
[06:46:17.385] Timer tick, asking Buildbucket for the build status
[06:46:17.509] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:17.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:27:0) after 1m0s
[06:47:17.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:27:0)
[06:47:17.560] Timer tick, asking Buildbucket for the build status
[06:47:17.715] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:17.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:28:0) after 1m0s
[06:48:17.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:28:0)
[06:48:17.820] Timer tick, asking Buildbucket for the build status
[06:48:17.979] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:17.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:29:0) after 1m0s
[06:49:18.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:29:0)
[06:49:18.044] Timer tick, asking Buildbucket for the build status
[06:49:18.176] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:18.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:30:0) after 1m0s
[06:50:18.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:30:0)
[06:50:18.746] Timer tick, asking Buildbucket for the build status
[06:50:18.865] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:18.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:31:0) after 1m0s
[06:51:19.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:31:0)
[06:51:19.877] Timer tick, asking Buildbucket for the build status
[06:51:22.429] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:22.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:32:0) after 1m0s
[06:52:22.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:32:0)
[06:52:22.475] Timer tick, asking Buildbucket for the build status
[06:52:22.623] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:22.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:33:0) after 1m0s
[06:53:22.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:33:0)
[06:53:22.655] Timer tick, asking Buildbucket for the build status
[06:53:22.814] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:22.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:34:0) after 1m0s
[06:54:22.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:34:0)
[06:54:22.867] Timer tick, asking Buildbucket for the build status
[06:54:23.022] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:23.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:35:0) after 1m0s
[06:55:23.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:35:0)
[06:55:23.066] Timer tick, asking Buildbucket for the build status
[06:55:23.329] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:23.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:36:0) after 1m0s
[06:56:23.363] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:36:0)
[06:56:23.363] Timer tick, asking Buildbucket for the build status
[06:56:23.438] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:23.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:37:0) after 1m0s
[06:57:23.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:37:0)
[06:57:23.541] Timer tick, asking Buildbucket for the build status
[06:57:23.656] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:23.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:38:0) after 1m0s
[06:58:23.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:38:0)
[06:58:23.730] Timer tick, asking Buildbucket for the build status
[06:58:24.717] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:24.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:39:0) after 1m0s
[06:59:24.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:39:0)
[06:59:24.743] Timer tick, asking Buildbucket for the build status
[06:59:24.883] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:24.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:40:0) after 1m0s
[07:00:24.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:40:0)
[07:00:24.909] Timer tick, asking Buildbucket for the build status
[07:00:25.075] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:25.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:41:0) after 1m0s
[07:01:25.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:41:0)
[07:01:25.157] Timer tick, asking Buildbucket for the build status
[07:01:25.252] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:25.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:42:0) after 1m0s
[07:02:25.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:42:0)
[07:02:25.257] Timer tick, asking Buildbucket for the build status
[07:02:25.405] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:25.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:43:0) after 1m0s
[07:03:25.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:43:0)
[07:03:25.451] Timer tick, asking Buildbucket for the build status
[07:03:25.526] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:25.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:44:0) after 1m0s
[07:04:25.586] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:44:0)
[07:04:25.586] Timer tick, asking Buildbucket for the build status
[07:04:25.667] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:25.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:45:0) after 1m0s
[07:05:25.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:45:0)
[07:05:25.733] Timer tick, asking Buildbucket for the build status
[07:05:25.813] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:25.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:46:0) after 1m0s
[07:06:25.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:46:0)
[07:06:25.845] Timer tick, asking Buildbucket for the build status
[07:06:25.960] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:25.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:47:0) after 1m0s
[07:07:26.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:47:0)
[07:07:26.018] Timer tick, asking Buildbucket for the build status
[07:07:26.412] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:26.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:48:0) after 1m0s
[07:08:26.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:48:0)
[07:08:26.978] Timer tick, asking Buildbucket for the build status
[07:08:27.908] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:27.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:49:0) after 1m0s
[07:09:27.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:49:0)
[07:09:27.933] Timer tick, asking Buildbucket for the build status
[07:09:28.075] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:28.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:50:0) after 1m0s
[07:10:28.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:50:0)
[07:10:28.096] Timer tick, asking Buildbucket for the build status
[07:10:28.318] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:28.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:51:0) after 1m0s
[07:11:28.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:51:0)
[07:11:28.472] Timer tick, asking Buildbucket for the build status
[07:11:28.925] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:28.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:52:0) after 1m0s
[07:12:29.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:52:0)
[07:12:29.281] Timer tick, asking Buildbucket for the build status
[07:12:29.406] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:29.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:53:0) after 1m0s
[07:13:29.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:53:0)
[07:13:29.534] Timer tick, asking Buildbucket for the build status
[07:13:29.644] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:29.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:54:0) after 1m0s
[07:14:29.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:54:0)
[07:14:29.802] Timer tick, asking Buildbucket for the build status
[07:14:30.077] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:30.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:55:0) after 1m0s
[07:15:30.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:55:0)
[07:15:30.260] Timer tick, asking Buildbucket for the build status
[07:15:30.406] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:30.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:56:0) after 1m0s
[07:16:30.443] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:56:0)
[07:16:30.443] Timer tick, asking Buildbucket for the build status
[07:16:30.529] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:30.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:57:0) after 1m0s
[07:17:30.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:57:0)
[07:17:30.554] Timer tick, asking Buildbucket for the build status
[07:17:30.681] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:30.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:58:0) after 1m0s
[07:18:30.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:58:0)
[07:18:30.793] Timer tick, asking Buildbucket for the build status
[07:18:31.111] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:31.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:59:0) after 1m0s
[07:19:31.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:59:0)
[07:19:31.132] Timer tick, asking Buildbucket for the build status
[07:19:32.177] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:32.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:60:0) after 1m0s
[07:20:32.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:60:0)
[07:20:32.321] Timer tick, asking Buildbucket for the build status
[07:20:32.435] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:32.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:61:0) after 1m0s
[07:21:32.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:61:0)
[07:21:32.655] Timer tick, asking Buildbucket for the build status
[07:21:32.746] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:32.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:62:0) after 1m0s
[07:22:32.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:62:0)
[07:22:32.857] Timer tick, asking Buildbucket for the build status
[07:22:32.969] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:32.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:63:0) after 1m0s
[07:23:33.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:63:0)
[07:23:33.037] Timer tick, asking Buildbucket for the build status
[07:23:33.183] Build 8863406244013458896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:33.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028836306017389312:64:0) after 1m0s
[07:24:12.100] Received PubSub notification, asking Buildbucket for the build status
[07:24:12.194] Build 8863406244013458896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:24:12.194] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605597850110649",
"created_by": "project:chromium",
"created_ts": "1605594131417955",
"id": "8863406244013458896",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"32d9aec64baf77c82b80e10447f256299cacbded\"}}",
"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\": \"32d9aec64baf77c82b80e10447f256299cacbded\", \"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-5-fw95\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15681, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"c669bf5296197840ab86ee8ca1a2832b69849097\", \"got_dawn_revision\": \"f2bc3b3edd6f3a8424b230b17f844f59768d53b8\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"32d9aec64baf77c82b80e10447f256299cacbded\", \"got_revision_cp\": \"refs/heads/master@{#828091}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"04515da400d5fbc22d852af1369c4d46bd54991e\", \"got_v8_revision\": \"15247228bbf879eab29da6c7a0b1a608fccbc1fa\", \"got_v8_revision_cp\": \"refs/heads/8.9.4@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"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\": \"32d9aec64baf77c82b80e10447f256299cacbded\", \"swarm_hashes_refs/heads/master(at){#828091}_without_patch\": {\"absl_hardening_tests\": \"ddc021eb1ab1b8e3067c8ebe8c3e1bf29562813e\", \"accessibility_unittests\": \"f4f265cb1550df812e553933ca1e8e83ad5a3ebf\", \"angle_unittests\": \"67204761d2b6c21aa31785884ad4b1b9bd78cd70\", \"app_shell_unittests\": \"a8b02c472711f6cd47b95e2dfab452d9973f58b6\", \"aura_unittests\": \"431542ca5123144823ead9ddf1920e5b83916ef1\", \"base_unittests\": \"82254ef72f6f3c5fdc14468b4430ad029c767207\", \"base_util_unittests\": \"aa70745eca3c8119bcff18e7c1ccf659c3e8c350\", \"blink_common_unittests\": \"f4ea227b1ca3e27ce681a4601ec8bec4a4816e23\", \"blink_fuzzer_unittests\": \"51f7c760d30636cc4be43db45ca43f2b8c4bd8a9\", \"blink_heap_unittests\": \"21dee33c6aebd33e33cc64db164781f8c9c171c9\", \"blink_platform_unittests\": \"55e3d49e886f9d93525a0b5c3d43ef415bfa52bf\", \"blink_unittests\": \"42b85a007959c28892114285b2ffe5fea47f7e30\", \"boringssl_crypto_tests\": \"cb1231c21fc042aac5d39a7c9508e82db8502426\", \"boringssl_ssl_tests\": \"d1ab5c076b5ca433b9201f4707a1d6ad8332a046\", \"browser_switcher_bho_unittests\": \"c88d2b1814dec38b29875c732d910c7ac5275e07\", \"browser_tests\": \"7b8f88945d3e0104aeae9787aa7d8c8f76743ce4\", \"capture_unittests\": \"77ad6f728648ddc1717656beadcc15041eacb3e2\", \"cast_unittests\": \"54150dad8b37006d1537a5d866d6b92d27016e6c\", \"cc_unittests\": \"6d4f168500db0602982e55e19a0abdaa0ab7fe2d\", \"chrome_app_unittests\": \"57dc95988781518bbe2f84876b9f5041bd5b65f0\", \"chrome_cleaner_unittests\": \"c1bbef8eedb3c6f62cae775f6ddbc76a9f65e4e0\", \"chrome_elf_unittests\": \"a932831c3b67c5d0bfbdd9bd5ab23867d76c16e4\", \"chromedriver_unittests\": \"0cc9c27c9b7cabecdea6122785310d02889c6152\", \"components_browsertests\": \"fc91681b73e76f48a652bfc752121f1ab66ff455\", \"components_unittests\": \"d1effa9a2d362ba65f71ed9e51d252ec6c74c4cd\", \"compositor_unittests\": \"880c9f213afc18ad1d5c86fcf875f874e03ee698\", \"content_browsertests\": \"167238654d24b266abe3177a4914705463cb1068\", \"content_unittests\": \"71d30b89b7cb1fe5752b6236378ff14375e5528b\", \"courgette_unittests\": \"cf7eaa40162b280097609d051a8f804754ff52c6\", \"crashpad_tests\": \"0fb4a63179b48379010a13e9eb5691ac97194ee8\", \"cronet_tests\": \"3e156de067c08ef1a3d6dea15706532cde20e934\", \"cronet_unittests\": \"0b27812c9d4bce540132aff7517978128f275070\", \"crypto_unittests\": \"ec88f2844d353fcfe055b7565b28d397d6ad3327\", \"delayloads_unittests\": \"4b9c916a9f6d5f2149c351773dfd1b7b6240b75e\", \"device_unittests\": \"6904d865abee65dd9570f1faf08500cc4e3081bb\", \"display_unittests\": \"c07655bfc079fca3147d11594922ae9389a61250\", \"elevation_service_unittests\": \"6ec33bda78b00a6d9ef685a7ad4d32709dbddcac\", \"events_unittests\": \"05217ca6a16ca758f482b8bf245e2591c509d23f\", \"extensions_browsertests\": \"68eaeb4479860d850878f6c24fa8c6c9e2bbf4ac\", \"extensions_unittests\": \"0e7f1e4bc250c825080f3c5fde917908d138505a\", \"filesystem_service_unittests\": \"48431ce3bedcd73c5664ab5eda3362e26f533106\", \"gcm_unit_tests\": \"1bb49f077103a366e24aa06b6d9de9df4eaec44c\", \"gcp_unittests\": \"df4c9b2eb891f1bfa3b9be58ac1e6b374f7cdc5b\", \"gfx_unittests\": \"29bbb433061b2e428e2725360dfb4b93e8094d96\", \"gin_unittests\": \"765759000350342434ed40a632f6cbe6a5d6480a\", \"google_apis_unittests\": \"097830ea7d7736c8f4d02a08e736a7020f3ac558\", \"gpu_unittests\": \"9801e9d206f97ac12cbf7eb054d4066c8f662b0d\", \"gwp_asan_unittests\": \"52455896c29fddadcc8accfcf15e6f23db27a02f\", \"headless_browsertests\": \"e6ddd3d5efc9831d70d57dc115ac3accac6b2028\", \"headless_unittests\": \"bab6e4c285eb93d5a2ceaebc3877bdf67d0bedc0\", \"install_static_unittests\": \"81626381b5c1fba89af148b687cd834c489edbde\", \"installer_util_unittests\": \"c84c93dbac3592a238e41672ca9a3d850d276ce8\", \"interactive_ui_tests\": \"97becae33456fbc1283c2ce4337ebf2857cf4356\", \"ipc_tests\": \"a80da608093c1df9f7d7bd1baf06e9973208cdee\", \"jingle_unittests\": \"6e4293a90dcee40b0bdec70a7951c71d8cf7c812\", \"latency_unittests\": \"be17a77a0c5b64f9fed0fc11cb66985ff0475753\", \"libjingle_xmpp_unittests\": \"c26a5e5f7dc0f8e69f7ec734fa09a7467a8d2e4e\", \"liburlpattern_unittests\": \"c72629253b60c04695568931828eaf4a2005d79a\", \"media_blink_unittests\": \"6d32d6abc151f678f90eb11119f0a9a8474b77b6\", \"media_unittests\": \"ab8fd874d854a1b51ec2f3bdee8db2922a7a5b78\", \"message_center_unittests\": \"afa8921a6659ff0b117620b52c2eb3eebf13b03e\", \"midi_unittests\": \"2d746801604adc7734ce36f7b52f41b450e3d602\", \"mojo_core_unittests\": \"328ecf62eab7cc5e3f832d48f8bfe3b39aa915c5\", \"mojo_unittests\": \"c66d51395a01ed9b3b4b3fac0c767d260c034af1\", \"nacl_loader_unittests\": \"d16f97e331f46f61441a9ec74a14a25f2160820a\", \"native_theme_unittests\": \"5c96a85719736147c1dd632ff5fb9d5429610b3e\", \"net_unittests\": \"158f23640b657c16a980a5986ff2b27ef3adbfac\", \"notification_helper_unittests\": \"17f7e04264a772db6e99bd0a6f4cac8538ff2248\", \"pdf_unittests\": \"db0dd0f0a0bc6c5b19aee819e427eb6bc3791593\", \"perfetto_unittests\": \"7657d2971dee3e208ce8b1c783572393c9ca0866\", \"ppapi_unittests\": \"0214f7a53d8625f850314b8386ae5d3dc4cd325f\", \"printing_unittests\": \"b980d944e1fc3469b32a7683ef160be8c9359518\", \"remoting_unittests\": \"98e724a0d3001371b8375cacfd454f20bb4e3a3a\", \"sbox_integration_tests\": \"23993c0e8fe76eccf55514ad872d98dff3f45bf0\", \"sbox_unittests\": \"c750b8282e179d8d668216f1892c3226076f488b\", \"sbox_validation_tests\": \"811b84f51d8ddd102d5065762791eea2306fb26f\", \"service_manager_unittests\": \"1a2bf5542bc8617aaafc07bd487026914751b771\", \"services_unittests\": \"15d628a16aa4ec54b95cc78cdce729c50f3da463\", \"setup_unittests\": \"c7dfbd7c92e3c39beb4461e6d6ad4c90b6a7ebe5\", \"shell_dialogs_unittests\": \"2da1dd40d2bb3c416e2eedb3f7dd3d9edd8ccbad\", \"skia_unittests\": \"01c89cb6e7496c9382bbfe532ef33556634f61bb\", \"snapshot_unittests\": \"08adac10bb3ac90456b6b26cca8986eecc257a58\", \"sql_unittests\": \"b5a5acec4d109e4b08bd1e9ad3cf7fe70414909d\", \"storage_unittests\": \"849e415b32d01333d26ec6a795ea7c550dd20b57\", \"sync_integration_tests\": \"1c20fe37f432453f04346ddcfa69a4091e3357e1\", \"traffic_annotation_auditor_unittests\": \"5237f5a0e75e810a5a62058c3c8892ef192840e7\", \"ui_base_unittests\": \"0ec065c36448e52749c75dc730ca14c52774ed69\", \"ui_touch_selection_unittests\": \"08426cca338a89765b49e7ffba482c4b082bb761\", \"unit_tests\": \"82ebb3a44a9a376b4da1bd5587461491f94b9f64\", \"updater_tests\": \"44cdec370d64b5e2acbd53710ce887d786d75e8a\", \"url_unittests\": \"fdff5f552723b6b9e6a908c1205a71003ae8cfcd\", \"views_unittests\": \"fa13015062f780faf2d6c5857f71c3bb116424b5\", \"viz_unittests\": \"871ccbf94057d0c3a307fd5628b89b823e80d1ce\", \"vr_common_unittests\": \"3c3fd352d299794ef00667bdd1860093f5b703a9\", \"vr_pixeltests\": \"70f45168532c0e2d6f2ef27b4c645da85f99a35a\", \"weblayer_browsertests\": \"fb68fa1921135a455dc7f688990c7d8e48c6b28a\", \"weblayer_unittests\": \"3fe85bf53957031751c54bee56af40818562a76a\", \"wm_unittests\": \"b547a553263c42f0b0d6893a2837f36293a237df\", \"wtf_unittests\": \"7d1205942087192939b1d9b529be063a138ac101\", \"zlib_unittests\": \"72a9931ec3faf8c41ea04632440b8c15c6d55d60\", \"zucchini_unittests\": \"1603099186c59fa35551d77ac292e18d1659fe0b\"}}, \"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-5-fw95\"], \"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\": [\"5632-2e614e1\"], \"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": "1605594135089446",
"status": "COMPLETED",
"status_changed_ts": "1605597850685091",
"tags": [
"build_address:luci.chromium.ci/win-asan/15681",
"builder:win-asan",
"buildset:commit/git/32d9aec64baf77c82b80e10447f256299cacbded",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/32d9aec64baf77c82b80e10447f256299cacbded",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028836306017389312",
"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/8863406244013458896/+/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:4fedafb6aaabb810",
"user_agent:luci-scheduler"
],
"updated_ts": "1605597850711993",
"url": "https://ci.chromium.org/b/8863406244013458896",
"utcnow_ts": "1605597852185908"
}
}
[07:24:12.194] Invocation finished in 1h2m2.072475926s with status SUCCEEDED