[01:35:14.932] New invocation is queued and will start shortly
[01:35:16.418] Starting the invocation (attempt 1)
[01:35:16.452] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:35:16.453] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:35:16.453] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021968988022077872",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"61c36babbce51fb69aeeb5768e3c71274315dbbf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021968988022077872",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/61c36babbce51fb69aeeb5768e3c71274315dbbf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/61c36babbce51fb69aeeb5768e3c71274315dbbf",
"gitiles_ref:refs/heads/master"
]
}
[01:35:18.392] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612143316620841",
"id": "8856538925589598560",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"61c36babbce51fb69aeeb5768e3c71274315dbbf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612143317987393",
"tags": [
"build_address:luci.chromium.ci/win-asan/17103",
"builder:win-asan",
"buildset:commit/git/61c36babbce51fb69aeeb5768e3c71274315dbbf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/61c36babbce51fb69aeeb5768e3c71274315dbbf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021968988022077872",
"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/8856538925589598560/+/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": "1612143317988678",
"url": "https://ci.chromium.org/b/8856538925589598560",
"utcnow_ts": "1612143318335633"
}
}
[01:35:18.392] Task URL: https://ci.chromium.org/b/8856538925589598560
[01:35:18.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:2:0) after 1m0s
[01:35:24.284] Received PubSub notification, asking Buildbucket for the build status
[01:35:24.356] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:18.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:2:0)
[01:36:18.441] Timer tick, asking Buildbucket for the build status
[01:36:18.517] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:18.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:4:0) after 1m0s
[01:37:18.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:4:0)
[01:37:18.565] Timer tick, asking Buildbucket for the build status
[01:37:18.690] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:18.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:5:0) after 1m0s
[01:38:18.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:5:0)
[01:38:18.827] Timer tick, asking Buildbucket for the build status
[01:38:18.938] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:18.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:6:0) after 1m0s
[01:39:18.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:6:0)
[01:39:18.991] Timer tick, asking Buildbucket for the build status
[01:39:19.318] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:19.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:7:0) after 1m0s
[01:40:19.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:7:0)
[01:40:19.336] Timer tick, asking Buildbucket for the build status
[01:40:19.441] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:19.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:8:0) after 1m0s
[01:41:19.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:8:0)
[01:41:19.530] Timer tick, asking Buildbucket for the build status
[01:41:19.626] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:19.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:9:0) after 1m0s
[01:42:19.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:9:0)
[01:42:19.632] Timer tick, asking Buildbucket for the build status
[01:42:19.691] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:19.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:10:0) after 1m0s
[01:43:19.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:10:0)
[01:43:19.669] Timer tick, asking Buildbucket for the build status
[01:43:19.731] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:19.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:11:0) after 1m0s
[01:44:19.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:11:0)
[01:44:19.766] Timer tick, asking Buildbucket for the build status
[01:44:19.902] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:19.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:12:0) after 1m0s
[01:45:19.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:12:0)
[01:45:19.936] Timer tick, asking Buildbucket for the build status
[01:45:20.140] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:20.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:13:0) after 1m0s
[01:46:20.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:13:0)
[01:46:20.171] Timer tick, asking Buildbucket for the build status
[01:46:20.281] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:20.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:14:0) after 1m0s
[01:47:20.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:14:0)
[01:47:20.298] Timer tick, asking Buildbucket for the build status
[01:47:20.508] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:20.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:15:0) after 1m0s
[01:48:20.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:15:0)
[01:48:20.527] Timer tick, asking Buildbucket for the build status
[01:48:20.629] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:20.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:16:0) after 1m0s
[01:49:20.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:16:0)
[01:49:20.638] Timer tick, asking Buildbucket for the build status
[01:49:20.921] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:20.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:17:0) after 1m0s
[01:50:20.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:17:0)
[01:50:20.938] Timer tick, asking Buildbucket for the build status
[01:50:21.250] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:21.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:18:0) after 1m0s
[01:51:21.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:18:0)
[01:51:21.273] Timer tick, asking Buildbucket for the build status
[01:51:21.396] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:21.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:19:0) after 1m0s
[01:52:21.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:19:0)
[01:52:21.573] Timer tick, asking Buildbucket for the build status
[01:52:21.683] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:21.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:20:0) after 1m0s
[01:53:21.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:20:0)
[01:53:21.702] Timer tick, asking Buildbucket for the build status
[01:53:21.823] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:21.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:21:0) after 1m0s
[01:54:21.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:21:0)
[01:54:21.898] Timer tick, asking Buildbucket for the build status
[01:54:22.040] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:22.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:22:0) after 1m0s
[01:55:22.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:22:0)
[01:55:22.073] Timer tick, asking Buildbucket for the build status
[01:55:22.438] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:22.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:23:0) after 1m0s
[01:56:22.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:23:0)
[01:56:22.556] Timer tick, asking Buildbucket for the build status
[01:56:23.132] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:23.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:24:0) after 1m0s
[01:57:23.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:24:0)
[01:57:23.154] Timer tick, asking Buildbucket for the build status
[01:57:23.316] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:23.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:25:0) after 1m0s
[01:58:23.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:25:0)
[01:58:23.374] Timer tick, asking Buildbucket for the build status
[01:58:23.486] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:23.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:26:0) after 1m0s
[01:59:23.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:26:0)
[01:59:23.544] Timer tick, asking Buildbucket for the build status
[01:59:23.832] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:23.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:27:0) after 1m0s
[02:00:23.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:27:0)
[02:00:23.925] Timer tick, asking Buildbucket for the build status
[02:00:24.037] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:24.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:28:0) after 1m0s
[02:01:24.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:28:0)
[02:01:24.055] Timer tick, asking Buildbucket for the build status
[02:01:24.419] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:24.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:29:0) after 1m0s
[02:02:24.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:29:0)
[02:02:24.438] Timer tick, asking Buildbucket for the build status
[02:02:25.701] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:25.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:30:0) after 1m0s
[02:03:25.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:30:0)
[02:03:25.692] Timer tick, asking Buildbucket for the build status
[02:03:25.781] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:25.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:31:0) after 1m0s
[02:04:25.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:31:0)
[02:04:25.775] Timer tick, asking Buildbucket for the build status
[02:04:25.995] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:25.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:32:0) after 1m0s
[02:05:26.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:32:0)
[02:05:26.014] Timer tick, asking Buildbucket for the build status
[02:05:26.152] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:26.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:33:0) after 1m0s
[02:06:26.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:33:0)
[02:06:26.193] Timer tick, asking Buildbucket for the build status
[02:06:26.434] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:26.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:34:0) after 1m0s
[02:07:26.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:34:0)
[02:07:26.453] Timer tick, asking Buildbucket for the build status
[02:07:27.124] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:27.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:35:0) after 1m0s
[02:08:27.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:35:0)
[02:08:27.232] Timer tick, asking Buildbucket for the build status
[02:08:27.299] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:27.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:36:0) after 1m0s
[02:09:27.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:36:0)
[02:09:27.416] Timer tick, asking Buildbucket for the build status
[02:09:27.486] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:27.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:37:0) after 1m0s
[02:10:27.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:37:0)
[02:10:27.555] Timer tick, asking Buildbucket for the build status
[02:10:27.673] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:27.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:38:0) after 1m0s
[02:11:27.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:38:0)
[02:11:27.741] Timer tick, asking Buildbucket for the build status
[02:11:28.036] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:28.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:39:0) after 1m0s
[02:12:28.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:39:0)
[02:12:28.136] Timer tick, asking Buildbucket for the build status
[02:12:28.223] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:28.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:40:0) after 1m0s
[02:13:28.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:40:0)
[02:13:28.262] Timer tick, asking Buildbucket for the build status
[02:13:29.265] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:29.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:41:0) after 1m0s
[02:14:29.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:41:0)
[02:14:29.279] Timer tick, asking Buildbucket for the build status
[02:14:29.851] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:29.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:42:0) after 1m0s
[02:15:29.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:42:0)
[02:15:29.853] Timer tick, asking Buildbucket for the build status
[02:15:30.213] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:30.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:43:0) after 1m0s
[02:16:30.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:43:0)
[02:16:30.238] Timer tick, asking Buildbucket for the build status
[02:16:30.444] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:30.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:44:0) after 1m0s
[02:17:30.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:44:0)
[02:17:30.448] Timer tick, asking Buildbucket for the build status
[02:17:30.589] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:30.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:45:0) after 1m0s
[02:18:30.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:45:0)
[02:18:30.608] Timer tick, asking Buildbucket for the build status
[02:18:30.812] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:30.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:46:0) after 1m0s
[02:19:30.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:46:0)
[02:19:30.847] Timer tick, asking Buildbucket for the build status
[02:19:31.013] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:31.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:47:0) after 1m0s
[02:20:31.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:47:0)
[02:20:31.022] Timer tick, asking Buildbucket for the build status
[02:20:31.143] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:31.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:48:0) after 1m0s
[02:21:31.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:48:0)
[02:21:31.244] Timer tick, asking Buildbucket for the build status
[02:21:31.365] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:31.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:49:0) after 1m0s
[02:22:31.352] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:49:0)
[02:22:31.352] Timer tick, asking Buildbucket for the build status
[02:22:31.566] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:31.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:50:0) after 1m0s
[02:23:31.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:50:0)
[02:23:31.624] Timer tick, asking Buildbucket for the build status
[02:23:31.687] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:31.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:51:0) after 1m0s
[02:24:31.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:51:0)
[02:24:31.802] Timer tick, asking Buildbucket for the build status
[02:24:31.881] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:31.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:52:0) after 1m0s
[02:25:31.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:52:0)
[02:25:31.956] Timer tick, asking Buildbucket for the build status
[02:25:32.063] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:32.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:53:0) after 1m0s
[02:26:32.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:53:0)
[02:26:32.463] Timer tick, asking Buildbucket for the build status
[02:26:32.578] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:32.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:54:0) after 1m0s
[02:27:32.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:54:0)
[02:27:32.704] Timer tick, asking Buildbucket for the build status
[02:27:32.827] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:32.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:55:0) after 1m0s
[02:28:33.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:55:0)
[02:28:33.095] Timer tick, asking Buildbucket for the build status
[02:28:33.159] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:33.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:56:0) after 1m0s
[02:29:33.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:56:0)
[02:29:33.444] Timer tick, asking Buildbucket for the build status
[02:29:33.529] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:33.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:57:0) after 1m0s
[02:30:33.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:57:0)
[02:30:33.631] Timer tick, asking Buildbucket for the build status
[02:30:33.759] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:33.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:58:0) after 1m0s
[02:31:33.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:58:0)
[02:31:33.841] Timer tick, asking Buildbucket for the build status
[02:31:33.905] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:33.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:59:0) after 1m0s
[02:32:35.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:59:0)
[02:32:35.638] Timer tick, asking Buildbucket for the build status
[02:32:35.789] Build 8856538925589598560: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:35.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021968988022077872:60:0) after 1m0s
[02:33:02.028] Received PubSub notification, asking Buildbucket for the build status
[02:33:02.115] Build 8856538925589598560: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:33:02.115] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612146781006998",
"created_by": "project:chromium",
"created_ts": "1612143316620841",
"id": "8856538925589598560",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"61c36babbce51fb69aeeb5768e3c71274315dbbf\"}}",
"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\": \"61c36babbce51fb69aeeb5768e3c71274315dbbf\", \"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-us-central1-b-32-1-y2bx\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17103, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"0be050a44a4308ace01229b89bd4560457356007\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"61c36babbce51fb69aeeb5768e3c71274315dbbf\", \"got_revision_cp\": \"refs/heads/master@{#848980}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"8b4cf1c2491d56cf9eda153b5455e4b295732b7c\", \"got_v8_revision\": \"cbbe339c1ca9754b3123fe2bdb83c0f58ee5d728\", \"got_v8_revision_cp\": \"refs/heads/9.0.101@{#1}\", \"got_webrtc_revision\": \"41bfcf4a63611409220fcd458a03deaa2cd23619\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33116}\", \"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\": \"61c36babbce51fb69aeeb5768e3c71274315dbbf\", \"swarm_hashes_refs/heads/master(at){#848980}_without_patch\": {\"absl_hardening_tests\": \"54efe653eb29ffd0408a4310ba6170cd3f723d26\", \"accessibility_unittests\": \"7b09de4016baf5728a4c613e4cd45208fa5d1da1\", \"angle_unittests\": \"4cd792690eeb1b221fbda31e6b5762998eae92a3\", \"app_shell_unittests\": \"ef3c5caa5c3807feccc707070e05284f8b186c42\", \"aura_unittests\": \"908e5801f262242173ffcaa3eb253ded55c260f0\", \"base_unittests\": \"59ca6465fde0e52df071d0976cc81c47fa039154\", \"base_util_unittests\": \"b8a938daa05583c3356c2b6658659f4a4496dc76\", \"blink_common_unittests\": \"6bf0ce12bd2ae087c852559fa86326e5fa430e08\", \"blink_fuzzer_unittests\": \"c23591b1ece14dba6cccd18a21b976f5ada4fea2\", \"blink_heap_unittests\": \"b96523ed16af7bdc2e94ebb10d32854be884d73e\", \"blink_platform_unittests\": \"b4e3b661795d2e9c44acb08c388473109a706620\", \"blink_unittests\": \"17a29705c9906235e7c9b03166aeb44d90f7489b\", \"boringssl_crypto_tests\": \"3c8d2559da392fae7f9aeef8f796d574e470b898\", \"boringssl_ssl_tests\": \"537a3ff703d15263942349feea13c0091e9cebd5\", \"browser_switcher_bho_unittests\": \"5aa750a9772e4e130f025c48361cb62cba0e5597\", \"browser_tests\": \"864f18203979a1676c918563e3dd626d953e987b\", \"capture_unittests\": \"df47d2f118533d0b046f9e00b0c96e301c650346\", \"cast_unittests\": \"15cab57b5547670e2eeb5fb4e2f1d02d3fefbb10\", \"cc_unittests\": \"c2f1ae2e6172a46c74e4da8d3a53147fe4d3ee34\", \"chrome_app_unittests\": \"4f430d163a4ad38ec046cbfcf7dd77c87b830234\", \"chrome_cleaner_unittests\": \"6972b673cb403d105861b1178e2697ce14fa15aa\", \"chrome_elf_unittests\": \"83a1872b4b0e11c717b4001ec3ddd97c9a80d322\", \"chromedriver_unittests\": \"f7813667e939f80337b60cec0a8ae133594c5982\", \"components_browsertests\": \"37f2c203116b8ad16e8900593a0a4a998e5aa2ef\", \"components_unittests\": \"01979621101fdb27983806f1168d2b5a7687c142\", \"compositor_unittests\": \"c64c7e4e0f206038435be4a37274b131f8dbf865\", \"content_browsertests\": \"b7d46cf57b866cd7e9ed884da00249a90e9d4cee\", \"content_unittests\": \"aee02ecc0525a5b34ee8be7796275cd851857047\", \"courgette_unittests\": \"01836ce5c6dd616175cd1913a7397fdb7b914055\", \"crashpad_tests\": \"1bc71d38c563b9953a09ced2dfb7195ee06cb3a2\", \"cronet_tests\": \"73f0fabeda4c9846ee859151c39ee2c344423fbd\", \"cronet_unittests\": \"46e3fcf02c4c5415e64dc3f12c7a987b416e03c7\", \"crypto_unittests\": \"9ba1caa01ef2664436cec9cc0327c9eaeae91602\", \"delayloads_unittests\": \"a9c4b7e2d7b6912e1a3043095d40a5bce7c9e091\", \"device_unittests\": \"0993f7ff4273ca7db15aa9c3ec40507ebc158324\", \"display_unittests\": \"33ace8f1410d3f6349d8bb23ad9723ba50bcdeaf\", \"elevation_service_unittests\": \"fbcaf6491e1d38c665a90426f2770cdab7d8adff\", \"events_unittests\": \"e775887ba2ca2903d52e6c2d491efe11b55d8cb5\", \"extensions_browsertests\": \"e9e20132e66ed841110806c882ca926853c261dd\", \"extensions_unittests\": \"9985ca7d3812fa77f7c9721ff88cf0f231da5f67\", \"filesystem_service_unittests\": \"0b7c53b96121ba682338d67bd8759716655adc23\", \"gcm_unit_tests\": \"a91d9924e16451ce6c329a12439907c4c8e3dc73\", \"gcp_unittests\": \"171f48727c0497fb53680fe053156b64081ab5e9\", \"gfx_unittests\": \"8e3ef9d8434edcc742bf09d37fb257204bd3e9c3\", \"gin_unittests\": \"c01087ea85cda169438957138e62a4ec5694e889\", \"google_apis_unittests\": \"979ecdec49e5763134b5799756aaf63dfa968e90\", \"gpu_unittests\": \"95250902c081571bb16bd97f4f5b99b0f11bc1e5\", \"gwp_asan_unittests\": \"d922626f4d63943e2b30c1dee963555c20d07e61\", \"headless_browsertests\": \"5af4638a3cfcb28db42d6cff5540a52d5db6aed2\", \"headless_unittests\": \"5c4499502485608ea0b1349a2bac4298c075d126\", \"install_static_unittests\": \"c7cf8633cf329daefb3d7093c646612b8487ef0d\", \"installer_util_unittests\": \"0bfcbd0bd5a0dc34b4c87477a29af6cb58d46b7c\", \"interactive_ui_tests\": \"9720f317cd9d87e6515c4b00eb16abd408da81d4\", \"ipc_tests\": \"981e28a98a0c6f9b882f687ff7e4e9ba113a9173\", \"jingle_unittests\": \"0733c551b68cab5934fbc7b843acdb3a651a956b\", \"latency_unittests\": \"bad79be6996af3b0b26dd0e79769c12905ab7d26\", \"libjingle_xmpp_unittests\": \"1327d25f5573d93a4c711cfc01541512a5a724c5\", \"liburlpattern_unittests\": \"9d8a436360cafe877a842ac86e172549ed43b66e\", \"media_blink_unittests\": \"9e13de741ab1e4e53bc775bf438be5c12fe3a8fa\", \"media_unittests\": \"a20b6ee0bcccf0743e16c7d7d9f6f222041f6e37\", \"message_center_unittests\": \"a3af172ce96a3a2ff89290b635593fb3524c785b\", \"midi_unittests\": \"dc4bb666d6c61f615fd61dbc61d09eb0c2bcce7c\", \"mojo_core_unittests\": \"5e99d6e01d751cfa0fc244a36c25fcf7bf6954db\", \"mojo_unittests\": \"f893fcd9170974d7decc795c4890130c7c7ccb2a\", \"nacl_loader_unittests\": \"99d936705ecab4a2c8fc3192f3863dcf978ac903\", \"native_theme_unittests\": \"e89203da97e05274d997cecc37457a7c6691d95d\", \"net_unittests\": \"a0e7352c4ce4612013d432b30e9253a554632145\", \"notification_helper_unittests\": \"c10f76afe8c2c166f2045652dbdfef06fe858d20\", \"pdf_unittests\": \"b381433ffae15aa5b8c1d20c5295cbb9bd239264\", \"perfetto_unittests\": \"250b8a7a7bdb2c8185a5d9ec0f70562d44005242\", \"ppapi_unittests\": \"dcc5ddd6f70013438d529f7e506947a9e4f55343\", \"printing_unittests\": \"5c013c790d79fef9e1114bfd5198ad81b1692208\", \"remoting_unittests\": \"756dd7d0d72ff540c3f06fd0085981272befeec9\", \"sbox_integration_tests\": \"b8e746a2f9de2048eed46435e17f422ecd6e14d0\", \"sbox_unittests\": \"e9728144e8bd0110aedb71bf34553d0b3c45f525\", \"sbox_validation_tests\": \"80fbe13df3a07fe477a7450b65ddc4aa83b228d2\", \"service_manager_unittests\": \"0d31728ee44eb17bad3bfff468c1e8e26b25c5fd\", \"services_unittests\": \"9d619914c14cb8330b852d7daa77a6a1809e55fc\", \"setup_unittests\": \"1a8051bae7db17a94c8658d0d7fcfdb666173994\", \"shell_dialogs_unittests\": \"c4baea57210c41e3529cf07e6ef7f81329a9560c\", \"skia_unittests\": \"4836d03443606b0cd0ca7efee7718cb5952ff45b\", \"snapshot_unittests\": \"7474338b8f3d2d6d8def49ae06d310018e47842b\", \"sql_unittests\": \"267040b731bfc2161f3dfa7b233b4d5d604cb3bf\", \"storage_unittests\": \"fd349436e9d74e7384d8bf09dc11e601656467c2\", \"sync_integration_tests\": \"e2daa40d9feef178f6c0a2ad10c90cc9415b264e\", \"traffic_annotation_auditor_unittests\": \"ec5d35c2e34616b972c0e51487143429415f061a\", \"ui_base_unittests\": \"e715a05c36280005c83f69f57eb1997e0b77e7d5\", \"ui_touch_selection_unittests\": \"f5f3b592fc8f322b23da4131dd2756e7d076a160\", \"unit_tests\": \"74d65a21886df386cf4e2df02a327dfc9cfc9557\", \"updater_tests\": \"0be40955af0086a5da037a3bdc036f6441a1e233\", \"url_unittests\": \"07bb83330d8d20976982bf9e382019f2e8f41efe\", \"views_unittests\": \"14a7293e650ea1ec6d5b91b6022a3c75fab11d05\", \"viz_unittests\": \"bcb5a1d031e6263286cfe5d24b791c628a05e1b6\", \"vr_common_unittests\": \"282f7ce5277f4dcd91356a2bb865d657f4f90712\", \"vr_pixeltests\": \"46f7333812ff84120b3b0b8dd26183c7fdb3b8c4\", \"weblayer_browsertests\": \"fddc5878c44397cdfffff5b3746786b36139075f\", \"weblayer_unittests\": \"60967a31a0d5553a0f6b1029a5383e8e50a2a22d\", \"wm_unittests\": \"6e6a608b2dfaead931684151049dc7e3d469d400\", \"wtf_unittests\": \"1b34fe72f3bd8f17820e53521105443b9eb3c120\", \"zlib_unittests\": \"e5553eff791df2decc8f7026c12654aaaa409792\", \"zucchini_unittests\": \"84d957c3537bb5c8a9b4834f7e2c8e032ca6792d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-y2bx\"], \"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\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612143322855722",
"status": "COMPLETED",
"status_changed_ts": "1612146781572642",
"tags": [
"build_address:luci.chromium.ci/win-asan/17103",
"builder:win-asan",
"buildset:commit/git/61c36babbce51fb69aeeb5768e3c71274315dbbf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/61c36babbce51fb69aeeb5768e3c71274315dbbf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021968988022077872",
"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/8856538925589598560/+/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:51740c6b8cc09a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612146781577238",
"url": "https://ci.chromium.org/b/8856538925589598560",
"utcnow_ts": "1612146782105661"
}
}
[02:33:02.115] Invocation finished in 57m47.197545503s with status SUCCEEDED