[04:08:54.491] New invocation is queued and will start shortly
[04:08:55.567] Starting the invocation (attempt 1)
[04:08:55.603] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:08:55.604] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:08:55.604] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028391705229375296",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"727e3eab36bb6cd654269ec2a8b4b0390941d41f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028391705229375296",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/727e3eab36bb6cd654269ec2a8b4b0390941d41f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/727e3eab36bb6cd654269ec2a8b4b0390941d41f",
"gitiles_ref:refs/heads/master"
]
}
[04:08:56.787] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606018135727905",
"id": "8862961643270119232",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"727e3eab36bb6cd654269ec2a8b4b0390941d41f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606018136615542",
"tags": [
"build_address:luci.chromium.ci/win-asan/15772",
"builder:win-asan",
"buildset:commit/git/727e3eab36bb6cd654269ec2a8b4b0390941d41f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/727e3eab36bb6cd654269ec2a8b4b0390941d41f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028391705229375296",
"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/8862961643270119232/+/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": "1606018136615755",
"url": "https://ci.chromium.org/b/8862961643270119232",
"utcnow_ts": "1606018136779220"
}
}
[04:08:56.787] Task URL: https://ci.chromium.org/b/8862961643270119232
[04:08:56.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:2:0) after 1m0s
[04:09:35.893] Received PubSub notification, asking Buildbucket for the build status
[04:09:36.079] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:59.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:2:0)
[04:09:59.710] Timer tick, asking Buildbucket for the build status
[04:09:59.770] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:59.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:4:0) after 1m0s
[04:10:59.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:4:0)
[04:10:59.825] Timer tick, asking Buildbucket for the build status
[04:10:59.940] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:59.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:5:0) after 1m0s
[04:11:59.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:5:0)
[04:11:59.964] Timer tick, asking Buildbucket for the build status
[04:12:00.195] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:00.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:6:0) after 1m0s
[04:13:00.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:6:0)
[04:13:00.236] Timer tick, asking Buildbucket for the build status
[04:13:00.577] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:00.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:7:0) after 1m0s
[04:14:00.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:7:0)
[04:14:00.635] Timer tick, asking Buildbucket for the build status
[04:14:00.742] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:00.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:8:0) after 1m0s
[04:15:00.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:8:0)
[04:15:00.810] Timer tick, asking Buildbucket for the build status
[04:15:01.235] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:01.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:9:0) after 1m0s
[04:16:01.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:9:0)
[04:16:01.254] Timer tick, asking Buildbucket for the build status
[04:16:01.311] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:01.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:10:0) after 1m0s
[04:17:01.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:10:0)
[04:17:01.334] Timer tick, asking Buildbucket for the build status
[04:17:01.403] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:01.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:11:0) after 1m0s
[04:18:01.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:11:0)
[04:18:01.433] Timer tick, asking Buildbucket for the build status
[04:18:01.879] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:01.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:12:0) after 1m0s
[04:19:01.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:12:0)
[04:19:01.897] Timer tick, asking Buildbucket for the build status
[04:19:02.007] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:02.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:13:0) after 1m0s
[04:20:02.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:13:0)
[04:20:02.041] Timer tick, asking Buildbucket for the build status
[04:20:02.170] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:02.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:14:0) after 1m0s
[04:21:02.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:14:0)
[04:21:02.224] Timer tick, asking Buildbucket for the build status
[04:21:02.280] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:02.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:15:0) after 1m0s
[04:22:02.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:15:0)
[04:22:02.334] Timer tick, asking Buildbucket for the build status
[04:22:02.478] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:02.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:16:0) after 1m0s
[04:23:02.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:16:0)
[04:23:02.561] Timer tick, asking Buildbucket for the build status
[04:23:02.699] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:02.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:17:0) after 1m0s
[04:24:02.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:17:0)
[04:24:02.836] Timer tick, asking Buildbucket for the build status
[04:24:03.368] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:03.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:18:0) after 1m0s
[04:25:03.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:18:0)
[04:25:03.388] Timer tick, asking Buildbucket for the build status
[04:25:03.864] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:03.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:19:0) after 1m0s
[04:26:03.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:19:0)
[04:26:03.923] Timer tick, asking Buildbucket for the build status
[04:26:04.044] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:04.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:20:0) after 1m0s
[04:27:04.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:20:0)
[04:27:04.062] Timer tick, asking Buildbucket for the build status
[04:27:04.329] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:04.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:21:0) after 1m0s
[04:28:04.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:21:0)
[04:28:04.352] Timer tick, asking Buildbucket for the build status
[04:28:04.620] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:04.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:22:0) after 1m0s
[04:29:04.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:22:0)
[04:29:04.671] Timer tick, asking Buildbucket for the build status
[04:29:04.812] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:04.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:23:0) after 1m0s
[04:30:05.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:23:0)
[04:30:05.088] Timer tick, asking Buildbucket for the build status
[04:30:05.215] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:05.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:24:0) after 1m0s
[04:31:06.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:24:0)
[04:31:06.216] Timer tick, asking Buildbucket for the build status
[04:31:06.347] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:06.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:25:0) after 1m0s
[04:32:06.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:25:0)
[04:32:06.372] Timer tick, asking Buildbucket for the build status
[04:32:06.498] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:06.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:26:0) after 1m0s
[04:33:06.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:26:0)
[04:33:06.575] Timer tick, asking Buildbucket for the build status
[04:33:07.193] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:07.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:27:0) after 1m0s
[04:34:07.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:27:0)
[04:34:07.310] Timer tick, asking Buildbucket for the build status
[04:34:07.394] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:07.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:28:0) after 1m0s
[04:35:07.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:28:0)
[04:35:07.455] Timer tick, asking Buildbucket for the build status
[04:35:08.170] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:08.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:29:0) after 1m0s
[04:36:08.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:29:0)
[04:36:08.195] Timer tick, asking Buildbucket for the build status
[04:36:08.316] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:08.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:30:0) after 1m0s
[04:37:08.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:30:0)
[04:37:08.335] Timer tick, asking Buildbucket for the build status
[04:37:08.448] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:08.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:31:0) after 1m0s
[04:38:08.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:31:0)
[04:38:08.502] Timer tick, asking Buildbucket for the build status
[04:38:08.620] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:08.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:32:0) after 1m0s
[04:39:08.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:32:0)
[04:39:08.649] Timer tick, asking Buildbucket for the build status
[04:39:08.824] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:08.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:33:0) after 1m0s
[04:40:08.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:33:0)
[04:40:08.847] Timer tick, asking Buildbucket for the build status
[04:40:09.251] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:09.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:34:0) after 1m0s
[04:41:09.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:34:0)
[04:41:09.269] Timer tick, asking Buildbucket for the build status
[04:41:09.393] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:09.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:35:0) after 1m0s
[04:42:09.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:35:0)
[04:42:09.412] Timer tick, asking Buildbucket for the build status
[04:42:09.740] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:09.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:36:0) after 1m0s
[04:43:09.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:36:0)
[04:43:09.758] Timer tick, asking Buildbucket for the build status
[04:43:09.844] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:09.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:37:0) after 1m0s
[04:44:09.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:37:0)
[04:44:09.898] Timer tick, asking Buildbucket for the build status
[04:44:10.029] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:10.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:38:0) after 1m0s
[04:45:10.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:38:0)
[04:45:10.050] Timer tick, asking Buildbucket for the build status
[04:45:10.170] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:10.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:39:0) after 1m0s
[04:46:10.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:39:0)
[04:46:10.195] Timer tick, asking Buildbucket for the build status
[04:46:10.668] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:10.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:40:0) after 1m0s
[04:47:10.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:40:0)
[04:47:10.799] Timer tick, asking Buildbucket for the build status
[04:47:11.305] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:11.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:41:0) after 1m0s
[04:48:11.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:41:0)
[04:48:11.326] Timer tick, asking Buildbucket for the build status
[04:48:11.444] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:11.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:42:0) after 1m0s
[04:49:11.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:42:0)
[04:49:11.516] Timer tick, asking Buildbucket for the build status
[04:49:12.283] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:12.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:43:0) after 1m0s
[04:50:12.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:43:0)
[04:50:12.311] Timer tick, asking Buildbucket for the build status
[04:50:12.440] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:12.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:44:0) after 1m0s
[04:51:12.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:44:0)
[04:51:12.460] Timer tick, asking Buildbucket for the build status
[04:51:12.571] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:12.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:45:0) after 1m0s
[04:52:12.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:45:0)
[04:52:12.592] Timer tick, asking Buildbucket for the build status
[04:52:12.779] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:12.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:46:0) after 1m0s
[04:53:12.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:46:0)
[04:53:12.802] Timer tick, asking Buildbucket for the build status
[04:53:13.094] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:13.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:47:0) after 1m0s
[04:54:13.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:47:0)
[04:54:13.147] Timer tick, asking Buildbucket for the build status
[04:54:13.271] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:13.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:48:0) after 1m0s
[04:55:13.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:48:0)
[04:55:13.291] Timer tick, asking Buildbucket for the build status
[04:55:13.766] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:13.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:49:0) after 1m0s
[04:56:13.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:49:0)
[04:56:13.785] Timer tick, asking Buildbucket for the build status
[04:56:13.868] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:13.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:50:0) after 1m0s
[04:57:13.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:50:0)
[04:57:13.923] Timer tick, asking Buildbucket for the build status
[04:57:14.547] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:14.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:51:0) after 1m0s
[04:58:14.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:51:0)
[04:58:14.590] Timer tick, asking Buildbucket for the build status
[04:58:14.809] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:14.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:52:0) after 1m0s
[04:59:14.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:52:0)
[04:59:14.830] Timer tick, asking Buildbucket for the build status
[04:59:14.931] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:14.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:53:0) after 1m0s
[05:00:14.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:53:0)
[05:00:14.957] Timer tick, asking Buildbucket for the build status
[05:00:15.113] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:15.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:54:0) after 1m0s
[05:01:15.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:54:0)
[05:01:15.135] Timer tick, asking Buildbucket for the build status
[05:01:15.281] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:15.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:55:0) after 1m0s
[05:02:15.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:55:0)
[05:02:15.304] Timer tick, asking Buildbucket for the build status
[05:02:15.389] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:15.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:56:0) after 1m0s
[05:03:15.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:56:0)
[05:03:15.409] Timer tick, asking Buildbucket for the build status
[05:03:15.496] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:15.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:57:0) after 1m0s
[05:04:15.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:57:0)
[05:04:15.515] Timer tick, asking Buildbucket for the build status
[05:04:16.475] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:16.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:58:0) after 1m0s
[05:05:16.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:58:0)
[05:05:16.456] Timer tick, asking Buildbucket for the build status
[05:05:16.643] Build 8862961643270119232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:16.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028391705229375296:59:0) after 1m0s
[05:06:06.309] Received PubSub notification, asking Buildbucket for the build status
[05:06:06.393] Build 8862961643270119232: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:06:06.393] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606021565312046",
"created_by": "project:chromium",
"created_ts": "1606018135727905",
"id": "8862961643270119232",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"727e3eab36bb6cd654269ec2a8b4b0390941d41f\"}}",
"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\": \"727e3eab36bb6cd654269ec2a8b4b0390941d41f\", \"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-3-xoby\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15772, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"e51c9068a84f14e42157c097eb764ec3009e0e54\", \"got_dawn_revision\": \"546a7c98c89718f2dadf1ae07088fa7cf2ccb53f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"727e3eab36bb6cd654269ec2a8b4b0390941d41f\", \"got_revision_cp\": \"refs/heads/master@{#830018}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"22f2e5ef66ed5c820532f27b141096d3ddb1966e\", \"got_v8_revision_cp\": \"refs/heads/8.9.31@{#1}\", \"got_webrtc_revision\": \"7d75f2ca782f38006bb65bc67533e582190454e8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32659}\", \"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\": \"727e3eab36bb6cd654269ec2a8b4b0390941d41f\", \"swarm_hashes_refs/heads/master(at){#830018}_without_patch\": {\"absl_hardening_tests\": \"03f8cf3a2f4169627e0ed28ba69c5c84c44e9bca\", \"accessibility_unittests\": \"a03d356cd0980b4b8d3b250e46cb0c26a370c7b6\", \"angle_unittests\": \"61d00a2ca7432bf2f24d1cfe2e38adfe0b5dada6\", \"app_shell_unittests\": \"7d7e65cd78f67e236960cf234ed234dcf8c6a2bc\", \"aura_unittests\": \"15fc04cd4537a71d8b6096617dd2349d051eb744\", \"base_unittests\": \"2cc4ea7aa9ee47c7008d4f476ce533cb2a1e7409\", \"base_util_unittests\": \"812836bc6dd6835f64320125857ead7b6fcb58da\", \"blink_common_unittests\": \"b15d318ba4edf8dde2fc6254367e4beb5b8896d2\", \"blink_fuzzer_unittests\": \"11f1573c7c1341241d8c4158553427bfa73b9de1\", \"blink_heap_unittests\": \"06de74592ac1f68981e8c9c1a89a7afbe0232338\", \"blink_platform_unittests\": \"21f8ff501205ea8f74ee4be7aa0d8d8f212e3b35\", \"blink_unittests\": \"f83c50abed7ede8094f0fe8c4c0db01c4f37c4e2\", \"boringssl_crypto_tests\": \"437c75175903f17674a9426a491631b4128312e4\", \"boringssl_ssl_tests\": \"f4ff432e1af43e5acb487de694833bb9c814729b\", \"browser_switcher_bho_unittests\": \"919516cdd26c2924eef5e801aba557510c19e98d\", \"browser_tests\": \"27149258c6d263a39023fbd5b8532c5b7de719fc\", \"capture_unittests\": \"22a609f5748795bb789c96b08548b04933a1253b\", \"cast_unittests\": \"6a1a7ed419288a50b120b1b1a17e0aced47a843c\", \"cc_unittests\": \"a14208cdd5b0cc45c85967da15a6de0c1123d805\", \"chrome_app_unittests\": \"aac988fcfc4f60fbe3c29d281202a97da628c31d\", \"chrome_cleaner_unittests\": \"ff2eceab41babadf86747e5e3f195104d067ef5c\", \"chrome_elf_unittests\": \"9d1d6b6266d048e758b8c6681b08a2ef3fac27ef\", \"chromedriver_unittests\": \"d8c4d1bad06e4c05ee4738379f2767aa1a399741\", \"components_browsertests\": \"41b9f03d85a7407ca7e98e7d9bbf415828177eb6\", \"components_unittests\": \"73bbba3eddd894e100252258d871995918922910\", \"compositor_unittests\": \"a4d82e9ecde394eed211cb2604be21a8562e02fd\", \"content_browsertests\": \"f56a514fb980e20127b3622ec2980579b30de1f8\", \"content_unittests\": \"7570289bdd99ab3c9ac682bfabf3d8400a2d011e\", \"courgette_unittests\": \"a17f5074c9c443370901ebbd313a73fad4f2232d\", \"crashpad_tests\": \"2fa8caccdc442fd1d58e8af08fc7595ed23482c8\", \"cronet_tests\": \"7013a86c17aa90c67530062a099c6b9bed7a735d\", \"cronet_unittests\": \"2ff79ee07887d8ed9f8c3da6ffacf63e68a1a49f\", \"crypto_unittests\": \"3da2dbc00740440fb318d4c7ff7565d884f2c7d4\", \"delayloads_unittests\": \"be36e7b87ccac8e94770566058e85901484db22a\", \"device_unittests\": \"6f982c61bef9136b75b2c2563582821a1cffabc4\", \"display_unittests\": \"0e7859cfabf5c929767bb12e87ee08954c1ffd2f\", \"elevation_service_unittests\": \"d67a4b6107c4d43b1b17c11e164e2cfe9a70cd06\", \"events_unittests\": \"45f3250a158c56a7e3420bc4c177e56ea16dd51d\", \"extensions_browsertests\": \"9c4392dacefa0e47723f2020653109899c27e3b8\", \"extensions_unittests\": \"c01d9cba11811d502971a18d4e90b8e641aa0710\", \"filesystem_service_unittests\": \"2c49fa12db6a28d75e3e6a8e78b3895e56c070e7\", \"gcm_unit_tests\": \"7960c2d3dd12dc1b7e285ed16e1e95847f7673f6\", \"gcp_unittests\": \"d1a4a11e6ee9ce591351ff932d2a64105ca48d34\", \"gfx_unittests\": \"cb6729b14366ef24107306417491488c5b2bbc66\", \"gin_unittests\": \"f3c145ba478a44a5a280790d3f2f8f22aa41d9e1\", \"google_apis_unittests\": \"8353c6698e102efc9ef158e9fe7f2e0718cf5906\", \"gpu_unittests\": \"3839049e0825876ebbd0627a46983c7ce4db9749\", \"gwp_asan_unittests\": \"d089adbe34f9eb3581284df92e7c9b9cae7bf80e\", \"headless_browsertests\": \"19dfb7d6ec13d9aa8d0d692e659949c28f5a06bf\", \"headless_unittests\": \"e75c5918f20f4ef4702f7fb038982d77d45c8f76\", \"install_static_unittests\": \"2eac198fddd4533a8bcd49f6988822bd932d23a1\", \"installer_util_unittests\": \"b3336d16f4ac2cd8a6c1d9d698e1ebbd1b0c6a6b\", \"interactive_ui_tests\": \"3823eeac192fde54f3d208cc9586a3b79cfa3a47\", \"ipc_tests\": \"6dc23c598bec6492b3b35c703fa754faf8898f2e\", \"jingle_unittests\": \"86b5b063273e2166ab9717f1f48afa04293f19f8\", \"latency_unittests\": \"1f5e76b18cc977300f57e8b499f32cbfcbb08f41\", \"libjingle_xmpp_unittests\": \"572ece09f722d4c605e3b5329e22992e71ddf109\", \"liburlpattern_unittests\": \"4d9b160b32a730f6228271723d31ba3533ea8a90\", \"media_blink_unittests\": \"f50e6381ad42ce70195369b5ea45f47bc4632712\", \"media_unittests\": \"f89a4e52cabe288e61ec1409d8b11936a826f0b4\", \"message_center_unittests\": \"028ae60abb1649de9075b574a071987b9bd2f5c9\", \"midi_unittests\": \"45ecca3c4a94403279fd3d8bcd8a2c4614e6db7e\", \"mojo_core_unittests\": \"475f21aeb586ace23eee0c18ba22b4aafbea0bc3\", \"mojo_unittests\": \"351773f5c83516ed9ba6221b5f3ede064b812f80\", \"nacl_loader_unittests\": \"2c55e3a3dadcc7e092d366d85e72f6973dc127b1\", \"native_theme_unittests\": \"ca5738b52eba8b510209eb143e06c9c2b0991998\", \"net_unittests\": \"3cd506f18c18a86a4994dbfe009d8c251640b384\", \"notification_helper_unittests\": \"8b45dc7c7d067a8bcca2791d638181e551acf338\", \"pdf_unittests\": \"079ad2fbeb4b1778055c705b1dcf45690ad4104c\", \"perfetto_unittests\": \"82bd99ddee7719e34966774e38dc3b74958506d3\", \"ppapi_unittests\": \"b1345821cd862156a5315ac21ba2dc8d835628da\", \"printing_unittests\": \"617c67c3875c5a01d7fff612561fcf4144e27615\", \"remoting_unittests\": \"e5a4a9f35cf48acf5cb991dea16e73797fc5478b\", \"sbox_integration_tests\": \"e6bd515c87268308824a2c219974ac6df2d152d1\", \"sbox_unittests\": \"fb25190ce879ae23856e8fa9ec2df388ea4a81f0\", \"sbox_validation_tests\": \"73711daf6e4bee6a49876d8236a80596d9026575\", \"service_manager_unittests\": \"7ec3a8a73c0fe2b89fa36375be5c937ff462bd7f\", \"services_unittests\": \"f55f053622e16815a95c38f7fb90e48c2f0b5c7a\", \"setup_unittests\": \"69379b6fbb8ab43b39d7f48ece2adfbe31c87a10\", \"shell_dialogs_unittests\": \"9b354db54eaf9acd42505ab65b9c894e6d6d7b5e\", \"skia_unittests\": \"23f518601809eec29f28ebf03b115857d16e4f55\", \"snapshot_unittests\": \"a9a4d3f7f3df2dc06344bcae749e915697cbf45b\", \"sql_unittests\": \"b758c138cfee371b392954b82b108423636c39bc\", \"storage_unittests\": \"1d5a6928191b2040d42f42ce4cf1d0e89ffa509c\", \"sync_integration_tests\": \"8097aacdc5f3fbdaa39b4465b33c20cb9920e27d\", \"traffic_annotation_auditor_unittests\": \"39ff804cda1e32068d856c2d734810601354f6cb\", \"ui_base_unittests\": \"eaff6cfc3c57d07dd27593df7241691ae88c126a\", \"ui_touch_selection_unittests\": \"a46b6240343f3a0147f3a620cf589802dbc6a833\", \"unit_tests\": \"61b7afe95ea9a0cdc64a230feca92b55180fec8c\", \"updater_tests\": \"995229a8725422e549e2381f223b3b1f93e1f5ba\", \"url_unittests\": \"42e19c600bfbc7921d0ad6fa17bc513be9975490\", \"views_unittests\": \"29f4ec61aecc6f0d8f1cace153bf9d54b71be54b\", \"viz_unittests\": \"cecc30b4085f6ead294150bee201650cf923c17e\", \"vr_common_unittests\": \"c1e9406bbd90a45b3a99faff80fcf18cd8e8256a\", \"vr_pixeltests\": \"1a401115e13ece2f2e54c5a44b5dfac6690edf0c\", \"weblayer_browsertests\": \"3fb3ad026da26564455b85083c2d8f2626515ef8\", \"weblayer_unittests\": \"a62ca58e73c3e0c3c73dd20c668c882ee647c8ca\", \"wm_unittests\": \"7ceca351d355792b601f51b4f1009b12ef8a7639\", \"wtf_unittests\": \"e3f8302c98b419326cc52acb562f21599ec81b39\", \"zlib_unittests\": \"ba6da342d7ae880f9c35d222d5c5daa0c2a982a3\", \"zucchini_unittests\": \"75335a4920051e8a5388fc68fa6c32da0ae47dce\"}}, \"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-3-xoby\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5637-4d8b832\"], \"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": "1606018175086264",
"status": "COMPLETED",
"status_changed_ts": "1606021565755581",
"tags": [
"build_address:luci.chromium.ci/win-asan/15772",
"builder:win-asan",
"buildset:commit/git/727e3eab36bb6cd654269ec2a8b4b0390941d41f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/727e3eab36bb6cd654269ec2a8b4b0390941d41f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028391705229375296",
"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/8862961643270119232/+/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:5006f5b57ae5da10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606021565759884",
"url": "https://ci.chromium.org/b/8862961643270119232",
"utcnow_ts": "1606021566383919"
}
}
[05:06:06.393] Invocation finished in 57m11.918062262s with status SUCCEEDED