[00:51:42.225] New invocation is queued and will start shortly
[00:51:43.336] Starting the invocation (attempt 1)
[00:51:43.366] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:51:43.367] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:51:43.367] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020612773149009616",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2d8cc01ac63d8524744f4996c2a537a36414e93e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020612773149009616",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2d8cc01ac63d8524744f4996c2a537a36414e93e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d8cc01ac63d8524744f4996c2a537a36414e93e",
"gitiles_ref:refs/heads/master"
]
}
[00:51:43.991] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613436703438015",
"id": "8855182711213907008",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d8cc01ac63d8524744f4996c2a537a36414e93e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613436703862042",
"tags": [
"build_address:luci.chromium.ci/win-asan/17365",
"builder:win-asan",
"buildset:commit/git/2d8cc01ac63d8524744f4996c2a537a36414e93e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d8cc01ac63d8524744f4996c2a537a36414e93e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020612773149009616",
"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/8855182711213907008/+/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": "1613436703862153",
"url": "https://ci.chromium.org/b/8855182711213907008",
"utcnow_ts": "1613436703983751"
}
}
[00:51:43.991] Task URL: https://ci.chromium.org/b/8855182711213907008
[00:51:43.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:2:0) after 1m0s
[00:51:48.248] Received PubSub notification, asking Buildbucket for the build status
[00:51:48.369] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:44.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:2:0)
[00:52:44.046] Timer tick, asking Buildbucket for the build status
[00:52:44.470] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:44.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:4:0) after 1m0s
[00:53:44.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:4:0)
[00:53:44.481] Timer tick, asking Buildbucket for the build status
[00:53:44.553] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:44.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:5:0) after 1m0s
[00:54:44.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:5:0)
[00:54:44.626] Timer tick, asking Buildbucket for the build status
[00:54:44.715] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:44.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:6:0) after 1m0s
[00:55:44.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:6:0)
[00:55:44.770] Timer tick, asking Buildbucket for the build status
[00:55:44.934] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:44.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:7:0) after 1m0s
[00:56:44.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:7:0)
[00:56:44.988] Timer tick, asking Buildbucket for the build status
[00:56:45.072] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:45.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:8:0) after 1m0s
[00:57:45.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:8:0)
[00:57:45.158] Timer tick, asking Buildbucket for the build status
[00:57:45.231] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:45.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:9:0) after 1m0s
[00:58:45.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:9:0)
[00:58:45.266] Timer tick, asking Buildbucket for the build status
[00:58:45.348] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:45.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:10:0) after 1m0s
[00:59:45.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:10:0)
[00:59:45.373] Timer tick, asking Buildbucket for the build status
[00:59:45.639] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:45.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:11:0) after 1m0s
[01:00:45.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:11:0)
[01:00:45.655] Timer tick, asking Buildbucket for the build status
[01:00:45.763] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:45.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:12:0) after 1m0s
[01:01:45.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:12:0)
[01:01:45.798] Timer tick, asking Buildbucket for the build status
[01:01:45.905] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:45.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:13:0) after 1m0s
[01:02:45.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:13:0)
[01:02:45.931] Timer tick, asking Buildbucket for the build status
[01:02:46.004] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:46.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:14:0) after 1m0s
[01:03:46.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:14:0)
[01:03:46.026] Timer tick, asking Buildbucket for the build status
[01:03:46.132] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:46.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:15:0) after 1m0s
[01:04:46.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:15:0)
[01:04:46.150] Timer tick, asking Buildbucket for the build status
[01:04:46.299] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:46.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:16:0) after 1m0s
[01:05:46.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:16:0)
[01:05:46.319] Timer tick, asking Buildbucket for the build status
[01:05:46.516] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:46.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:17:0) after 1m0s
[01:06:46.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:17:0)
[01:06:46.588] Timer tick, asking Buildbucket for the build status
[01:06:46.686] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:46.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:18:0) after 1m0s
[01:07:46.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:18:0)
[01:07:46.672] Timer tick, asking Buildbucket for the build status
[01:07:46.743] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:46.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:19:0) after 1m0s
[01:08:46.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:19:0)
[01:08:46.815] Timer tick, asking Buildbucket for the build status
[01:08:46.924] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:46.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:20:0) after 1m0s
[01:09:46.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:20:0)
[01:09:46.981] Timer tick, asking Buildbucket for the build status
[01:09:47.327] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:47.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:21:0) after 1m0s
[01:10:47.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:21:0)
[01:10:47.400] Timer tick, asking Buildbucket for the build status
[01:10:47.638] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:47.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:22:0) after 1m0s
[01:11:47.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:22:0)
[01:11:47.693] Timer tick, asking Buildbucket for the build status
[01:11:48.062] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:48.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:23:0) after 1m0s
[01:12:48.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:23:0)
[01:12:48.079] Timer tick, asking Buildbucket for the build status
[01:12:48.194] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:48.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:24:0) after 1m0s
[01:13:48.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:24:0)
[01:13:48.225] Timer tick, asking Buildbucket for the build status
[01:13:48.341] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:48.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:25:0) after 1m0s
[01:14:48.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:25:0)
[01:14:48.426] Timer tick, asking Buildbucket for the build status
[01:14:48.522] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:48.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:26:0) after 1m0s
[01:15:48.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:26:0)
[01:15:48.720] Timer tick, asking Buildbucket for the build status
[01:15:49.024] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:49.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:27:0) after 1m0s
[01:16:49.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:27:0)
[01:16:49.084] Timer tick, asking Buildbucket for the build status
[01:16:49.214] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:49.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:28:0) after 1m0s
[01:17:49.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:28:0)
[01:17:49.215] Timer tick, asking Buildbucket for the build status
[01:17:49.554] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:49.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:29:0) after 1m0s
[01:18:49.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:29:0)
[01:18:49.634] Timer tick, asking Buildbucket for the build status
[01:18:49.737] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:49.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:30:0) after 1m0s
[01:19:49.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:30:0)
[01:19:49.757] Timer tick, asking Buildbucket for the build status
[01:19:49.847] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:49.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:31:0) after 1m0s
[01:20:49.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:31:0)
[01:20:49.863] Timer tick, asking Buildbucket for the build status
[01:20:50.019] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:50.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:32:0) after 1m0s
[01:21:50.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:32:0)
[01:21:50.123] Timer tick, asking Buildbucket for the build status
[01:21:50.223] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:50.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:33:0) after 1m0s
[01:22:50.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:33:0)
[01:22:50.388] Timer tick, asking Buildbucket for the build status
[01:22:50.491] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:50.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:34:0) after 1m0s
[01:23:50.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:34:0)
[01:23:50.511] Timer tick, asking Buildbucket for the build status
[01:23:50.818] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:50.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:35:0) after 1m0s
[01:24:50.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:35:0)
[01:24:50.944] Timer tick, asking Buildbucket for the build status
[01:24:51.020] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:51.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:36:0) after 1m0s
[01:25:51.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:36:0)
[01:25:51.199] Timer tick, asking Buildbucket for the build status
[01:25:51.342] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:51.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:37:0) after 1m0s
[01:26:51.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:37:0)
[01:26:51.438] Timer tick, asking Buildbucket for the build status
[01:26:51.559] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:51.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:38:0) after 1m0s
[01:27:51.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:38:0)
[01:27:51.576] Timer tick, asking Buildbucket for the build status
[01:27:51.693] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:51.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:39:0) after 1m0s
[01:28:51.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:39:0)
[01:28:51.712] Timer tick, asking Buildbucket for the build status
[01:28:52.166] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:52.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:40:0) after 1m0s
[01:29:52.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:40:0)
[01:29:52.233] Timer tick, asking Buildbucket for the build status
[01:29:52.349] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:52.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:41:0) after 1m0s
[01:30:52.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:41:0)
[01:30:52.446] Timer tick, asking Buildbucket for the build status
[01:30:52.639] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:52.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:42:0) after 1m0s
[01:31:52.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:42:0)
[01:31:52.661] Timer tick, asking Buildbucket for the build status
[01:31:52.979] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:52.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:43:0) after 1m0s
[01:32:52.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:43:0)
[01:32:52.999] Timer tick, asking Buildbucket for the build status
[01:32:53.129] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:53.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:44:0) after 1m0s
[01:33:53.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:44:0)
[01:33:53.178] Timer tick, asking Buildbucket for the build status
[01:33:53.296] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:53.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:45:0) after 1m0s
[01:34:53.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:45:0)
[01:34:53.315] Timer tick, asking Buildbucket for the build status
[01:34:53.559] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:53.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:46:0) after 1m0s
[01:35:53.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:46:0)
[01:35:53.581] Timer tick, asking Buildbucket for the build status
[01:35:53.677] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:53.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:47:0) after 1m0s
[01:36:53.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:47:0)
[01:36:53.700] Timer tick, asking Buildbucket for the build status
[01:36:53.807] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:53.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:48:0) after 1m0s
[01:37:53.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:48:0)
[01:37:53.831] Timer tick, asking Buildbucket for the build status
[01:37:53.924] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:53.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:49:0) after 1m0s
[01:38:53.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:49:0)
[01:38:53.965] Timer tick, asking Buildbucket for the build status
[01:38:54.077] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:54.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:50:0) after 1m0s
[01:39:54.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:50:0)
[01:39:54.097] Timer tick, asking Buildbucket for the build status
[01:39:54.201] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:54.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:51:0) after 1m0s
[01:40:54.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:51:0)
[01:40:54.195] Timer tick, asking Buildbucket for the build status
[01:40:54.296] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:54.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:52:0) after 1m0s
[01:41:54.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:52:0)
[01:41:54.325] Timer tick, asking Buildbucket for the build status
[01:41:54.438] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:54.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:53:0) after 1m0s
[01:42:54.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:53:0)
[01:42:54.444] Timer tick, asking Buildbucket for the build status
[01:42:54.694] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:54.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:54:0) after 1m0s
[01:43:54.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:54:0)
[01:43:54.723] Timer tick, asking Buildbucket for the build status
[01:43:54.818] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:54.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:55:0) after 1m0s
[01:44:54.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:55:0)
[01:44:54.871] Timer tick, asking Buildbucket for the build status
[01:44:55.045] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:55.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:56:0) after 1m0s
[01:45:55.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:56:0)
[01:45:55.294] Timer tick, asking Buildbucket for the build status
[01:45:55.455] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:55.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:57:0) after 1m0s
[01:46:55.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:57:0)
[01:46:55.498] Timer tick, asking Buildbucket for the build status
[01:46:55.965] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:55.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:58:0) after 1m0s
[01:47:56.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:58:0)
[01:47:56.003] Timer tick, asking Buildbucket for the build status
[01:47:56.378] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:56.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:59:0) after 1m0s
[01:48:56.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:59:0)
[01:48:56.473] Timer tick, asking Buildbucket for the build status
[01:48:56.894] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:56.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:60:0) after 1m0s
[01:49:56.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:60:0)
[01:49:56.916] Timer tick, asking Buildbucket for the build status
[01:49:57.046] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:57.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:61:0) after 1m0s
[01:50:57.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:61:0)
[01:50:57.089] Timer tick, asking Buildbucket for the build status
[01:50:57.296] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:57.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:62:0) after 1m0s
[01:51:57.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:62:0)
[01:51:57.397] Timer tick, asking Buildbucket for the build status
[01:51:57.579] Build 8855182711213907008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:57.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020612773149009616:63:0) after 1m0s
[01:52:31.372] Received PubSub notification, asking Buildbucket for the build status
[01:52:31.479] Build 8855182711213907008: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:52:31.479] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613440350535103",
"created_by": "project:chromium",
"created_ts": "1613436703438015",
"id": "8855182711213907008",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d8cc01ac63d8524744f4996c2a537a36414e93e\"}}",
"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\": \"2d8cc01ac63d8524744f4996c2a537a36414e93e\", \"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\": 17365, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-1\", \"got_angle_revision\": \"ca6573ebb08a3048f94965aec7514750579a158d\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"2d8cc01ac63d8524744f4996c2a537a36414e93e\", \"got_revision_cp\": \"refs/heads/master@{#854125}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"ebacb645b20531cd78dc088730fb72269822eeb6\", \"got_v8_revision_cp\": \"refs/heads/9.0.172@{#2}\", \"got_webrtc_revision\": \"a24f3d035f7d9acee551d5547f4fb16838cf98d6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33258}\", \"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\": \"2d8cc01ac63d8524744f4996c2a537a36414e93e\", \"swarm_hashes_refs/heads/master(at){#854125}_without_patch\": {\"absl_hardening_tests\": \"e3b1188b5fad238d6f229bec22f1743b1238d9ff\", \"accessibility_unittests\": \"4b77db15d1ff15a288018241e6492aa8982571bb\", \"angle_unittests\": \"aea3647269147993535855a0917783f144da6591\", \"app_shell_unittests\": \"2edb1ed9483c9466e7eb41c70369d6fea43f39ab\", \"aura_unittests\": \"cbedd7d2a4a275ae9ccfc685bdfb1195693e9077\", \"base_unittests\": \"d375874e71924337f293d23f95f2eedfbf583866\", \"base_util_unittests\": \"2cba5f008f6330d2c5c6059f173c454c47e888ea\", \"blink_common_unittests\": \"c7a9764bf185920b61598f8ec91acf4eef7a7670\", \"blink_fuzzer_unittests\": \"63e6b8151a857cbf1c47af695a806f9cdf13a928\", \"blink_heap_unittests\": \"16e1e76d946432b48a1692bae5d6e98af6dca122\", \"blink_platform_unittests\": \"5965e1359c225866fedc88e67da5f03197bad95d\", \"blink_unittests\": \"18120ec2f71855fba9e31d0f285bcebc7cdd5006\", \"boringssl_crypto_tests\": \"e453d8fb80cee363177f3af7c343f6c3a86b3d13\", \"boringssl_ssl_tests\": \"afa4a155e532b35133044e699839888f5d9bf45d\", \"browser_switcher_bho_unittests\": \"4d769f0b7cd5a4f8a51cf51be4378f0de55d1d20\", \"browser_tests\": \"2cdebfd5cba541d35eb22ee0e62619a983066642\", \"capture_unittests\": \"4954e0e1fab2b4a78d5439950b00d852ba308ef4\", \"cast_unittests\": \"a889b403fc847b946bd165711a014acf0050976c\", \"cc_unittests\": \"a60ca5fe0a8deaf6ba4ec9d3104adef21c7e63c7\", \"chrome_app_unittests\": \"427961d5515ffc64d6c6792f8ba02e8f17e08813\", \"chrome_cleaner_unittests\": \"f4eb7b8d399758abfbdb635be533a7a535629417\", \"chrome_elf_unittests\": \"fd73742bdf9c00e68cee6a3559f921372356dc37\", \"chromedriver_unittests\": \"1373c52bdcda00e0352875b2ea83537f9415c0ac\", \"components_browsertests\": \"3d8906c724031459912f95c92bf7b373fa0c6a8f\", \"components_unittests\": \"a8a8220f6e7db0407d90265af78e0edfba05f438\", \"compositor_unittests\": \"3a44afcbfb03f6ecd41ec078f89d8ff7e7c972ae\", \"content_browsertests\": \"2bc3edcbde0b5f7712c85750e1eb38214e5b062f\", \"content_unittests\": \"7dfceaec875f6d66b86aa88da132e1b781a46778\", \"courgette_unittests\": \"13418605631c010b5ace8d92ca36d5dc9f1fa485\", \"crashpad_tests\": \"c13ba8aa0d80dbd6fce4a1754159b76557e9f399\", \"cronet_tests\": \"fc0ac87e795fb3858b22290c9c5775f3a85b0e55\", \"cronet_unittests\": \"e87ede121216ad430a70d9906225494cd92a5f39\", \"crypto_unittests\": \"13812472fc3d571411430677e0f45e97d1bc6d50\", \"delayloads_unittests\": \"7551ad81933516e9ff08ca7187dc59c0a2bdd4c1\", \"device_unittests\": \"87a4a9e0e73fc13c202c7caba896841ecac8e2ec\", \"display_unittests\": \"213de280977e43505ce7aa82e9ff7d169363657e\", \"elevation_service_unittests\": \"1fd48ab5ed849ef4cb59235aea17132e4a3a9510\", \"events_unittests\": \"6dcc80310c13e97ade1edd2e045f96973c4c3bd7\", \"extensions_browsertests\": \"47d789a8d395cac70e1bce7dff13a067371280e7\", \"extensions_unittests\": \"17d9e2c8f61872ddfbeac32ff985b5343978d059\", \"filesystem_service_unittests\": \"0b09869a337516f997ea8b25807ca91ec24a1ac4\", \"gcm_unit_tests\": \"18e937e6dc270ea8494156260ddde15068bbf0cd\", \"gcp_unittests\": \"9576adfc765ed6c09451805480fa72868b77fd8a\", \"gfx_unittests\": \"54b76b4f20c5257f12a9b83935899e7dac7bd2ac\", \"gin_unittests\": \"8962850f51be3f38dd69ca9811c8d90f3f1bdf9b\", \"google_apis_unittests\": \"26ef3a5baff8cf2324910529623104243ecb1a72\", \"gpu_unittests\": \"dfa8d4aa788ec9b5e0be8edb46b53c01fc8b0ecc\", \"gwp_asan_unittests\": \"dbbd3ab0fa39fe49942932361a2ca26e23b0f886\", \"headless_browsertests\": \"1f38236c73908e1a18dd785cc213a280b2b97208\", \"headless_unittests\": \"762670c3b4238ec74979671cfc58708be236e898\", \"install_static_unittests\": \"ca6f27a19be4579ee38f46cb51a092f58a82ced0\", \"installer_util_unittests\": \"d1521e11a45287c41df3e1257519296ad51a1548\", \"interactive_ui_tests\": \"666ef9a7fda47048c14be6e5c7682104e5b8a888\", \"ipc_tests\": \"3a969fb9c9463f171c50676932a0b41c4e095673\", \"jingle_unittests\": \"b5804fd0d878bf97c5580ac65a59c2d2d419dfa0\", \"latency_unittests\": \"69d274308ed126b4cca940ff557a2c0236a95c32\", \"libjingle_xmpp_unittests\": \"bdb664e4f2dc2c8af3840bacd5c27ceb59e49980\", \"liburlpattern_unittests\": \"e844e823a53d1a78b907d636fdb20126a9af5cfa\", \"media_blink_unittests\": \"6da22218760190f94102d13f7e081b32c2147802\", \"media_unittests\": \"6d8706938f0dc48b04ff1a106b4a5191e94d8dcf\", \"message_center_unittests\": \"586ec54565eef465134f754fe6615608593dab2b\", \"midi_unittests\": \"483334dadc3fefa6a77250a5852e162ca8138214\", \"mojo_core_unittests\": \"8c818f763b75103bddf464ddd44804b3a7d6309a\", \"mojo_unittests\": \"142b803886bebbb93c9a62651d8da24e157b033b\", \"nacl_loader_unittests\": \"6cf43e44f413cb8b2edee6d82e15567486c7d3bd\", \"native_theme_unittests\": \"c087feca606704d23f04785aef4c68b1cf166712\", \"net_unittests\": \"11d1fde15e58cd36b5c063f53e8234898c02f6ae\", \"notification_helper_unittests\": \"c26fd09ccdf5b2c3f5a414dd66e289ef6b26614b\", \"pdf_unittests\": \"04652d7b47e793c7f31d2417f9cc5e625daa6405\", \"perfetto_unittests\": \"39895e2b2700a8648315c2c2826544055acfbbd1\", \"ppapi_unittests\": \"3e5e00c9c69b56d6646078bf9810b714ac3c5904\", \"printing_unittests\": \"a68513c4e6c4a75266451dfef3596066ea86f815\", \"remoting_unittests\": \"9bfb176733b6aef073db8725bc3e61a07958f4a1\", \"sbox_integration_tests\": \"860de998907bec8da442ece4ad0b0283c51c0dd8\", \"sbox_unittests\": \"630e831656cd65b1214c301b476fdb005e79deb0\", \"sbox_validation_tests\": \"7f7cdb2c92977ba383951e8b9c44295eb5a9260f\", \"service_manager_unittests\": \"672d41d4bd6c75491b5ac482386eb00d58caf736\", \"services_unittests\": \"b55418bf53064786d2937f990615319e9b75a87c\", \"setup_unittests\": \"4d832c0a30e8d74edf71e8234084480a62b6f0ef\", \"shell_dialogs_unittests\": \"521f99d03fb07c37537daecc3a4a1d083d884d56\", \"skia_unittests\": \"aa39681752e724255a56d8af013c046725ee4c84\", \"snapshot_unittests\": \"088d2f298151e4c3c3d890ce8e02ab948ade96cd\", \"sql_unittests\": \"e0cfcc8283db937a9caaed5706adde1a98beca2c\", \"storage_unittests\": \"68a4bc2f7eda40da6a386f7b12efc9cc63f6f408\", \"sync_integration_tests\": \"87e08ffbd3ecfe849365884405d8a45c91018a7c\", \"traffic_annotation_auditor_unittests\": \"84ad7c2b2b7fcb937c2dbb97af067788c6f55a3a\", \"ui_base_unittests\": \"3307248ad0e3cbd481cf4ef598f2001b30d2e466\", \"ui_touch_selection_unittests\": \"a0ab175a5a276a466d7e186e0f1aa74a6354c446\", \"unit_tests\": \"b9360e4174f2182d912fa89f08e10a23021d93c4\", \"updater_tests\": \"911f6025e7eaa554eeee35a826090409beb84709\", \"url_unittests\": \"c38d24df0fa4634830ed808cc203dfd5781584b3\", \"views_unittests\": \"767946497dc95b991c7665dea006eedc35e1786b\", \"viz_unittests\": \"8bc46c5ca194325926991796aa8f8f2d3abf9df4\", \"vr_common_unittests\": \"cd4789fda9326c19e2875f83e80ac4e7d1348a9f\", \"vr_pixeltests\": \"433ebda1a9fc7a937c4b653274be4a7b2e75f4b1\", \"weblayer_browsertests\": \"72f4bab3bf669bcca360023afe93c3299c296fdc\", \"weblayer_unittests\": \"5690822b1979a53336e5800b4f411fa45dc0a029\", \"wm_unittests\": \"8173603f7562d014788c1d0ecd494848dcd991ed\", \"wtf_unittests\": \"7cf4fb4b986b91d264fd9d60a3f1330b302fdd3a\", \"zlib_unittests\": \"2ec4857729edb34bc28a3e39ded86866b4a2c7b7\", \"zucchini_unittests\": \"d5fb8bac5ae5af03a2b58b0579838ff6ce2b83cf\"}}, \"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\": [\"5696-2a606bc\"], \"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": "1613436707255657",
"status": "COMPLETED",
"status_changed_ts": "1613440350927401",
"tags": [
"build_address:luci.chromium.ci/win-asan/17365",
"builder:win-asan",
"buildset:commit/git/2d8cc01ac63d8524744f4996c2a537a36414e93e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d8cc01ac63d8524744f4996c2a537a36414e93e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020612773149009616",
"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/8855182711213907008/+/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:51c123de8d904a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613440350931714",
"url": "https://ci.chromium.org/b/8855182711213907008",
"utcnow_ts": "1613440351470538"
}
}
[01:52:31.479] Invocation finished in 1h0m49.26914905s with status SUCCEEDED