[14:02:31.314] New invocation is queued and will start shortly
[14:02:32.525] Starting the invocation (attempt 1)
[14:02:32.574] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:02:32.574] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:02:32.574] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022556152435517936",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"05d1c087fb06f42d200fdc112910676d17e6336b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022556152435517936",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/05d1c087fb06f42d200fdc112910676d17e6336b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/05d1c087fb06f42d200fdc112910676d17e6336b",
"gitiles_ref:refs/heads/master"
]
}
[14:02:35.444] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611583352848619",
"id": "8857126090162220096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"05d1c087fb06f42d200fdc112910676d17e6336b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611583354494358",
"tags": [
"build_address:luci.chromium.ci/win-asan/17006",
"builder:win-asan",
"buildset:commit/git/05d1c087fb06f42d200fdc112910676d17e6336b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/05d1c087fb06f42d200fdc112910676d17e6336b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022556152435517936",
"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/8857126090162220096/+/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": "1611583354494463",
"url": "https://ci.chromium.org/b/8857126090162220096",
"utcnow_ts": "1611583354933187"
}
}
[14:02:35.444] Task URL: https://ci.chromium.org/b/8857126090162220096
[14:02:35.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:2:0) after 1m0s
[14:03:35.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:2:0)
[14:03:35.484] Timer tick, asking Buildbucket for the build status
[14:03:35.742] Build 8857126090162220096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:03:35.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:3:0) after 1m0s
[14:04:35.764] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:3:0)
[14:04:35.764] Timer tick, asking Buildbucket for the build status
[14:04:35.852] Build 8857126090162220096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:04:35.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:4:0) after 1m0s
[14:05:35.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:4:0)
[14:05:35.874] Timer tick, asking Buildbucket for the build status
[14:05:35.953] Build 8857126090162220096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:05:35.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:5:0) after 1m0s
[14:06:36.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:5:0)
[14:06:36.021] Timer tick, asking Buildbucket for the build status
[14:06:36.460] Build 8857126090162220096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:06:36.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:6:0) after 1m0s
[14:07:08.575] Received PubSub notification, asking Buildbucket for the build status
[14:07:08.655] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:36.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:6:0)
[14:07:36.489] Timer tick, asking Buildbucket for the build status
[14:07:36.651] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:36.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:8:0) after 1m0s
[14:08:36.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:8:0)
[14:08:36.673] Timer tick, asking Buildbucket for the build status
[14:08:36.888] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:36.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:9:0) after 1m0s
[14:09:36.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:9:0)
[14:09:36.975] Timer tick, asking Buildbucket for the build status
[14:09:37.175] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:37.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:10:0) after 1m0s
[14:10:37.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:10:0)
[14:10:37.227] Timer tick, asking Buildbucket for the build status
[14:10:37.442] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:37.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:11:0) after 1m0s
[14:11:37.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:11:0)
[14:11:37.597] Timer tick, asking Buildbucket for the build status
[14:11:37.661] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:37.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:12:0) after 1m0s
[14:12:37.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:12:0)
[14:12:37.779] Timer tick, asking Buildbucket for the build status
[14:12:37.987] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:37.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:13:0) after 1m0s
[14:13:38.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:13:0)
[14:13:38.054] Timer tick, asking Buildbucket for the build status
[14:13:38.117] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:38.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:14:0) after 1m0s
[14:14:38.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:14:0)
[14:14:38.200] Timer tick, asking Buildbucket for the build status
[14:14:38.814] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:38.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:15:0) after 1m0s
[14:15:38.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:15:0)
[14:15:38.897] Timer tick, asking Buildbucket for the build status
[14:15:38.973] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:38.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:16:0) after 1m0s
[14:16:39.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:16:0)
[14:16:39.073] Timer tick, asking Buildbucket for the build status
[14:16:39.137] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:39.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:17:0) after 1m0s
[14:17:39.181] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:17:0)
[14:17:39.181] Timer tick, asking Buildbucket for the build status
[14:17:39.294] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:39.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:18:0) after 1m0s
[14:18:39.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:18:0)
[14:18:39.329] Timer tick, asking Buildbucket for the build status
[14:18:39.617] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:39.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:19:0) after 1m0s
[14:19:39.651] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:19:0)
[14:19:39.651] Timer tick, asking Buildbucket for the build status
[14:19:39.744] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:39.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:20:0) after 1m0s
[14:20:39.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:20:0)
[14:20:39.771] Timer tick, asking Buildbucket for the build status
[14:20:39.900] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:39.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:21:0) after 1m0s
[14:21:39.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:21:0)
[14:21:39.945] Timer tick, asking Buildbucket for the build status
[14:21:40.153] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:40.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:22:0) after 1m0s
[14:22:40.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:22:0)
[14:22:40.197] Timer tick, asking Buildbucket for the build status
[14:22:40.323] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:40.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:23:0) after 1m0s
[14:23:40.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:23:0)
[14:23:40.365] Timer tick, asking Buildbucket for the build status
[14:23:40.434] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:40.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:24:0) after 1m0s
[14:24:40.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:24:0)
[14:24:40.608] Timer tick, asking Buildbucket for the build status
[14:24:40.684] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:40.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:25:0) after 1m0s
[14:25:40.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:25:0)
[14:25:40.809] Timer tick, asking Buildbucket for the build status
[14:25:40.924] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:40.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:26:0) after 1m0s
[14:26:40.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:26:0)
[14:26:40.966] Timer tick, asking Buildbucket for the build status
[14:26:41.033] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:41.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:27:0) after 1m0s
[14:27:41.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:27:0)
[14:27:41.131] Timer tick, asking Buildbucket for the build status
[14:27:41.313] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:41.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:28:0) after 1m0s
[14:28:41.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:28:0)
[14:28:41.368] Timer tick, asking Buildbucket for the build status
[14:28:41.424] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:41.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:29:0) after 1m0s
[14:29:41.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:29:0)
[14:29:41.535] Timer tick, asking Buildbucket for the build status
[14:29:41.637] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:41.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:30:0) after 1m0s
[14:30:41.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:30:0)
[14:30:41.712] Timer tick, asking Buildbucket for the build status
[14:30:41.767] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:41.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:31:0) after 1m0s
[14:31:41.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:31:0)
[14:31:41.813] Timer tick, asking Buildbucket for the build status
[14:31:41.882] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:41.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:32:0) after 1m0s
[14:32:41.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:32:0)
[14:32:41.932] Timer tick, asking Buildbucket for the build status
[14:32:42.068] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:42.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:33:0) after 1m0s
[14:33:42.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:33:0)
[14:33:42.142] Timer tick, asking Buildbucket for the build status
[14:33:42.222] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:42.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:34:0) after 1m0s
[14:34:42.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:34:0)
[14:34:42.461] Timer tick, asking Buildbucket for the build status
[14:34:42.544] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:42.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:35:0) after 1m0s
[14:35:42.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:35:0)
[14:35:42.663] Timer tick, asking Buildbucket for the build status
[14:35:42.740] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:42.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:36:0) after 1m0s
[14:36:42.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:36:0)
[14:36:42.855] Timer tick, asking Buildbucket for the build status
[14:36:42.932] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:42.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:37:0) after 1m0s
[14:37:42.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:37:0)
[14:37:42.933] Timer tick, asking Buildbucket for the build status
[14:37:43.386] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:43.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:38:0) after 1m0s
[14:38:43.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:38:0)
[14:38:43.407] Timer tick, asking Buildbucket for the build status
[14:38:43.476] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:43.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:39:0) after 1m0s
[14:39:43.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:39:0)
[14:39:43.474] Timer tick, asking Buildbucket for the build status
[14:39:43.540] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:43.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:40:0) after 1m0s
[14:40:43.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:40:0)
[14:40:43.618] Timer tick, asking Buildbucket for the build status
[14:40:43.777] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:43.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:41:0) after 1m0s
[14:41:43.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:41:0)
[14:41:43.816] Timer tick, asking Buildbucket for the build status
[14:41:43.874] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:43.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:42:0) after 1m0s
[14:42:43.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:42:0)
[14:42:43.909] Timer tick, asking Buildbucket for the build status
[14:42:44.025] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:44.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:43:0) after 1m0s
[14:43:44.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:43:0)
[14:43:44.073] Timer tick, asking Buildbucket for the build status
[14:43:44.311] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:44.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:44:0) after 1m0s
[14:44:44.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:44:0)
[14:44:44.355] Timer tick, asking Buildbucket for the build status
[14:44:45.044] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:45.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:45:0) after 1m0s
[14:45:45.163] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:45:0)
[14:45:45.163] Timer tick, asking Buildbucket for the build status
[14:45:45.255] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:45.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:46:0) after 1m0s
[14:46:45.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:46:0)
[14:46:45.246] Timer tick, asking Buildbucket for the build status
[14:46:46.315] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:46.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:47:0) after 1m0s
[14:47:46.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:47:0)
[14:47:46.366] Timer tick, asking Buildbucket for the build status
[14:47:46.862] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:46.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:48:0) after 1m0s
[14:48:47.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:48:0)
[14:48:47.038] Timer tick, asking Buildbucket for the build status
[14:48:47.112] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:47.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:49:0) after 1m0s
[14:49:47.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:49:0)
[14:49:47.157] Timer tick, asking Buildbucket for the build status
[14:49:47.462] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:47.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:50:0) after 1m0s
[14:50:47.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:50:0)
[14:50:47.507] Timer tick, asking Buildbucket for the build status
[14:50:47.580] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:47.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:51:0) after 1m0s
[14:51:47.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:51:0)
[14:51:47.657] Timer tick, asking Buildbucket for the build status
[14:51:47.729] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:47.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:52:0) after 1m0s
[14:52:47.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:52:0)
[14:52:47.797] Timer tick, asking Buildbucket for the build status
[14:52:48.153] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:48.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:53:0) after 1m0s
[14:53:48.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:53:0)
[14:53:48.198] Timer tick, asking Buildbucket for the build status
[14:53:48.318] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:48.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:54:0) after 1m0s
[14:54:48.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:54:0)
[14:54:48.350] Timer tick, asking Buildbucket for the build status
[14:54:48.406] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:48.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:55:0) after 1m0s
[14:55:48.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:55:0)
[14:55:48.408] Timer tick, asking Buildbucket for the build status
[14:55:48.470] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:48.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:56:0) after 1m0s
[14:56:48.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:56:0)
[14:56:48.521] Timer tick, asking Buildbucket for the build status
[14:56:48.582] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:48.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:57:0) after 1m0s
[14:57:48.603] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:57:0)
[14:57:48.603] Timer tick, asking Buildbucket for the build status
[14:57:48.712] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:48.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:58:0) after 1m0s
[14:58:48.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:58:0)
[14:58:48.766] Timer tick, asking Buildbucket for the build status
[14:58:48.922] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:48.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:59:0) after 1m0s
[14:59:48.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:59:0)
[14:59:48.927] Timer tick, asking Buildbucket for the build status
[14:59:49.361] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:49.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:60:0) after 1m0s
[15:00:49.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:60:0)
[15:00:49.482] Timer tick, asking Buildbucket for the build status
[15:00:49.592] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:49.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:61:0) after 1m0s
[15:01:49.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:61:0)
[15:01:49.648] Timer tick, asking Buildbucket for the build status
[15:01:49.737] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:49.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:62:0) after 1m0s
[15:02:49.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:62:0)
[15:02:49.843] Timer tick, asking Buildbucket for the build status
[15:02:50.064] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:50.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:63:0) after 1m0s
[15:03:50.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:63:0)
[15:03:50.094] Timer tick, asking Buildbucket for the build status
[15:03:50.236] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:50.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:64:0) after 1m0s
[15:04:50.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:64:0)
[15:04:50.304] Timer tick, asking Buildbucket for the build status
[15:04:50.482] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:50.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:65:0) after 1m0s
[15:05:50.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:65:0)
[15:05:50.512] Timer tick, asking Buildbucket for the build status
[15:05:50.651] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:50.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:66:0) after 1m0s
[15:06:50.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:66:0)
[15:06:50.711] Timer tick, asking Buildbucket for the build status
[15:06:50.868] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:50.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:67:0) after 1m0s
[15:07:50.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:67:0)
[15:07:50.965] Timer tick, asking Buildbucket for the build status
[15:07:51.153] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:51.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:68:0) after 1m0s
[15:08:51.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:68:0)
[15:08:51.208] Timer tick, asking Buildbucket for the build status
[15:08:51.325] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:51.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:69:0) after 1m0s
[15:09:51.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:69:0)
[15:09:51.379] Timer tick, asking Buildbucket for the build status
[15:09:52.124] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:52.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:70:0) after 1m0s
[15:10:52.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:70:0)
[15:10:52.296] Timer tick, asking Buildbucket for the build status
[15:10:52.634] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:52.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:71:0) after 1m0s
[15:11:52.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:71:0)
[15:11:52.731] Timer tick, asking Buildbucket for the build status
[15:11:52.872] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:52.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:72:0) after 1m0s
[15:12:52.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:72:0)
[15:12:52.940] Timer tick, asking Buildbucket for the build status
[15:12:53.057] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:53.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:73:0) after 1m0s
[15:13:53.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:73:0)
[15:13:53.088] Timer tick, asking Buildbucket for the build status
[15:13:53.662] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:53.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:74:0) after 1m0s
[15:14:53.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:74:0)
[15:14:53.694] Timer tick, asking Buildbucket for the build status
[15:14:54.234] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:54.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:75:0) after 1m0s
[15:15:54.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:75:0)
[15:15:54.238] Timer tick, asking Buildbucket for the build status
[15:15:54.318] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:54.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:76:0) after 1m0s
[15:16:54.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:76:0)
[15:16:54.346] Timer tick, asking Buildbucket for the build status
[15:16:54.576] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:54.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:77:0) after 1m0s
[15:17:54.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:77:0)
[15:17:54.633] Timer tick, asking Buildbucket for the build status
[15:17:55.085] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:55.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:78:0) after 1m0s
[15:18:55.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:78:0)
[15:18:55.117] Timer tick, asking Buildbucket for the build status
[15:18:55.399] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:55.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:79:0) after 1m0s
[15:19:55.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:79:0)
[15:19:55.447] Timer tick, asking Buildbucket for the build status
[15:19:55.539] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:55.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:80:0) after 1m0s
[15:20:55.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:80:0)
[15:20:55.621] Timer tick, asking Buildbucket for the build status
[15:20:55.916] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:55.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:81:0) after 1m0s
[15:21:55.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:81:0)
[15:21:55.896] Timer tick, asking Buildbucket for the build status
[15:21:56.402] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:56.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:82:0) after 1m0s
[15:22:56.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:82:0)
[15:22:56.453] Timer tick, asking Buildbucket for the build status
[15:22:56.570] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:56.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:83:0) after 1m0s
[15:23:56.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:83:0)
[15:23:56.595] Timer tick, asking Buildbucket for the build status
[15:23:56.676] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:56.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:84:0) after 1m0s
[15:24:56.701] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:84:0)
[15:24:56.701] Timer tick, asking Buildbucket for the build status
[15:24:56.856] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:56.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:85:0) after 1m0s
[15:25:56.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:85:0)
[15:25:56.983] Timer tick, asking Buildbucket for the build status
[15:25:57.148] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:57.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:86:0) after 1m0s
[15:26:57.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:86:0)
[15:26:57.194] Timer tick, asking Buildbucket for the build status
[15:26:57.296] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:57.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:87:0) after 1m0s
[15:27:57.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:87:0)
[15:27:57.360] Timer tick, asking Buildbucket for the build status
[15:27:57.470] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:57.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:88:0) after 1m0s
[15:28:58.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:88:0)
[15:28:58.239] Timer tick, asking Buildbucket for the build status
[15:28:58.374] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:58.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:89:0) after 1m0s
[15:29:58.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:89:0)
[15:29:58.476] Timer tick, asking Buildbucket for the build status
[15:29:59.317] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:59.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:90:0) after 1m0s
[15:30:59.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:90:0)
[15:30:59.373] Timer tick, asking Buildbucket for the build status
[15:30:59.472] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:59.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:91:0) after 1m0s
[15:31:59.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:91:0)
[15:31:59.492] Timer tick, asking Buildbucket for the build status
[15:31:59.724] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:59.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:92:0) after 1m0s
[15:32:59.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:92:0)
[15:32:59.830] Timer tick, asking Buildbucket for the build status
[15:33:00.344] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:00.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:93:0) after 1m0s
[15:34:00.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:93:0)
[15:34:00.427] Timer tick, asking Buildbucket for the build status
[15:34:00.531] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:00.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:94:0) after 1m0s
[15:35:00.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:94:0)
[15:35:00.688] Timer tick, asking Buildbucket for the build status
[15:35:01.114] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:01.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:95:0) after 1m0s
[15:36:01.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:95:0)
[15:36:01.298] Timer tick, asking Buildbucket for the build status
[15:36:01.447] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:01.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:96:0) after 1m0s
[15:37:01.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:96:0)
[15:37:01.617] Timer tick, asking Buildbucket for the build status
[15:37:02.059] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:02.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:97:0) after 1m0s
[15:38:02.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:97:0)
[15:38:02.150] Timer tick, asking Buildbucket for the build status
[15:38:03.507] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:03.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:98:0) after 1m0s
[15:39:03.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:98:0)
[15:39:03.601] Timer tick, asking Buildbucket for the build status
[15:39:04.532] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:04.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:99:0) after 1m0s
[15:40:04.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:99:0)
[15:40:04.617] Timer tick, asking Buildbucket for the build status
[15:40:04.843] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:04.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:100:0) after 1m0s
[15:41:04.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:100:0)
[15:41:04.919] Timer tick, asking Buildbucket for the build status
[15:41:05.135] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:05.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:101:0) after 1m0s
[15:42:05.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:101:0)
[15:42:05.174] Timer tick, asking Buildbucket for the build status
[15:42:05.254] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:05.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:102:0) after 1m0s
[15:43:05.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:102:0)
[15:43:05.335] Timer tick, asking Buildbucket for the build status
[15:43:05.496] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:05.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:103:0) after 1m0s
[15:44:05.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:103:0)
[15:44:05.554] Timer tick, asking Buildbucket for the build status
[15:44:05.636] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:05.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:104:0) after 1m0s
[15:45:05.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:104:0)
[15:45:05.717] Timer tick, asking Buildbucket for the build status
[15:45:05.818] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:05.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:105:0) after 1m0s
[15:46:05.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:105:0)
[15:46:05.850] Timer tick, asking Buildbucket for the build status
[15:46:05.922] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:05.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:106:0) after 1m0s
[15:47:06.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:106:0)
[15:47:06.079] Timer tick, asking Buildbucket for the build status
[15:47:06.165] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:06.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:107:0) after 1m0s
[15:48:06.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:107:0)
[15:48:06.211] Timer tick, asking Buildbucket for the build status
[15:48:06.610] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:06.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:108:0) after 1m0s
[15:49:06.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:108:0)
[15:49:06.737] Timer tick, asking Buildbucket for the build status
[15:49:06.874] Build 8857126090162220096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:06.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022556152435517936:109:0) after 1m0s
[15:50:01.619] Received PubSub notification, asking Buildbucket for the build status
[15:50:02.665] Build 8857126090162220096: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:50:02.665] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611589799432739",
"created_by": "project:chromium",
"created_ts": "1611583352848619",
"failure_reason": "BUILD_FAILURE",
"id": "8857126090162220096",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"05d1c087fb06f42d200fdc112910676d17e6336b\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**content_unittests** failed because of:\\n\\n- NativeIOManagerTest.DeleteOriginData_WipesDataForActiveHost\"}, \"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\": \"05d1c087fb06f42d200fdc112910676d17e6336b\", \"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-9-snau\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17006, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"cd0b933d387a48849f056c7a9ab9e12f328c739f\", \"got_dawn_revision\": \"4d66fb2d61d69e6487ba0f1ca89cdb083fe80fac\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"05d1c087fb06f42d200fdc112910676d17e6336b\", \"got_revision_cp\": \"refs/heads/master@{#846697}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"2905ae7414eaa966a504c5fc95f3d64da3d8d65c\", \"got_v8_revision_cp\": \"refs/heads/9.0.58@{#1}\", \"got_webrtc_revision\": \"90776cb0ef72d437983c8d29e5a7f2ac264c107d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33064}\", \"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\": \"05d1c087fb06f42d200fdc112910676d17e6336b\", \"swarm_hashes_refs/heads/master(at){#846697}_without_patch\": {\"absl_hardening_tests\": \"1f2a710a685ac73b35047b1818ef70ed124bf33c\", \"accessibility_unittests\": \"edbbd02cb550c376a0906febc312f1b936c2f85e\", \"angle_unittests\": \"70dcc20e27186de65d86010344873e6beb1e4cc1\", \"app_shell_unittests\": \"b028defd5c9dace3dcdaa9fdf15b415fa091858c\", \"aura_unittests\": \"e86f0c0a39bd5e7bd98541329fb4d0f754ed7f99\", \"base_unittests\": \"03c9f8284415296924fc16c069af2c063b916a97\", \"base_util_unittests\": \"9bf07a1a7a634ed272988db3c91c890b2ec5e7a8\", \"blink_common_unittests\": \"71e8adbeb55e160ad1766b088728da92c5118806\", \"blink_fuzzer_unittests\": \"19222f996848c2b00564b347d0decd86f1f75b20\", \"blink_heap_unittests\": \"0394532f5015b89942de6bc0cbdfc98444ee4acb\", \"blink_platform_unittests\": \"f080a4c2c4cdf786238ca866229c5067b18834fa\", \"blink_unittests\": \"d30cb2e9222440a535a796d1c59524d4039e612f\", \"boringssl_crypto_tests\": \"3d92088223316e0de4cd3a7dc50aaf695ac5258f\", \"boringssl_ssl_tests\": \"fce259e3006c34c2b95622e11032abf419efc721\", \"browser_switcher_bho_unittests\": \"5c3794f88ec637239bd1bc0ad82f29c5fb18f1ce\", \"browser_tests\": \"10e5760ddba799c45d6aedae8bc686d06bcd7da9\", \"capture_unittests\": \"d1ed8613f923f8ff44aa984546c38fb19dd25a5e\", \"cast_unittests\": \"2b1a2ae08e5d6de299bc8763c9f6db70928ad82a\", \"cc_unittests\": \"a44a472c58655c2eb97423c736fda2539b6487bc\", \"chrome_app_unittests\": \"b0be66390e4937b3a1ab124f2c4aba57cd6ed05f\", \"chrome_cleaner_unittests\": \"9bdfbbfafc76a03e18dd41d9992333722e887f5b\", \"chrome_elf_unittests\": \"5f6d667d9bf10587c804157b3d5a991ed43587f3\", \"chromedriver_unittests\": \"89f11b381a180de5bcab5cf5b6a5c2fe5281f429\", \"components_browsertests\": \"8c1e3433d399e502738990789555359f268ecc42\", \"components_unittests\": \"89b612ccefbc26806775d01f07e309f3b6bde83b\", \"compositor_unittests\": \"e63374f0d28cb3a057eb7595cd333949faf86326\", \"content_browsertests\": \"5aa69cb08bd86a7f581d972fe27ef67344ebad89\", \"content_unittests\": \"1336673e1afbe3e5ffd8ddafebc8d3ff314e3c46\", \"courgette_unittests\": \"a2d2e2239b7d22e3b0ba41f472e09f5b23561dde\", \"crashpad_tests\": \"dc7f13cd1fd56d2cd76af1088da017ceafc7ba51\", \"cronet_tests\": \"bbbdbdc41649fb149c21f6cafc99dc26e15fcf83\", \"cronet_unittests\": \"f69125f805f1f96dce7ef10db9165b22700f97f8\", \"crypto_unittests\": \"0dc89e8571a84296c9811a6bd97927b6cf192e8f\", \"delayloads_unittests\": \"f024561a218445e1246ad88c7ce2228f34396a8a\", \"device_unittests\": \"dfeb115ad17eba87b4d3bca89993748c8bc22073\", \"display_unittests\": \"65b66faa77d8ff264da4bba70467f46b7698191d\", \"elevation_service_unittests\": \"67bd92387ba791048f9db0baddbb157f7fdb2eed\", \"events_unittests\": \"88ef1275a7e5ce4932149e7158dde53262b4d2bc\", \"extensions_browsertests\": \"eca5d02d600777db2d5b849ea515195413d21638\", \"extensions_unittests\": \"e9885f11827dfd161c0ef8b383ea9d42ed42109e\", \"filesystem_service_unittests\": \"0b7d26d7af03c98f453535ac4a1a3a062139f5e4\", \"gcm_unit_tests\": \"95e19cac55ac76da207bf208f575a8d3bd14fc2c\", \"gcp_unittests\": \"671c21bbff65afd12f36c49b4e3d8d8cfd4aac2a\", \"gfx_unittests\": \"116c1dd4cbb76ee521297e9643ad9416b40b03ac\", \"gin_unittests\": \"6c2ff9e19c775f100caeeee6e574ba340ec53a91\", \"google_apis_unittests\": \"05a72aa9906a7eb7ec24f970c26a27afc8bdc31a\", \"gpu_unittests\": \"84747f1c460a0e89262b2af4ae9e1f37a0591844\", \"gwp_asan_unittests\": \"30f8313b9ab2f78b9f4cdd226a780db923ced183\", \"headless_browsertests\": \"0da36cd470ec6f2c642041df03b1425c83b745ff\", \"headless_unittests\": \"9a1270d04fe9b9f238b1f35abfae704a5e667ff9\", \"install_static_unittests\": \"d889c80a64f8464f3a6c9580ee46d5417f7e3d22\", \"installer_util_unittests\": \"0a759f76ed60ff83a6db100227d4bfb20917de29\", \"interactive_ui_tests\": \"db98f3acb3977538409e2be05b6e323c7bf6b853\", \"ipc_tests\": \"bab8dd443cf48c9a6dc4fa37c2e51821d69904a4\", \"jingle_unittests\": \"37ec6a3b76d0b8f110413fe9a4757cf942150b43\", \"latency_unittests\": \"108ef02b07525bcdf14dc33254622cdab2ae66bd\", \"libjingle_xmpp_unittests\": \"1367ca97893cee47c5d71299dd2687e252a9c502\", \"liburlpattern_unittests\": \"9d811e5b616542cbe0210fdff76c31068fdfe2a2\", \"media_blink_unittests\": \"8c98781cf45ef6a9202b0da268d3c7a061795b30\", \"media_unittests\": \"5e1ea05c15cadd27225a2ebe027f2f5410fe076f\", \"message_center_unittests\": \"c1a7d4431d001ddeb776bfc33b238fdd6e852163\", \"midi_unittests\": \"16b44043d22b2612c9acf759bd698aec336c87a5\", \"mojo_core_unittests\": \"df8edf8260ced679732c2ee056270feaeaab2edf\", \"mojo_unittests\": \"e3da1b33bd9ffb9a30b2c44d57595e36d61bae45\", \"nacl_loader_unittests\": \"49f555f2291e4d37c099b71a09eddf51794ae9b6\", \"native_theme_unittests\": \"1ee90037ef7eb6ff1cf452a6d5353d1181ce049f\", \"net_unittests\": \"328cdbfdc9aa9a9f766562745b5415921536e2fb\", \"notification_helper_unittests\": \"0027ee524052f12672d493bb94f69b8fab829a1c\", \"pdf_unittests\": \"9b8443fd6d06ef814028f4470d165792ccd0f8cc\", \"perfetto_unittests\": \"e26f45f7cfb9f7c86a6f3927dfdb2c22e707497d\", \"ppapi_unittests\": \"fab0e00a3d3d9b563b8f234ee3df6fd7e0d5fdfe\", \"printing_unittests\": \"e10f19a7b9e69c2cd198c32a1dca37d38caf7c52\", \"remoting_unittests\": \"aca600f2e9653688304b3e2571e3c3382421a147\", \"sbox_integration_tests\": \"362400fca18f6eb3dc8b0aaaa7acae27bb72f8fa\", \"sbox_unittests\": \"346139aa123aabada021834405b492381ac48b30\", \"sbox_validation_tests\": \"2c47a7279953de0af7cbb7eb52f3771ae8b9b70f\", \"service_manager_unittests\": \"887903bcd21a00eb52bf2de9978abcd2ed0e880f\", \"services_unittests\": \"0c87005cf196c563592c96206470da14f5151cb9\", \"setup_unittests\": \"2bd8237fe04f398f3b0a3344e6f240b5c2c7e4a6\", \"shell_dialogs_unittests\": \"0274ef5d136998ab3dfe70e8fbfbd3c3d6888254\", \"skia_unittests\": \"01c24f5e5dbf0d664362b58d31d0116e9671b255\", \"snapshot_unittests\": \"6cf8ee333644d205c7677c07717f36928fcab450\", \"sql_unittests\": \"4bdb684ccd1bd6fa2acaa4e9c4df7c254ae2d8e5\", \"storage_unittests\": \"75e7e621604dcd0acf603b14c1b01b813e033bd1\", \"sync_integration_tests\": \"f97db38080945063f55d46af14e421f178ec8f83\", \"traffic_annotation_auditor_unittests\": \"ee3adcd1cecef8a6e0f8d4c600d469845b811ca6\", \"ui_base_unittests\": \"eccf1b71539a482ad3580349e32d744b738392ae\", \"ui_touch_selection_unittests\": \"b42df09f64ea8e9febc7d349456b0372127b1eef\", \"unit_tests\": \"ee6ace370fc2015ffd9a98af9f161554588de631\", \"updater_tests\": \"1dab085c9d97b14cf0d25e3a5abd720838337a0b\", \"url_unittests\": \"8fe0fc744017d04ed1065535da8492d23a879d66\", \"views_unittests\": \"ef005ee6f93572733cdf7fc99d182109aeffd79a\", \"viz_unittests\": \"ae4d29b3cf2ab2a3081d92ed6c96c5905be6715a\", \"vr_common_unittests\": \"5660c38a6189f5c6931b159813d51c5ebcf09db3\", \"vr_pixeltests\": \"4eaf26026a5e386ab7ba103887011762c4640ca8\", \"weblayer_browsertests\": \"df3d11faa137ee1c4a943b6a77b7b6149ea44eaa\", \"weblayer_unittests\": \"8cacc116e5d0d0a760b482648367def1d24ff7ca\", \"wm_unittests\": \"22ff4e5c001a1f98fc05ac76bfec54d91ca4bf78\", \"wtf_unittests\": \"104ebeac8eaa006db7e08a490d683641173fbb9d\", \"zlib_unittests\": \"a00dc59ab6d5709883183d97c12c7376b66062a8\", \"zucchini_unittests\": \"38d2844117cfb1def6325f047d5b35fd6b3096ee\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"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-9-snau\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5672-4713de1\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**content_unittests** failed because of:\\n\\n- NativeIOManagerTest.DeleteOriginData_WipesDataForActiveHost\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611583627808568",
"status": "COMPLETED",
"status_changed_ts": "1611583628236151",
"tags": [
"build_address:luci.chromium.ci/win-asan/17006",
"builder:win-asan",
"buildset:commit/git/05d1c087fb06f42d200fdc112910676d17e6336b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/05d1c087fb06f42d200fdc112910676d17e6336b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022556152435517936",
"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/8857126090162220096/+/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:5152ac014823b210",
"user_agent:luci-scheduler"
],
"updated_ts": "1611589800370793",
"url": "https://ci.chromium.org/b/8857126090162220096",
"utcnow_ts": "1611589802637206"
}
}
[15:50:02.665] Invocation finished in 1h47m31.368350721s with status FAILED