[23:11:04.755] New invocation is queued and will start shortly
[23:11:06.255] Starting the invocation (attempt 1)
[23:11:06.338] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:11:06.340] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:11:06.340] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030131785372424704",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f281490398ff5612c5c45dc5e07f3464f855ed39\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030131785372424704",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f281490398ff5612c5c45dc5e07f3464f855ed39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f281490398ff5612c5c45dc5e07f3464f855ed39",
"gitiles_ref:refs/heads/master"
]
}
[23:11:07.026] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604358666472468",
"id": "8864701722904031824",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f281490398ff5612c5c45dc5e07f3464f855ed39\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604358666895830",
"tags": [
"build_address:luci.chromium.ci/win-asan/15422",
"builder:win-asan",
"buildset:commit/git/f281490398ff5612c5c45dc5e07f3464f855ed39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f281490398ff5612c5c45dc5e07f3464f855ed39",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030131785372424704",
"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/8864701722904031824/+/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": "1604358666895959",
"url": "https://ci.chromium.org/b/8864701722904031824",
"utcnow_ts": "1604358667018216"
}
}
[23:11:07.026] Task URL: https://ci.chromium.org/b/8864701722904031824
[23:11:07.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:2:0) after 1m0s
[23:12:07.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:2:0)
[23:12:07.095] Timer tick, asking Buildbucket for the build status
[23:12:07.205] Build 8864701722904031824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:07.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:3:0) after 1m0s
[23:13:07.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:3:0)
[23:13:07.351] Timer tick, asking Buildbucket for the build status
[23:13:07.511] Build 8864701722904031824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:07.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:4:0) after 1m0s
[23:14:07.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:4:0)
[23:14:07.553] Timer tick, asking Buildbucket for the build status
[23:14:07.678] Build 8864701722904031824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:07.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:5:0) after 1m0s
[23:14:20.355] Received PubSub notification, asking Buildbucket for the build status
[23:14:20.425] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:07.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:5:0)
[23:15:07.811] Timer tick, asking Buildbucket for the build status
[23:15:07.883] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:07.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:7:0) after 1m0s
[23:16:07.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:7:0)
[23:16:07.991] Timer tick, asking Buildbucket for the build status
[23:16:09.663] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:09.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:8:0) after 1m0s
[23:17:09.803] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:8:0)
[23:17:09.804] Timer tick, asking Buildbucket for the build status
[23:17:09.866] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:09.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:9:0) after 1m0s
[23:18:09.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:9:0)
[23:18:09.956] Timer tick, asking Buildbucket for the build status
[23:18:10.027] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:10.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:10:0) after 1m0s
[23:19:10.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:10:0)
[23:19:10.117] Timer tick, asking Buildbucket for the build status
[23:19:10.206] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:10.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:11:0) after 1m0s
[23:20:10.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:11:0)
[23:20:10.334] Timer tick, asking Buildbucket for the build status
[23:20:10.430] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:10.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:12:0) after 1m0s
[23:21:10.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:12:0)
[23:21:10.545] Timer tick, asking Buildbucket for the build status
[23:21:10.652] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:10.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:13:0) after 1m0s
[23:22:10.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:13:0)
[23:22:10.724] Timer tick, asking Buildbucket for the build status
[23:22:10.789] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:10.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:14:0) after 1m0s
[23:23:10.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:14:0)
[23:23:10.938] Timer tick, asking Buildbucket for the build status
[23:23:11.711] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:11.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:15:0) after 1m0s
[23:24:11.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:15:0)
[23:24:11.959] Timer tick, asking Buildbucket for the build status
[23:24:12.097] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:12.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:16:0) after 1m0s
[23:25:12.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:16:0)
[23:25:12.175] Timer tick, asking Buildbucket for the build status
[23:25:12.852] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:12.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:17:0) after 1m0s
[23:26:12.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:17:0)
[23:26:12.881] Timer tick, asking Buildbucket for the build status
[23:26:13.266] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:13.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:18:0) after 1m0s
[23:27:13.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:18:0)
[23:27:13.254] Timer tick, asking Buildbucket for the build status
[23:27:13.330] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:13.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:19:0) after 1m0s
[23:28:13.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:19:0)
[23:28:13.357] Timer tick, asking Buildbucket for the build status
[23:28:13.650] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:13.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:20:0) after 1m0s
[23:29:13.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:20:0)
[23:29:13.739] Timer tick, asking Buildbucket for the build status
[23:29:13.902] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:13.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:21:0) after 1m0s
[23:30:13.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:21:0)
[23:30:13.975] Timer tick, asking Buildbucket for the build status
[23:30:14.146] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:14.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:22:0) after 1m0s
[23:31:14.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:22:0)
[23:31:14.200] Timer tick, asking Buildbucket for the build status
[23:31:14.345] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:14.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:23:0) after 1m0s
[23:32:14.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:23:0)
[23:32:14.399] Timer tick, asking Buildbucket for the build status
[23:32:14.486] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:14.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:24:0) after 1m0s
[23:33:14.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:24:0)
[23:33:14.645] Timer tick, asking Buildbucket for the build status
[23:33:14.711] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:14.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:25:0) after 1m0s
[23:34:14.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:25:0)
[23:34:14.781] Timer tick, asking Buildbucket for the build status
[23:34:14.844] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:14.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:26:0) after 1m0s
[23:35:14.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:26:0)
[23:35:14.967] Timer tick, asking Buildbucket for the build status
[23:35:15.285] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:15.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:27:0) after 1m0s
[23:36:15.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:27:0)
[23:36:15.354] Timer tick, asking Buildbucket for the build status
[23:36:16.087] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:16.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:28:0) after 1m0s
[23:37:16.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:28:0)
[23:37:16.172] Timer tick, asking Buildbucket for the build status
[23:37:16.253] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:16.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:29:0) after 1m0s
[23:38:16.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:29:0)
[23:38:16.380] Timer tick, asking Buildbucket for the build status
[23:38:17.103] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:17.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:30:0) after 1m0s
[23:39:17.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:30:0)
[23:39:17.252] Timer tick, asking Buildbucket for the build status
[23:39:17.325] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:17.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:31:0) after 1m0s
[23:40:17.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:31:0)
[23:40:17.390] Timer tick, asking Buildbucket for the build status
[23:40:17.621] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:17.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:32:0) after 1m0s
[23:41:17.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:32:0)
[23:41:17.621] Timer tick, asking Buildbucket for the build status
[23:41:17.732] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:17.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:33:0) after 1m0s
[23:42:17.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:33:0)
[23:42:17.768] Timer tick, asking Buildbucket for the build status
[23:42:17.841] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:17.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:34:0) after 1m0s
[23:43:17.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:34:0)
[23:43:17.824] Timer tick, asking Buildbucket for the build status
[23:43:17.902] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:17.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:35:0) after 1m0s
[23:44:17.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:35:0)
[23:44:17.925] Timer tick, asking Buildbucket for the build status
[23:44:17.988] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:17.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:36:0) after 1m0s
[23:45:18.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:36:0)
[23:45:18.027] Timer tick, asking Buildbucket for the build status
[23:45:18.096] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:18.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:37:0) after 1m0s
[23:46:18.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:37:0)
[23:46:18.167] Timer tick, asking Buildbucket for the build status
[23:46:18.506] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:18.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:38:0) after 1m0s
[23:47:18.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:38:0)
[23:47:18.538] Timer tick, asking Buildbucket for the build status
[23:47:18.678] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:18.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:39:0) after 1m0s
[23:48:18.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:39:0)
[23:48:18.789] Timer tick, asking Buildbucket for the build status
[23:48:18.857] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:18.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:40:0) after 1m0s
[23:49:18.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:40:0)
[23:49:18.928] Timer tick, asking Buildbucket for the build status
[23:49:19.258] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:19.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:41:0) after 1m0s
[23:50:19.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:41:0)
[23:50:19.438] Timer tick, asking Buildbucket for the build status
[23:50:19.737] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:19.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:42:0) after 1m0s
[23:51:19.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:42:0)
[23:51:19.844] Timer tick, asking Buildbucket for the build status
[23:51:20.148] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:20.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:43:0) after 1m0s
[23:52:20.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:43:0)
[23:52:20.238] Timer tick, asking Buildbucket for the build status
[23:52:20.303] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:20.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:44:0) after 1m0s
[23:53:20.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:44:0)
[23:53:20.378] Timer tick, asking Buildbucket for the build status
[23:53:20.450] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:20.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:45:0) after 1m0s
[23:54:20.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:45:0)
[23:54:20.476] Timer tick, asking Buildbucket for the build status
[23:54:20.540] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:20.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:46:0) after 1m0s
[23:55:20.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:46:0)
[23:55:20.626] Timer tick, asking Buildbucket for the build status
[23:55:20.848] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:20.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:47:0) after 1m0s
[23:56:20.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:47:0)
[23:56:20.837] Timer tick, asking Buildbucket for the build status
[23:56:21.302] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:21.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:48:0) after 1m0s
[23:57:21.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:48:0)
[23:57:21.346] Timer tick, asking Buildbucket for the build status
[23:57:21.420] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:21.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:49:0) after 1m0s
[23:58:21.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:49:0)
[23:58:21.567] Timer tick, asking Buildbucket for the build status
[23:58:21.640] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:21.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:50:0) after 1m0s
[23:59:21.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:50:0)
[23:59:21.652] Timer tick, asking Buildbucket for the build status
[23:59:21.717] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:21.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:51:0) after 1m0s
[00:00:21.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:51:0)
[00:00:21.951] Timer tick, asking Buildbucket for the build status
[00:00:22.174] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:22.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:52:0) after 1m0s
[00:01:22.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:52:0)
[00:01:22.247] Timer tick, asking Buildbucket for the build status
[00:01:23.120] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:23.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:53:0) after 1m0s
[00:02:23.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:53:0)
[00:02:23.191] Timer tick, asking Buildbucket for the build status
[00:02:23.263] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:23.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:54:0) after 1m0s
[00:03:23.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:54:0)
[00:03:23.321] Timer tick, asking Buildbucket for the build status
[00:03:23.394] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:23.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:55:0) after 1m0s
[00:04:23.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:55:0)
[00:04:23.478] Timer tick, asking Buildbucket for the build status
[00:04:23.709] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:23.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:56:0) after 1m0s
[00:05:23.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:56:0)
[00:05:23.734] Timer tick, asking Buildbucket for the build status
[00:05:23.804] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:23.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:57:0) after 1m0s
[00:06:23.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:57:0)
[00:06:23.832] Timer tick, asking Buildbucket for the build status
[00:06:23.945] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:23.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:58:0) after 1m0s
[00:07:23.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:58:0)
[00:07:23.967] Timer tick, asking Buildbucket for the build status
[00:07:24.091] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:24.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:59:0) after 1m0s
[00:08:24.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:59:0)
[00:08:24.183] Timer tick, asking Buildbucket for the build status
[00:08:24.333] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:24.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:60:0) after 1m0s
[00:09:24.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:60:0)
[00:09:24.348] Timer tick, asking Buildbucket for the build status
[00:09:24.463] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:24.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:61:0) after 1m0s
[00:10:24.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:61:0)
[00:10:24.525] Timer tick, asking Buildbucket for the build status
[00:10:24.686] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:24.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:62:0) after 1m0s
[00:11:24.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:62:0)
[00:11:24.779] Timer tick, asking Buildbucket for the build status
[00:11:24.892] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:24.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:63:0) after 1m0s
[00:12:24.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:63:0)
[00:12:24.912] Timer tick, asking Buildbucket for the build status
[00:12:24.982] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:24.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:64:0) after 1m0s
[00:13:25.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:64:0)
[00:13:25.109] Timer tick, asking Buildbucket for the build status
[00:13:25.258] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:25.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:65:0) after 1m0s
[00:14:25.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:65:0)
[00:14:25.404] Timer tick, asking Buildbucket for the build status
[00:14:25.673] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:25.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:66:0) after 1m0s
[00:15:25.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:66:0)
[00:15:25.804] Timer tick, asking Buildbucket for the build status
[00:15:26.146] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:26.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:67:0) after 1m0s
[00:16:26.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:67:0)
[00:16:26.200] Timer tick, asking Buildbucket for the build status
[00:16:26.409] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:26.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:68:0) after 1m0s
[00:17:26.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:68:0)
[00:17:26.545] Timer tick, asking Buildbucket for the build status
[00:17:26.945] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:26.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:69:0) after 1m0s
[00:18:27.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:69:0)
[00:18:27.065] Timer tick, asking Buildbucket for the build status
[00:18:27.185] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:27.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:70:0) after 1m0s
[00:19:27.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:70:0)
[00:19:27.186] Timer tick, asking Buildbucket for the build status
[00:19:27.329] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:27.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:71:0) after 1m0s
[00:20:27.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:71:0)
[00:20:27.371] Timer tick, asking Buildbucket for the build status
[00:20:27.520] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:27.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:72:0) after 1m0s
[00:21:27.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:72:0)
[00:21:27.503] Timer tick, asking Buildbucket for the build status
[00:21:28.185] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:28.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:73:0) after 1m0s
[00:22:28.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:73:0)
[00:22:28.188] Timer tick, asking Buildbucket for the build status
[00:22:28.557] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:28.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:74:0) after 1m0s
[00:23:28.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:74:0)
[00:23:28.589] Timer tick, asking Buildbucket for the build status
[00:23:28.758] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:28.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:75:0) after 1m0s
[00:24:28.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:75:0)
[00:24:28.782] Timer tick, asking Buildbucket for the build status
[00:24:28.942] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:28.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:76:0) after 1m0s
[00:25:28.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:76:0)
[00:25:28.954] Timer tick, asking Buildbucket for the build status
[00:25:29.059] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:29.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:77:0) after 1m0s
[00:26:29.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:77:0)
[00:26:29.088] Timer tick, asking Buildbucket for the build status
[00:26:29.233] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:29.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:78:0) after 1m0s
[00:27:29.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:78:0)
[00:27:29.287] Timer tick, asking Buildbucket for the build status
[00:27:29.767] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:29.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:79:0) after 1m0s
[00:28:29.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:79:0)
[00:28:29.802] Timer tick, asking Buildbucket for the build status
[00:28:38.539] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:38.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:80:0) after 1m0s
[00:29:38.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:80:0)
[00:29:38.562] Timer tick, asking Buildbucket for the build status
[00:29:38.670] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:38.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:81:0) after 1m0s
[00:30:38.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:81:0)
[00:30:38.685] Timer tick, asking Buildbucket for the build status
[00:30:39.626] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:39.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:82:0) after 1m0s
[00:31:39.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:82:0)
[00:31:39.682] Timer tick, asking Buildbucket for the build status
[00:31:39.821] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:39.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:83:0) after 1m0s
[00:32:39.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:83:0)
[00:32:39.872] Timer tick, asking Buildbucket for the build status
[00:32:40.172] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:40.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:84:0) after 1m0s
[00:33:40.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:84:0)
[00:33:40.210] Timer tick, asking Buildbucket for the build status
[00:33:40.920] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:40.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:85:0) after 1m0s
[00:34:40.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:85:0)
[00:34:40.940] Timer tick, asking Buildbucket for the build status
[00:34:41.143] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:41.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:86:0) after 1m0s
[00:35:41.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:86:0)
[00:35:41.177] Timer tick, asking Buildbucket for the build status
[00:35:41.388] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:41.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:87:0) after 1m0s
[00:36:41.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:87:0)
[00:36:41.408] Timer tick, asking Buildbucket for the build status
[00:36:41.980] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:41.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:88:0) after 1m0s
[00:37:42.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:88:0)
[00:37:42.048] Timer tick, asking Buildbucket for the build status
[00:37:42.635] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:42.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:89:0) after 1m0s
[00:38:42.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:89:0)
[00:38:42.738] Timer tick, asking Buildbucket for the build status
[00:38:43.370] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:43.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:90:0) after 1m0s
[00:39:43.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:90:0)
[00:39:43.395] Timer tick, asking Buildbucket for the build status
[00:39:44.036] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:44.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:91:0) after 1m0s
[00:40:44.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:91:0)
[00:40:44.067] Timer tick, asking Buildbucket for the build status
[00:40:44.455] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:44.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:92:0) after 1m0s
[00:41:44.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:92:0)
[00:41:44.503] Timer tick, asking Buildbucket for the build status
[00:41:44.741] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:44.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:93:0) after 1m0s
[00:42:44.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:93:0)
[00:42:44.768] Timer tick, asking Buildbucket for the build status
[00:42:44.906] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:44.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:94:0) after 1m0s
[00:43:44.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:94:0)
[00:43:44.995] Timer tick, asking Buildbucket for the build status
[00:43:45.153] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:45.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:95:0) after 1m0s
[00:44:45.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:95:0)
[00:44:45.245] Timer tick, asking Buildbucket for the build status
[00:44:45.471] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:45.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:96:0) after 1m0s
[00:45:45.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:96:0)
[00:45:45.475] Timer tick, asking Buildbucket for the build status
[00:45:45.858] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:45.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:97:0) after 1m0s
[00:46:45.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:97:0)
[00:46:45.922] Timer tick, asking Buildbucket for the build status
[00:46:46.140] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:46.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:98:0) after 1m0s
[00:47:46.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:98:0)
[00:47:46.243] Timer tick, asking Buildbucket for the build status
[00:47:46.355] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:46.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:99:0) after 1m0s
[00:48:46.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:99:0)
[00:48:46.436] Timer tick, asking Buildbucket for the build status
[00:48:46.571] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:46.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:100:0) after 1m0s
[00:49:46.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:100:0)
[00:49:46.601] Timer tick, asking Buildbucket for the build status
[00:49:47.023] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:47.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:101:0) after 1m0s
[00:50:47.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:101:0)
[00:50:47.079] Timer tick, asking Buildbucket for the build status
[00:50:47.152] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:47.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:102:0) after 1m0s
[00:51:47.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:102:0)
[00:51:47.133] Timer tick, asking Buildbucket for the build status
[00:51:47.281] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:47.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:103:0) after 1m0s
[00:52:47.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:103:0)
[00:52:47.331] Timer tick, asking Buildbucket for the build status
[00:52:47.415] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:47.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:104:0) after 1m0s
[00:53:47.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:104:0)
[00:53:47.424] Timer tick, asking Buildbucket for the build status
[00:53:47.525] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:47.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:105:0) after 1m0s
[00:54:47.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:105:0)
[00:54:47.518] Timer tick, asking Buildbucket for the build status
[00:54:47.597] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:47.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:106:0) after 1m0s
[00:55:47.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:106:0)
[00:55:47.682] Timer tick, asking Buildbucket for the build status
[00:55:47.771] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:47.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:107:0) after 1m0s
[00:56:47.792] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:107:0)
[00:56:47.792] Timer tick, asking Buildbucket for the build status
[00:56:47.955] Build 8864701722904031824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:47.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030131785372424704:108:0) after 1m0s
[00:57:00.684] Received PubSub notification, asking Buildbucket for the build status
[00:57:00.764] Build 8864701722904031824: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:57:00.765] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604365015234900",
"created_by": "project:chromium",
"created_ts": "1604358666472468",
"failure_reason": "BUILD_FAILURE",
"id": "8864701722904031824",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f281490398ff5612c5c45dc5e07f3464f855ed39\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**base_unittests** failed because of:\\n\\n- EtwTraceConsumerRealtimeTest.ConsumerReturnsWhenSessionClosed\"}, \"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\": \"f281490398ff5612c5c45dc5e07f3464f855ed39\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-5-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15422, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-3\", \"got_angle_revision\": \"ed916ddf61cc0021cebf55fb6901ba71fe9e6a2e\", \"got_dawn_revision\": \"71d2e1d23d49c3616209dbf4776fd720aaa11560\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"f281490398ff5612c5c45dc5e07f3464f855ed39\", \"got_revision_cp\": \"refs/heads/master@{#823341}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"eaf1c53544a7ffe2c7716246ceb2dce315639733\", \"got_v8_revision\": \"fcca1a5101d5185549432a342e9ea90a3aad600f\", \"got_v8_revision_cp\": \"refs/heads/8.8.216@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f281490398ff5612c5c45dc5e07f3464f855ed39\", \"swarm_hashes_refs/heads/master(at){#823341}_without_patch\": {\"absl_hardening_tests\": \"0979e211dff75b2bedbff36038687ed6c868a44f\", \"accessibility_unittests\": \"19eb5db19c7e59f58dbc85af95b03a8fd921b1f5\", \"angle_unittests\": \"4773d438f0ac2b7f11b3a820eec42f7dbb081b27\", \"app_shell_unittests\": \"b981bd1c4ae9a5ace89ff7e6d08c2086d79ecf0a\", \"aura_unittests\": \"2dd6559e74016a05ce97948e9bc92eaeaa3f7274\", \"base_unittests\": \"5b45b305dbda4b8981420479ddd8846995a5238e\", \"base_util_unittests\": \"34d8cb3b0907c95c3ec16fa47e471275f38ec7eb\", \"blink_common_unittests\": \"c6df6b78ad332cbd50ac0b48c20a3746ef52b26e\", \"blink_fuzzer_unittests\": \"2d9cc855478ef5da9308265a98cc572630d23d5b\", \"blink_heap_unittests\": \"1843994ffc81f5af023fb7d65e62074b65b82039\", \"blink_platform_unittests\": \"6015429a7a6a2a794759acb073d10a0ec5be6411\", \"blink_unittests\": \"b7ff4c95e7796429b273e6fb78cdf6797b6b412c\", \"boringssl_crypto_tests\": \"4188a0deabd2561ec21e35ba72ce5417158ffeea\", \"boringssl_ssl_tests\": \"d220d7e48d053546c8098a82a6c677e97ebd13f9\", \"browser_switcher_bho_unittests\": \"204b9791280c4d4914a6ac8786aef4226cef7937\", \"browser_tests\": \"4e5152f529b5d46660b274b49e0e339c110231b6\", \"capture_unittests\": \"be8ff8835def288cf991ddb0605bdad3c3e57a8d\", \"cast_unittests\": \"d5f01ccfea83512425656305c3c56922ca6b8a35\", \"cc_unittests\": \"4e74743f5be82f53a2fc0804430b4c1effc3a96e\", \"chrome_app_unittests\": \"ec5ec4936738afe8352fb74c1a802da6f5e8f1fd\", \"chrome_cleaner_unittests\": \"ff7fcbc87b0d3402fa3799420055ff0e91368eb9\", \"chrome_elf_unittests\": \"27e4f6fd1238b31b2b87b905c935be6b866881a4\", \"chromedriver_unittests\": \"5ce12eb0e87041f3348910290bfc07523ebc4ef1\", \"components_browsertests\": \"fc08a2d56b8d0be4ef6bf9874c04d8216af1a06a\", \"components_unittests\": \"8c281bbf2545389a3fec9a40aba67916230d0ed3\", \"compositor_unittests\": \"beb4cb2fff5e85cf3390b693981e3894296019dc\", \"content_browsertests\": \"a5f6079a147d1fddb5a13ee53d5cc7cfcf35588c\", \"content_unittests\": \"9a60d4ba203df643f2bacc5c687a3cc3b436ab09\", \"courgette_unittests\": \"2eb97ce94f62c69ec62970508b0eab41e71fb5d0\", \"crashpad_tests\": \"ac1f05ace3e6cead49d9efeaa7a1702594e39a57\", \"cronet_tests\": \"247ded9190548b8039aba6a65ef69d07f127cc02\", \"cronet_unittests\": \"13db3d466eb3e6986d5c68896cf7c7dd8472b5cb\", \"crypto_unittests\": \"93c3ecca9a65545b4986253cdde82af897ed6998\", \"delayloads_unittests\": \"34ef4db3136f12480da4e4b547e8f90a35897233\", \"device_unittests\": \"7a187fafbb9285b329eb281fee8ed3e4a85cce4b\", \"display_unittests\": \"095034df948ec3c2d011991b1951ca55ed58cb21\", \"elevation_service_unittests\": \"5c53d782c01bced66087c9ee82dc2050be2b2d40\", \"events_unittests\": \"ee5cb948ee949cd0386a897fe536a15440798c1f\", \"extensions_browsertests\": \"7d0525477e4c20f117957749b5ff641abe74b4cf\", \"extensions_unittests\": \"77abaea7e5e3b748fb1a22b34cbdda3c3e7b91e5\", \"filesystem_service_unittests\": \"9c37bd1fd458fd63627e3b395caf8fd27ecaeab9\", \"gcm_unit_tests\": \"43bdbe83ae0daa034393e97de70cb92db00390fb\", \"gcp_unittests\": \"73fce91076ac1895a83058ff29d805580f224917\", \"gfx_unittests\": \"19df1276bf2cde95dff3b4b10d7f31d88f59d146\", \"gin_unittests\": \"3fb4e2ae0ccc9f5a5e9d000dcbe4e58d4a289ee4\", \"google_apis_unittests\": \"d382563b9f33e3c9dfda521a9f2e424058b863c7\", \"gpu_unittests\": \"28637f89e7c7b410f8d2ab5b6c55cd139e68dc6d\", \"gwp_asan_unittests\": \"0b5a3f75e6c689ab3cf054fb93b981bf7885d739\", \"headless_browsertests\": \"239717c58d030a6f3c359cab587703418a79821e\", \"headless_unittests\": \"4bd2a9fe5cf530778ff827a6730e7b9792beca6d\", \"install_static_unittests\": \"042f62931d27fedfd9d72958139bd38cb62e8bd1\", \"installer_util_unittests\": \"33ca8554e397a9c2482d4bf60ede886621d1d565\", \"interactive_ui_tests\": \"2974ba08b0fd307bc2ce10b0a08938d86f2e34a5\", \"ipc_tests\": \"3003f87c17a3320e0786dee0234c45b07104a93d\", \"jingle_unittests\": \"1cf06a2e808d496c2b32db426013b08cf12c349d\", \"latency_unittests\": \"386bc41baf535ca1f9cc3b1a933c1deaeedde8e1\", \"libjingle_xmpp_unittests\": \"a932424e1805bb50a6885586c2eee7e35092a869\", \"liburlpattern_unittests\": \"fbe139c3e4d0a82a33b09f020496b9bfbf60802a\", \"media_blink_unittests\": \"a1ef8059ca311ea7f96925e728207216b52dcf55\", \"media_unittests\": \"b88655b64e11487da5ab9f4080ae979095c361df\", \"message_center_unittests\": \"f2891878ee985b23bd0dfef47931cba75e8132e0\", \"midi_unittests\": \"e5d579bef3e3f837e1d5497d68f6130e355a7a91\", \"mojo_core_unittests\": \"3051f29f90dd6d75c2e1cc9ca7638f4eb89582bd\", \"mojo_unittests\": \"0b6e3700386e1a62df9bf0aea567719aaa9ccdfb\", \"nacl_loader_unittests\": \"c247f80677e8b49047eb7bfc52dcd8657909ac05\", \"native_theme_unittests\": \"904ebeb82627057cec843889669746af9d66b0da\", \"net_unittests\": \"890f515c5dbf402c532272a1dbfc3df0350ce5d9\", \"notification_helper_unittests\": \"a932f8de6fbe83b50243ab7d284d1374c02e412d\", \"pdf_unittests\": \"ffea55e6ffbb09e20474a32b9fdfd86c5a7f9d3a\", \"perfetto_unittests\": \"1824cd7a0b718afc2243dc065ad489f4e0077b7a\", \"ppapi_unittests\": \"9f6236d1d63daf0342d94d9b6b1fc2e18061f759\", \"printing_unittests\": \"8a5867b181930f5111fcbf154024cf1b3d0623b2\", \"remoting_unittests\": \"d799cd10024e963e1e5fc2ebbcb1bb9b64435822\", \"sbox_integration_tests\": \"a16fb5cc19cd926d95ace79c998e51876515c5f9\", \"sbox_unittests\": \"a21b4c8cb1c0eeb88ec2bbb1554908253865a84e\", \"sbox_validation_tests\": \"03888581661332c067847984d48458b77c6c5af5\", \"service_manager_unittests\": \"2b372299e8f7cf7547de126c9f7fb2e0c341cdea\", \"services_unittests\": \"a9f1ab17aac4a09b015679f4b214d7955842f524\", \"setup_unittests\": \"d72f5cdd96e680fcedc8d7641c3cf99da6820911\", \"shell_dialogs_unittests\": \"83252e6af07c390ce0741a68444fab5f942b6401\", \"skia_unittests\": \"6626ee8408a8dfdb665e0dd8987e23fa42eac32e\", \"snapshot_unittests\": \"a6b2254d77c7eae901160a741a3df6605ee55cc5\", \"sql_unittests\": \"945443c20014d036b56e82ee39b247f562a0ed03\", \"storage_unittests\": \"bae4444a4448ba6a4070407645698473718cf46b\", \"sync_integration_tests\": \"ad60ace701abb94164722519610eb3c57ab8c593\", \"traffic_annotation_auditor_unittests\": \"c07e37e13c6204ad689a6e65ac11e63b3fc36ca5\", \"ui_base_unittests\": \"571ebaca8e609db87acfb6f9b5ed0212ace07da3\", \"ui_touch_selection_unittests\": \"6efa3e6360ea0e1452886412514818867ac3a81d\", \"unit_tests\": \"2671f044b2767a3628aa094357d7ea0b7869b9a8\", \"updater_tests\": \"fdab465019d9aed2df8e665e48819a32a9aacb3e\", \"url_unittests\": \"3a81b90ef5025df2411c012dae1a3ae4d09c8a0a\", \"views_unittests\": \"7cce9c290e193d59717dfadb41f9bedb8f9b5a9b\", \"viz_unittests\": \"3789bc8d36c608d5c01c53836abc2556f48df8cc\", \"vr_common_unittests\": \"3c5c8b9c391fb842a96f708c915482cc71465061\", \"vr_pixeltests\": \"cdd83f44acb1c618bf5329d55c70940540e35d79\", \"weblayer_browsertests\": \"41f03220e7970460f408fec58ae560556543c211\", \"weblayer_unittests\": \"5cf0ecfe870b5df0111e584d5689e9af1a0b86da\", \"wm_unittests\": \"244d5ca175875018cd342190a609f179bcfa5cff\", \"wtf_unittests\": \"421349e53b6dec37434e76eebe25afd7222c518b\", \"zlib_unittests\": \"e07821a9877f2b741464b9a344166e0e0b02ec24\", \"zucchini_unittests\": \"34a4a72b7a8fe827830b57d7cdb6715ee8240b02\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-vrjs\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**base_unittests** failed because of:\\n\\n- EtwTraceConsumerRealtimeTest.ConsumerReturnsWhenSessionClosed\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604358859197372",
"status": "COMPLETED",
"status_changed_ts": "1604358860165277",
"tags": [
"build_address:luci.chromium.ci/win-asan/15422",
"builder:win-asan",
"buildset:commit/git/f281490398ff5612c5c45dc5e07f3464f855ed39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f281490398ff5612c5c45dc5e07f3464f855ed39",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030131785372424704",
"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/8864701722904031824/+/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:4fa40c03a7217b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604365016162789",
"url": "https://ci.chromium.org/b/8864701722904031824",
"utcnow_ts": "1604365020756366"
}
}
[00:57:00.765] Invocation finished in 1h45m56.030255812s with status FAILED