[13:44:39.004] New invocation is queued and will start shortly
[13:44:40.221] Starting the invocation (attempt 1)
[13:44:40.276] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:44:40.276] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:44:40.276] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028808467515597216",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5c6276499e416b0381a51d9fed05ae98e37e8b60\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028808467515597216",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/5c6276499e416b0381a51d9fed05ae98e37e8b60",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5c6276499e416b0381a51d9fed05ae98e37e8b60",
"gitiles_ref:refs/heads/master"
]
}
[13:44:40.969] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1605620680415114",
"id": "8863378405371573856",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5c6276499e416b0381a51d9fed05ae98e37e8b60\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605620680796611",
"tags": [
"build_address:luci.chromium.ci/win-asan/15688",
"builder:win-asan",
"buildset:commit/git/5c6276499e416b0381a51d9fed05ae98e37e8b60",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5c6276499e416b0381a51d9fed05ae98e37e8b60",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028808467515597216",
"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/8863378405371573856/+/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": "1605620680796755",
"url": "https://ci.chromium.org/b/8863378405371573856",
"utcnow_ts": "1605620680960628"
}
}
[13:44:40.969] Task URL: https://ci.chromium.org/b/8863378405371573856
[13:44:40.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:2:0) after 1m0s
[13:44:46.795] Received PubSub notification, asking Buildbucket for the build status
[13:44:46.865] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:41.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:2:0)
[13:45:41.104] Timer tick, asking Buildbucket for the build status
[13:45:41.235] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:41.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:4:0) after 1m0s
[13:46:41.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:4:0)
[13:46:41.256] Timer tick, asking Buildbucket for the build status
[13:46:41.408] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:41.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:5:0) after 1m0s
[13:47:41.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:5:0)
[13:47:41.440] Timer tick, asking Buildbucket for the build status
[13:47:41.555] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:41.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:6:0) after 1m0s
[13:48:41.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:6:0)
[13:48:41.585] Timer tick, asking Buildbucket for the build status
[13:48:42.067] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:42.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:7:0) after 1m0s
[13:49:42.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:7:0)
[13:49:42.163] Timer tick, asking Buildbucket for the build status
[13:49:42.514] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:42.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:8:0) after 1m0s
[13:50:42.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:8:0)
[13:50:42.581] Timer tick, asking Buildbucket for the build status
[13:50:42.710] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:42.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:9:0) after 1m0s
[13:51:42.849] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:9:0)
[13:51:42.849] Timer tick, asking Buildbucket for the build status
[13:51:43.043] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:43.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:10:0) after 1m0s
[13:52:43.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:10:0)
[13:52:43.066] Timer tick, asking Buildbucket for the build status
[13:52:43.129] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:43.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:11:0) after 1m0s
[13:53:43.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:11:0)
[13:53:43.139] Timer tick, asking Buildbucket for the build status
[13:53:43.211] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:43.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:12:0) after 1m0s
[13:54:43.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:12:0)
[13:54:43.235] Timer tick, asking Buildbucket for the build status
[13:54:43.531] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:43.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:13:0) after 1m0s
[13:55:43.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:13:0)
[13:55:43.617] Timer tick, asking Buildbucket for the build status
[13:55:43.825] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:43.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:14:0) after 1m0s
[13:56:43.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:14:0)
[13:56:43.915] Timer tick, asking Buildbucket for the build status
[13:56:44.457] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:44.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:15:0) after 1m0s
[13:57:44.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:15:0)
[13:57:44.595] Timer tick, asking Buildbucket for the build status
[13:57:44.858] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:44.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:16:0) after 1m0s
[13:58:44.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:16:0)
[13:58:44.895] Timer tick, asking Buildbucket for the build status
[13:58:45.137] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:45.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:17:0) after 1m0s
[13:59:47.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:17:0)
[13:59:47.154] Timer tick, asking Buildbucket for the build status
[13:59:47.251] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:47.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:18:0) after 1m0s
[14:00:47.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:18:0)
[14:00:47.378] Timer tick, asking Buildbucket for the build status
[14:00:47.464] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:47.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:19:0) after 1m0s
[14:01:47.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:19:0)
[14:01:47.524] Timer tick, asking Buildbucket for the build status
[14:01:47.625] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:47.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:20:0) after 1m0s
[14:02:47.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:20:0)
[14:02:47.744] Timer tick, asking Buildbucket for the build status
[14:02:47.835] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:47.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:21:0) after 1m0s
[14:03:47.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:21:0)
[14:03:47.888] Timer tick, asking Buildbucket for the build status
[14:03:47.954] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:47.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:22:0) after 1m0s
[14:04:47.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:22:0)
[14:04:47.991] Timer tick, asking Buildbucket for the build status
[14:04:48.124] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:48.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:23:0) after 1m0s
[14:05:48.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:23:0)
[14:05:48.116] Timer tick, asking Buildbucket for the build status
[14:05:48.657] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:48.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:24:0) after 1m0s
[14:06:48.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:24:0)
[14:06:48.742] Timer tick, asking Buildbucket for the build status
[14:06:48.864] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:48.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:25:0) after 1m0s
[14:07:49.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:25:0)
[14:07:49.013] Timer tick, asking Buildbucket for the build status
[14:07:49.083] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:49.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:26:0) after 1m0s
[14:08:49.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:26:0)
[14:08:49.236] Timer tick, asking Buildbucket for the build status
[14:08:49.379] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:49.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:27:0) after 1m0s
[14:09:49.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:27:0)
[14:09:49.480] Timer tick, asking Buildbucket for the build status
[14:09:49.670] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:49.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:28:0) after 1m0s
[14:10:49.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:28:0)
[14:10:49.726] Timer tick, asking Buildbucket for the build status
[14:10:49.956] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:49.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:29:0) after 1m0s
[14:11:50.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:29:0)
[14:11:50.005] Timer tick, asking Buildbucket for the build status
[14:11:50.115] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:50.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:30:0) after 1m0s
[14:12:50.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:30:0)
[14:12:50.247] Timer tick, asking Buildbucket for the build status
[14:12:50.459] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:50.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:31:0) after 1m0s
[14:13:50.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:31:0)
[14:13:50.606] Timer tick, asking Buildbucket for the build status
[14:13:50.737] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:50.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:32:0) after 1m0s
[14:14:50.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:32:0)
[14:14:50.887] Timer tick, asking Buildbucket for the build status
[14:14:51.017] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:51.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:33:0) after 1m0s
[14:15:51.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:33:0)
[14:15:51.078] Timer tick, asking Buildbucket for the build status
[14:15:51.212] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:51.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:34:0) after 1m0s
[14:16:51.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:34:0)
[14:16:51.222] Timer tick, asking Buildbucket for the build status
[14:16:51.329] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:51.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:35:0) after 1m0s
[14:17:51.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:35:0)
[14:17:51.376] Timer tick, asking Buildbucket for the build status
[14:17:51.493] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:51.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:36:0) after 1m0s
[14:18:51.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:36:0)
[14:18:51.562] Timer tick, asking Buildbucket for the build status
[14:18:51.963] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:51.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:37:0) after 1m0s
[14:19:52.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:37:0)
[14:19:52.824] Timer tick, asking Buildbucket for the build status
[14:19:52.947] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:52.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:38:0) after 1m0s
[14:20:53.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:38:0)
[14:20:53.116] Timer tick, asking Buildbucket for the build status
[14:20:53.256] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:53.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:39:0) after 1m0s
[14:21:53.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:39:0)
[14:21:53.311] Timer tick, asking Buildbucket for the build status
[14:21:53.390] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:53.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:40:0) after 1m0s
[14:22:53.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:40:0)
[14:22:53.477] Timer tick, asking Buildbucket for the build status
[14:22:53.547] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:53.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:41:0) after 1m0s
[14:23:53.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:41:0)
[14:23:53.646] Timer tick, asking Buildbucket for the build status
[14:23:53.802] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:53.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:42:0) after 1m0s
[14:24:53.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:42:0)
[14:24:53.861] Timer tick, asking Buildbucket for the build status
[14:24:55.390] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:55.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:43:0) after 1m0s
[14:25:55.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:43:0)
[14:25:55.688] Timer tick, asking Buildbucket for the build status
[14:25:55.876] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:55.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:44:0) after 1m0s
[14:26:56.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:44:0)
[14:26:56.113] Timer tick, asking Buildbucket for the build status
[14:26:56.196] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:56.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:45:0) after 1m0s
[14:27:56.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:45:0)
[14:27:56.335] Timer tick, asking Buildbucket for the build status
[14:27:56.441] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:56.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:46:0) after 1m0s
[14:28:56.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:46:0)
[14:28:56.453] Timer tick, asking Buildbucket for the build status
[14:28:56.529] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:56.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:47:0) after 1m0s
[14:29:56.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:47:0)
[14:29:56.680] Timer tick, asking Buildbucket for the build status
[14:29:56.923] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:56.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:48:0) after 1m0s
[14:30:57.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:48:0)
[14:30:57.079] Timer tick, asking Buildbucket for the build status
[14:31:00.255] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:00.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:49:0) after 1m0s
[14:32:00.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:49:0)
[14:32:00.472] Timer tick, asking Buildbucket for the build status
[14:32:00.608] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:00.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:50:0) after 1m0s
[14:33:00.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:50:0)
[14:33:00.800] Timer tick, asking Buildbucket for the build status
[14:33:00.913] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:00.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:51:0) after 1m0s
[14:34:01.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:51:0)
[14:34:01.329] Timer tick, asking Buildbucket for the build status
[14:34:01.495] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:01.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:52:0) after 1m0s
[14:35:01.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:52:0)
[14:35:01.745] Timer tick, asking Buildbucket for the build status
[14:35:04.026] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:04.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:53:0) after 1m0s
[14:36:04.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:53:0)
[14:36:04.116] Timer tick, asking Buildbucket for the build status
[14:36:04.332] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:04.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:54:0) after 1m0s
[14:37:04.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:54:0)
[14:37:04.490] Timer tick, asking Buildbucket for the build status
[14:37:05.385] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:05.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:55:0) after 1m0s
[14:38:05.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:55:0)
[14:38:05.492] Timer tick, asking Buildbucket for the build status
[14:38:05.610] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:05.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:56:0) after 1m0s
[14:39:05.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:56:0)
[14:39:05.796] Timer tick, asking Buildbucket for the build status
[14:39:05.873] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:05.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:57:0) after 1m0s
[14:40:06.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:57:0)
[14:40:06.233] Timer tick, asking Buildbucket for the build status
[14:40:06.417] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:06.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:58:0) after 1m0s
[14:41:06.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:58:0)
[14:41:06.932] Timer tick, asking Buildbucket for the build status
[14:41:07.089] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:07.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:59:0) after 1m0s
[14:42:07.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:59:0)
[14:42:07.234] Timer tick, asking Buildbucket for the build status
[14:42:07.323] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:07.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:60:0) after 1m0s
[14:43:07.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:60:0)
[14:43:07.373] Timer tick, asking Buildbucket for the build status
[14:43:09.614] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:09.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:61:0) after 1m0s
[14:44:09.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:61:0)
[14:44:09.674] Timer tick, asking Buildbucket for the build status
[14:44:09.924] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:09.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:62:0) after 1m0s
[14:45:09.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:62:0)
[14:45:09.977] Timer tick, asking Buildbucket for the build status
[14:45:10.113] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:10.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:63:0) after 1m0s
[14:46:10.199] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:63:0)
[14:46:10.199] Timer tick, asking Buildbucket for the build status
[14:46:10.336] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:10.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:64:0) after 1m0s
[14:47:10.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:64:0)
[14:47:10.377] Timer tick, asking Buildbucket for the build status
[14:47:11.077] Build 8863378405371573856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:11.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028808467515597216:65:0) after 1m0s
[14:47:43.147] Received PubSub notification, asking Buildbucket for the build status
[14:47:43.236] Build 8863378405371573856: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:47:43.236] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1605624461435430",
"created_by": "project:chromium",
"created_ts": "1605620680415114",
"id": "8863378405371573856",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5c6276499e416b0381a51d9fed05ae98e37e8b60\"}}",
"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\": \"5c6276499e416b0381a51d9fed05ae98e37e8b60\", \"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-5-fw95\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15688, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"2f5d646c928eb989467cf53ed17e2e8d7e7ad357\", \"got_dawn_revision\": \"f2bc3b3edd6f3a8424b230b17f844f59768d53b8\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"5c6276499e416b0381a51d9fed05ae98e37e8b60\", \"got_revision_cp\": \"refs/heads/master@{#828183}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"04515da400d5fbc22d852af1369c4d46bd54991e\", \"got_v8_revision\": \"15247228bbf879eab29da6c7a0b1a608fccbc1fa\", \"got_v8_revision_cp\": \"refs/heads/8.9.4@{#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\": \"5c6276499e416b0381a51d9fed05ae98e37e8b60\", \"swarm_hashes_refs/heads/master(at){#828183}_without_patch\": {\"absl_hardening_tests\": \"ddc021eb1ab1b8e3067c8ebe8c3e1bf29562813e\", \"accessibility_unittests\": \"492674516f1050ef0f7056a4041419af76bf7972\", \"angle_unittests\": \"cdddf33d9925b0fd2f8a8cfd3b0c6ed01591b6f1\", \"app_shell_unittests\": \"35a29787d7766c19bf7d0ea7c3a7e9028c65de58\", \"aura_unittests\": \"bfc7b30b2db9aee077d8c869a5241283a42c6b59\", \"base_unittests\": \"a5d3506cabdd665649f2e2118e60aac1332214f4\", \"base_util_unittests\": \"aa70745eca3c8119bcff18e7c1ccf659c3e8c350\", \"blink_common_unittests\": \"c1716e292b9e7a0151a2ee61220f6ade9fb6a74b\", \"blink_fuzzer_unittests\": \"0c06de31e13d4e5feaf27db7bf4caf2b5c4618a3\", \"blink_heap_unittests\": \"4394d51439cec79a97dde3e192876b7a38236def\", \"blink_platform_unittests\": \"c6d96d5519df607fa299883c08a9f95748ba3636\", \"blink_unittests\": \"c7c48ac162e1fec50c7b144ca4c4164f7fc1d1d6\", \"boringssl_crypto_tests\": \"cb1231c21fc042aac5d39a7c9508e82db8502426\", \"boringssl_ssl_tests\": \"d1ab5c076b5ca433b9201f4707a1d6ad8332a046\", \"browser_switcher_bho_unittests\": \"c88d2b1814dec38b29875c732d910c7ac5275e07\", \"browser_tests\": \"46a7adbe1d1c5cfb77eaa4e3a3edd5c4b84cff3b\", \"capture_unittests\": \"51106d77e0a79962319b4979c8f164570c2a35c3\", \"cast_unittests\": \"f16ba543aa74b8982ecc12f5d10d207650d6a05b\", \"cc_unittests\": \"c075005c0dfa5e54cd519b2125774e3b8760bb2e\", \"chrome_app_unittests\": \"ccc08ad85f410a6110a420989e7ba154cbe2f518\", \"chrome_cleaner_unittests\": \"c1bbef8eedb3c6f62cae775f6ddbc76a9f65e4e0\", \"chrome_elf_unittests\": \"9c1966b2f5df803cedaa160c01cdd5ec5c3b6d9e\", \"chromedriver_unittests\": \"b60c0706c9bd7293408669702bda37f44c6d2a69\", \"components_browsertests\": \"dbc2298b386d0ab24bb68e7851e3d2b4b93e1fc6\", \"components_unittests\": \"3136bcb17580317701bc00bd73562eaf3e278a84\", \"compositor_unittests\": \"58c534d8d17facd4a15b44d1054811f624f95434\", \"content_browsertests\": \"46f1030564d5b9399472affa6ee5bd0d521cdada\", \"content_unittests\": \"ea15cf061cbab3d9febfb05ef46391ab792a32ee\", \"courgette_unittests\": \"cf7eaa40162b280097609d051a8f804754ff52c6\", \"crashpad_tests\": \"0fb4a63179b48379010a13e9eb5691ac97194ee8\", \"cronet_tests\": \"6e4b6552598d03d3d945ad1c9f4f73a03740485a\", \"cronet_unittests\": \"2c7d1ee03b1f6eb86ca5f42ba806bdec079bcf5a\", \"crypto_unittests\": \"ec88f2844d353fcfe055b7565b28d397d6ad3327\", \"delayloads_unittests\": \"3c84a7ccf5b483bd915795e642cd30f8dda5a2aa\", \"device_unittests\": \"aa916e83589993642eb50dee01d9a78de66c1f5a\", \"display_unittests\": \"a53477465770430120f5cd6a054bd0e849e29def\", \"elevation_service_unittests\": \"74fa767139b9838398bff3631b41daccde5b6f46\", \"events_unittests\": \"098d60134272534273c639e5d2c0c52b45549d2d\", \"extensions_browsertests\": \"ede599327ddc89f05366f1c6faf7b824f02bb224\", \"extensions_unittests\": \"6c580b9adf4ec2cb86b674bb1b2c91294776fcc7\", \"filesystem_service_unittests\": \"48431ce3bedcd73c5664ab5eda3362e26f533106\", \"gcm_unit_tests\": \"37af9ea5494966c182d088d71c644caea5d7aa93\", \"gcp_unittests\": \"37d0e1461b0d0d84bb890eab657ea01dd792d71e\", \"gfx_unittests\": \"1c6462096c564f346135ad617785c08329342be8\", \"gin_unittests\": \"765759000350342434ed40a632f6cbe6a5d6480a\", \"google_apis_unittests\": \"9d647bcc55ba9d217fc3c9ffa72168ca418c3db4\", \"gpu_unittests\": \"d6de90496fd0a55c882b8c758a9c98d5b581692e\", \"gwp_asan_unittests\": \"52455896c29fddadcc8accfcf15e6f23db27a02f\", \"headless_browsertests\": \"90c08737e2cf9b39073c65e50a9240971de945e7\", \"headless_unittests\": \"167da50f744dac9484b46b99bf0069bf156d225f\", \"install_static_unittests\": \"81626381b5c1fba89af148b687cd834c489edbde\", \"installer_util_unittests\": \"562d229faf0f3ec983eca605984bcaaf1f3e8291\", \"interactive_ui_tests\": \"65ea33b2799f81d578ecf852ec83b6af0e0df3a5\", \"ipc_tests\": \"a80da608093c1df9f7d7bd1baf06e9973208cdee\", \"jingle_unittests\": \"3c5d59ebb28a5793ce92a45188d937010831b58c\", \"latency_unittests\": \"be17a77a0c5b64f9fed0fc11cb66985ff0475753\", \"libjingle_xmpp_unittests\": \"be86001ce22f5bfb6db3e76a0bff511824888181\", \"liburlpattern_unittests\": \"c72629253b60c04695568931828eaf4a2005d79a\", \"media_blink_unittests\": \"5ebb4f1e780f8dcedf79a6e45c5c4a77f71632a3\", \"media_unittests\": \"8aba0f405746c9bb25a54624d397cd15d1aafe62\", \"message_center_unittests\": \"961e22eea51eb587bce1323909f9dc92f5338215\", \"midi_unittests\": \"2d746801604adc7734ce36f7b52f41b450e3d602\", \"mojo_core_unittests\": \"328ecf62eab7cc5e3f832d48f8bfe3b39aa915c5\", \"mojo_unittests\": \"96dbd4eb2faa7e4374f665e72d83e44c94db45eb\", \"nacl_loader_unittests\": \"3a20ce64d47129f97d55514ee282b6bdbfa74623\", \"native_theme_unittests\": \"b8a4071d23ccb41a847d0cccd6538b1277065eec\", \"net_unittests\": \"b575b75854d9bef9012afaed36e239218ebf6e3e\", \"notification_helper_unittests\": \"456b4dbbc7485ed71d01389f56bf1c5b5204996b\", \"pdf_unittests\": \"2a2564711a1eaf78b6b118ad4c9b4be97202ce52\", \"perfetto_unittests\": \"7657d2971dee3e208ce8b1c783572393c9ca0866\", \"ppapi_unittests\": \"0dd22dbdaf8b09893155ac1f46c6b374efee20b3\", \"printing_unittests\": \"e5fb95d76e1037780d9974677bd471304c33dbcc\", \"remoting_unittests\": \"5e9095225764f1653214b95aa869aaf6b0ec57f5\", \"sbox_integration_tests\": \"23993c0e8fe76eccf55514ad872d98dff3f45bf0\", \"sbox_unittests\": \"c750b8282e179d8d668216f1892c3226076f488b\", \"sbox_validation_tests\": \"811b84f51d8ddd102d5065762791eea2306fb26f\", \"service_manager_unittests\": \"e59c1ff2db4f4c22256236ebaec42a9374022e6a\", \"services_unittests\": \"c8e716385a3d0711a70146cf4f0119ee153d4409\", \"setup_unittests\": \"3d06a7c764ba956ad4a866b0956c8c4b1261ba55\", \"shell_dialogs_unittests\": \"b63716fcb5d3c7078531b7e816ea7603fa2498a5\", \"skia_unittests\": \"01c89cb6e7496c9382bbfe532ef33556634f61bb\", \"snapshot_unittests\": \"2f4999d3225765eafc6187a04999d9754c70a329\", \"sql_unittests\": \"b5a5acec4d109e4b08bd1e9ad3cf7fe70414909d\", \"storage_unittests\": \"185ea6dd239c91abf22a71756ab40cc0c4af4e07\", \"sync_integration_tests\": \"82a76a1dd78e56fd5dbc8e415f2f6843c6ec9f02\", \"traffic_annotation_auditor_unittests\": \"dbe137b187635779ed3c5371a1c558f23b173f38\", \"ui_base_unittests\": \"4295831cbc8cb13dfc1990f19c2b4b8baca9e89f\", \"ui_touch_selection_unittests\": \"1e50cdd16bc3a808f065328e4d738f6300fe6e1a\", \"unit_tests\": \"9cdda8a280302984a2ae3d176e03c1e864e310b4\", \"updater_tests\": \"74deafd085d1f9b038864ac9598e7a61cf44a97d\", \"url_unittests\": \"fdff5f552723b6b9e6a908c1205a71003ae8cfcd\", \"views_unittests\": \"c875574c3a65682fb70b608db9c0872c68242a0b\", \"viz_unittests\": \"6260510c8e62ab46accd32dde34b45f7043aa0b4\", \"vr_common_unittests\": \"9885245252247450357e539bad92689a1ef37c48\", \"vr_pixeltests\": \"d5db8e9a10587aeda3200eed1e74312a83c5a18d\", \"weblayer_browsertests\": \"891443910bb7171a9da968cf44af5dac9a19a338\", \"weblayer_unittests\": \"f0da519a6583c28d47239188faad52919f75f172\", \"wm_unittests\": \"2c090f8d0574ab12e608533897cd839b5142d63e\", \"wtf_unittests\": \"dc4c2b94321b88c32ab4fc83398e4631f41e00e5\", \"zlib_unittests\": \"72a9931ec3faf8c41ea04632440b8c15c6d55d60\", \"zucchini_unittests\": \"1603099186c59fa35551d77ac292e18d1659fe0b\"}}, \"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-5-fw95\"], \"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\": [\"5632-2e614e1\"], \"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": "1605620685784460",
"status": "COMPLETED",
"status_changed_ts": "1605624462779520",
"tags": [
"build_address:luci.chromium.ci/win-asan/15688",
"builder:win-asan",
"buildset:commit/git/5c6276499e416b0381a51d9fed05ae98e37e8b60",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5c6276499e416b0381a51d9fed05ae98e37e8b60",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028808467515597216",
"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/8863378405371573856/+/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:4fef44d342c6ce10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605624462783454",
"url": "https://ci.chromium.org/b/8863378405371573856",
"utcnow_ts": "1605624463221484"
}
}
[14:47:43.236] Invocation finished in 1h3m4.248513342s with status SUCCEEDED