[00:46:08.939] New invocation is queued and will start shortly
[00:46:10.212] Starting the invocation (attempt 1)
[00:46:10.260] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:46:10.260] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:46:10.260] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021337898355430352",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"38397856b6df53334ee5d2597012137475aa8554\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021337898355430352",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/38397856b6df53334ee5d2597012137475aa8554",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38397856b6df53334ee5d2597012137475aa8554",
"gitiles_ref:refs/heads/master"
]
}
[00:46:11.202] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612745170420515",
"id": "8855907836139459568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"38397856b6df53334ee5d2597012137475aa8554\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612745171023522",
"tags": [
"build_address:luci.chromium.ci/win-asan/17228",
"builder:win-asan",
"buildset:commit/git/38397856b6df53334ee5d2597012137475aa8554",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38397856b6df53334ee5d2597012137475aa8554",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021337898355430352",
"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/8855907836139459568/+/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": "1612745171023666",
"url": "https://ci.chromium.org/b/8855907836139459568",
"utcnow_ts": "1612745171192863"
}
}
[00:46:11.202] Task URL: https://ci.chromium.org/b/8855907836139459568
[00:46:11.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:2:0) after 1m0s
[00:46:14.804] Received PubSub notification, asking Buildbucket for the build status
[00:46:14.873] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:11.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:2:0)
[00:47:11.387] Timer tick, asking Buildbucket for the build status
[00:47:11.482] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:11.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:4:0) after 1m0s
[00:48:11.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:4:0)
[00:48:11.555] Timer tick, asking Buildbucket for the build status
[00:48:11.663] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:11.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:5:0) after 1m0s
[00:49:11.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:5:0)
[00:49:11.678] Timer tick, asking Buildbucket for the build status
[00:49:12.463] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:12.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:6:0) after 1m0s
[00:50:12.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:6:0)
[00:50:12.447] Timer tick, asking Buildbucket for the build status
[00:50:12.736] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:12.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:7:0) after 1m0s
[00:51:13.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:7:0)
[00:51:13.244] Timer tick, asking Buildbucket for the build status
[00:51:13.385] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:13.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:8:0) after 1m0s
[00:52:13.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:8:0)
[00:52:13.723] Timer tick, asking Buildbucket for the build status
[00:52:13.782] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:13.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:9:0) after 1m0s
[00:53:13.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:9:0)
[00:53:13.821] Timer tick, asking Buildbucket for the build status
[00:53:14.089] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:14.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:10:0) after 1m0s
[00:54:14.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:10:0)
[00:54:14.210] Timer tick, asking Buildbucket for the build status
[00:54:14.303] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:14.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:11:0) after 1m0s
[00:55:14.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:11:0)
[00:55:14.362] Timer tick, asking Buildbucket for the build status
[00:55:14.526] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:14.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:12:0) after 1m0s
[00:56:14.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:12:0)
[00:56:14.611] Timer tick, asking Buildbucket for the build status
[00:56:15.109] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:15.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:13:0) after 1m0s
[00:57:15.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:13:0)
[00:57:15.094] Timer tick, asking Buildbucket for the build status
[00:57:15.157] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:15.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:14:0) after 1m0s
[00:58:15.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:14:0)
[00:58:15.281] Timer tick, asking Buildbucket for the build status
[00:58:15.355] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:15.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:15:0) after 1m0s
[00:59:15.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:15:0)
[00:59:15.364] Timer tick, asking Buildbucket for the build status
[00:59:15.528] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:15.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:16:0) after 1m0s
[01:00:16.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:16:0)
[01:00:16.228] Timer tick, asking Buildbucket for the build status
[01:00:16.477] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:16.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:17:0) after 1m0s
[01:01:16.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:17:0)
[01:01:16.582] Timer tick, asking Buildbucket for the build status
[01:01:16.693] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:16.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:18:0) after 1m0s
[01:02:16.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:18:0)
[01:02:16.846] Timer tick, asking Buildbucket for the build status
[01:02:17.115] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:17.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:19:0) after 1m0s
[01:03:17.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:19:0)
[01:03:17.106] Timer tick, asking Buildbucket for the build status
[01:03:17.291] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:17.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:20:0) after 1m0s
[01:04:17.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:20:0)
[01:04:17.337] Timer tick, asking Buildbucket for the build status
[01:04:17.804] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:17.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:21:0) after 1m0s
[01:05:17.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:21:0)
[01:05:17.854] Timer tick, asking Buildbucket for the build status
[01:05:17.982] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:17.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:22:0) after 1m0s
[01:06:18.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:22:0)
[01:06:18.050] Timer tick, asking Buildbucket for the build status
[01:06:18.203] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:18.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:23:0) after 1m0s
[01:07:18.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:23:0)
[01:07:18.222] Timer tick, asking Buildbucket for the build status
[01:07:18.425] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:18.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:24:0) after 1m0s
[01:08:18.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:24:0)
[01:08:18.523] Timer tick, asking Buildbucket for the build status
[01:08:18.657] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:18.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:25:0) after 1m0s
[01:09:18.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:25:0)
[01:09:18.694] Timer tick, asking Buildbucket for the build status
[01:09:18.821] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:18.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:26:0) after 1m0s
[01:10:18.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:26:0)
[01:10:18.930] Timer tick, asking Buildbucket for the build status
[01:10:19.119] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:19.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:27:0) after 1m0s
[01:11:19.158] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:27:0)
[01:11:19.158] Timer tick, asking Buildbucket for the build status
[01:11:19.349] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:19.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:28:0) after 1m0s
[01:12:19.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:28:0)
[01:12:19.430] Timer tick, asking Buildbucket for the build status
[01:12:19.735] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:19.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:29:0) after 1m0s
[01:13:19.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:29:0)
[01:13:19.853] Timer tick, asking Buildbucket for the build status
[01:13:20.315] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:20.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:30:0) after 1m0s
[01:14:20.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:30:0)
[01:14:20.366] Timer tick, asking Buildbucket for the build status
[01:14:20.636] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:20.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:31:0) after 1m0s
[01:15:20.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:31:0)
[01:15:20.664] Timer tick, asking Buildbucket for the build status
[01:15:20.776] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:20.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:32:0) after 1m0s
[01:16:20.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:32:0)
[01:16:20.857] Timer tick, asking Buildbucket for the build status
[01:16:21.000] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:21.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:33:0) after 1m0s
[01:17:21.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:33:0)
[01:17:21.092] Timer tick, asking Buildbucket for the build status
[01:17:21.168] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:21.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:34:0) after 1m0s
[01:18:21.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:34:0)
[01:18:21.223] Timer tick, asking Buildbucket for the build status
[01:18:22.412] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:22.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:35:0) after 1m0s
[01:19:22.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:35:0)
[01:19:22.557] Timer tick, asking Buildbucket for the build status
[01:19:22.649] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:22.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:36:0) after 1m0s
[01:20:22.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:36:0)
[01:20:22.790] Timer tick, asking Buildbucket for the build status
[01:20:23.181] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:23.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:37:0) after 1m0s
[01:21:23.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:37:0)
[01:21:23.348] Timer tick, asking Buildbucket for the build status
[01:21:25.196] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:25.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:38:0) after 1m0s
[01:22:25.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:38:0)
[01:22:25.593] Timer tick, asking Buildbucket for the build status
[01:22:25.683] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:25.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:39:0) after 1m0s
[01:23:25.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:39:0)
[01:23:25.973] Timer tick, asking Buildbucket for the build status
[01:23:26.076] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:26.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:40:0) after 1m0s
[01:24:26.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:40:0)
[01:24:26.098] Timer tick, asking Buildbucket for the build status
[01:24:26.344] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:26.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:41:0) after 1m0s
[01:25:26.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:41:0)
[01:25:26.389] Timer tick, asking Buildbucket for the build status
[01:25:26.686] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:26.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:42:0) after 1m0s
[01:26:26.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:42:0)
[01:26:26.713] Timer tick, asking Buildbucket for the build status
[01:26:27.799] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:27.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:43:0) after 1m0s
[01:27:27.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:43:0)
[01:27:27.825] Timer tick, asking Buildbucket for the build status
[01:27:28.120] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:28.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:44:0) after 1m0s
[01:28:28.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:44:0)
[01:28:28.232] Timer tick, asking Buildbucket for the build status
[01:28:28.703] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:28.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:45:0) after 1m0s
[01:29:28.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:45:0)
[01:29:28.765] Timer tick, asking Buildbucket for the build status
[01:29:29.200] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:29.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:46:0) after 1m0s
[01:30:29.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:46:0)
[01:30:29.229] Timer tick, asking Buildbucket for the build status
[01:30:29.314] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:29.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:47:0) after 1m0s
[01:31:29.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:47:0)
[01:31:29.341] Timer tick, asking Buildbucket for the build status
[01:31:29.462] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:29.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:48:0) after 1m0s
[01:32:29.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:48:0)
[01:32:29.503] Timer tick, asking Buildbucket for the build status
[01:32:30.002] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:30.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:49:0) after 1m0s
[01:33:30.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:49:0)
[01:33:30.014] Timer tick, asking Buildbucket for the build status
[01:33:30.122] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:30.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:50:0) after 1m0s
[01:34:30.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:50:0)
[01:34:30.164] Timer tick, asking Buildbucket for the build status
[01:34:30.250] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:30.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:51:0) after 1m0s
[01:35:30.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:51:0)
[01:35:30.295] Timer tick, asking Buildbucket for the build status
[01:35:30.703] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:30.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:52:0) after 1m0s
[01:36:30.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:52:0)
[01:36:30.691] Timer tick, asking Buildbucket for the build status
[01:36:31.022] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:31.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:53:0) after 1m0s
[01:37:31.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:53:0)
[01:37:31.063] Timer tick, asking Buildbucket for the build status
[01:37:31.159] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:31.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:54:0) after 1m0s
[01:38:31.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:54:0)
[01:38:31.273] Timer tick, asking Buildbucket for the build status
[01:38:31.378] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:31.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:55:0) after 1m0s
[01:39:31.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:55:0)
[01:39:31.423] Timer tick, asking Buildbucket for the build status
[01:39:31.532] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:31.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:56:0) after 1m0s
[01:40:31.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:56:0)
[01:40:31.634] Timer tick, asking Buildbucket for the build status
[01:40:32.060] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:32.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:57:0) after 1m0s
[01:41:32.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:57:0)
[01:41:32.263] Timer tick, asking Buildbucket for the build status
[01:41:32.606] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:32.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:58:0) after 1m0s
[01:42:32.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:58:0)
[01:42:32.700] Timer tick, asking Buildbucket for the build status
[01:42:33.029] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:33.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:59:0) after 1m0s
[01:43:33.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:59:0)
[01:43:33.053] Timer tick, asking Buildbucket for the build status
[01:43:33.248] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:33.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:60:0) after 1m0s
[01:44:33.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:60:0)
[01:44:33.405] Timer tick, asking Buildbucket for the build status
[01:44:33.526] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:33.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:61:0) after 1m0s
[01:45:33.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:61:0)
[01:45:33.575] Timer tick, asking Buildbucket for the build status
[01:45:33.775] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:33.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:62:0) after 1m0s
[01:46:33.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:62:0)
[01:46:33.852] Timer tick, asking Buildbucket for the build status
[01:46:33.957] Build 8855907836139459568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:33.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021337898355430352:63:0) after 1m0s
[01:46:39.577] Received PubSub notification, asking Buildbucket for the build status
[01:46:39.731] Build 8855907836139459568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:46:39.731] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612748798502159",
"created_by": "project:chromium",
"created_ts": "1612745170420515",
"id": "8855907836139459568",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"38397856b6df53334ee5d2597012137475aa8554\"}}",
"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\": \"38397856b6df53334ee5d2597012137475aa8554\", \"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-7-1hzs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17228, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"580961fbb01984d2d9b2f382609f9fc0c44e6b82\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"38397856b6df53334ee5d2597012137475aa8554\", \"got_revision_cp\": \"refs/heads/master@{#851562}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"462378addab907ab7452d38baccb27fe2ef7289a\", \"got_v8_revision_cp\": \"refs/heads/9.0.138@{#1}\", \"got_webrtc_revision\": \"c3c63c8ceaf4ac3646b1e17bfe8a97e7102f0913\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33185}\", \"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\": \"38397856b6df53334ee5d2597012137475aa8554\", \"swarm_hashes_refs/heads/master(at){#851562}_without_patch\": {\"absl_hardening_tests\": \"e923a172c088f98e05d18ec38e1f1a09870cd82b\", \"accessibility_unittests\": \"0c29d06caf7e42548edefc68e7e15a1caf670106\", \"angle_unittests\": \"dc83224d874422486f3d29d377b0c1d50f2c90db\", \"app_shell_unittests\": \"74f3d3222dbd759dbe83eb183256fdd54532e9d2\", \"aura_unittests\": \"923d3aee546b2e93c49089d5e34b8d3906339f08\", \"base_unittests\": \"6254fbe991c4faa73327cc1c04a668b8df736e92\", \"base_util_unittests\": \"cac3139eb4c7310596545a872b20b3535465153c\", \"blink_common_unittests\": \"77b9eb68538c199e2a15afe0fb167d5e69096d6b\", \"blink_fuzzer_unittests\": \"4585e8053c93dfa762fa83e53646a983e89c9b19\", \"blink_heap_unittests\": \"15b42170e9394e7b4447fa06c52d583c094e19ca\", \"blink_platform_unittests\": \"678586b1acc2c58d65abed5ef5bbbdd8a5b86189\", \"blink_unittests\": \"53b90d693692119203b9c6b958040cca070c62d5\", \"boringssl_crypto_tests\": \"2ce249d500b147cb8b3383d194860d56c98980e5\", \"boringssl_ssl_tests\": \"285463a5fbab12512d318753623a9364b24cc624\", \"browser_switcher_bho_unittests\": \"b0e86d55a229bc47f2f00fdc763151c979864031\", \"browser_tests\": \"16c8fd776e5637a3eb273f9765e3147277459f50\", \"capture_unittests\": \"e430065ddefbd052af62c912a025f7c48bfa465f\", \"cast_unittests\": \"91ed11b40ccfa9e72b6fc3e412afd3229bf0f06a\", \"cc_unittests\": \"d52edd02f6354ad21df951d4b022559e4b357c05\", \"chrome_app_unittests\": \"e53b1401af44559f9aa5cfa33395d437508b6675\", \"chrome_cleaner_unittests\": \"c6185b19c7f316d2c6d809e9cee6a2000a8af41a\", \"chrome_elf_unittests\": \"1703a76ce115450670b94570a01e196f9e7f8de8\", \"chromedriver_unittests\": \"679c4d60141c9aaa5724253c679523ebee548fc8\", \"components_browsertests\": \"4b592a49babc5a928bec41828e407dc7457af605\", \"components_unittests\": \"dd46341c5ae52c167bd162a0f470cb8554c1afe4\", \"compositor_unittests\": \"b4146cdc4396f570c3f51231a24b805210cb2c09\", \"content_browsertests\": \"3bb330aeb1f5ade33dc7ed2291539bcf54fb95fb\", \"content_unittests\": \"46e9967f68a0551acac0210252b8781d989a2049\", \"courgette_unittests\": \"227317c338ac853b0db91a6712b18156ca99beef\", \"crashpad_tests\": \"659ce6a3aa3f7bd13949413748e068bee8f094a7\", \"cronet_tests\": \"f76190e9ae2f148dbab3ecc345e7b488874eb3a9\", \"cronet_unittests\": \"bf143fd63b7ae58d55b6613ab11fba2de864c141\", \"crypto_unittests\": \"019ce5214aeac7b9ac33888c8e1feff043d7a407\", \"delayloads_unittests\": \"883f7008812f3fc02ddf1aecab340ef0713a8acd\", \"device_unittests\": \"7c8461ba993f067e98a428103c6d6cdf9af30167\", \"display_unittests\": \"c856634c238cf2cb4e6b16f0056489c584a97295\", \"elevation_service_unittests\": \"6f14272a15e22bf0d5adb3d8911ff51f6bf5a32c\", \"events_unittests\": \"3577f56c4a8b8d6d8558e6cc0522fbee8836684d\", \"extensions_browsertests\": \"95950d478433ed282907565fb42aa93b225ac1fa\", \"extensions_unittests\": \"82b8199c50b0ed1e1b63c9c3883207ac94c18e49\", \"filesystem_service_unittests\": \"5eb6a8b40c41405801e8b12388643ad2ff6a90e5\", \"gcm_unit_tests\": \"ef0779dec423a3fc582b23fb0c3386d4893e2f26\", \"gcp_unittests\": \"f6350041b6bf0a9c5796ef3dbe3b21c5df25498f\", \"gfx_unittests\": \"de30b3e714dcd11532b8812253602fb691fe495c\", \"gin_unittests\": \"3f1f57455883b9d84012ff738be54a8e942e5050\", \"google_apis_unittests\": \"ea843cc37e897bcb2392be909bfc7627f46d30bd\", \"gpu_unittests\": \"76dcb4682b0b554c2bc10fe47e03935711e40a3c\", \"gwp_asan_unittests\": \"c3e390a489c6b38809020c26229665d2f4d1e4b6\", \"headless_browsertests\": \"f6dc989ca6ba197f3eab364a14b008e2e44fc653\", \"headless_unittests\": \"863ada5d6b50318d265e930e90a0960b937f6342\", \"install_static_unittests\": \"3885baf17c0c89985d6706843feb1c123112b09b\", \"installer_util_unittests\": \"8dba9f02aa4e4e3a6c9895f5a123604e31a2d0f1\", \"interactive_ui_tests\": \"ac335bc1277c7f95bdcaaf287912425c978a77b1\", \"ipc_tests\": \"ff911ba348d51d98f36027ac4ad16c82a8a983cf\", \"jingle_unittests\": \"3a3404fb78c1896ad4e60edb7f310119f66e2f71\", \"latency_unittests\": \"8f8aca0472c6fdc48588ec96892bcbd664b4fdd1\", \"libjingle_xmpp_unittests\": \"e5a88047c4a5809b812962c6d79bcbc7bb9a0e1b\", \"liburlpattern_unittests\": \"1e38d5b7f267e3ca5b11688cd8ab3152c7d9bee0\", \"media_blink_unittests\": \"d83d9b3f044c96c1cf28a254cfe1c9ae216862c9\", \"media_unittests\": \"3ba31ec20810f77c8bd1217926e9bdf59f26ec22\", \"message_center_unittests\": \"7a075fb6cfd5000a520b89dfd88918e7390ea8ac\", \"midi_unittests\": \"f4f7cea006f123aefb2af3a54673a17c197b9273\", \"mojo_core_unittests\": \"61a9f0b3426be0e1622a2433fa95a2be2b3f5c49\", \"mojo_unittests\": \"0943db630e399fd3b1c26fd834ba7f675ac61c68\", \"nacl_loader_unittests\": \"795cd177f7945fbb70a3353933c11b388f2e0956\", \"native_theme_unittests\": \"12ea67b9ba5b38821435430f23e18167718ef17e\", \"net_unittests\": \"0f82ebd3fd034069e00b0cf8d20127e659dd3c0a\", \"notification_helper_unittests\": \"1fcaa58473e3147fcb6be95bbf570c9f645c6c71\", \"pdf_unittests\": \"f90ffd95cefe0f878071621e29d4d9a2a62abb58\", \"perfetto_unittests\": \"a006052ed55b90510625c6629b5c6c6eebf448ff\", \"ppapi_unittests\": \"9a0d6d6278f089bba580bc2195a6b24e41eca92f\", \"printing_unittests\": \"28b5d253ed8887444c5800420643ad5d174b307a\", \"remoting_unittests\": \"d71c0263d05ea12c3dcf23da096f87779435ac21\", \"sbox_integration_tests\": \"7834297f0b2125a54210a55c9f289e85925a8f01\", \"sbox_unittests\": \"c3561d031d353db6e4aba1992cb3d5f48902aa64\", \"sbox_validation_tests\": \"9e1cc4ffee760e39ebd3273281939f4c71336314\", \"service_manager_unittests\": \"35bdcdf7df4d25bf916ce0e05c610ab12a5c37e0\", \"services_unittests\": \"05c240f96c3639866544b710b436ddccd7970f0b\", \"setup_unittests\": \"58231176641e462e2e2f5782e76634885dfe9f53\", \"shell_dialogs_unittests\": \"62ea1499b87113f08c0f7a2752355fa777dcb7bb\", \"skia_unittests\": \"59c827e281c639ccac26a6aae1c8062d454a528b\", \"snapshot_unittests\": \"8f7ad9468a30801dc331a5d140e4ca193134d43c\", \"sql_unittests\": \"79bf7f461d34af2644e12f3f91df634c6153adc1\", \"storage_unittests\": \"dad733289913e8e7f86fc508247230e5e3331100\", \"sync_integration_tests\": \"bb7137ee882ad66572c6d7caa280dd8defd67f6a\", \"traffic_annotation_auditor_unittests\": \"0d8e96dca4d410c5e45f6cdec03e8d21564d0393\", \"ui_base_unittests\": \"e1569f17d52619c0f9ab0592315b046df849caba\", \"ui_touch_selection_unittests\": \"3b4e3eccf8cb4fc441816d7af4f48332730899fc\", \"unit_tests\": \"1e80d512ba2081446ff705deab063439115df222\", \"updater_tests\": \"a352e2843e6fa244e0057862205ca3343ac777fb\", \"url_unittests\": \"a52a481c06b7f5322ad7bca2fc7d7ff6d15e1897\", \"views_unittests\": \"4b72addbc9e56f4469acb06dec2716dc3b1adaa6\", \"viz_unittests\": \"9d36440d25daf7620ff3e1c7a8d2ec497ced6bcf\", \"vr_common_unittests\": \"fe22b567e08391e2e6146e072bb4a4b2726415a8\", \"vr_pixeltests\": \"771db46a308675b7d4f27318b55d4da0115eee77\", \"weblayer_browsertests\": \"4eaae43b6238c6baf0d3dba4d845594b05a9cf74\", \"weblayer_unittests\": \"2d98ca48fdb80ef0e0c0b290e96ef6ae4be4ec36\", \"wm_unittests\": \"4e24feeeda7b6404426cb46b484749ef323c42e3\", \"wtf_unittests\": \"f1f44ccccebd476af8706899d9d551228bba5ebb\", \"zlib_unittests\": \"900318e66f2280297884e1cf9aebe09bf35a6c96\", \"zucchini_unittests\": \"d2c53134b45b4fe241590a278f97769626ed653f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"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-7-1hzs\"], \"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\": [\"5676-c76598c\"], \"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": "1612745174108855",
"status": "COMPLETED",
"status_changed_ts": "1612748798787092",
"tags": [
"build_address:luci.chromium.ci/win-asan/17228",
"builder:win-asan",
"buildset:commit/git/38397856b6df53334ee5d2597012137475aa8554",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38397856b6df53334ee5d2597012137475aa8554",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021337898355430352",
"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/8855907836139459568/+/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:5197ebe9cb1ddc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612748798792013",
"url": "https://ci.chromium.org/b/8855907836139459568",
"utcnow_ts": "1612748799721159"
}
}
[01:46:39.731] Invocation finished in 1h0m30.807348888s with status SUCCEEDED