[09:08:46.754] New invocation is queued and will start shortly
[09:08:47.837] Starting the invocation (attempt 1)
[09:08:47.877] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:08:47.877] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:08:47.877] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029188211672364816",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fd299cbee3dd515d18757a695bf61d59344eb554\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029188211672364816",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/fd299cbee3dd515d18757a695bf61d59344eb554",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd299cbee3dd515d18757a695bf61d59344eb554",
"gitiles_ref:refs/heads/master"
]
}
[09:08:49.149] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605258528416061",
"id": "8863758149265577344",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fd299cbee3dd515d18757a695bf61d59344eb554\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605258529010579",
"tags": [
"build_address:luci.chromium.ci/win-asan/15617",
"builder:win-asan",
"buildset:commit/git/fd299cbee3dd515d18757a695bf61d59344eb554",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd299cbee3dd515d18757a695bf61d59344eb554",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029188211672364816",
"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/8863758149265577344/+/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": "1605258529010721",
"url": "https://ci.chromium.org/b/8863758149265577344",
"utcnow_ts": "1605258529139654"
}
}
[09:08:49.149] Task URL: https://ci.chromium.org/b/8863758149265577344
[09:08:49.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:2:0) after 1m0s
[09:09:48.316] Received PubSub notification, asking Buildbucket for the build status
[09:09:48.449] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:49.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:2:0)
[09:09:49.209] Timer tick, asking Buildbucket for the build status
[09:09:49.410] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:49.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:4:0) after 1m0s
[09:10:49.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:4:0)
[09:10:49.406] Timer tick, asking Buildbucket for the build status
[09:10:49.618] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:49.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:5:0) after 1m0s
[09:11:49.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:5:0)
[09:11:49.979] Timer tick, asking Buildbucket for the build status
[09:11:50.215] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:50.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:6:0) after 1m0s
[09:12:50.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:6:0)
[09:12:50.565] Timer tick, asking Buildbucket for the build status
[09:12:50.685] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:50.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:7:0) after 1m0s
[09:13:50.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:7:0)
[09:13:50.973] Timer tick, asking Buildbucket for the build status
[09:13:51.106] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:51.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:8:0) after 1m0s
[09:14:51.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:8:0)
[09:14:51.480] Timer tick, asking Buildbucket for the build status
[09:14:52.790] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:52.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:9:0) after 1m0s
[09:15:52.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:9:0)
[09:15:52.867] Timer tick, asking Buildbucket for the build status
[09:15:53.039] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:53.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:10:0) after 1m0s
[09:16:53.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:10:0)
[09:16:53.095] Timer tick, asking Buildbucket for the build status
[09:16:53.212] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:53.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:11:0) after 1m0s
[09:17:53.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:11:0)
[09:17:53.243] Timer tick, asking Buildbucket for the build status
[09:17:53.916] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:53.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:12:0) after 1m0s
[09:18:53.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:12:0)
[09:18:53.992] Timer tick, asking Buildbucket for the build status
[09:18:55.499] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:55.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:13:0) after 1m0s
[09:19:56.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:13:0)
[09:19:56.671] Timer tick, asking Buildbucket for the build status
[09:19:56.744] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:56.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:14:0) after 1m0s
[09:20:57.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:14:0)
[09:20:57.084] Timer tick, asking Buildbucket for the build status
[09:20:57.165] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:57.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:15:0) after 1m0s
[09:21:57.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:15:0)
[09:21:57.377] Timer tick, asking Buildbucket for the build status
[09:21:57.455] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:57.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:16:0) after 1m0s
[09:22:57.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:16:0)
[09:22:57.736] Timer tick, asking Buildbucket for the build status
[09:22:57.823] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:57.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:17:0) after 1m0s
[09:23:57.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:17:0)
[09:23:57.908] Timer tick, asking Buildbucket for the build status
[09:23:58.012] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:58.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:18:0) after 1m0s
[09:24:58.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:18:0)
[09:24:58.127] Timer tick, asking Buildbucket for the build status
[09:24:58.204] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:58.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:19:0) after 1m0s
[09:25:58.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:19:0)
[09:25:58.247] Timer tick, asking Buildbucket for the build status
[09:25:58.607] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:58.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:20:0) after 1m0s
[09:26:58.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:20:0)
[09:26:58.734] Timer tick, asking Buildbucket for the build status
[09:26:59.559] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:59.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:21:0) after 1m0s
[09:27:59.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:21:0)
[09:27:59.692] Timer tick, asking Buildbucket for the build status
[09:27:59.753] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:59.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:22:0) after 1m0s
[09:28:59.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:22:0)
[09:28:59.767] Timer tick, asking Buildbucket for the build status
[09:28:59.896] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:59.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:23:0) after 1m0s
[09:29:59.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:23:0)
[09:29:59.993] Timer tick, asking Buildbucket for the build status
[09:30:00.234] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:00.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:24:0) after 1m0s
[09:31:00.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:24:0)
[09:31:00.312] Timer tick, asking Buildbucket for the build status
[09:31:00.382] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:00.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:25:0) after 1m0s
[09:32:02.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:25:0)
[09:32:02.200] Timer tick, asking Buildbucket for the build status
[09:32:02.374] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:02.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:26:0) after 1m0s
[09:33:02.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:26:0)
[09:33:02.390] Timer tick, asking Buildbucket for the build status
[09:33:03.717] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:03.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:27:0) after 1m0s
[09:34:03.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:27:0)
[09:34:03.751] Timer tick, asking Buildbucket for the build status
[09:34:03.948] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:03.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:28:0) after 1m0s
[09:35:04.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:28:0)
[09:35:04.036] Timer tick, asking Buildbucket for the build status
[09:35:04.210] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:04.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:29:0) after 1m0s
[09:36:04.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:29:0)
[09:36:04.337] Timer tick, asking Buildbucket for the build status
[09:36:04.602] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:04.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:30:0) after 1m0s
[09:37:04.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:30:0)
[09:37:04.637] Timer tick, asking Buildbucket for the build status
[09:37:05.524] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:05.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:31:0) after 1m0s
[09:38:05.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:31:0)
[09:38:05.595] Timer tick, asking Buildbucket for the build status
[09:38:05.786] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:05.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:32:0) after 1m0s
[09:39:05.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:32:0)
[09:39:05.845] Timer tick, asking Buildbucket for the build status
[09:39:06.024] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:06.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:33:0) after 1m0s
[09:40:06.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:33:0)
[09:40:06.055] Timer tick, asking Buildbucket for the build status
[09:40:06.197] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:06.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:34:0) after 1m0s
[09:41:06.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:34:0)
[09:41:06.355] Timer tick, asking Buildbucket for the build status
[09:41:06.519] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:06.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:35:0) after 1m0s
[09:42:06.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:35:0)
[09:42:06.680] Timer tick, asking Buildbucket for the build status
[09:42:07.166] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:07.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:36:0) after 1m0s
[09:43:07.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:36:0)
[09:43:07.327] Timer tick, asking Buildbucket for the build status
[09:43:07.469] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:07.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:37:0) after 1m0s
[09:44:07.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:37:0)
[09:44:07.562] Timer tick, asking Buildbucket for the build status
[09:44:07.702] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:07.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:38:0) after 1m0s
[09:45:07.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:38:0)
[09:45:07.741] Timer tick, asking Buildbucket for the build status
[09:45:07.860] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:07.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:39:0) after 1m0s
[09:46:08.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:39:0)
[09:46:08.030] Timer tick, asking Buildbucket for the build status
[09:46:08.166] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:08.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:40:0) after 1m0s
[09:47:08.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:40:0)
[09:47:08.250] Timer tick, asking Buildbucket for the build status
[09:47:08.366] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:08.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:41:0) after 1m0s
[09:48:08.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:41:0)
[09:48:08.420] Timer tick, asking Buildbucket for the build status
[09:48:08.595] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:08.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:42:0) after 1m0s
[09:49:08.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:42:0)
[09:49:08.645] Timer tick, asking Buildbucket for the build status
[09:49:08.788] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:08.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:43:0) after 1m0s
[09:50:08.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:43:0)
[09:50:08.879] Timer tick, asking Buildbucket for the build status
[09:50:08.953] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:08.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:44:0) after 1m0s
[09:51:09.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:44:0)
[09:51:09.032] Timer tick, asking Buildbucket for the build status
[09:51:09.294] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:09.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:45:0) after 1m0s
[09:52:09.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:45:0)
[09:52:09.343] Timer tick, asking Buildbucket for the build status
[09:52:09.484] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:09.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:46:0) after 1m0s
[09:53:09.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:46:0)
[09:53:09.497] Timer tick, asking Buildbucket for the build status
[09:53:11.568] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:11.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:47:0) after 1m0s
[09:54:11.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:47:0)
[09:54:11.717] Timer tick, asking Buildbucket for the build status
[09:54:12.983] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:12.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:48:0) after 1m0s
[09:55:13.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:48:0)
[09:55:13.034] Timer tick, asking Buildbucket for the build status
[09:55:13.142] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:13.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:49:0) after 1m0s
[09:56:13.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:49:0)
[09:56:13.181] Timer tick, asking Buildbucket for the build status
[09:56:13.796] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:13.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:50:0) after 1m0s
[09:57:13.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:50:0)
[09:57:13.861] Timer tick, asking Buildbucket for the build status
[09:57:13.944] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:13.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:51:0) after 1m0s
[09:58:13.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:51:0)
[09:58:13.932] Timer tick, asking Buildbucket for the build status
[09:58:15.213] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:15.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:52:0) after 1m0s
[09:59:15.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:52:0)
[09:59:15.227] Timer tick, asking Buildbucket for the build status
[09:59:15.614] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:15.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:53:0) after 1m0s
[10:00:15.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:53:0)
[10:00:15.700] Timer tick, asking Buildbucket for the build status
[10:00:16.780] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:16.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:54:0) after 1m0s
[10:01:16.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:54:0)
[10:01:16.806] Timer tick, asking Buildbucket for the build status
[10:01:17.239] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:17.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:55:0) after 1m0s
[10:02:17.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:55:0)
[10:02:17.323] Timer tick, asking Buildbucket for the build status
[10:02:17.726] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:17.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:56:0) after 1m0s
[10:03:17.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:56:0)
[10:03:17.781] Timer tick, asking Buildbucket for the build status
[10:03:17.923] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:17.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:57:0) after 1m0s
[10:04:17.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:57:0)
[10:04:17.921] Timer tick, asking Buildbucket for the build status
[10:04:18.481] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:18.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:58:0) after 1m0s
[10:05:18.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:58:0)
[10:05:18.520] Timer tick, asking Buildbucket for the build status
[10:05:18.653] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:18.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:59:0) after 1m0s
[10:06:18.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:59:0)
[10:06:18.748] Timer tick, asking Buildbucket for the build status
[10:06:18.887] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:18.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:60:0) after 1m0s
[10:07:18.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:60:0)
[10:07:18.998] Timer tick, asking Buildbucket for the build status
[10:07:19.242] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:19.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:61:0) after 1m0s
[10:08:19.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:61:0)
[10:08:19.246] Timer tick, asking Buildbucket for the build status
[10:08:19.319] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:19.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:62:0) after 1m0s
[10:09:19.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:62:0)
[10:09:19.483] Timer tick, asking Buildbucket for the build status
[10:09:19.604] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:19.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:63:0) after 1m0s
[10:10:19.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:63:0)
[10:10:19.710] Timer tick, asking Buildbucket for the build status
[10:10:19.856] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:19.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:64:0) after 1m0s
[10:11:20.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:64:0)
[10:11:20.042] Timer tick, asking Buildbucket for the build status
[10:11:20.337] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:20.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:65:0) after 1m0s
[10:12:20.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:65:0)
[10:12:20.445] Timer tick, asking Buildbucket for the build status
[10:12:20.522] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:20.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:66:0) after 1m0s
[10:13:20.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:66:0)
[10:13:20.747] Timer tick, asking Buildbucket for the build status
[10:13:21.009] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:21.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:67:0) after 1m0s
[10:14:21.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:67:0)
[10:14:21.068] Timer tick, asking Buildbucket for the build status
[10:14:21.259] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:21.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:68:0) after 1m0s
[10:15:21.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:68:0)
[10:15:21.367] Timer tick, asking Buildbucket for the build status
[10:15:21.519] Build 8863758149265577344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:21.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029188211672364816:69:0) after 1m0s
[10:15:42.536] Received PubSub notification, asking Buildbucket for the build status
[10:15:43.857] Build 8863758149265577344: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:15:43.864] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605262541722099",
"created_by": "project:chromium",
"created_ts": "1605258528416061",
"id": "8863758149265577344",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fd299cbee3dd515d18757a695bf61d59344eb554\"}}",
"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\": \"fd299cbee3dd515d18757a695bf61d59344eb554\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-0-4yp8\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15617, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"f9e59ad078552424ca165644f4da3b4e2687c3dc\", \"got_dawn_revision\": \"b6f4d53126d3f0ad522df5fc77da4ac919ea43f6\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"fd299cbee3dd515d18757a695bf61d59344eb554\", \"got_revision_cp\": \"refs/heads/master@{#827203}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e4c1a25cc6797a47043fc415c53b8edcd7b3e37e\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"daab6896e2938e28f01e305ce2fff038f47554c4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32599}\", \"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\": \"fd299cbee3dd515d18757a695bf61d59344eb554\", \"swarm_hashes_refs/heads/master(at){#827203}_without_patch\": {\"absl_hardening_tests\": \"031b581f9f62ab6e39aa40acfcdd05d5a4dd8752\", \"accessibility_unittests\": \"98ba944b25ac126735b30de6bcbb05f9d7946e72\", \"angle_unittests\": \"ea027ee8eb7dfb9721a2cdac3e0079676ae5d30e\", \"app_shell_unittests\": \"a1b218d9d925ecf7244bc44a60f72e454c022b08\", \"aura_unittests\": \"3bb95a97ae92b1308016a59e44c670c3de5e2e46\", \"base_unittests\": \"7a3e8793387468a4027b9c51d75d5d801be7acdf\", \"base_util_unittests\": \"503da7bc173204b02efe0cc1b121911ac8fffbf8\", \"blink_common_unittests\": \"5db024ff08a0cb26bfd308697264dcc37a0a2c7f\", \"blink_fuzzer_unittests\": \"16ab6cf916682f4081b6a34d8a6bc0c3de3fa01e\", \"blink_heap_unittests\": \"29ee6c37d2fa16df4ee7c13969a4cec6c608f618\", \"blink_platform_unittests\": \"65999460485b79cc1627a0c645ca8da431335da4\", \"blink_unittests\": \"1380104cf5b4ef0a720fae07000900860afa54e5\", \"boringssl_crypto_tests\": \"ac15cfe4d1b96a4a72fd92ec7cfa49affa5e406b\", \"boringssl_ssl_tests\": \"099d047a4106e5c0b66709bbd5f6f35799cf0138\", \"browser_switcher_bho_unittests\": \"fe7d476ad95c3abb4f69a1c3a7249f39009686be\", \"browser_tests\": \"fbc756be118de1e0547e767f7b4067c1a5e740b2\", \"capture_unittests\": \"daf09d90cb00c04e7690c51b9f951368c3fe5a71\", \"cast_unittests\": \"385771b5c5e6ea9ed478b77313a049c2a59a9270\", \"cc_unittests\": \"c8a99f614a39b667428e6b9da8319769680afc73\", \"chrome_app_unittests\": \"5a46e115c0ac1f17ffa7586506b88f3efeedacd5\", \"chrome_cleaner_unittests\": \"dbe07adb4f5affaa7036e4029876e77c29ec834c\", \"chrome_elf_unittests\": \"0d82cfa01e21f19aedddc49a95a3cbcfa2c038ea\", \"chromedriver_unittests\": \"6983b4badf9b1b239b5cddb2306f2a9b0fbafc0a\", \"components_browsertests\": \"9e63c80ebb9f5fe6fa81f3effb8b465f99563870\", \"components_unittests\": \"e4bb24c1d1830c1bc662bee8c104bd55bd6fad8a\", \"compositor_unittests\": \"3ff68e1c95fc54e79e67a6dfa99c6f86b35913d9\", \"content_browsertests\": \"c08b596a3fda2ce81673a9cb818ec144ea15c862\", \"content_unittests\": \"3641c39f12ab3db0f04f9ff0a68eb0fa0df7835d\", \"courgette_unittests\": \"b04521e2e94868a8b3f3431869bf8bc2b7495408\", \"crashpad_tests\": \"b08770a44ac12ad4434e4597f20fc0b8673b93e9\", \"cronet_tests\": \"0c5547e17ed71fac121dde993dbde5e02d5a3272\", \"cronet_unittests\": \"ed36ae82ca7d5a097593c241370a71202d0897a8\", \"crypto_unittests\": \"4da431705650e44d3b1fcdbc19937b729a246ee1\", \"delayloads_unittests\": \"30615b19c4279efd9cf92dce15728bb7519d752f\", \"device_unittests\": \"6961e8dd408d14f22299f14efd1b8c18d136f702\", \"display_unittests\": \"f85207d9aa2b849fe5bd00f55f4c6e11c61474de\", \"elevation_service_unittests\": \"f1bd363288b3549a6a45777f05af961135ccdd34\", \"events_unittests\": \"3a60838ba1765f954ec5026b9dcedd4212dfea1c\", \"extensions_browsertests\": \"3bd96d96a3a103e307d319fbefae59efc7ba09df\", \"extensions_unittests\": \"f384848012be7dc5c0e40c389330a8e1ba20962f\", \"filesystem_service_unittests\": \"865a64b166cf27e685e525fc74983ce5040f5f53\", \"gcm_unit_tests\": \"cb0969a6495ad672b19be30d66c1ed1872e399d5\", \"gcp_unittests\": \"0e13ad5a5b7ab4d5c9bf876b2d89a7bc52353775\", \"gfx_unittests\": \"575f4c408f910d820ebdb087c4d19daeb08b959d\", \"gin_unittests\": \"ebda4a4ca629f0a82cdcec9e49dc4cad813af1a0\", \"google_apis_unittests\": \"58c581184518d51c00c0dfabc93ad40e72f22958\", \"gpu_unittests\": \"96f3ebfa38acfd80f39c42801254832f28b0f478\", \"gwp_asan_unittests\": \"d828b6174303603c8848c3a387004ea004d799ac\", \"headless_browsertests\": \"65130c2ca607560247a7de69bd38ad259b684f3c\", \"headless_unittests\": \"3bba84ca4f61dcedafd3edb86b702bcab76b405a\", \"install_static_unittests\": \"2f762cc22b08dc104572434bbd7143193595a152\", \"installer_util_unittests\": \"bbb8e05aeb18c061284f08b06aef7788a219f18f\", \"interactive_ui_tests\": \"8b3d83b10131d35fda376dab0ce82a2bc4a5d415\", \"ipc_tests\": \"3c41e09d1fd2259161f7452378c52b73da7a29ed\", \"jingle_unittests\": \"0d77d55452e6eea6e1cf147b1ebda391089fcf79\", \"latency_unittests\": \"6ef20ea6bf4549a551d0c77101bcba5e09581196\", \"libjingle_xmpp_unittests\": \"dd8ab94a5fcc31c3e5f2647b9e5f01ace65cc3ce\", \"liburlpattern_unittests\": \"65f8b6ffd6be9d3f00a9d5b069e8fa287f241fb9\", \"media_blink_unittests\": \"33c5b1672f5202dfea637a30d6d487b9530e990b\", \"media_unittests\": \"b61875c7a7f1643d52587b7368785b08a7878d26\", \"message_center_unittests\": \"64f3520c8d9c69a0cf3480cbe2ef8eaffb111112\", \"midi_unittests\": \"d42f26529293d95dab73145264ce4ec07a858255\", \"mojo_core_unittests\": \"9058aeeb717b2d20c935cc1fefbb1d6ea4b535dc\", \"mojo_unittests\": \"cd240c7a0295bd8dd9d54501a4e3ec117f7de06a\", \"nacl_loader_unittests\": \"ba26c60b471599c364bf7b4554bf16c5df235e5c\", \"native_theme_unittests\": \"7744f3e58902f9238eb2815db0d61025fbb19e85\", \"net_unittests\": \"7e6845fefda4245d7b0da354350163706bbe5030\", \"notification_helper_unittests\": \"eb7f459555615cc94bbdbda5aac596bd927a4955\", \"pdf_unittests\": \"ce516ef622e3934dfdc1d9366e596b919a305d8d\", \"perfetto_unittests\": \"554bb6bcb9f48fb76dff5a30928aa18df83e1ddb\", \"ppapi_unittests\": \"ebebe5d4901fca613a35624de03b2ff6f32b80e1\", \"printing_unittests\": \"6bd6263a9f741df47a35c449fc4aaefa359c8232\", \"remoting_unittests\": \"4ee97b4cc9802750c26cd6da6d4e642353d552ac\", \"sbox_integration_tests\": \"71c79e991385d9e7a82f039e66306e665a693dc9\", \"sbox_unittests\": \"5ae9cbbe6dc490466cb431c509e90948e2526e6c\", \"sbox_validation_tests\": \"46fb30a077c05168e78fe84cddaa7e264e1fc1ee\", \"service_manager_unittests\": \"37ec2e0321fd84f67cd8d5b838ffbc255a30b867\", \"services_unittests\": \"b4f7b67b63a73deff978d8bc3356b63363478e0b\", \"setup_unittests\": \"f3bd9b7a5f153c29fdd0d903774282d739dd5e60\", \"shell_dialogs_unittests\": \"bbe5965d89f6234cc5c961d965f0d2915b00b6f6\", \"skia_unittests\": \"b30f8ac9db22f1d601dd593133a255a227433306\", \"snapshot_unittests\": \"d231f4514c2bba5d70f6a3e18e41c5d112ec3758\", \"sql_unittests\": \"9ff8e221ccd55dc73ef0caa50965ca08d9ca9616\", \"storage_unittests\": \"39e7cb924820ff3f7c8f8fe48972100dde17d7c7\", \"sync_integration_tests\": \"b6ba9e5d36cc1aff3d50a374854dfde56566bd1b\", \"traffic_annotation_auditor_unittests\": \"2c4c312a279e7bc767e4d28ed34160f53e4fd5fb\", \"ui_base_unittests\": \"872efb6d28bba25feb74d9876ec3affccf3cd4c6\", \"ui_touch_selection_unittests\": \"aa751bca437b4fb9d9060c1b83ff424dbc968c8f\", \"unit_tests\": \"df91cbd19df55aac65651fd0965cfccd827808c4\", \"updater_tests\": \"cb95fb153c60f2bcdea6f19f505da2ff05f2e4f4\", \"url_unittests\": \"85220e5402da1bf426dec10eff1883a6d54cdcbd\", \"views_unittests\": \"0bbc702d2eeb08c00e3c38596c6298d83bea6814\", \"viz_unittests\": \"6ab2d2e9820b279439790b3962a1a26cfdd0958c\", \"vr_common_unittests\": \"e82b3ee48d063603f94e3626f2725c983321c5f4\", \"vr_pixeltests\": \"2b0bb5195a502a1bff71dfceff2ecfba49ebeec1\", \"weblayer_browsertests\": \"8ed324381583151172708187b186ced197f47fa2\", \"weblayer_unittests\": \"b98a0abaa804e0fff428f2008886ffc782dd10b9\", \"wm_unittests\": \"dd580f0bb4cf63d0ae5451fa4d92133c1031b1ce\", \"wtf_unittests\": \"68954d069fe5b70fefc83a0351594ed4a6553dd0\", \"zlib_unittests\": \"aeeb15751296f5729ae84d73ad460690e0d2bea2\", \"zucchini_unittests\": \"39e63085384cc9ff2e3072fdc21f67b7f08c6080\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-0-4yp8\"], \"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\": [\"5626-39642e9\"], \"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": "1605258587538894",
"status": "COMPLETED",
"status_changed_ts": "1605262542040864",
"tags": [
"build_address:luci.chromium.ci/win-asan/15617",
"builder:win-asan",
"buildset:commit/git/fd299cbee3dd515d18757a695bf61d59344eb554",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd299cbee3dd515d18757a695bf61d59344eb554",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029188211672364816",
"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/8863758149265577344/+/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:4fd9aed256586010",
"user_agent:luci-scheduler"
],
"updated_ts": "1605262542045584",
"url": "https://ci.chromium.org/b/8863758149265577344",
"utcnow_ts": "1605262543744973"
}
}
[10:15:43.864] Invocation finished in 1h6m57.127398617s with status SUCCEEDED