[15:52:23.376] New invocation is queued and will start shortly
[15:52:24.835] Starting the invocation (attempt 1)
[15:52:24.919] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:52:24.919] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:52:24.919] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028528639939791904",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"65306b8ce34ae035996a11511a48eac34d1b48fe\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028528639939791904",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/65306b8ce34ae035996a11511a48eac34d1b48fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/65306b8ce34ae035996a11511a48eac34d1b48fe",
"gitiles_ref:refs/heads/master"
]
}
[15:52:25.817] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605887545075501",
"id": "8863098577493900304",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"65306b8ce34ae035996a11511a48eac34d1b48fe\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605887545665328",
"tags": [
"build_address:luci.chromium.ci/win-asan/15742",
"builder:win-asan",
"buildset:commit/git/65306b8ce34ae035996a11511a48eac34d1b48fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/65306b8ce34ae035996a11511a48eac34d1b48fe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028528639939791904",
"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/8863098577493900304/+/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": "1605887545665481",
"url": "https://ci.chromium.org/b/8863098577493900304",
"utcnow_ts": "1605887545807251"
}
}
[15:52:25.817] Task URL: https://ci.chromium.org/b/8863098577493900304
[15:52:25.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:2:0) after 1m0s
[15:52:33.936] Received PubSub notification, asking Buildbucket for the build status
[15:52:34.012] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:25.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:2:0)
[15:53:25.893] Timer tick, asking Buildbucket for the build status
[15:53:26.241] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:26.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:4:0) after 1m0s
[15:54:26.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:4:0)
[15:54:26.327] Timer tick, asking Buildbucket for the build status
[15:54:26.861] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:26.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:5:0) after 1m0s
[15:55:26.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:5:0)
[15:55:26.995] Timer tick, asking Buildbucket for the build status
[15:55:27.198] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:27.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:6:0) after 1m0s
[15:56:27.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:6:0)
[15:56:27.270] Timer tick, asking Buildbucket for the build status
[15:56:27.377] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:27.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:7:0) after 1m0s
[15:57:27.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:7:0)
[15:57:27.482] Timer tick, asking Buildbucket for the build status
[15:57:27.606] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:27.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:8:0) after 1m0s
[15:58:27.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:8:0)
[15:58:27.645] Timer tick, asking Buildbucket for the build status
[15:58:27.846] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:27.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:9:0) after 1m0s
[15:59:28.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:9:0)
[15:59:28.032] Timer tick, asking Buildbucket for the build status
[15:59:28.221] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:28.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:10:0) after 1m0s
[16:00:28.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:10:0)
[16:00:28.410] Timer tick, asking Buildbucket for the build status
[16:00:28.575] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:28.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:11:0) after 1m0s
[16:01:28.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:11:0)
[16:01:28.677] Timer tick, asking Buildbucket for the build status
[16:01:30.884] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:30.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:12:0) after 1m0s
[16:02:30.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:12:0)
[16:02:30.931] Timer tick, asking Buildbucket for the build status
[16:02:31.073] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:31.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:13:0) after 1m0s
[16:03:31.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:13:0)
[16:03:31.244] Timer tick, asking Buildbucket for the build status
[16:03:31.455] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:31.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:14:0) after 1m0s
[16:04:31.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:14:0)
[16:04:31.494] Timer tick, asking Buildbucket for the build status
[16:04:31.681] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:31.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:15:0) after 1m0s
[16:05:31.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:15:0)
[16:05:31.732] Timer tick, asking Buildbucket for the build status
[16:05:31.826] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:31.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:16:0) after 1m0s
[16:06:31.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:16:0)
[16:06:31.879] Timer tick, asking Buildbucket for the build status
[16:06:31.962] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:31.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:17:0) after 1m0s
[16:07:32.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:17:0)
[16:07:32.043] Timer tick, asking Buildbucket for the build status
[16:07:32.128] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:32.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:18:0) after 1m0s
[16:08:32.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:18:0)
[16:08:32.197] Timer tick, asking Buildbucket for the build status
[16:08:32.312] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:32.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:19:0) after 1m0s
[16:09:32.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:19:0)
[16:09:32.331] Timer tick, asking Buildbucket for the build status
[16:09:32.433] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:32.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:20:0) after 1m0s
[16:10:32.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:20:0)
[16:10:32.485] Timer tick, asking Buildbucket for the build status
[16:10:33.255] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:33.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:21:0) after 1m0s
[16:11:33.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:21:0)
[16:11:33.265] Timer tick, asking Buildbucket for the build status
[16:11:33.501] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:33.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:22:0) after 1m0s
[16:12:33.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:22:0)
[16:12:33.524] Timer tick, asking Buildbucket for the build status
[16:12:33.679] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:33.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:23:0) after 1m0s
[16:13:33.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:23:0)
[16:13:33.915] Timer tick, asking Buildbucket for the build status
[16:13:34.043] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:34.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:24:0) after 1m0s
[16:14:34.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:24:0)
[16:14:34.085] Timer tick, asking Buildbucket for the build status
[16:14:34.256] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:34.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:25:0) after 1m0s
[16:15:34.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:25:0)
[16:15:34.299] Timer tick, asking Buildbucket for the build status
[16:15:34.433] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:34.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:26:0) after 1m0s
[16:16:34.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:26:0)
[16:16:34.492] Timer tick, asking Buildbucket for the build status
[16:16:34.614] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:34.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:27:0) after 1m0s
[16:17:34.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:27:0)
[16:17:34.631] Timer tick, asking Buildbucket for the build status
[16:17:35.877] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:35.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:28:0) after 1m0s
[16:18:35.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:28:0)
[16:18:35.902] Timer tick, asking Buildbucket for the build status
[16:18:36.745] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:36.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:29:0) after 1m0s
[16:19:36.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:29:0)
[16:19:36.805] Timer tick, asking Buildbucket for the build status
[16:19:36.987] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:36.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:30:0) after 1m0s
[16:20:37.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:30:0)
[16:20:37.039] Timer tick, asking Buildbucket for the build status
[16:20:37.179] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:37.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:31:0) after 1m0s
[16:21:37.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:31:0)
[16:21:37.202] Timer tick, asking Buildbucket for the build status
[16:21:37.390] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:37.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:32:0) after 1m0s
[16:22:37.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:32:0)
[16:22:37.418] Timer tick, asking Buildbucket for the build status
[16:22:37.630] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:37.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:33:0) after 1m0s
[16:23:37.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:33:0)
[16:23:37.661] Timer tick, asking Buildbucket for the build status
[16:23:37.848] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:37.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:34:0) after 1m0s
[16:24:37.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:34:0)
[16:24:37.872] Timer tick, asking Buildbucket for the build status
[16:24:38.962] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:38.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:35:0) after 1m0s
[16:25:38.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:35:0)
[16:25:38.987] Timer tick, asking Buildbucket for the build status
[16:25:39.099] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:39.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:36:0) after 1m0s
[16:26:39.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:36:0)
[16:26:39.125] Timer tick, asking Buildbucket for the build status
[16:26:39.453] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:39.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:37:0) after 1m0s
[16:27:39.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:37:0)
[16:27:39.467] Timer tick, asking Buildbucket for the build status
[16:27:40.107] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:40.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:38:0) after 1m0s
[16:28:40.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:38:0)
[16:28:40.238] Timer tick, asking Buildbucket for the build status
[16:28:40.398] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:40.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:39:0) after 1m0s
[16:29:40.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:39:0)
[16:29:40.409] Timer tick, asking Buildbucket for the build status
[16:29:40.819] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:40.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:40:0) after 1m0s
[16:30:40.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:40:0)
[16:30:40.846] Timer tick, asking Buildbucket for the build status
[16:30:41.373] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:41.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:41:0) after 1m0s
[16:31:41.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:41:0)
[16:31:41.408] Timer tick, asking Buildbucket for the build status
[16:31:41.910] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:41.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:42:0) after 1m0s
[16:32:41.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:42:0)
[16:32:41.943] Timer tick, asking Buildbucket for the build status
[16:32:42.075] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:42.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:43:0) after 1m0s
[16:33:42.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:43:0)
[16:33:42.106] Timer tick, asking Buildbucket for the build status
[16:33:42.200] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:42.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:44:0) after 1m0s
[16:34:42.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:44:0)
[16:34:42.233] Timer tick, asking Buildbucket for the build status
[16:34:42.320] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:42.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:45:0) after 1m0s
[16:35:42.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:45:0)
[16:35:42.356] Timer tick, asking Buildbucket for the build status
[16:35:43.472] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:43.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:46:0) after 1m0s
[16:36:43.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:46:0)
[16:36:43.533] Timer tick, asking Buildbucket for the build status
[16:36:43.655] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:43.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:47:0) after 1m0s
[16:37:43.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:47:0)
[16:37:43.655] Timer tick, asking Buildbucket for the build status
[16:37:44.103] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:44.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:48:0) after 1m0s
[16:38:44.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:48:0)
[16:38:44.235] Timer tick, asking Buildbucket for the build status
[16:38:44.644] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:44.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:49:0) after 1m0s
[16:39:44.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:49:0)
[16:39:44.713] Timer tick, asking Buildbucket for the build status
[16:39:44.823] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:44.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:50:0) after 1m0s
[16:40:44.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:50:0)
[16:40:44.879] Timer tick, asking Buildbucket for the build status
[16:40:45.107] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:45.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:51:0) after 1m0s
[16:41:45.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:51:0)
[16:41:45.169] Timer tick, asking Buildbucket for the build status
[16:41:45.296] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:45.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:52:0) after 1m0s
[16:42:45.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:52:0)
[16:42:45.311] Timer tick, asking Buildbucket for the build status
[16:42:45.897] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:45.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:53:0) after 1m0s
[16:43:45.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:53:0)
[16:43:45.947] Timer tick, asking Buildbucket for the build status
[16:43:46.083] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:46.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:54:0) after 1m0s
[16:44:46.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:54:0)
[16:44:46.137] Timer tick, asking Buildbucket for the build status
[16:44:46.280] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:46.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:55:0) after 1m0s
[16:45:46.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:55:0)
[16:45:46.335] Timer tick, asking Buildbucket for the build status
[16:45:46.476] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:46.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:56:0) after 1m0s
[16:46:46.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:56:0)
[16:46:46.481] Timer tick, asking Buildbucket for the build status
[16:46:46.554] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:46.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:57:0) after 1m0s
[16:47:46.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:57:0)
[16:47:46.579] Timer tick, asking Buildbucket for the build status
[16:47:47.008] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:47.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:58:0) after 1m0s
[16:48:47.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:58:0)
[16:48:47.089] Timer tick, asking Buildbucket for the build status
[16:48:47.472] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:47.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:59:0) after 1m0s
[16:49:47.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:59:0)
[16:49:47.481] Timer tick, asking Buildbucket for the build status
[16:49:47.703] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:47.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:60:0) after 1m0s
[16:50:47.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:60:0)
[16:50:47.752] Timer tick, asking Buildbucket for the build status
[16:50:48.025] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:48.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:61:0) after 1m0s
[16:51:48.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:61:0)
[16:51:48.128] Timer tick, asking Buildbucket for the build status
[16:51:48.498] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:48.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:62:0) after 1m0s
[16:52:48.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:62:0)
[16:52:48.557] Timer tick, asking Buildbucket for the build status
[16:52:49.348] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:49.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:63:0) after 1m0s
[16:53:49.435] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:63:0)
[16:53:49.435] Timer tick, asking Buildbucket for the build status
[16:53:49.904] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:49.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:64:0) after 1m0s
[16:54:49.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:64:0)
[16:54:49.969] Timer tick, asking Buildbucket for the build status
[16:54:50.059] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:50.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:65:0) after 1m0s
[16:55:50.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:65:0)
[16:55:50.128] Timer tick, asking Buildbucket for the build status
[16:55:50.540] Build 8863098577493900304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:50.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028528639939791904:66:0) after 1m0s
[16:56:45.685] Received PubSub notification, asking Buildbucket for the build status
[16:56:46.087] Build 8863098577493900304: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:56:46.087] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605891400846451",
"created_by": "project:chromium",
"created_ts": "1605887545075501",
"id": "8863098577493900304",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"65306b8ce34ae035996a11511a48eac34d1b48fe\"}}",
"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\": \"65306b8ce34ae035996a11511a48eac34d1b48fe\", \"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\": 15742, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"777749aafaac268b3468b551050f5bf4b28cf9b6\", \"got_dawn_revision\": \"75a1f5234e4b544b4d16eddb995d39685da21361\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"65306b8ce34ae035996a11511a48eac34d1b48fe\", \"got_revision_cp\": \"refs/heads/master@{#829665}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"de9e649438ed3cc2ecf5c30ebf08f033d01270e6\", \"got_v8_revision\": \"94b2c943f7f37e0450e7c5d029fbd22a9d7f6946\", \"got_v8_revision_cp\": \"refs/heads/8.9.14@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"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\": \"65306b8ce34ae035996a11511a48eac34d1b48fe\", \"swarm_hashes_refs/heads/master(at){#829665}_without_patch\": {\"absl_hardening_tests\": \"f06b8e182f91d96c374e3bd1b5a8dd24a0c457bf\", \"accessibility_unittests\": \"266bd229fd0c4d0fab1f7c8061320aea3041e355\", \"angle_unittests\": \"b1a9151f77f8378c1e42d70225198be184b79db5\", \"app_shell_unittests\": \"29f4360f5affdd89a6c09baed55f143cef297f2c\", \"aura_unittests\": \"41cf7b2d37e4ced79d26ab16c341b018a82c5b6c\", \"base_unittests\": \"1aa43db30a0aa4b5847030dde4c23bb42b5f465f\", \"base_util_unittests\": \"d73d41d68eafcbb3f43c03c5d0342e893c08f580\", \"blink_common_unittests\": \"4fc79ea61fcf20c7d4cabb3ddff27999621b3ad6\", \"blink_fuzzer_unittests\": \"c4790163f3b35c206327f28fec977ebb93f28492\", \"blink_heap_unittests\": \"c3e9040c7b8952f56a8b41a6fef549a2a1aa21a7\", \"blink_platform_unittests\": \"c0125432f62719b5bf1c3b24d968b79cab32db6c\", \"blink_unittests\": \"c0a415bb80ea2e8eb8eb866422fe7f932515e7dc\", \"boringssl_crypto_tests\": \"8e4bcc4f5953c9f5b5158e19e6264b9b7cb27058\", \"boringssl_ssl_tests\": \"677a8f73ff635b8eb4519d731e438b303065d3f3\", \"browser_switcher_bho_unittests\": \"ae6de0200892e1a957cbc8f1723658c00c36c86b\", \"browser_tests\": \"fc9be4a7901a6dbf5e22fab4abc0a2b80a929463\", \"capture_unittests\": \"aba807619a920f249cfd3e66148aea3697539ad8\", \"cast_unittests\": \"65762cdb864ef43064e2a0b4ce488eca0f1e3b27\", \"cc_unittests\": \"042d83c4eee343e3c66d79c9429279e078cda9e5\", \"chrome_app_unittests\": \"d91937143822ea1e0e6bb430e4611aecb0ed07d8\", \"chrome_cleaner_unittests\": \"1bcc19036eb272b34f3c877865602a636db6d9dc\", \"chrome_elf_unittests\": \"aa4d2cf8410233b6d0eaee29f3f45eee06059b69\", \"chromedriver_unittests\": \"314917c16301034bbaba84b16f443f668a215512\", \"components_browsertests\": \"364d4bffacb5956724f375293d7e8c02655bca7d\", \"components_unittests\": \"30f1327366bd5baa15dbd99a242275e61688e47b\", \"compositor_unittests\": \"ced115f8419218429e49e1312cd9c055d4bd12be\", \"content_browsertests\": \"9eaad9f22bc0e21dab8a8b3c8af1123deca8f406\", \"content_unittests\": \"902255302e171fedaf3a5009435710a225501b17\", \"courgette_unittests\": \"fa19ef6d7ebf6ec93ad130f7913803e81b6f20fe\", \"crashpad_tests\": \"15e00eeb7ed95f8bc5bd165c521a53d1f5af3b95\", \"cronet_tests\": \"abdac313abe8848ea7b696943ad1fe537951044b\", \"cronet_unittests\": \"d748b06c0390a0d0539fb58b88349ca50fa0b895\", \"crypto_unittests\": \"938653690544de6ce0eabf6d3d0cbf246c1aee87\", \"delayloads_unittests\": \"02cab3c898cb274a056132b7e0787e0d3a338b23\", \"device_unittests\": \"dadd2c3aea35bbbe34760185eee39fbdac6f1fe1\", \"display_unittests\": \"902b477bb58472225e833643d5b61ca365bbaaf5\", \"elevation_service_unittests\": \"400330065f472817643e6b14fdd3ed20b7c42cbc\", \"events_unittests\": \"ac284dcdb3bb35eaa3b27f2b7f2d6242169cbe09\", \"extensions_browsertests\": \"2edc0a2d408b5348e1c87b96b0f53b160fab1580\", \"extensions_unittests\": \"962ab03564e3b44a0469a36ae62fc7126a48d8c3\", \"filesystem_service_unittests\": \"eed3a7573de05ea8b31260fb398b6e3f52f8f429\", \"gcm_unit_tests\": \"c6ab0345615e32be8feb215068ee55fe66d02e96\", \"gcp_unittests\": \"3ca34a0a150501c5fee64293d75fcd56ef5847d0\", \"gfx_unittests\": \"36ecc4a39429a5e8325330646a52dc53966978e4\", \"gin_unittests\": \"8e758b2c076f2200b7008d76337a91fff8c6d53a\", \"google_apis_unittests\": \"76213008e69407b16bb7c735d592042c75d57017\", \"gpu_unittests\": \"13eff086073fd28aa4f8e70360a2b0664ae8957a\", \"gwp_asan_unittests\": \"01843b85ea0b88ad478fd17011a60db2adec67cf\", \"headless_browsertests\": \"9b971f296df6a84d08633aa2c5c4537bdce85db4\", \"headless_unittests\": \"352a67e62bf5f639ff22be94ac591003adb466cf\", \"install_static_unittests\": \"de3d0d3c6ea6efd84556f5d66fb190f2e005882a\", \"installer_util_unittests\": \"053b29abff39758d76429fe59f64282784bf8a29\", \"interactive_ui_tests\": \"49bed81a977f20bc614237bc1823ab76fabf5600\", \"ipc_tests\": \"39691bf30edb323a735d76b2f57a6a8c433d4eef\", \"jingle_unittests\": \"1dfb45890a730f1fb46d66182acb1bd04862804d\", \"latency_unittests\": \"271495e9ba4afa0fc990470c585ccec39c174e25\", \"libjingle_xmpp_unittests\": \"f84d57c462a95e79756bb66484a848cb74645c94\", \"liburlpattern_unittests\": \"6e575255f6fb1ede93a071fb0f21de50fbf88a0b\", \"media_blink_unittests\": \"222081287ee967c0b5e0c288c202e8e6a41b8b12\", \"media_unittests\": \"fdc443a29997338da2e443d136b20a381f05029e\", \"message_center_unittests\": \"05476024fc8c7bca19d6192b0ab04f07ff255674\", \"midi_unittests\": \"4e4a09465b554f4d8fe397e2781c30b40c2d2f73\", \"mojo_core_unittests\": \"3701867261c5349b5e73169b8b4c5652c9cf50f3\", \"mojo_unittests\": \"f87dae0bd85db7e01e27cf087730515f82fa3a7d\", \"nacl_loader_unittests\": \"c2fd84289d93136912daa60958b7f4f511858451\", \"native_theme_unittests\": \"e23d2d98cdb89eb54ed7272e8a095d5742795689\", \"net_unittests\": \"9a5cb0c263ebb089b84090b24a0f11506a04b3a3\", \"notification_helper_unittests\": \"0d16211e619f54da415fb2a8c96725a2a1bf5bcf\", \"pdf_unittests\": \"18a18d1b21f3d32d2098d602550f540f4b558ede\", \"perfetto_unittests\": \"7387bc504a826fa5f29206c79b5e5ccd03a9f8cc\", \"ppapi_unittests\": \"cda3c290458b7626e6a9c1c18939103f31bad763\", \"printing_unittests\": \"aa5caae10097c4a43469aac5a10d5de36cfa3a94\", \"remoting_unittests\": \"646ea853a1fec73af89bb325a749e8221016d568\", \"sbox_integration_tests\": \"849204802376b773aee17daa2645adc83c4d6ccd\", \"sbox_unittests\": \"201a7c606e6e7c4c5ad33e1c2a3e2cf39bcad4a2\", \"sbox_validation_tests\": \"eaa3301c9fcaf90cfa392f43759b3107f14cf32b\", \"service_manager_unittests\": \"89ac269d6a92938578e19a31c7ef7f2a2aa1578a\", \"services_unittests\": \"2862017fff9fac4f1a4572b3d3f129e8e3b8db92\", \"setup_unittests\": \"f2977038e2f153fa1a45a1c253bf4e693995d72d\", \"shell_dialogs_unittests\": \"da1b648a933d60530fc1897b3c78e370c0e76990\", \"skia_unittests\": \"45acea4f588f966a209f4d62e44a4e149fad1cf9\", \"snapshot_unittests\": \"37aeb8d415be2868fb53d9bff9cc180b9b2557fc\", \"sql_unittests\": \"c63938f50f1f58bde2e21f6766f9bf9869aa9b64\", \"storage_unittests\": \"52dbf1796ce107fc3a6b93d6d196193793bc84c2\", \"sync_integration_tests\": \"34a4fc444231cfe138d41a196418bcf48344ca55\", \"traffic_annotation_auditor_unittests\": \"e23d1178082c6f773ea653c67279199b78e9e4b1\", \"ui_base_unittests\": \"3d66ef4e491a26a44a2228b99833414ccf42a71d\", \"ui_touch_selection_unittests\": \"3c8f1df9ae3d59d7a52be31a73b1ed9c856639bd\", \"unit_tests\": \"136fcbf36c9da9eaf26c5563470c581eabea9ecd\", \"updater_tests\": \"0b77151684992132ce0d0cb2a1f9af4f3dd7fd40\", \"url_unittests\": \"d97ae4d3dd1f2c13e544ceb546f01d8415e77050\", \"views_unittests\": \"5c37dc339766e3b54b5973fc986a9795c1d7e6df\", \"viz_unittests\": \"65a91688c78eb1dc35deef0c79a938004bfeab1f\", \"vr_common_unittests\": \"0f1db15806103377ace4226739728c9b6767cef2\", \"vr_pixeltests\": \"de3c79be6493d8cf56c602dd7b9a695bb5061aef\", \"weblayer_browsertests\": \"2dcef85ab6a96db87366f4ae8f08013030fbf3a1\", \"weblayer_unittests\": \"f77de91c4eccbb8af317740cce44ffdc8aa2a442\", \"wm_unittests\": \"2111028ed4fc6fe6c82afa4af4ca6104c734d253\", \"wtf_unittests\": \"cc5803654fb3d4c6e81224fa3c37989b05ba1589\", \"zlib_unittests\": \"5080115b9399fe5445165daf863d6aa3d42eb15a\", \"zucchini_unittests\": \"64f0dc7b9f2e9aed2fb7eef771fba318df698d9f\"}}, \"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": "1605887552420413",
"status": "COMPLETED",
"status_changed_ts": "1605891401902715",
"tags": [
"build_address:luci.chromium.ci/win-asan/15742",
"builder:win-asan",
"buildset:commit/git/65306b8ce34ae035996a11511a48eac34d1b48fe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/65306b8ce34ae035996a11511a48eac34d1b48fe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028528639939791904",
"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/8863098577493900304/+/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:4fff2cdc51920410",
"user_agent:luci-scheduler"
],
"updated_ts": "1605891401937573",
"url": "https://ci.chromium.org/b/8863098577493900304",
"utcnow_ts": "1605891406037274"
}
}
[16:56:46.088] Invocation finished in 1h4m22.728891852s with status SUCCEEDED