[04:01:24.395] New invocation is queued and will start shortly
[04:01:25.632] Starting the invocation (attempt 1)
[04:01:25.669] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:01:25.669] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:01:25.669] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021597404718104176",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e94d2f0da382cf20efefe39df3ec793e5af77be4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021597404718104176",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e94d2f0da382cf20efefe39df3ec793e5af77be4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e94d2f0da382cf20efefe39df3ec793e5af77be4",
"gitiles_ref:refs/heads/master"
]
}
[04:01:26.631] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612497685796169",
"id": "8856167342576626640",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e94d2f0da382cf20efefe39df3ec793e5af77be4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612497686395765",
"tags": [
"build_address:luci.chromium.ci/win-asan/17174",
"builder:win-asan",
"buildset:commit/git/e94d2f0da382cf20efefe39df3ec793e5af77be4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e94d2f0da382cf20efefe39df3ec793e5af77be4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021597404718104176",
"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/8856167342576626640/+/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": "1612497686395895",
"url": "https://ci.chromium.org/b/8856167342576626640",
"utcnow_ts": "1612497686604354"
}
}
[04:01:26.631] Task URL: https://ci.chromium.org/b/8856167342576626640
[04:01:26.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:2:0) after 1m0s
[04:01:28.938] Received PubSub notification, asking Buildbucket for the build status
[04:01:29.042] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:26.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:2:0)
[04:02:26.604] Timer tick, asking Buildbucket for the build status
[04:02:26.675] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:26.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:4:0) after 1m0s
[04:03:26.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:4:0)
[04:03:26.693] Timer tick, asking Buildbucket for the build status
[04:03:26.892] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:26.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:5:0) after 1m0s
[04:04:26.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:5:0)
[04:04:26.940] Timer tick, asking Buildbucket for the build status
[04:04:27.027] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:27.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:6:0) after 1m0s
[04:05:27.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:6:0)
[04:05:27.079] Timer tick, asking Buildbucket for the build status
[04:05:27.256] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:27.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:7:0) after 1m0s
[04:06:27.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:7:0)
[04:06:27.273] Timer tick, asking Buildbucket for the build status
[04:06:27.393] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:27.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:8:0) after 1m0s
[04:07:27.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:8:0)
[04:07:27.436] Timer tick, asking Buildbucket for the build status
[04:07:27.542] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:27.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:9:0) after 1m0s
[04:08:27.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:9:0)
[04:08:27.583] Timer tick, asking Buildbucket for the build status
[04:08:27.684] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:27.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:10:0) after 1m0s
[04:09:27.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:10:0)
[04:09:27.728] Timer tick, asking Buildbucket for the build status
[04:09:27.829] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:27.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:11:0) after 1m0s
[04:10:27.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:11:0)
[04:10:27.927] Timer tick, asking Buildbucket for the build status
[04:10:27.977] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:27.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:12:0) after 1m0s
[04:11:28.098] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:12:0)
[04:11:28.098] Timer tick, asking Buildbucket for the build status
[04:11:28.207] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:28.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:13:0) after 1m0s
[04:12:28.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:13:0)
[04:12:28.310] Timer tick, asking Buildbucket for the build status
[04:12:28.461] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:28.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:14:0) after 1m0s
[04:13:28.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:14:0)
[04:13:28.521] Timer tick, asking Buildbucket for the build status
[04:13:28.575] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:28.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:15:0) after 1m0s
[04:14:28.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:15:0)
[04:14:28.701] Timer tick, asking Buildbucket for the build status
[04:14:28.941] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:28.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:16:0) after 1m0s
[04:15:29.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:16:0)
[04:15:29.013] Timer tick, asking Buildbucket for the build status
[04:15:29.072] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:29.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:17:0) after 1m0s
[04:16:29.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:17:0)
[04:16:29.088] Timer tick, asking Buildbucket for the build status
[04:16:29.186] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:29.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:18:0) after 1m0s
[04:17:29.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:18:0)
[04:17:29.231] Timer tick, asking Buildbucket for the build status
[04:17:29.325] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:29.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:19:0) after 1m0s
[04:18:29.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:19:0)
[04:18:29.378] Timer tick, asking Buildbucket for the build status
[04:18:29.448] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:29.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:20:0) after 1m0s
[04:19:29.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:20:0)
[04:19:29.469] Timer tick, asking Buildbucket for the build status
[04:19:29.578] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:29.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:21:0) after 1m0s
[04:20:29.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:21:0)
[04:20:29.584] Timer tick, asking Buildbucket for the build status
[04:20:29.710] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:29.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:22:0) after 1m0s
[04:21:29.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:22:0)
[04:21:29.745] Timer tick, asking Buildbucket for the build status
[04:21:30.050] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:30.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:23:0) after 1m0s
[04:22:30.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:23:0)
[04:22:30.040] Timer tick, asking Buildbucket for the build status
[04:22:30.117] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:30.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:24:0) after 1m0s
[04:23:30.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:24:0)
[04:23:30.157] Timer tick, asking Buildbucket for the build status
[04:23:30.216] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:30.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:25:0) after 1m0s
[04:24:30.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:25:0)
[04:24:30.255] Timer tick, asking Buildbucket for the build status
[04:24:30.430] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:30.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:26:0) after 1m0s
[04:25:30.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:26:0)
[04:25:30.511] Timer tick, asking Buildbucket for the build status
[04:25:30.756] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:30.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:27:0) after 1m0s
[04:26:30.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:27:0)
[04:26:30.875] Timer tick, asking Buildbucket for the build status
[04:26:31.358] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:31.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:28:0) after 1m0s
[04:27:31.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:28:0)
[04:27:31.439] Timer tick, asking Buildbucket for the build status
[04:27:31.837] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:31.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:29:0) after 1m0s
[04:28:31.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:29:0)
[04:28:31.859] Timer tick, asking Buildbucket for the build status
[04:28:32.417] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:32.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:30:0) after 1m0s
[04:29:32.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:30:0)
[04:29:32.481] Timer tick, asking Buildbucket for the build status
[04:29:32.588] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:32.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:31:0) after 1m0s
[04:30:32.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:31:0)
[04:30:32.624] Timer tick, asking Buildbucket for the build status
[04:30:32.834] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:32.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:32:0) after 1m0s
[04:31:32.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:32:0)
[04:31:32.870] Timer tick, asking Buildbucket for the build status
[04:31:33.009] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:33.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:33:0) after 1m0s
[04:32:33.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:33:0)
[04:32:33.108] Timer tick, asking Buildbucket for the build status
[04:32:33.293] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:33.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:34:0) after 1m0s
[04:33:33.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:34:0)
[04:33:33.389] Timer tick, asking Buildbucket for the build status
[04:33:34.002] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:34.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:35:0) after 1m0s
[04:34:34.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:35:0)
[04:34:34.092] Timer tick, asking Buildbucket for the build status
[04:34:34.284] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:34.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:36:0) after 1m0s
[04:35:34.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:36:0)
[04:35:34.526] Timer tick, asking Buildbucket for the build status
[04:35:34.698] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:34.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:37:0) after 1m0s
[04:36:34.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:37:0)
[04:36:34.899] Timer tick, asking Buildbucket for the build status
[04:36:35.037] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:35.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:38:0) after 1m0s
[04:37:35.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:38:0)
[04:37:35.084] Timer tick, asking Buildbucket for the build status
[04:37:35.188] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:35.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:39:0) after 1m0s
[04:38:35.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:39:0)
[04:38:35.210] Timer tick, asking Buildbucket for the build status
[04:38:35.275] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:35.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:40:0) after 1m0s
[04:39:35.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:40:0)
[04:39:35.333] Timer tick, asking Buildbucket for the build status
[04:39:35.429] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:35.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:41:0) after 1m0s
[04:40:35.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:41:0)
[04:40:35.494] Timer tick, asking Buildbucket for the build status
[04:40:35.554] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:35.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:42:0) after 1m0s
[04:41:36.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:42:0)
[04:41:36.119] Timer tick, asking Buildbucket for the build status
[04:41:36.428] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:36.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:43:0) after 1m0s
[04:42:36.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:43:0)
[04:42:36.766] Timer tick, asking Buildbucket for the build status
[04:42:36.845] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:36.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:44:0) after 1m0s
[04:43:36.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:44:0)
[04:43:36.932] Timer tick, asking Buildbucket for the build status
[04:43:37.016] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:37.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:45:0) after 1m0s
[04:44:37.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:45:0)
[04:44:37.026] Timer tick, asking Buildbucket for the build status
[04:44:37.391] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:37.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:46:0) after 1m0s
[04:45:37.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:46:0)
[04:45:37.548] Timer tick, asking Buildbucket for the build status
[04:45:37.693] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:37.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:47:0) after 1m0s
[04:46:37.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:47:0)
[04:46:37.752] Timer tick, asking Buildbucket for the build status
[04:46:37.867] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:37.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:48:0) after 1m0s
[04:47:37.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:48:0)
[04:47:37.906] Timer tick, asking Buildbucket for the build status
[04:47:38.195] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:38.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:49:0) after 1m0s
[04:48:38.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:49:0)
[04:48:38.279] Timer tick, asking Buildbucket for the build status
[04:48:38.607] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:38.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:50:0) after 1m0s
[04:49:38.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:50:0)
[04:49:38.735] Timer tick, asking Buildbucket for the build status
[04:49:38.807] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:38.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:51:0) after 1m0s
[04:50:38.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:51:0)
[04:50:38.809] Timer tick, asking Buildbucket for the build status
[04:50:39.195] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:39.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:52:0) after 1m0s
[04:51:39.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:52:0)
[04:51:39.225] Timer tick, asking Buildbucket for the build status
[04:51:39.402] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:39.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:53:0) after 1m0s
[04:52:39.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:53:0)
[04:52:39.449] Timer tick, asking Buildbucket for the build status
[04:52:39.821] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:39.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:54:0) after 1m0s
[04:53:39.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:54:0)
[04:53:39.852] Timer tick, asking Buildbucket for the build status
[04:53:39.974] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:39.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:55:0) after 1m0s
[04:54:40.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:55:0)
[04:54:40.004] Timer tick, asking Buildbucket for the build status
[04:54:40.307] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:40.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:56:0) after 1m0s
[04:55:40.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:56:0)
[04:55:40.352] Timer tick, asking Buildbucket for the build status
[04:55:40.470] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:40.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:57:0) after 1m0s
[04:56:40.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:57:0)
[04:56:40.516] Timer tick, asking Buildbucket for the build status
[04:56:41.304] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:41.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:58:0) after 1m0s
[04:57:41.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:58:0)
[04:57:41.573] Timer tick, asking Buildbucket for the build status
[04:57:41.650] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:41.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:59:0) after 1m0s
[04:58:41.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:59:0)
[04:58:41.669] Timer tick, asking Buildbucket for the build status
[04:58:41.747] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:41.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:60:0) after 1m0s
[04:59:41.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:60:0)
[04:59:41.796] Timer tick, asking Buildbucket for the build status
[04:59:41.926] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:41.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:61:0) after 1m0s
[05:00:42.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:61:0)
[05:00:42.023] Timer tick, asking Buildbucket for the build status
[05:00:42.859] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:42.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:62:0) after 1m0s
[05:01:42.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:62:0)
[05:01:42.893] Timer tick, asking Buildbucket for the build status
[05:01:43.108] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:43.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:63:0) after 1m0s
[05:02:43.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:63:0)
[05:02:43.157] Timer tick, asking Buildbucket for the build status
[05:02:43.442] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:43.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:64:0) after 1m0s
[05:03:43.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:64:0)
[05:03:43.467] Timer tick, asking Buildbucket for the build status
[05:03:43.621] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:43.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:65:0) after 1m0s
[05:04:43.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:65:0)
[05:04:43.696] Timer tick, asking Buildbucket for the build status
[05:04:43.776] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:43.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:66:0) after 1m0s
[05:05:43.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:66:0)
[05:05:43.891] Timer tick, asking Buildbucket for the build status
[05:05:44.066] Build 8856167342576626640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:44.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021597404718104176:67:0) after 1m0s
[05:06:31.092] Received PubSub notification, asking Buildbucket for the build status
[05:06:31.318] Build 8856167342576626640: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:06:31.318] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612501589844381",
"created_by": "project:chromium",
"created_ts": "1612497685796169",
"id": "8856167342576626640",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e94d2f0da382cf20efefe39df3ec793e5af77be4\"}}",
"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\": \"e94d2f0da382cf20efefe39df3ec793e5af77be4\", \"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-8-jw2s\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17174, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-2\", \"got_angle_revision\": \"ad985fcdd806a26343b9b5f8408e551b515493e5\", \"got_dawn_revision\": \"4846281d3680e1e34ffeebe556ca25c285bc1151\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"e94d2f0da382cf20efefe39df3ec793e5af77be4\", \"got_revision_cp\": \"refs/heads/master@{#850955}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"a71154a1ede8ce4ce90eb23ede108aef7e94691d\", \"got_v8_revision_cp\": \"refs/heads/9.0.133@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"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\": \"e94d2f0da382cf20efefe39df3ec793e5af77be4\", \"swarm_hashes_refs/heads/master(at){#850955}_without_patch\": {\"absl_hardening_tests\": \"7d9681b79a2ebed5702191b789bbf39d1fe41f8d\", \"accessibility_unittests\": \"dc5042f0661d3259ed6fac3bcb688ad4e8ad9cb1\", \"angle_unittests\": \"a2fbea988b8fca8ddea5c844c645cb858fe64d3b\", \"app_shell_unittests\": \"8f1f45ee5661a174127e3687c6f45dd048001161\", \"aura_unittests\": \"87bd5125bb90a8040a6fab3c6100d65b35d253a3\", \"base_unittests\": \"6b798db92efa4ad3bfe31b445843691f58cdab76\", \"base_util_unittests\": \"22d8ce25d45c2d93856e21761d71471fdd85e4dc\", \"blink_common_unittests\": \"38a3c9b5bf98548f2c0f9b52fedd0448254c1128\", \"blink_fuzzer_unittests\": \"0c5adc01cdef6690a3a9ea747152136b0d6697cb\", \"blink_heap_unittests\": \"71f263a26ac4b6023b5b71ed7e46ee2165083ce3\", \"blink_platform_unittests\": \"d2f0f7f2530f658430cd511990f0cf23610e8a98\", \"blink_unittests\": \"c9d6d98784b03c3a893852cde5ce4f1ad84e561a\", \"boringssl_crypto_tests\": \"5f64a5c0e6ab10a1cb9fd471784a3e3c56b352ee\", \"boringssl_ssl_tests\": \"85cedc95589da50f8911772494c63c3a167198e7\", \"browser_switcher_bho_unittests\": \"2b3ac65085c19ac55c3c1eea810dcb03a44738d9\", \"browser_tests\": \"f495397a407a99eac40431f473e14c413e741522\", \"capture_unittests\": \"62feeb9ecd0d69fd60b859cb1f6cdb0d6c23c908\", \"cast_unittests\": \"f4c4c05a4a82f14f540c6579ae2234179aad92a7\", \"cc_unittests\": \"9367396869a5b82fc158113b8ec72589e76dac33\", \"chrome_app_unittests\": \"aee6d634b76e457d21e7b93b85d8cdbfb0288e27\", \"chrome_cleaner_unittests\": \"6ceaf47c3faba690111f0881aa6530b3832de6e0\", \"chrome_elf_unittests\": \"19adc6f04605d5cf15ae532b3022ee7f5cd29fb4\", \"chromedriver_unittests\": \"177991cc04f702a13a3cbd43a1faaa4b3bd47441\", \"components_browsertests\": \"56cd4c2c2305a3534d6a5adda39401ee47861450\", \"components_unittests\": \"afadb75203b24ba2df18b471c1f3823ead305b7d\", \"compositor_unittests\": \"b0f063da099fb25bdc4259a9b9284372e1fb5cd1\", \"content_browsertests\": \"c7213c9ad8ee5a63fbdae1a0a4663fbde236a958\", \"content_unittests\": \"7cf4dc51d35a50c556e51ad1e659034bd49f805c\", \"courgette_unittests\": \"6724c1d81ca18d810bdd3c3668ccad8858f0b417\", \"crashpad_tests\": \"6ac61b01e68b83411407f22efcdf7d0574ab036f\", \"cronet_tests\": \"a066f0c0431416409d02b1cfe5974515da8796fc\", \"cronet_unittests\": \"1f4011b1ca4e8b735693846e4eb92512e974216a\", \"crypto_unittests\": \"7e4808aa0a95af6e56a13fbd52dcdfa84e5261ea\", \"delayloads_unittests\": \"eb956cbf0a5f20914e3dd8bfd7f83a09c80f700b\", \"device_unittests\": \"dbfa76859f98e26020ac58f5ee69895146915a16\", \"display_unittests\": \"2020443f744a89baaa75fc5281a8dd6c41d7832c\", \"elevation_service_unittests\": \"15b429bffbc02b80914b5f465f54746f229ad517\", \"events_unittests\": \"675310dae4fa982cd18680f31b8cb8db3cd26937\", \"extensions_browsertests\": \"cd762e102f951471cfd17978bc2a12f8e1c5c00e\", \"extensions_unittests\": \"3179888e25ff6712c621b2d04708270aebc5764a\", \"filesystem_service_unittests\": \"e780a09f050680f5d9396ffa4f7035583d549b01\", \"gcm_unit_tests\": \"db2d57fa95a8b6969af10bc1176d74113e98b2e4\", \"gcp_unittests\": \"0165ea01ab0c5e80d69b0b2ec0f2930dfbe10325\", \"gfx_unittests\": \"905cd1db881c598fc3cb9a5d3f57bbc2a7ac7264\", \"gin_unittests\": \"f9b49fb1533e70d06a26bde14ae729341d8f0aa7\", \"google_apis_unittests\": \"299247c4a7da64c6e10eba85b13a83f573c8638b\", \"gpu_unittests\": \"489ea10cad4bf0af5746427aac8ef642b32b1785\", \"gwp_asan_unittests\": \"1df3f4f3b00ae3e49bc2d1a85c1ddae4cbede7b4\", \"headless_browsertests\": \"2104adf6e85b7e60e860bb4f80f07aee4297cfc4\", \"headless_unittests\": \"4ca23aca29bbde6f26a7dbde078dbfb579e7b6a3\", \"install_static_unittests\": \"5eaeef908f7e1ee78ebaf56645449613ccee2499\", \"installer_util_unittests\": \"b68618138dde63e4b863e2b108358321ca20f956\", \"interactive_ui_tests\": \"0827b15ba1e60900d02c61a9f828358ed254e42a\", \"ipc_tests\": \"3507f405af6e4194001df09ee3809e1a88e80ee4\", \"jingle_unittests\": \"d295fe62ae546e0c572ad6f745faec0af462f783\", \"latency_unittests\": \"859a06db9020c0fc8a2e12da9842235b5b77f6b7\", \"libjingle_xmpp_unittests\": \"3818a00e6116fd0123a635a5c1f902e2a7dd90bf\", \"liburlpattern_unittests\": \"cb999862e7d37204b6299c85df8b3bd2d2240ca1\", \"media_blink_unittests\": \"a7c3ce0137e13e925b41db23a217ff9bd92be0ee\", \"media_unittests\": \"2c3fa159b1a799d9666e9add37a8a86fbcd3edc9\", \"message_center_unittests\": \"a2a644682e368e6a8c024edc01e4135d79f78d63\", \"midi_unittests\": \"babcd296606bc631e29e45f5d40a6d21d7520377\", \"mojo_core_unittests\": \"6a3295ff0f7835ef20ca124a8bcf7e929cc1c89b\", \"mojo_unittests\": \"261dcc018de7e3c9ebc035dc16c22d85e717783c\", \"nacl_loader_unittests\": \"3b89240f1e1f40f81fe25f5dc7ae758f58b53e8e\", \"native_theme_unittests\": \"db5fa17d02a7baa71d3b8530f02b33331e432f4b\", \"net_unittests\": \"8003f8f13522e59622ac2d7044da0d1f9775a6b2\", \"notification_helper_unittests\": \"49b578ad878ce856e416aa656d8430b09c21bb45\", \"pdf_unittests\": \"6d505c32d5966c64254957b0305193e5518c493c\", \"perfetto_unittests\": \"651ac92c6ae9ffd2c3c7edce2055722048e6de83\", \"ppapi_unittests\": \"7921bd91ba64a8878560af2f670e3c66c76d844e\", \"printing_unittests\": \"e144442ca8b040e0e9a8f192a949e7ce866291b8\", \"remoting_unittests\": \"8412518f0824c05f28f8b3f1f298aae361fef17f\", \"sbox_integration_tests\": \"2ced6b1d08140f292833cbaf6abda35733886e6c\", \"sbox_unittests\": \"dc4147c142198d208e1d664dfe9248103bf335d1\", \"sbox_validation_tests\": \"21471a9cf52e2bc03652a38508e7f4e8cc30e0d6\", \"service_manager_unittests\": \"f504f0ee50e6aaa124c5ef7b2119193353a62f3b\", \"services_unittests\": \"667cdc23eff501145d659445e9ca8fe715c3c4f6\", \"setup_unittests\": \"3f96afc170f8db2ad7f86cd873235a468e74b11a\", \"shell_dialogs_unittests\": \"d52b17262b6b68f00275924fdee325dbc8933c3d\", \"skia_unittests\": \"252f9b1cb4ea28c024b87391aaed48e2a8788b6e\", \"snapshot_unittests\": \"9c4c01c8f59c9571fc93bec6e2acfdd48dfa9a06\", \"sql_unittests\": \"82a63b15846fa284542fac4af88b1fc7ecb03042\", \"storage_unittests\": \"14941396e96cd395476f168667b8db178e81e381\", \"sync_integration_tests\": \"053a9fda088bad9b5c27988bee7835c1300e4ca1\", \"traffic_annotation_auditor_unittests\": \"b743c03b7a2cc763c1261bbcd7a6b17a8b41f476\", \"ui_base_unittests\": \"0617b89a70c5d9bdd01a84ad421de2647696bc28\", \"ui_touch_selection_unittests\": \"26dd44b87e4155b8c7365628575a2294e0a15bc0\", \"unit_tests\": \"349329a202bbeff45f4c9796f7d30d7b61bb812c\", \"updater_tests\": \"0f6fb74e35f952cc35108b0cb555c610a155ec63\", \"url_unittests\": \"c22094e07b0d059df9fe2a9b48d58b40ecfb7a35\", \"views_unittests\": \"436de09637017da34701a1546a24158df8c238d5\", \"viz_unittests\": \"11f0e0757f8a055c47ed82ca634a5fe051b7c24c\", \"vr_common_unittests\": \"2324026c24c8f67c138a605760a7b1aaf07ca35b\", \"vr_pixeltests\": \"dd6dfed04c1f43ebf765e2bb74f5c2c0d768e280\", \"weblayer_browsertests\": \"1a2ea06b704fa11ae064bceca4f636028d32439c\", \"weblayer_unittests\": \"68cced45355dde8f4c87328fd503a504412d154c\", \"wm_unittests\": \"2ec98a82d510815b5161c38032fb7eb403e3aae3\", \"wtf_unittests\": \"67269ebffd605439ffc718d840323782e1f841a5\", \"zlib_unittests\": \"95f1464ae660e2d227425acf197c4da9e92e3f02\", \"zucchini_unittests\": \"371ecd3c8a70bb5339ca08e28d958b987225f3df\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-8-jw2s\"], \"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": "1612497688373877",
"status": "COMPLETED",
"status_changed_ts": "1612501590430421",
"tags": [
"build_address:luci.chromium.ci/win-asan/17174",
"builder:win-asan",
"buildset:commit/git/e94d2f0da382cf20efefe39df3ec793e5af77be4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e94d2f0da382cf20efefe39df3ec793e5af77be4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021597404718104176",
"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/8856167342576626640/+/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:51892b992bc31710",
"user_agent:luci-scheduler"
],
"updated_ts": "1612501590495279",
"url": "https://ci.chromium.org/b/8856167342576626640",
"utcnow_ts": "1612501591303082"
}
}
[05:06:31.318] Invocation finished in 1h5m6.947232421s with status SUCCEEDED