[07:02:26.711] New invocation is queued and will start shortly
[07:02:27.984] Starting the invocation (attempt 1)
[07:02:28.017] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:02:28.017] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:02:28.017] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017962136088381904",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4262b46f9d7b76c4ec2adf0e12bb6444567f409b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9017962136088381904",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4262b46f9d7b76c4ec2adf0e12bb6444567f409b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4262b46f9d7b76c4ec2adf0e12bb6444567f409b",
"gitiles_ref:refs/heads/master"
]
}
[07:02:31.396] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1615964548162752",
"id": "8852532073904514656",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4262b46f9d7b76c4ec2adf0e12bb6444567f409b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615964549217150",
"tags": [
"build_address:luci.chromium.ci/win-asan/17830",
"builder:win-asan",
"buildset:commit/git/4262b46f9d7b76c4ec2adf0e12bb6444567f409b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4262b46f9d7b76c4ec2adf0e12bb6444567f409b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017962136088381904",
"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/8852532073904514656/+/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": "1615964549217810",
"url": "https://ci.chromium.org/b/8852532073904514656",
"utcnow_ts": "1615964549792629"
}
}
[07:02:31.396] Task URL: https://ci.chromium.org/b/8852532073904514656
[07:02:31.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:2:0) after 1m0s
[07:02:33.583] Received PubSub notification, asking Buildbucket for the build status
[07:02:33.746] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:31.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:2:0)
[07:03:31.594] Timer tick, asking Buildbucket for the build status
[07:03:31.667] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:31.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:4:0) after 1m0s
[07:04:31.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:4:0)
[07:04:31.701] Timer tick, asking Buildbucket for the build status
[07:04:31.797] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:31.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:5:0) after 1m0s
[07:05:31.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:5:0)
[07:05:31.845] Timer tick, asking Buildbucket for the build status
[07:05:32.108] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:32.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:6:0) after 1m0s
[07:06:32.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:6:0)
[07:06:32.186] Timer tick, asking Buildbucket for the build status
[07:06:32.248] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:32.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:7:0) after 1m0s
[07:07:32.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:7:0)
[07:07:32.280] Timer tick, asking Buildbucket for the build status
[07:07:32.427] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:32.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:8:0) after 1m0s
[07:08:32.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:8:0)
[07:08:32.482] Timer tick, asking Buildbucket for the build status
[07:08:32.571] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:32.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:9:0) after 1m0s
[07:09:32.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:9:0)
[07:09:32.588] Timer tick, asking Buildbucket for the build status
[07:09:32.661] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:32.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:10:0) after 1m0s
[07:10:32.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:10:0)
[07:10:32.770] Timer tick, asking Buildbucket for the build status
[07:10:32.870] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:32.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:11:0) after 1m0s
[07:11:32.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:11:0)
[07:11:32.993] Timer tick, asking Buildbucket for the build status
[07:11:33.375] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:33.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:12:0) after 1m0s
[07:12:33.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:12:0)
[07:12:33.400] Timer tick, asking Buildbucket for the build status
[07:12:33.517] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:33.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:13:0) after 1m0s
[07:13:33.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:13:0)
[07:13:33.545] Timer tick, asking Buildbucket for the build status
[07:13:33.736] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:33.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:14:0) after 1m0s
[07:14:33.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:14:0)
[07:14:33.808] Timer tick, asking Buildbucket for the build status
[07:14:33.881] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:33.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:15:0) after 1m0s
[07:15:33.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:15:0)
[07:15:33.920] Timer tick, asking Buildbucket for the build status
[07:15:33.997] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:33.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:16:0) after 1m0s
[07:16:34.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:16:0)
[07:16:34.040] Timer tick, asking Buildbucket for the build status
[07:16:34.132] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:34.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:17:0) after 1m0s
[07:17:34.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:17:0)
[07:17:34.182] Timer tick, asking Buildbucket for the build status
[07:17:34.395] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:34.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:18:0) after 1m0s
[07:18:34.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:18:0)
[07:18:34.454] Timer tick, asking Buildbucket for the build status
[07:18:34.541] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:34.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:19:0) after 1m0s
[07:19:34.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:19:0)
[07:19:34.565] Timer tick, asking Buildbucket for the build status
[07:19:34.673] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:34.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:20:0) after 1m0s
[07:20:34.730] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:20:0)
[07:20:34.730] Timer tick, asking Buildbucket for the build status
[07:20:34.804] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:34.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:21:0) after 1m0s
[07:21:34.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:21:0)
[07:21:34.960] Timer tick, asking Buildbucket for the build status
[07:21:35.061] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:35.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:22:0) after 1m0s
[07:22:35.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:22:0)
[07:22:35.224] Timer tick, asking Buildbucket for the build status
[07:22:35.393] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:35.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:23:0) after 1m0s
[07:23:35.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:23:0)
[07:23:35.412] Timer tick, asking Buildbucket for the build status
[07:23:35.588] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:35.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:24:0) after 1m0s
[07:24:35.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:24:0)
[07:24:35.606] Timer tick, asking Buildbucket for the build status
[07:24:36.108] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:36.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:25:0) after 1m0s
[07:25:36.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:25:0)
[07:25:36.129] Timer tick, asking Buildbucket for the build status
[07:25:36.310] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:36.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:26:0) after 1m0s
[07:26:36.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:26:0)
[07:26:36.378] Timer tick, asking Buildbucket for the build status
[07:26:36.449] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:36.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:27:0) after 1m0s
[07:27:36.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:27:0)
[07:27:36.583] Timer tick, asking Buildbucket for the build status
[07:27:36.660] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:36.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:28:0) after 1m0s
[07:28:36.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:28:0)
[07:28:36.853] Timer tick, asking Buildbucket for the build status
[07:28:37.060] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:37.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:29:0) after 1m0s
[07:29:37.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:29:0)
[07:29:37.091] Timer tick, asking Buildbucket for the build status
[07:29:37.164] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:37.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:30:0) after 1m0s
[07:30:37.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:30:0)
[07:30:37.206] Timer tick, asking Buildbucket for the build status
[07:30:37.291] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:37.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:31:0) after 1m0s
[07:31:37.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:31:0)
[07:31:37.365] Timer tick, asking Buildbucket for the build status
[07:31:37.439] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:37.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:32:0) after 1m0s
[07:32:37.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:32:0)
[07:32:37.655] Timer tick, asking Buildbucket for the build status
[07:32:37.843] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:37.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:33:0) after 1m0s
[07:33:37.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:33:0)
[07:33:37.960] Timer tick, asking Buildbucket for the build status
[07:33:38.179] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:38.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:34:0) after 1m0s
[07:34:38.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:34:0)
[07:34:38.195] Timer tick, asking Buildbucket for the build status
[07:34:38.276] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:38.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:35:0) after 1m0s
[07:35:38.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:35:0)
[07:35:38.315] Timer tick, asking Buildbucket for the build status
[07:35:38.386] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:38.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:36:0) after 1m0s
[07:36:38.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:36:0)
[07:36:38.519] Timer tick, asking Buildbucket for the build status
[07:36:38.749] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:38.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:37:0) after 1m0s
[07:37:38.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:37:0)
[07:37:38.891] Timer tick, asking Buildbucket for the build status
[07:37:39.092] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:39.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:38:0) after 1m0s
[07:38:39.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:38:0)
[07:38:39.150] Timer tick, asking Buildbucket for the build status
[07:38:39.235] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:39.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:39:0) after 1m0s
[07:39:39.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:39:0)
[07:39:39.335] Timer tick, asking Buildbucket for the build status
[07:39:39.463] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:39.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:40:0) after 1m0s
[07:40:39.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:40:0)
[07:40:39.507] Timer tick, asking Buildbucket for the build status
[07:40:39.590] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:39.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:41:0) after 1m0s
[07:41:39.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:41:0)
[07:41:39.766] Timer tick, asking Buildbucket for the build status
[07:41:39.852] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:39.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:42:0) after 1m0s
[07:42:39.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:42:0)
[07:42:39.923] Timer tick, asking Buildbucket for the build status
[07:42:40.271] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:40.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:43:0) after 1m0s
[07:43:40.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:43:0)
[07:43:40.401] Timer tick, asking Buildbucket for the build status
[07:43:40.587] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:40.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:44:0) after 1m0s
[07:44:40.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:44:0)
[07:44:40.684] Timer tick, asking Buildbucket for the build status
[07:44:41.240] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:41.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:45:0) after 1m0s
[07:45:41.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:45:0)
[07:45:41.339] Timer tick, asking Buildbucket for the build status
[07:45:41.425] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:41.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:46:0) after 1m0s
[07:46:41.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:46:0)
[07:46:41.474] Timer tick, asking Buildbucket for the build status
[07:46:41.556] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:41.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:47:0) after 1m0s
[07:47:41.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:47:0)
[07:47:41.666] Timer tick, asking Buildbucket for the build status
[07:47:42.079] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:42.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:48:0) after 1m0s
[07:48:42.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:48:0)
[07:48:42.071] Timer tick, asking Buildbucket for the build status
[07:48:43.162] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:43.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:49:0) after 1m0s
[07:49:43.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:49:0)
[07:49:43.286] Timer tick, asking Buildbucket for the build status
[07:49:43.503] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:43.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:50:0) after 1m0s
[07:50:43.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:50:0)
[07:50:43.706] Timer tick, asking Buildbucket for the build status
[07:50:43.925] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:43.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:51:0) after 1m0s
[07:51:43.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:51:0)
[07:51:43.942] Timer tick, asking Buildbucket for the build status
[07:51:44.059] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:44.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:52:0) after 1m0s
[07:52:44.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:52:0)
[07:52:44.103] Timer tick, asking Buildbucket for the build status
[07:52:44.194] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:44.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:53:0) after 1m0s
[07:53:44.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:53:0)
[07:53:44.344] Timer tick, asking Buildbucket for the build status
[07:53:44.626] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:44.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:54:0) after 1m0s
[07:54:51.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:54:0)
[07:54:51.894] Timer tick, asking Buildbucket for the build status
[07:54:52.118] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:52.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:55:0) after 1m0s
[07:55:52.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:55:0)
[07:55:52.099] Timer tick, asking Buildbucket for the build status
[07:55:52.179] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:52.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:56:0) after 1m0s
[07:56:52.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:56:0)
[07:56:52.299] Timer tick, asking Buildbucket for the build status
[07:56:52.697] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:52.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:57:0) after 1m0s
[07:57:52.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:57:0)
[07:57:52.762] Timer tick, asking Buildbucket for the build status
[07:57:52.869] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:52.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:58:0) after 1m0s
[07:58:52.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:58:0)
[07:58:52.892] Timer tick, asking Buildbucket for the build status
[07:58:52.974] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:52.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:59:0) after 1m0s
[07:59:52.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:59:0)
[07:59:52.993] Timer tick, asking Buildbucket for the build status
[07:59:53.081] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:53.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:60:0) after 1m0s
[08:00:53.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:60:0)
[08:00:53.237] Timer tick, asking Buildbucket for the build status
[08:00:53.330] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:53.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:61:0) after 1m0s
[08:01:53.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:61:0)
[08:01:53.404] Timer tick, asking Buildbucket for the build status
[08:01:53.592] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:53.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:62:0) after 1m0s
[08:02:53.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:62:0)
[08:02:53.658] Timer tick, asking Buildbucket for the build status
[08:02:54.527] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:54.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:63:0) after 1m0s
[08:03:54.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:63:0)
[08:03:54.547] Timer tick, asking Buildbucket for the build status
[08:03:54.628] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:54.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:64:0) after 1m0s
[08:04:54.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:64:0)
[08:04:54.646] Timer tick, asking Buildbucket for the build status
[08:04:54.855] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:54.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:65:0) after 1m0s
[08:05:54.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:65:0)
[08:05:54.878] Timer tick, asking Buildbucket for the build status
[08:05:54.959] Build 8852532073904514656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:54.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017962136088381904:66:0) after 1m0s
[08:05:55.402] Received PubSub notification, asking Buildbucket for the build status
[08:05:55.474] Build 8852532073904514656: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:05:55.474] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1615968354171504",
"created_by": "project:chromium",
"created_ts": "1615964548162752",
"id": "8852532073904514656",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4262b46f9d7b76c4ec2adf0e12bb6444567f409b\"}}",
"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\": \"4262b46f9d7b76c4ec2adf0e12bb6444567f409b\", \"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-rwcz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17830, \"clang_revision\": \"llvmorg-13-init-4330-g08a5277a-1\", \"got_angle_revision\": \"27e12398b33894bf345dc8c496d0bcde527f9892\", \"got_dawn_revision\": \"ed16517e32ce29c64abc05b19c0ea31671c8b053\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"4262b46f9d7b76c4ec2adf0e12bb6444567f409b\", \"got_revision_cp\": \"refs/heads/master@{#863661}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"c47dfc8ab1b8c17a6959f42e069091a38e72c040\", \"got_v8_revision_cp\": \"refs/heads/9.1.140@{#1}\", \"got_webrtc_revision\": \"ab6335041130fdc0365a604f49668eac9194f9d6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33479}\", \"is_cached\": true, \"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\": \"4262b46f9d7b76c4ec2adf0e12bb6444567f409b\", \"swarm_hashes_refs/heads/master(at){#863661}_without_patch\": {\"absl_hardening_tests\": \"077e2b391173e57ee321a29fa0f352a6bd46bb79\", \"accessibility_unittests\": \"19edc3301861854132ef3350b61dbebaa961db81\", \"angle_unittests\": \"0ea38f8bb19c81e3e42dcae906c059b7147678c0\", \"app_shell_unittests\": \"9a10f6994fbea02d440bc9a9cbdae63cd7059f74\", \"aura_unittests\": \"2acad7f3d8eb4a59745c184273d029d81a68c5ff\", \"base_unittests\": \"2bdae0a07d2ce13aba3a494b1d93c2f99aa79bef\", \"base_util_unittests\": \"3fc0d26c7d96f2f6432f19d7486fa122e4c40c7e\", \"blink_common_unittests\": \"1970422f6ff32b6086774b6986717401b844bffd\", \"blink_fuzzer_unittests\": \"1afcb4923957df2b0788017909354e7c7fb8a936\", \"blink_heap_unittests\": \"e66289b81a90f98454ff2c3da29c2a81660e1124\", \"blink_platform_unittests\": \"03f264170de663eae4f7908c6512a737f7badc01\", \"blink_unittests\": \"488e09aad3704fbc0c62f247ba2ce8028f318eec\", \"boringssl_crypto_tests\": \"f947378eee4d2d578843965bb77ac69846464919\", \"boringssl_ssl_tests\": \"e236b883fa89656da7ed4664349e169206b89b95\", \"browser_switcher_bho_unittests\": \"adade16fb07baa8e4b24e9d7ef62dc33ffc98a55\", \"browser_tests\": \"a0330a64370fd556ede1039bbd5cacfa2a3c1b1c\", \"capture_unittests\": \"b5fbe4e6559412610785f809000d6137c0cf3836\", \"cast_unittests\": \"f47549479fabbf0739b9c7e85ad82da28dc4b083\", \"cc_unittests\": \"8f08c54bc7f6171004ef4733a70f0c55aafd89e5\", \"chrome_app_unittests\": \"31a3889fea39a44b6cb4434432b98cbe9378e61a\", \"chrome_cleaner_unittests\": \"3ee2c6cb917c628d31655f7ab9d0cda9c57973db\", \"chrome_elf_unittests\": \"7c3a5efc8210577b7583a2247a50247b14cc6c7d\", \"chromedriver_unittests\": \"8a2bb9449be38f7e05fc52c7d2835fd4677939c3\", \"color_unittests\": \"022d04f102d7ccdf4dddc007d6ada37d689dc127\", \"components_browsertests\": \"5fecde5e604a823a42a29c38d84bdca9880b5050\", \"components_unittests\": \"337ee47b1b580a9ecdb4f02404f5e9b76c1efaf4\", \"compositor_unittests\": \"37573f810b8fc86f60322599e8cb85871a666fdc\", \"content_browsertests\": \"b964b5e327dd408a53528c8b21248faa64a13152\", \"content_unittests\": \"8cd4000f08436c1e5264cad4687945ae4555e527\", \"courgette_unittests\": \"bef05f782b53b04042a95cd58a307369148b06eb\", \"crashpad_tests\": \"f2b840200eb3a7ca99f8407e7448488afd0d02c8\", \"cronet_tests\": \"d86ef2cf49dcdc1a4516e68e1fa7156696bf8a4e\", \"cronet_unittests\": \"a158faa8166854d49c91cbbc5a2dd3a6b05cb50f\", \"crypto_unittests\": \"be85617b18b0b012b3c86d5f19326800200d4b77\", \"delayloads_unittests\": \"697d3bca29cb0a092e0ac6aa179f087b63a75c0e\", \"device_unittests\": \"81b07a215a753218d9789a3734fbe7127ab7142e\", \"display_unittests\": \"8f7bb171b6bde85b6059615dc4c5f534083aac31\", \"elevation_service_unittests\": \"2a5582c39d5c19131360cddbcfe4e5e9a8626231\", \"events_unittests\": \"84a10d4b49ff4fa726bfd724c864075806812dc7\", \"extensions_browsertests\": \"5fb93b64dad137c68d5dfa502ccaf662193a9690\", \"extensions_unittests\": \"084dcfc7eaeae4c09ab16e6877604686fbac23c7\", \"filesystem_service_unittests\": \"ffcf1b278a27db041af28fedc7bab14d4ce36c3b\", \"gcm_unit_tests\": \"9901087f3e14204521a272d8d0734650a9832a26\", \"gcp_unittests\": \"1d72464e0801e4ade6aff470645b9997adfba66d\", \"gfx_unittests\": \"c36ee442bf70e4337ab6537b72a2b9dbe8be961c\", \"gin_unittests\": \"888b09ec0df476d54f20bca86b5ba8e7d1cffd2e\", \"google_apis_unittests\": \"45d3ec60e6e3b36949aea8155ef5530d57c920f6\", \"gpu_unittests\": \"640cf491cd58684dc61e4d90d1ab247dc5027715\", \"gwp_asan_unittests\": \"7c6e3be289176f8dba812fe211ebcbebe14076ce\", \"headless_browsertests\": \"10db40c2c300e542f2fd527705360dfd91c6f513\", \"headless_unittests\": \"5fb8b40381127e79f243802a445169d4402f826b\", \"install_static_unittests\": \"496f2fcbfe4c5519b61d6009f81d6c06c2843f9f\", \"installer_util_unittests\": \"610da89c4e789faf9de05e743cbc31ee6fdce05f\", \"interactive_ui_tests\": \"a3dcea668b88ebc7b02b43c186b46d4faa5c43a6\", \"ipc_tests\": \"78864a5bd76268c170b9aa62897764b67f13d6d2\", \"jingle_unittests\": \"3f89d7fca748cd0a4cb48313cfca2739a8f6626c\", \"latency_unittests\": \"d5627af9a6ac3a0ed06bfb398fe1a73b1480d72f\", \"libjingle_xmpp_unittests\": \"1915e639bfa8c78a4aa5d562061f0595c127bacb\", \"liburlpattern_unittests\": \"2cb790234cf44ba93de78940005dab2ba536d193\", \"media_blink_unittests\": \"2ea53dcea5af79399c6ffac3bfe0710e323b2dec\", \"media_unittests\": \"55e0fb8fa3a234165f36d4dedde94a354bc897c3\", \"message_center_unittests\": \"1fe030b42c78af064328e4252789ec21fbf9d3df\", \"midi_unittests\": \"f460b2e6fbb09603cb9f7a38df7898eead14f4c5\", \"mojo_core_unittests\": \"843da6c637049f3a4a02d35ae7b136067b71dd5b\", \"mojo_unittests\": \"f170bc21ddeb2f4b58508bf36eb833eb9e57b3e0\", \"nacl_loader_unittests\": \"b16cea8515fe0d59cd28af7767342ae913a5fe3b\", \"native_theme_unittests\": \"b930367a02aa7ee0a29cea0c3f2c143c1cf2e488\", \"net_unittests\": \"0a2fb1f10070fe6e0dfddf7f205ef8da2fb4f074\", \"notification_helper_unittests\": \"dea6d32af14bb9b20d34e49123262ed48480449b\", \"pdf_unittests\": \"9076e63c8872074ddee3df0d278a4f10971e92e5\", \"perfetto_unittests\": \"4de7406a57744bedcdfe5ddccc2bd00be0a71051\", \"ppapi_unittests\": \"23f63638844fc0b69849333732833aa7bd994488\", \"printing_unittests\": \"b25bb9045f6402cad682f3b6521f822ee57dc6f3\", \"remoting_unittests\": \"8ea55c226af9f873d72a5786636279b42ec48d7f\", \"sbox_integration_tests\": \"126997089128f35d290eec98dee8daf00a2f7f14\", \"sbox_unittests\": \"1ff8e6a6fcd4004013a867ccec21da9af8f12775\", \"sbox_validation_tests\": \"8acba04f521eed8b666839cb5278e430231bdb99\", \"service_manager_unittests\": \"cff45a76055cfbcca62a14b1d03ec83003a3e18c\", \"services_unittests\": \"ecb7fa212b2f4c996141839d3ba64e5f866124d3\", \"setup_unittests\": \"7493f453d9e1fa1228ab3ba5794bf8a8ad6b6e08\", \"shell_dialogs_unittests\": \"3ca1abd4fc6c486701b97140ddc0a80d762662ac\", \"skia_unittests\": \"d49e01c428c1264cb8d2db4d2f7b751c669b5da7\", \"snapshot_unittests\": \"6e0ffd16314ff36d7baa5e9f5870409ecf216d73\", \"sql_unittests\": \"3c9b2efc596f6a351e460bcfe43f63495b6eb002\", \"storage_unittests\": \"d7a98b262ea3d9413faa8b9c3208ddadba8a3381\", \"sync_integration_tests\": \"95e770e489ea6f2151e410db6e4b135caeb2019a\", \"traffic_annotation_auditor_unittests\": \"4abc895b9bce21ed284e97a7ba97413590e439c9\", \"ui_base_unittests\": \"daacc74cca2b6ce8bb8724d9046d843d4ba91069\", \"ui_touch_selection_unittests\": \"f703ea737d70342cceb090932cb1d4d6280a30fd\", \"unit_tests\": \"f04f3c41b1eb3d2c7ca7d2080f2d3268423ddcb0\", \"updater_tests\": \"2b7f416af666b54b78ce6bacf3f8673ca17965f0\", \"url_unittests\": \"d9debe2a9301432455bc142ae9c879cebe51711b\", \"views_unittests\": \"4972b6800d8150f624fb0476f554b1fd9a53e194\", \"viz_unittests\": \"d5dbe52db16baca1d7784efe383287681ffd286c\", \"vr_common_unittests\": \"72bba6f81cbf68cc50687016a4251c03a7213bd9\", \"vr_pixeltests\": \"1268543e7f4e597ea6bb36f120512dbb33ae9fc9\", \"weblayer_browsertests\": \"74643b2c5c70049ca87ee0f554fb282b77845a57\", \"weblayer_unittests\": \"2d6b7c30d22973804d639bd3a3dc67023c72cdd6\", \"wm_unittests\": \"edb98b83886dadb329e9cdce2e19f181116c87ca\", \"wtf_unittests\": \"f0eb139a9539a5e0022ad6ab96457e90162b68a2\", \"zlib_unittests\": \"a3415dfe9288f7c5b6e47063123c5c9da48a0b30\", \"zucchini_unittests\": \"238cbb3d1e1bbb3fb494b7df6860f4f93bc2ce1f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-0-rwcz\"], \"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\": [\"5754-8c4f760\"], \"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": "1615964552271954",
"status": "COMPLETED",
"status_changed_ts": "1615968354652410",
"tags": [
"build_address:luci.chromium.ci/win-asan/17830",
"builder:win-asan",
"buildset:commit/git/4262b46f9d7b76c4ec2adf0e12bb6444567f409b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4262b46f9d7b76c4ec2adf0e12bb6444567f409b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017962136088381904",
"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/8852532073904514656/+/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:5257cfb9a8191f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615968354656280",
"url": "https://ci.chromium.org/b/8852532073904514656",
"utcnow_ts": "1615968355465662"
}
}
[08:05:55.474] Invocation finished in 1h3m28.778355941s with status SUCCEEDED