[10:08:58.328] New invocation is queued and will start shortly
[10:08:59.408] Starting the invocation (attempt 1)
[10:08:59.446] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:08:59.447] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:08:59.447] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017859803869117664",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"51bac23ba8e802f9cabefe489936aa316c98316f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9017859803869117664",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/51bac23ba8e802f9cabefe489936aa316c98316f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51bac23ba8e802f9cabefe489936aa316c98316f",
"gitiles_ref:refs/heads/master"
]
}
[10:09:00.254] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616062139581749",
"id": "8852429741885043888",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"51bac23ba8e802f9cabefe489936aa316c98316f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616062140064098",
"tags": [
"build_address:luci.chromium.ci/win-asan/17850",
"builder:win-asan",
"buildset:commit/git/51bac23ba8e802f9cabefe489936aa316c98316f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51bac23ba8e802f9cabefe489936aa316c98316f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017859803869117664",
"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/8852429741885043888/+/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": "1616062140064203",
"url": "https://ci.chromium.org/b/8852429741885043888",
"utcnow_ts": "1616062140212224"
}
}
[10:09:00.254] Task URL: https://ci.chromium.org/b/8852429741885043888
[10:09:00.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:2:0) after 1m0s
[10:10:00.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:2:0)
[10:10:00.266] Timer tick, asking Buildbucket for the build status
[10:10:00.384] Build 8852429741885043888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:00.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:3:0) after 1m0s
[10:11:00.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:3:0)
[10:11:00.430] Timer tick, asking Buildbucket for the build status
[10:11:00.617] Build 8852429741885043888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:00.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:4:0) after 1m0s
[10:12:00.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:4:0)
[10:12:00.636] Timer tick, asking Buildbucket for the build status
[10:12:00.716] Build 8852429741885043888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:00.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:5:0) after 1m0s
[10:13:00.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:5:0)
[10:13:00.827] Timer tick, asking Buildbucket for the build status
[10:13:01.114] Build 8852429741885043888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:01.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:6:0) after 1m0s
[10:13:41.213] Received PubSub notification, asking Buildbucket for the build status
[10:13:41.433] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:01.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:6:0)
[10:14:01.398] Timer tick, asking Buildbucket for the build status
[10:14:01.483] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:01.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:8:0) after 1m0s
[10:15:01.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:8:0)
[10:15:01.551] Timer tick, asking Buildbucket for the build status
[10:15:01.887] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:01.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:9:0) after 1m0s
[10:16:01.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:9:0)
[10:16:01.947] Timer tick, asking Buildbucket for the build status
[10:16:02.015] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:02.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:10:0) after 1m0s
[10:17:02.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:10:0)
[10:17:02.036] Timer tick, asking Buildbucket for the build status
[10:17:02.263] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:02.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:11:0) after 1m0s
[10:18:02.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:11:0)
[10:18:02.300] Timer tick, asking Buildbucket for the build status
[10:18:02.393] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:02.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:12:0) after 1m0s
[10:19:02.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:12:0)
[10:19:02.535] Timer tick, asking Buildbucket for the build status
[10:19:02.625] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:02.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:13:0) after 1m0s
[10:20:02.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:13:0)
[10:20:02.657] Timer tick, asking Buildbucket for the build status
[10:20:02.760] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:02.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:14:0) after 1m0s
[10:21:02.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:14:0)
[10:21:02.817] Timer tick, asking Buildbucket for the build status
[10:21:03.005] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:03.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:15:0) after 1m0s
[10:22:03.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:15:0)
[10:22:03.049] Timer tick, asking Buildbucket for the build status
[10:22:03.220] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:03.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:16:0) after 1m0s
[10:23:03.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:16:0)
[10:23:03.240] Timer tick, asking Buildbucket for the build status
[10:23:03.507] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:03.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:17:0) after 1m0s
[10:24:03.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:17:0)
[10:24:03.551] Timer tick, asking Buildbucket for the build status
[10:24:03.636] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:03.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:18:0) after 1m0s
[10:25:03.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:18:0)
[10:25:03.751] Timer tick, asking Buildbucket for the build status
[10:25:04.173] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:04.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:19:0) after 1m0s
[10:26:04.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:19:0)
[10:26:04.293] Timer tick, asking Buildbucket for the build status
[10:26:04.458] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:04.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:20:0) after 1m0s
[10:27:04.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:20:0)
[10:27:04.516] Timer tick, asking Buildbucket for the build status
[10:27:04.608] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:04.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:21:0) after 1m0s
[10:28:07.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:21:0)
[10:28:07.806] Timer tick, asking Buildbucket for the build status
[10:28:07.870] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:07.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:22:0) after 1m0s
[10:29:07.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:22:0)
[10:29:07.962] Timer tick, asking Buildbucket for the build status
[10:29:08.229] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:08.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:23:0) after 1m0s
[10:30:08.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:23:0)
[10:30:08.255] Timer tick, asking Buildbucket for the build status
[10:30:08.393] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:08.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:24:0) after 1m0s
[10:31:08.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:24:0)
[10:31:08.437] Timer tick, asking Buildbucket for the build status
[10:31:08.678] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:08.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:25:0) after 1m0s
[10:32:08.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:25:0)
[10:32:08.719] Timer tick, asking Buildbucket for the build status
[10:32:08.843] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:08.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:26:0) after 1m0s
[10:33:08.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:26:0)
[10:33:08.886] Timer tick, asking Buildbucket for the build status
[10:33:08.950] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:08.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:27:0) after 1m0s
[10:34:09.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:27:0)
[10:34:09.093] Timer tick, asking Buildbucket for the build status
[10:34:09.271] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:09.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:28:0) after 1m0s
[10:35:09.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:28:0)
[10:35:09.308] Timer tick, asking Buildbucket for the build status
[10:35:09.686] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:09.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:29:0) after 1m0s
[10:36:09.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:29:0)
[10:36:09.734] Timer tick, asking Buildbucket for the build status
[10:36:09.800] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:09.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:30:0) after 1m0s
[10:37:09.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:30:0)
[10:37:09.846] Timer tick, asking Buildbucket for the build status
[10:37:09.916] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:09.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:31:0) after 1m0s
[10:38:09.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:31:0)
[10:38:09.959] Timer tick, asking Buildbucket for the build status
[10:38:10.019] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:10.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:32:0) after 1m0s
[10:39:10.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:32:0)
[10:39:10.102] Timer tick, asking Buildbucket for the build status
[10:39:10.201] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:10.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:33:0) after 1m0s
[10:40:10.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:33:0)
[10:40:10.225] Timer tick, asking Buildbucket for the build status
[10:40:10.326] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:10.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:34:0) after 1m0s
[10:41:10.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:34:0)
[10:41:10.414] Timer tick, asking Buildbucket for the build status
[10:41:10.574] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:10.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:35:0) after 1m0s
[10:42:10.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:35:0)
[10:42:10.743] Timer tick, asking Buildbucket for the build status
[10:42:10.860] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:10.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:36:0) after 1m0s
[10:43:10.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:36:0)
[10:43:10.986] Timer tick, asking Buildbucket for the build status
[10:43:11.096] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:11.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:37:0) after 1m0s
[10:44:11.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:37:0)
[10:44:11.153] Timer tick, asking Buildbucket for the build status
[10:44:11.783] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:11.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:38:0) after 1m0s
[10:45:11.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:38:0)
[10:45:11.839] Timer tick, asking Buildbucket for the build status
[10:45:11.902] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:11.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:39:0) after 1m0s
[10:46:12.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:39:0)
[10:46:12.599] Timer tick, asking Buildbucket for the build status
[10:46:13.157] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:13.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:40:0) after 1m0s
[10:47:13.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:40:0)
[10:47:13.227] Timer tick, asking Buildbucket for the build status
[10:47:13.339] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:13.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:41:0) after 1m0s
[10:48:13.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:41:0)
[10:48:13.328] Timer tick, asking Buildbucket for the build status
[10:48:13.596] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:13.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:42:0) after 1m0s
[10:49:13.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:42:0)
[10:49:13.715] Timer tick, asking Buildbucket for the build status
[10:49:13.885] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:13.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:43:0) after 1m0s
[10:50:13.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:43:0)
[10:50:13.955] Timer tick, asking Buildbucket for the build status
[10:50:14.026] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:14.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:44:0) after 1m0s
[10:51:14.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:44:0)
[10:51:14.080] Timer tick, asking Buildbucket for the build status
[10:51:14.212] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:14.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:45:0) after 1m0s
[10:52:14.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:45:0)
[10:52:14.288] Timer tick, asking Buildbucket for the build status
[10:52:14.369] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:14.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:46:0) after 1m0s
[10:53:14.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:46:0)
[10:53:14.509] Timer tick, asking Buildbucket for the build status
[10:53:14.584] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:14.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:47:0) after 1m0s
[10:54:14.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:47:0)
[10:54:14.889] Timer tick, asking Buildbucket for the build status
[10:54:14.975] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:14.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:48:0) after 1m0s
[10:55:14.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:48:0)
[10:55:14.994] Timer tick, asking Buildbucket for the build status
[10:55:15.103] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:15.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:49:0) after 1m0s
[10:56:15.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:49:0)
[10:56:15.156] Timer tick, asking Buildbucket for the build status
[10:56:15.237] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:15.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:50:0) after 1m0s
[10:57:15.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:50:0)
[10:57:15.413] Timer tick, asking Buildbucket for the build status
[10:57:15.473] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:15.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:51:0) after 1m0s
[10:58:15.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:51:0)
[10:58:15.582] Timer tick, asking Buildbucket for the build status
[10:58:15.756] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:15.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:52:0) after 1m0s
[10:59:15.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:52:0)
[10:59:15.781] Timer tick, asking Buildbucket for the build status
[10:59:15.916] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:15.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:53:0) after 1m0s
[11:00:15.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:53:0)
[11:00:15.998] Timer tick, asking Buildbucket for the build status
[11:00:16.078] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:16.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:54:0) after 1m0s
[11:01:16.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:54:0)
[11:01:16.134] Timer tick, asking Buildbucket for the build status
[11:01:16.237] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:16.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:55:0) after 1m0s
[11:02:16.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:55:0)
[11:02:16.279] Timer tick, asking Buildbucket for the build status
[11:02:16.372] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:16.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:56:0) after 1m0s
[11:03:16.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:56:0)
[11:03:16.454] Timer tick, asking Buildbucket for the build status
[11:03:16.518] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:16.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:57:0) after 1m0s
[11:04:16.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:57:0)
[11:04:16.574] Timer tick, asking Buildbucket for the build status
[11:04:16.642] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:16.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:58:0) after 1m0s
[11:05:16.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:58:0)
[11:05:16.661] Timer tick, asking Buildbucket for the build status
[11:05:16.720] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:16.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:59:0) after 1m0s
[11:06:16.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:59:0)
[11:06:16.750] Timer tick, asking Buildbucket for the build status
[11:06:16.822] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:16.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:60:0) after 1m0s
[11:07:16.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:60:0)
[11:07:16.825] Timer tick, asking Buildbucket for the build status
[11:07:16.902] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:16.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:61:0) after 1m0s
[11:08:16.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:61:0)
[11:08:16.907] Timer tick, asking Buildbucket for the build status
[11:08:17.001] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:17.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:62:0) after 1m0s
[11:09:17.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:62:0)
[11:09:17.092] Timer tick, asking Buildbucket for the build status
[11:09:17.193] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:17.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:63:0) after 1m0s
[11:10:17.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:63:0)
[11:10:17.301] Timer tick, asking Buildbucket for the build status
[11:10:17.385] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:17.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:64:0) after 1m0s
[11:11:17.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:64:0)
[11:11:17.686] Timer tick, asking Buildbucket for the build status
[11:11:17.754] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:17.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:65:0) after 1m0s
[11:12:18.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:65:0)
[11:12:18.181] Timer tick, asking Buildbucket for the build status
[11:12:18.271] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:18.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:66:0) after 1m0s
[11:13:18.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:66:0)
[11:13:18.447] Timer tick, asking Buildbucket for the build status
[11:13:18.520] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:18.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:67:0) after 1m0s
[11:14:18.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:67:0)
[11:14:18.597] Timer tick, asking Buildbucket for the build status
[11:14:18.681] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:18.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:68:0) after 1m0s
[11:15:18.741] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:68:0)
[11:15:18.741] Timer tick, asking Buildbucket for the build status
[11:15:18.865] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:18.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:69:0) after 1m0s
[11:16:19.168] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:69:0)
[11:16:19.168] Timer tick, asking Buildbucket for the build status
[11:16:19.236] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:19.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:70:0) after 1m0s
[11:17:19.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:70:0)
[11:17:19.537] Timer tick, asking Buildbucket for the build status
[11:17:19.601] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:19.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:71:0) after 1m0s
[11:18:19.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:71:0)
[11:18:19.947] Timer tick, asking Buildbucket for the build status
[11:18:20.067] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:20.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:72:0) after 1m0s
[11:19:20.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:72:0)
[11:19:20.151] Timer tick, asking Buildbucket for the build status
[11:19:20.238] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:20.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:73:0) after 1m0s
[11:20:20.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:73:0)
[11:20:20.267] Timer tick, asking Buildbucket for the build status
[11:20:20.337] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:20.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:74:0) after 1m0s
[11:21:20.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:74:0)
[11:21:20.358] Timer tick, asking Buildbucket for the build status
[11:21:20.428] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:20.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:75:0) after 1m0s
[11:22:20.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:75:0)
[11:22:20.561] Timer tick, asking Buildbucket for the build status
[11:22:20.633] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:20.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:76:0) after 1m0s
[11:23:20.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:76:0)
[11:23:20.673] Timer tick, asking Buildbucket for the build status
[11:23:20.748] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:20.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:77:0) after 1m0s
[11:24:20.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:77:0)
[11:24:20.723] Timer tick, asking Buildbucket for the build status
[11:24:20.911] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:20.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:78:0) after 1m0s
[11:25:21.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:78:0)
[11:25:21.090] Timer tick, asking Buildbucket for the build status
[11:25:21.213] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:21.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:79:0) after 1m0s
[11:26:21.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:79:0)
[11:26:21.273] Timer tick, asking Buildbucket for the build status
[11:26:21.345] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:21.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:80:0) after 1m0s
[11:27:21.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:80:0)
[11:27:21.329] Timer tick, asking Buildbucket for the build status
[11:27:21.424] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:21.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:81:0) after 1m0s
[11:28:21.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:81:0)
[11:28:21.447] Timer tick, asking Buildbucket for the build status
[11:28:21.522] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:21.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:82:0) after 1m0s
[11:29:21.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:82:0)
[11:29:21.553] Timer tick, asking Buildbucket for the build status
[11:29:21.709] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:21.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:83:0) after 1m0s
[11:30:21.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:83:0)
[11:30:21.795] Timer tick, asking Buildbucket for the build status
[11:30:21.900] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:21.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:84:0) after 1m0s
[11:31:22.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:84:0)
[11:31:22.006] Timer tick, asking Buildbucket for the build status
[11:31:22.086] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:22.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:85:0) after 1m0s
[11:32:22.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:85:0)
[11:32:22.389] Timer tick, asking Buildbucket for the build status
[11:32:22.851] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:22.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:86:0) after 1m0s
[11:33:22.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:86:0)
[11:33:22.934] Timer tick, asking Buildbucket for the build status
[11:33:23.024] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:23.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:87:0) after 1m0s
[11:34:23.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:87:0)
[11:34:23.034] Timer tick, asking Buildbucket for the build status
[11:34:23.117] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:23.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:88:0) after 1m0s
[11:35:23.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:88:0)
[11:35:23.203] Timer tick, asking Buildbucket for the build status
[11:35:23.281] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:23.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:89:0) after 1m0s
[11:36:23.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:89:0)
[11:36:23.264] Timer tick, asking Buildbucket for the build status
[11:36:23.367] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:23.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:90:0) after 1m0s
[11:37:23.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:90:0)
[11:37:23.387] Timer tick, asking Buildbucket for the build status
[11:37:23.522] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:23.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:91:0) after 1m0s
[11:38:23.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:91:0)
[11:38:23.571] Timer tick, asking Buildbucket for the build status
[11:38:23.687] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:23.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:92:0) after 1m0s
[11:39:23.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:92:0)
[11:39:23.709] Timer tick, asking Buildbucket for the build status
[11:39:23.824] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:23.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:93:0) after 1m0s
[11:40:23.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:93:0)
[11:40:23.847] Timer tick, asking Buildbucket for the build status
[11:40:23.917] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:23.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:94:0) after 1m0s
[11:41:23.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:94:0)
[11:41:23.948] Timer tick, asking Buildbucket for the build status
[11:41:24.041] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:24.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:95:0) after 1m0s
[11:42:24.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:95:0)
[11:42:24.141] Timer tick, asking Buildbucket for the build status
[11:42:24.229] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:24.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:96:0) after 1m0s
[11:43:24.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:96:0)
[11:43:24.255] Timer tick, asking Buildbucket for the build status
[11:43:24.393] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:24.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:97:0) after 1m0s
[11:44:24.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:97:0)
[11:44:24.412] Timer tick, asking Buildbucket for the build status
[11:44:24.509] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:24.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:98:0) after 1m0s
[11:45:24.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:98:0)
[11:45:24.531] Timer tick, asking Buildbucket for the build status
[11:45:24.609] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:24.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:99:0) after 1m0s
[11:46:24.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:99:0)
[11:46:24.679] Timer tick, asking Buildbucket for the build status
[11:46:24.993] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:24.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:100:0) after 1m0s
[11:47:25.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:100:0)
[11:47:25.085] Timer tick, asking Buildbucket for the build status
[11:47:25.167] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:25.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:101:0) after 1m0s
[11:48:25.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:101:0)
[11:48:25.272] Timer tick, asking Buildbucket for the build status
[11:48:25.540] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:25.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:102:0) after 1m0s
[11:49:25.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:102:0)
[11:49:25.642] Timer tick, asking Buildbucket for the build status
[11:49:25.805] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:25.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:103:0) after 1m0s
[11:50:25.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:103:0)
[11:50:25.857] Timer tick, asking Buildbucket for the build status
[11:50:26.085] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:26.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:104:0) after 1m0s
[11:51:26.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:104:0)
[11:51:26.162] Timer tick, asking Buildbucket for the build status
[11:51:26.813] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:26.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:105:0) after 1m0s
[11:52:26.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:105:0)
[11:52:26.916] Timer tick, asking Buildbucket for the build status
[11:52:27.015] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:27.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:106:0) after 1m0s
[11:53:27.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:106:0)
[11:53:27.140] Timer tick, asking Buildbucket for the build status
[11:53:27.223] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:27.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:107:0) after 1m0s
[11:54:27.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:107:0)
[11:54:27.258] Timer tick, asking Buildbucket for the build status
[11:54:27.359] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:27.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:108:0) after 1m0s
[11:55:27.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:108:0)
[11:55:27.368] Timer tick, asking Buildbucket for the build status
[11:55:27.436] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:27.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:109:0) after 1m0s
[11:56:27.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:109:0)
[11:56:27.494] Timer tick, asking Buildbucket for the build status
[11:56:27.630] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:27.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:110:0) after 1m0s
[11:57:28.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:110:0)
[11:57:28.087] Timer tick, asking Buildbucket for the build status
[11:57:28.186] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:28.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:111:0) after 1m0s
[11:58:29.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:111:0)
[11:58:29.551] Timer tick, asking Buildbucket for the build status
[11:58:29.757] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:29.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:112:0) after 1m0s
[11:59:29.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:112:0)
[11:59:29.830] Timer tick, asking Buildbucket for the build status
[11:59:29.938] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:29.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:113:0) after 1m0s
[12:00:30.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:113:0)
[12:00:30.209] Timer tick, asking Buildbucket for the build status
[12:00:30.307] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:30.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:114:0) after 1m0s
[12:01:30.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:114:0)
[12:01:30.286] Timer tick, asking Buildbucket for the build status
[12:01:30.365] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:30.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:115:0) after 1m0s
[12:02:30.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:115:0)
[12:02:30.410] Timer tick, asking Buildbucket for the build status
[12:02:30.493] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:30.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:116:0) after 1m0s
[12:03:30.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:116:0)
[12:03:30.534] Timer tick, asking Buildbucket for the build status
[12:03:30.686] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:30.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:117:0) after 1m0s
[12:04:30.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:117:0)
[12:04:30.825] Timer tick, asking Buildbucket for the build status
[12:04:31.323] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:31.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:118:0) after 1m0s
[12:05:31.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:118:0)
[12:05:31.311] Timer tick, asking Buildbucket for the build status
[12:05:31.386] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:31.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:119:0) after 1m0s
[12:06:31.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:119:0)
[12:06:31.415] Timer tick, asking Buildbucket for the build status
[12:06:31.494] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:31.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:120:0) after 1m0s
[12:07:31.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:120:0)
[12:07:31.715] Timer tick, asking Buildbucket for the build status
[12:07:32.121] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:32.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:121:0) after 1m0s
[12:08:32.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:121:0)
[12:08:32.167] Timer tick, asking Buildbucket for the build status
[12:08:32.249] Build 8852429741885043888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:32.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9017859803869117664:122:0) after 1m0s
[12:08:44.752] Received PubSub notification, asking Buildbucket for the build status
[12:08:44.827] Build 8852429741885043888: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:08:44.827] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616069323644102",
"created_by": "project:chromium",
"created_ts": "1616062139581749",
"id": "8852429741885043888",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"51bac23ba8e802f9cabefe489936aa316c98316f\"}}",
"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\": \"51bac23ba8e802f9cabefe489936aa316c98316f\", \"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-6-xj00\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17850, \"clang_revision\": \"llvmorg-13-init-4720-g7bafe336-1\", \"got_angle_revision\": \"39296f396f5bf9abb8c8d80b147be7029c633964\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"51bac23ba8e802f9cabefe489936aa316c98316f\", \"got_revision_cp\": \"refs/heads/master@{#864173}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"4f0f4e2a3c5ad9dc7312774da5197e0179c30863\", \"got_v8_revision_cp\": \"refs/heads/9.1.146@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"is_cached\": false, \"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\": \"51bac23ba8e802f9cabefe489936aa316c98316f\", \"swarm_hashes_refs/heads/master(at){#864173}_without_patch\": {\"absl_hardening_tests\": \"ecece931648285147145fd62bedf5ecbbaa4d028\", \"accessibility_unittests\": \"8479b85b4d3521d29bb208f74a9650b121be03ee\", \"angle_unittests\": \"fb8f0289b77a22ea0a30b614c391189cc1541d1a\", \"app_shell_unittests\": \"d7122eef02824dd8f75bc11448e9f37510161bd5\", \"aura_unittests\": \"3453e8fbba665a1afd3593b968e23e7fc082e9b4\", \"base_unittests\": \"b398869d0793af28b81d028afacf42caa05c1a7b\", \"base_util_unittests\": \"4bb884a9f4393c7e35a9904ad8ea9f21aa04315e\", \"blink_common_unittests\": \"0610d53ab1961d01e21557108c0832f75228ca8d\", \"blink_fuzzer_unittests\": \"2650ea4c39fce9df15ccf1675fe5cba8c4a8daa9\", \"blink_heap_unittests\": \"653dd6ff007e2370fc9ef5139923c22579c8ea20\", \"blink_platform_unittests\": \"06b14d871499abef41be428bc4acdcb0b9a2be39\", \"blink_unittests\": \"9290e8491a87bf43d17b6881193ae12712b4be3d\", \"boringssl_crypto_tests\": \"599d73218da6a5a8d86ded3223e12ea12efa5b63\", \"boringssl_ssl_tests\": \"4d03ed04bb5c7b46f77c63a3eeac8b66c232370d\", \"browser_switcher_bho_unittests\": \"045b8d836fe6c6e6c97349c7c2325e6b81af1503\", \"browser_tests\": \"cdeaa12e513f96c8ee2c266bfbad68574a2d96a3\", \"capture_unittests\": \"b9d6057a91661a54685e2434358c9f95bc5b5259\", \"cast_unittests\": \"37a4553ec52ce62af8f521ebb59dffed0f22a43a\", \"cc_unittests\": \"ab18599f7e2b160d353938202cb70a372a15f119\", \"chrome_app_unittests\": \"d8279fea4a6a733b83eb2e7781c63f4c2766a33a\", \"chrome_cleaner_unittests\": \"aa368dc287ebff528cc46e2fb148c7d5f980b332\", \"chrome_elf_unittests\": \"f7258f254e1385e130a1cd8a131eb60227420da1\", \"chromedriver_unittests\": \"1fbe395ab1eece46d69b7317ac2533787857d2fa\", \"color_unittests\": \"64b485c658b72256976641a08d8d037c77a2ad13\", \"components_browsertests\": \"b6d1ade794aaf1bc8e57f93d0ddb69137bfcbf89\", \"components_unittests\": \"44811a7c522095e0283b228a89983dc8d46690db\", \"compositor_unittests\": \"7537886cd99db5003edbb65dd7fd1f6f23abbb50\", \"content_browsertests\": \"542760bfc464273c14b07ca51e7321a769dd761d\", \"content_unittests\": \"1771416a35e7a84a352b1e4f448cbb6cb9e31903\", \"courgette_unittests\": \"3058c1b6ecd92ae746020e9d44f14f57c5b8b12d\", \"crashpad_tests\": \"f1cde8c4918aae0ed70fb475461f7be7e9f20e7b\", \"cronet_tests\": \"21c652531a91e793bea9bcdea4ad937d0306396f\", \"cronet_unittests\": \"820c8fd542233196c7919d524a8c464b13645d08\", \"crypto_unittests\": \"ccd07e48d5b41ce30d8cf4f3b929c810a4094aad\", \"delayloads_unittests\": \"6fdb43719dc59c1b304b5cc32fd10dc4ccf58315\", \"device_unittests\": \"3b1d92c7f94cc412cfb6c48108fb0d2a295d03d4\", \"display_unittests\": \"29432c72e1d0633a99f60320de3e46f3a6782e09\", \"elevation_service_unittests\": \"b637c654b4fc9a828a0f0028349c77b4184c0a9f\", \"events_unittests\": \"3e0a30f4f70a87a05ef6f3f286a69409ad53c087\", \"extensions_browsertests\": \"d6042f43aa937655da04e70aa240b59841e25c8c\", \"extensions_unittests\": \"cb4d2d4b4af9fc67a903564bfe10aba48510ce2f\", \"filesystem_service_unittests\": \"1986bfed9576fc6498ce850cba1dff1310da85e6\", \"gcm_unit_tests\": \"d7e4825a01374259d59de2e35875dcd731b72f4b\", \"gcp_unittests\": \"78f639cb3d3762992ff2fa0310bd95f199e1262b\", \"gfx_unittests\": \"3895466ca427ea95961df6abaa3ffeff7d567847\", \"gin_unittests\": \"a61f18f7f4e75872f8b1a49e5aebe77b3f759846\", \"google_apis_unittests\": \"f4b0bad167c8646322521758b04577769cfa02f7\", \"gpu_unittests\": \"a19db82adf9325e9f1de08445c2c668f9e9a5593\", \"gwp_asan_unittests\": \"e7ca8aeb3e0053d99a060d293e599e20d545caec\", \"headless_browsertests\": \"05694b2b39df6b86e05e6d204d29665528b39bb9\", \"headless_unittests\": \"8b6e9cc74acd739213c73eb87c886ad07e4bdc87\", \"install_static_unittests\": \"ac663c2805ea6eb00db4b4ef56594cec539b0c96\", \"installer_util_unittests\": \"f3747c9d20a2428f31932f8fdf79a5cf3b6fd737\", \"interactive_ui_tests\": \"50bca8596f3bcccc5f7cd7fcc95ab2c832b0a5aa\", \"ipc_tests\": \"4211039d5e4784c8c0dfd24ee10a7e19f1e07cfd\", \"jingle_unittests\": \"db7f1a9cfb6248ec5fabc98579e49b45dd620feb\", \"latency_unittests\": \"a7c82a7fb406d9c7e36316a7a50f04ade1c10640\", \"libjingle_xmpp_unittests\": \"94126521625d0306c22cce7bbd57b951d5deae03\", \"liburlpattern_unittests\": \"3a7d2a688dda02af73b8b4c557e28dd233134a89\", \"media_blink_unittests\": \"13c0a1fae3fc1621ea1863f4261423fac87cf430\", \"media_unittests\": \"8405d70ad6eac8c8d86351c8ac8e4122cd279fc5\", \"message_center_unittests\": \"eb227cbc83a20c2951491e39620ab95bbe12955d\", \"midi_unittests\": \"65b73d540a86b39bdaaba43758508e18fd0391e0\", \"mojo_core_unittests\": \"e928eaa81a29675564c857786d6c7751daccfab6\", \"mojo_unittests\": \"fdfa976c7ba35aead74e3cc06f5f24aa3b55445d\", \"nacl_loader_unittests\": \"b64ac880a05c128624c753713f1311f0eb66e326\", \"native_theme_unittests\": \"051fb902d2712775eca0976d082532787750d1f4\", \"net_unittests\": \"dfc1f10d8135cbcc5729ca55d4e431077e5c4967\", \"notification_helper_unittests\": \"55de5df9f09bb96c87b1060e62e6660e26000122\", \"pdf_unittests\": \"a1faf7f62743c811750dde8809be0f8e2f8f122d\", \"perfetto_unittests\": \"9e5062c7d013a69d08b7abf451d7637f46e8cb16\", \"ppapi_unittests\": \"6c4dcaf52f06a6d7a8236bd675a51c923aae12c2\", \"printing_unittests\": \"5976d0eba8b007c97343fc66531e78120d622c3b\", \"remoting_unittests\": \"f1c96f5422e1b6cc4c18017bd74b9713028df5dc\", \"sbox_integration_tests\": \"afa99d8ee65af974a3651140b7d9d85574ea1dd1\", \"sbox_unittests\": \"7bb634e178f9c82287e7d2ed0fbb3dd0d49588b4\", \"sbox_validation_tests\": \"6ce7998f981273ce69a1923a38a238995599221f\", \"service_manager_unittests\": \"ddcb14adace176f34ae0016762bd6b0ded81a6fe\", \"services_unittests\": \"5877f4d15137b546398966e0a478665466547f4e\", \"setup_unittests\": \"8466e72fbfaefe8a9c6071295d9d9cafb325035f\", \"shell_dialogs_unittests\": \"4df710645b46fb9cc675650aec32c48f660d5a42\", \"skia_unittests\": \"3aabaa8edeef1a045c1130204920258630ec08cb\", \"snapshot_unittests\": \"3d8bd3233942112b7fe48639061d098a2d2d3a32\", \"sql_unittests\": \"fb3d57b08874c288cb3efb35f86b2a7a2b7eb58a\", \"storage_unittests\": \"d7eaad7898c21956b2b49189e065e117296072f2\", \"sync_integration_tests\": \"62883eed411a5dce91652cff8329cbfb23d1153e\", \"traffic_annotation_auditor_unittests\": \"248645751b2e7af4a0973088af0b8730a0cc5b01\", \"ui_base_unittests\": \"addd956fbc64aa902c59976b8693a770468d18e2\", \"ui_touch_selection_unittests\": \"e265aa6b529b8ea22e3f0fbeafb2ea662003d638\", \"unit_tests\": \"1eef6a841f9919e67124dc4753eb09fc3affd665\", \"updater_tests\": \"8e903670e096480795cba409a4b1d0211293db40\", \"url_unittests\": \"73700e4f977f67ceb7020ed30ead644a3910a098\", \"views_unittests\": \"32dd161f4e69164b7fe6037b4e1cb724d2ee70a1\", \"viz_unittests\": \"25380593874aac5c17ec83111ea9953d40a66813\", \"vr_common_unittests\": \"a528b93145ca4d299a1b5e66378fb7151c08e52d\", \"vr_pixeltests\": \"afaf28f46b70239dcdaa3d8c6bbf8a0035655073\", \"weblayer_browsertests\": \"58b3a937c966701a71d15dfb1e4c44c1c88f63ca\", \"weblayer_unittests\": \"cb723dcc8718263c799e78d02655c53f504d48d5\", \"wm_unittests\": \"947ee3424a63e5d296c58953022c276f24d4d86f\", \"wtf_unittests\": \"5a6f5747d45ccbbb122872d392a2a15baf2c74be\", \"zlib_unittests\": \"bfec9a307cab32979836f112a0cd2b23b6b4f65e\", \"zucchini_unittests\": \"8c5faca7c91fb9b999b82f53b7855f94820abd32\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-6-xj00\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2\", \"2.7\", \"2.7.13\"], \"server_version\": [\"5776-cb9e157\"], \"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": "1616062420194427",
"status": "COMPLETED",
"status_changed_ts": "1616069323879117",
"tags": [
"build_address:luci.chromium.ci/win-asan/17850",
"builder:win-asan",
"buildset:commit/git/51bac23ba8e802f9cabefe489936aa316c98316f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/51bac23ba8e802f9cabefe489936aa316c98316f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017859803869117664",
"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/8852429741885043888/+/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:525da0d7e40f5010",
"user_agent:luci-scheduler"
],
"updated_ts": "1616069323883816",
"url": "https://ci.chromium.org/b/8852429741885043888",
"utcnow_ts": "1616069324820008"
}
}
[12:08:44.827] Invocation finished in 1h59m46.522411003s with status SUCCEEDED