[20:20:00.681] New invocation is queued and will start shortly
[20:20:03.323] Starting the invocation (attempt 1)
[20:20:03.371] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:20:03.371] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:20:03.371] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023891357346767168",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"431a245c23d26370df1cfe0aa06d881b7720d09c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023891357346767168",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/431a245c23d26370df1cfe0aa06d881b7720d09c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/431a245c23d26370df1cfe0aa06d881b7720d09c",
"gitiles_ref:refs/heads/master"
]
}
[20:20:04.072] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610310003454328",
"id": "8858461293776298768",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"431a245c23d26370df1cfe0aa06d881b7720d09c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610310003869430",
"tags": [
"build_address:luci.chromium.ci/win-asan/16743",
"builder:win-asan",
"buildset:commit/git/431a245c23d26370df1cfe0aa06d881b7720d09c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/431a245c23d26370df1cfe0aa06d881b7720d09c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023891357346767168",
"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/8858461293776298768/+/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": "1610310003869576",
"url": "https://ci.chromium.org/b/8858461293776298768",
"utcnow_ts": "1610310004055090"
}
}
[20:20:04.072] Task URL: https://ci.chromium.org/b/8858461293776298768
[20:20:04.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:2:0) after 1m0s
[20:20:06.189] Received PubSub notification, asking Buildbucket for the build status
[20:20:06.287] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:04.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:2:0)
[20:21:04.151] Timer tick, asking Buildbucket for the build status
[20:21:04.242] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:04.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:4:0) after 1m0s
[20:22:04.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:4:0)
[20:22:04.282] Timer tick, asking Buildbucket for the build status
[20:22:04.381] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:04.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:5:0) after 1m0s
[20:23:04.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:5:0)
[20:23:04.460] Timer tick, asking Buildbucket for the build status
[20:23:04.731] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:04.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:6:0) after 1m0s
[20:24:04.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:6:0)
[20:24:04.785] Timer tick, asking Buildbucket for the build status
[20:24:04.888] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:04.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:7:0) after 1m0s
[20:25:04.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:7:0)
[20:25:04.880] Timer tick, asking Buildbucket for the build status
[20:25:05.023] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:05.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:8:0) after 1m0s
[20:26:05.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:8:0)
[20:26:05.054] Timer tick, asking Buildbucket for the build status
[20:26:05.137] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:05.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:9:0) after 1m0s
[20:27:05.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:9:0)
[20:27:05.236] Timer tick, asking Buildbucket for the build status
[20:27:05.406] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:05.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:10:0) after 1m0s
[20:28:05.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:10:0)
[20:28:05.417] Timer tick, asking Buildbucket for the build status
[20:28:05.702] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:05.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:11:0) after 1m0s
[20:29:05.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:11:0)
[20:29:05.754] Timer tick, asking Buildbucket for the build status
[20:29:05.856] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:05.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:12:0) after 1m0s
[20:30:05.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:12:0)
[20:30:05.868] Timer tick, asking Buildbucket for the build status
[20:30:06.275] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:06.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:13:0) after 1m0s
[20:31:06.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:13:0)
[20:31:06.296] Timer tick, asking Buildbucket for the build status
[20:31:06.387] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:06.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:14:0) after 1m0s
[20:32:06.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:14:0)
[20:32:06.521] Timer tick, asking Buildbucket for the build status
[20:32:07.095] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:07.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:15:0) after 1m0s
[20:33:07.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:15:0)
[20:33:07.267] Timer tick, asking Buildbucket for the build status
[20:33:07.416] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:07.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:16:0) after 1m0s
[20:34:07.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:16:0)
[20:34:07.454] Timer tick, asking Buildbucket for the build status
[20:34:07.922] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:07.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:17:0) after 1m0s
[20:35:07.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:17:0)
[20:35:07.969] Timer tick, asking Buildbucket for the build status
[20:35:08.111] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:08.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:18:0) after 1m0s
[20:36:08.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:18:0)
[20:36:08.119] Timer tick, asking Buildbucket for the build status
[20:36:08.218] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:08.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:19:0) after 1m0s
[20:37:08.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:19:0)
[20:37:08.238] Timer tick, asking Buildbucket for the build status
[20:37:08.426] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:08.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:20:0) after 1m0s
[20:38:08.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:20:0)
[20:38:08.449] Timer tick, asking Buildbucket for the build status
[20:38:08.671] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:08.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:21:0) after 1m0s
[20:39:08.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:21:0)
[20:39:08.758] Timer tick, asking Buildbucket for the build status
[20:39:08.918] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:08.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:22:0) after 1m0s
[20:40:08.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:22:0)
[20:40:08.965] Timer tick, asking Buildbucket for the build status
[20:40:09.229] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:09.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:23:0) after 1m0s
[20:41:09.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:23:0)
[20:41:09.314] Timer tick, asking Buildbucket for the build status
[20:41:09.490] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:09.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:24:0) after 1m0s
[20:42:09.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:24:0)
[20:42:09.512] Timer tick, asking Buildbucket for the build status
[20:42:09.668] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:09.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:25:0) after 1m0s
[20:43:09.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:25:0)
[20:43:09.682] Timer tick, asking Buildbucket for the build status
[20:43:09.867] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:09.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:26:0) after 1m0s
[20:44:09.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:26:0)
[20:44:09.976] Timer tick, asking Buildbucket for the build status
[20:44:10.956] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:10.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:27:0) after 1m0s
[20:45:11.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:27:0)
[20:45:11.039] Timer tick, asking Buildbucket for the build status
[20:45:11.157] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:11.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:28:0) after 1m0s
[20:46:11.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:28:0)
[20:46:11.226] Timer tick, asking Buildbucket for the build status
[20:46:11.294] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:11.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:29:0) after 1m0s
[20:47:11.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:29:0)
[20:47:11.321] Timer tick, asking Buildbucket for the build status
[20:47:11.704] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:11.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:30:0) after 1m0s
[20:48:11.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:30:0)
[20:48:11.824] Timer tick, asking Buildbucket for the build status
[20:48:11.961] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:11.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:31:0) after 1m0s
[20:49:12.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:31:0)
[20:49:12.073] Timer tick, asking Buildbucket for the build status
[20:49:12.349] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:12.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:32:0) after 1m0s
[20:50:12.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:32:0)
[20:50:12.502] Timer tick, asking Buildbucket for the build status
[20:50:12.659] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:12.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:33:0) after 1m0s
[20:51:12.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:33:0)
[20:51:12.674] Timer tick, asking Buildbucket for the build status
[20:51:12.906] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:12.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:34:0) after 1m0s
[20:52:12.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:34:0)
[20:52:12.929] Timer tick, asking Buildbucket for the build status
[20:52:13.005] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:13.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:35:0) after 1m0s
[20:53:13.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:35:0)
[20:53:13.094] Timer tick, asking Buildbucket for the build status
[20:53:13.340] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:13.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:36:0) after 1m0s
[20:54:13.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:36:0)
[20:54:13.358] Timer tick, asking Buildbucket for the build status
[20:54:13.440] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:13.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:37:0) after 1m0s
[20:55:13.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:37:0)
[20:55:13.774] Timer tick, asking Buildbucket for the build status
[20:55:14.401] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:14.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:38:0) after 1m0s
[20:56:14.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:38:0)
[20:56:14.736] Timer tick, asking Buildbucket for the build status
[20:56:14.822] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:14.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:39:0) after 1m0s
[20:57:14.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:39:0)
[20:57:14.855] Timer tick, asking Buildbucket for the build status
[20:57:16.032] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:16.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:40:0) after 1m0s
[20:58:16.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:40:0)
[20:58:16.197] Timer tick, asking Buildbucket for the build status
[20:58:16.399] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:16.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:41:0) after 1m0s
[20:59:16.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:41:0)
[20:59:16.469] Timer tick, asking Buildbucket for the build status
[20:59:16.688] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:16.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:42:0) after 1m0s
[21:00:16.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:42:0)
[21:00:16.757] Timer tick, asking Buildbucket for the build status
[21:00:16.868] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:16.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:43:0) after 1m0s
[21:01:16.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:43:0)
[21:01:16.924] Timer tick, asking Buildbucket for the build status
[21:01:17.102] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:17.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:44:0) after 1m0s
[21:02:17.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:44:0)
[21:02:17.112] Timer tick, asking Buildbucket for the build status
[21:02:17.584] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:17.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:45:0) after 1m0s
[21:03:17.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:45:0)
[21:03:17.607] Timer tick, asking Buildbucket for the build status
[21:03:17.997] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:17.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:46:0) after 1m0s
[21:04:18.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:46:0)
[21:04:18.069] Timer tick, asking Buildbucket for the build status
[21:04:18.242] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:18.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:47:0) after 1m0s
[21:05:18.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:47:0)
[21:05:18.314] Timer tick, asking Buildbucket for the build status
[21:05:18.426] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:18.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:48:0) after 1m0s
[21:06:18.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:48:0)
[21:06:18.599] Timer tick, asking Buildbucket for the build status
[21:06:18.933] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:18.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:49:0) after 1m0s
[21:07:19.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:49:0)
[21:07:19.002] Timer tick, asking Buildbucket for the build status
[21:07:19.175] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:19.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:50:0) after 1m0s
[21:08:19.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:50:0)
[21:08:19.575] Timer tick, asking Buildbucket for the build status
[21:08:19.877] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:19.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:51:0) after 1m0s
[21:09:19.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:51:0)
[21:09:19.979] Timer tick, asking Buildbucket for the build status
[21:09:20.091] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:20.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:52:0) after 1m0s
[21:10:20.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:52:0)
[21:10:20.277] Timer tick, asking Buildbucket for the build status
[21:10:20.521] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:20.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:53:0) after 1m0s
[21:11:20.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:53:0)
[21:11:20.558] Timer tick, asking Buildbucket for the build status
[21:11:20.628] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:20.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:54:0) after 1m0s
[21:12:20.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:54:0)
[21:12:20.789] Timer tick, asking Buildbucket for the build status
[21:12:21.078] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:21.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:55:0) after 1m0s
[21:13:21.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:55:0)
[21:13:21.325] Timer tick, asking Buildbucket for the build status
[21:13:21.394] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:21.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:56:0) after 1m0s
[21:14:21.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:56:0)
[21:14:21.743] Timer tick, asking Buildbucket for the build status
[21:14:21.802] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:21.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:57:0) after 1m0s
[21:15:21.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:57:0)
[21:15:21.808] Timer tick, asking Buildbucket for the build status
[21:15:22.334] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:22.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:58:0) after 1m0s
[21:16:22.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:58:0)
[21:16:22.510] Timer tick, asking Buildbucket for the build status
[21:16:22.623] Build 8858461293776298768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:22.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023891357346767168:59:0) after 1m0s
[21:17:21.725] Received PubSub notification, asking Buildbucket for the build status
[21:17:22.138] Build 8858461293776298768: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:17:22.138] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610313440776689",
"created_by": "project:chromium",
"created_ts": "1610310003454328",
"id": "8858461293776298768",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"431a245c23d26370df1cfe0aa06d881b7720d09c\"}}",
"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\": \"431a245c23d26370df1cfe0aa06d881b7720d09c\", \"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-unho\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16743, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"0b90eef09f607336330f318bc8e65fa981634160\", \"got_dawn_revision\": \"3a0405597374c9ae12fb93e33aac35826455a958\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"431a245c23d26370df1cfe0aa06d881b7720d09c\", \"got_revision_cp\": \"refs/heads/master@{#841896}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"348ba200aa29d5b3a36d696d48d8f619e055219f\", \"got_v8_revision\": \"5d60a181de0863d8d00ae42ec0ee3521f51b501a\", \"got_v8_revision_cp\": \"refs/heads/8.9.229@{#1}\", \"got_webrtc_revision\": \"42082f9045e74e65b9e3a66b51093e0dc2f9375a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32929}\", \"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\": \"431a245c23d26370df1cfe0aa06d881b7720d09c\", \"swarm_hashes_refs/heads/master(at){#841896}_without_patch\": {\"absl_hardening_tests\": \"263a2bb1f83e7e3466c0b288cc1d2659da464fa3\", \"accessibility_unittests\": \"22e63dbc06b80829b08339357908a88b47d8b2bc\", \"angle_unittests\": \"915f84af2f71a9f12f648dfed609c440ebc1b97d\", \"app_shell_unittests\": \"1f51e961363aee2556ab12d641b41ecf521ed923\", \"aura_unittests\": \"e7c657ed2f822c94759f3f3220989f08a37e7b14\", \"base_unittests\": \"918d47eab7f5258d7f810806a622148e4af87a6f\", \"base_util_unittests\": \"cf51485a34fa795fc8724b63d7ab7d89d13eb817\", \"blink_common_unittests\": \"4098acdaf50f26d7f789483c5abef48ea04769e1\", \"blink_fuzzer_unittests\": \"4d8e38bce18eff602181e555343cb275798fab78\", \"blink_heap_unittests\": \"8b1c6479e24c9f637651e5fd20bddbaa93d41ec7\", \"blink_platform_unittests\": \"687b9c5dc5c43d0183e44ba930c0a2e01c6b4653\", \"blink_unittests\": \"60c3df9e19a9fbca2c9c3971f932da39f74dcb44\", \"boringssl_crypto_tests\": \"7ce3b570ffdbdf77e800d94b7e4eb4f2fc2e6dce\", \"boringssl_ssl_tests\": \"b7fa7f4d2c184bb8778f54111768d535187d006b\", \"browser_switcher_bho_unittests\": \"13203f7ff96092eaa32a638298cc9cc849de9e64\", \"browser_tests\": \"48d3398cc51dcc4d8f3a0739ee9c6f508bcf6702\", \"capture_unittests\": \"78f645a379f0a02b20573485c2a686724e46905d\", \"cast_unittests\": \"a30e8f3633bd6d5445285429447dfaa3aea8013a\", \"cc_unittests\": \"b31b94bae5c62bb629793f6080d79d6974b5f5f2\", \"chrome_app_unittests\": \"322ddb420170a644eea94c8cb7c25e611b207132\", \"chrome_cleaner_unittests\": \"832091c5fcc578f40cb1a2540b843107d410e264\", \"chrome_elf_unittests\": \"b9ba31895f314700da0c22c01a9f258bbb302bb2\", \"chromedriver_unittests\": \"16ab52c05cc5578056b12e5e78c2e2d419b4aacd\", \"components_browsertests\": \"9a2d3356c9673181c1a6bc6851b1d2ab2967495c\", \"components_unittests\": \"4dc88e28b39bf0c7f99a357a7a552584ff7d1f43\", \"compositor_unittests\": \"a1e3a5a6f886d42471ea08751d5d18bf65b2197f\", \"content_browsertests\": \"ae34167d8b0f2fccf8f4fa82877a6e5f529fc106\", \"content_unittests\": \"b004af1de0a1943a8b30abe52b5a0db753b69c5a\", \"courgette_unittests\": \"4187f2d13b5f861ae1d1b51514badda04ade0ed8\", \"crashpad_tests\": \"d502bcec8b6a598327c5385e6bf6e118c8f779ef\", \"cronet_tests\": \"7123ae3277f1a37c864790af33dbf24eb59c08ff\", \"cronet_unittests\": \"ab57de9555d3e19d003406e7ebd8f41135b19331\", \"crypto_unittests\": \"57026856aad1fbb82ab97a70a6fb43468277bc09\", \"delayloads_unittests\": \"0f1a29188fdf7793c74cc12ab1fbd5331dbd1440\", \"device_unittests\": \"860a9ff806b73caf9e5261aee5f3c33fde67b623\", \"display_unittests\": \"dd7e5dd916b3ef27809f0746165e0592f55716c4\", \"elevation_service_unittests\": \"0c915a91017d21957d65fe885b6fdac9b2060032\", \"events_unittests\": \"b78b3a7d3588cf785bde3f545e923b3f1364725b\", \"extensions_browsertests\": \"84ae9fdbd01008c365538586af54ed9a74fb68f7\", \"extensions_unittests\": \"1d60a30a0524a195fa6a9a9b8d6a1132e364066f\", \"filesystem_service_unittests\": \"1ca0ea8d55c20beb3ae6b4e48ab09f3ec1e2a651\", \"gcm_unit_tests\": \"225f2c5c516974e59c73bee1002927c678d3098a\", \"gcp_unittests\": \"bf022caadeace175ab996c3d25bae2e44d5402b6\", \"gfx_unittests\": \"c06da246fde7da2022dd44d204724b7946bbc048\", \"gin_unittests\": \"3b7aebbd9e419f002a6b10290f3aa8332baf4c19\", \"google_apis_unittests\": \"7df4e22be522699236c530aa8e1135c178f9b9c7\", \"gpu_unittests\": \"90349a780ca4fad596a8539a90be6fb289f4f1d3\", \"gwp_asan_unittests\": \"c532943201f95285ffff105b15098c14aba5a9a0\", \"headless_browsertests\": \"0835c51703d4489894bdb830f616fae32bdc0f85\", \"headless_unittests\": \"b5f70f626c253e8febc3c6768232c3ad254054f0\", \"install_static_unittests\": \"665d59413c0859a7b535b75b6a70438b5a201644\", \"installer_util_unittests\": \"9ec8e69e26a56dc5982d6899e52c82c0187dd57b\", \"interactive_ui_tests\": \"56e0fd15fcf126b48ddff5abe1603d5861c31aff\", \"ipc_tests\": \"23db75503de726520d1edf35673649aacf8bda5d\", \"jingle_unittests\": \"5ca18edcf3fd130c4a160b9c288141b34d0d1224\", \"latency_unittests\": \"9e3c872a9769cab94a1285954e55c1b5cd3715f7\", \"libjingle_xmpp_unittests\": \"951a8696c0ce92c1a9bfffb15adccd6391095199\", \"liburlpattern_unittests\": \"c873b6028142a0327d2a6b995de111159dbf54df\", \"media_blink_unittests\": \"7572c2222494885fe81b79fe3376c76885efc1b5\", \"media_unittests\": \"e87b4bf19578bbb70ce45dba081b7b5d898c107f\", \"message_center_unittests\": \"0c9e033e8077e7a89778f3383d075d68704744cb\", \"midi_unittests\": \"f63929d9edcc17808621281e80048a4248ec1008\", \"mojo_core_unittests\": \"61fdd7110655a1bd4e8e3a7b4d4a9238db7eb6bc\", \"mojo_unittests\": \"8744c043471ab521b3f923714e4dbc1db231f7d3\", \"nacl_loader_unittests\": \"be7d0a28943de31da5e008157794abaccef4c6f7\", \"native_theme_unittests\": \"2fc380bcfc887940a07369c0a0c73b93d909b739\", \"net_unittests\": \"22d1bfd47a1b93d0fb3a120a133c4485f1380572\", \"notification_helper_unittests\": \"e2702878ddc63fc83c2185fa85943ef71020658f\", \"pdf_unittests\": \"8beebbc03de566701163d0c948cb21a0a336df0d\", \"perfetto_unittests\": \"d956693d0cab28655fdd0ccff1cd2d952921a9bb\", \"ppapi_unittests\": \"c38c67b9caed5536fc22f751aa029afe80b1a8bc\", \"printing_unittests\": \"56500350115fbfde2decb89bb9b7ad90d6ea0b73\", \"remoting_unittests\": \"5cf504f0a06ac69951c95e1b213a1edf2155f617\", \"sbox_integration_tests\": \"1638ef3ea4e52bbafba3ba62b0e5fc8c708a9cfa\", \"sbox_unittests\": \"327163edbf124562c39110e07862fa88299ac951\", \"sbox_validation_tests\": \"7af29d15a8af9a0045e2da0349b0d275ba013016\", \"service_manager_unittests\": \"aaa77c679c7c70aee9d8de2aad75fa7302a1ad72\", \"services_unittests\": \"66ed7ce13d0b95e13e7d75d8693c7d9c3e689bae\", \"setup_unittests\": \"70410d503fd5341ca3adb137b1ca847fe8807ca0\", \"shell_dialogs_unittests\": \"3827543ad809aa901c2b18b7317260e3e9c7c289\", \"skia_unittests\": \"49c8b39eb161cd8d97e6a6093f4ad8bfb7f1da36\", \"snapshot_unittests\": \"64307f82b740f93dcde314f6f2c273b0643b6390\", \"sql_unittests\": \"b5c57c6691b922b73882152601541fcd9b1a411d\", \"storage_unittests\": \"654941301ad7ec4a3aa9d12a00cf6fb7053e67c2\", \"sync_integration_tests\": \"76242644e306446d8b00dc2121a3f977add3f786\", \"traffic_annotation_auditor_unittests\": \"e5fe4906589f5ffed060838cb5fdca5f283d8abc\", \"ui_base_unittests\": \"d95d2242c117b7a33f8bc3574138d0156b8a7dd1\", \"ui_touch_selection_unittests\": \"edb2f367eb0a8b630d74fe0b5d1dba6ef036a43d\", \"unit_tests\": \"03806e34e26fa459ea395e1017ab261f71394b3b\", \"updater_tests\": \"892efeccfc25137ad8b178a2639989919df5c416\", \"url_unittests\": \"7b7cadd7054df84738e2d3945782ccccdb27787f\", \"views_unittests\": \"db5a536e938b7dec01a048d5ca29e09c5aa813da\", \"viz_unittests\": \"2b0ad241aac4443ec5fcaf82decc0b2e34f432c6\", \"vr_common_unittests\": \"9e2e78a9c8cda2117122985e81a053a9b038928f\", \"vr_pixeltests\": \"6d39624ef778168d0aa58674ca593f190507331c\", \"weblayer_browsertests\": \"fc4e667cd73e913b03ca1ac583b2e84d1a2ef1db\", \"weblayer_unittests\": \"66f001961508dddf8d8097b9d9a2a08f1ba15dd3\", \"wm_unittests\": \"d25eb458a82f2652ec6ab3dc7235787cd4b5e122\", \"wtf_unittests\": \"8c570e4471cf120905789ab40b1f50fc8172e0a1\", \"zlib_unittests\": \"830d0834bbb812f50e90c5b7c59d5ad437289cf9\", \"zucchini_unittests\": \"00ebe21a752a5d428de2a77cdb979ead2f61ca14\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"builder_f77c1c08af9d0a4522ad60c9181aa925a8ae87fd7ec8443f284fb40f3b6bc27b_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-unho\"], \"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\": [\"5657-0417a3f\"], \"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": "1610310004451361",
"status": "COMPLETED",
"status_changed_ts": "1610313441321746",
"tags": [
"build_address:luci.chromium.ci/win-asan/16743",
"builder:win-asan",
"buildset:commit/git/431a245c23d26370df1cfe0aa06d881b7720d09c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/431a245c23d26370df1cfe0aa06d881b7720d09c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023891357346767168",
"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/8858461293776298768/+/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:5106c635caeea010",
"user_agent:luci-scheduler"
],
"updated_ts": "1610313441326175",
"url": "https://ci.chromium.org/b/8858461293776298768",
"utcnow_ts": "1610313442114309"
}
}
[21:17:22.138] Invocation finished in 57m21.472621795s with status SUCCEEDED