[04:07:09.111] New invocation is queued and will start shortly
[04:07:10.279] Starting the invocation (attempt 1)
[04:07:10.311] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:07:10.311] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:07:10.311] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026942264266025920",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"21f0e105c35140df83999c2514783765229c91ba\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026942264266025920",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/21f0e105c35140df83999c2514783765229c91ba",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/21f0e105c35140df83999c2514783765229c91ba",
"gitiles_ref:refs/heads/master"
]
}
[04:07:11.234] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607400430408451",
"id": "8861512202242422112",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"21f0e105c35140df83999c2514783765229c91ba\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607400431014579",
"tags": [
"build_address:luci.chromium.ci/win-asan/16094",
"builder:win-asan",
"buildset:commit/git/21f0e105c35140df83999c2514783765229c91ba",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/21f0e105c35140df83999c2514783765229c91ba",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026942264266025920",
"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/8861512202242422112/+/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": "1607400431014724",
"url": "https://ci.chromium.org/b/8861512202242422112",
"utcnow_ts": "1607400431205634"
}
}
[04:07:11.234] Task URL: https://ci.chromium.org/b/8861512202242422112
[04:07:11.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:2:0) after 1m0s
[04:08:11.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:2:0)
[04:08:11.276] Timer tick, asking Buildbucket for the build status
[04:08:11.513] Build 8861512202242422112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:08:11.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:3:0) after 1m0s
[04:09:11.521] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:3:0)
[04:09:11.521] Timer tick, asking Buildbucket for the build status
[04:09:11.602] Build 8861512202242422112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:09:11.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:4:0) after 1m0s
[04:10:11.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:4:0)
[04:10:11.619] Timer tick, asking Buildbucket for the build status
[04:10:11.769] Build 8861512202242422112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:11.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:5:0) after 1m0s
[04:11:11.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:5:0)
[04:11:11.774] Timer tick, asking Buildbucket for the build status
[04:11:11.870] Build 8861512202242422112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:11.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:6:0) after 1m0s
[04:12:11.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:6:0)
[04:12:11.887] Timer tick, asking Buildbucket for the build status
[04:12:12.220] Build 8861512202242422112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:12.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:7:0) after 1m0s
[04:12:55.111] Received PubSub notification, asking Buildbucket for the build status
[04:12:55.188] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:12.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:7:0)
[04:13:12.247] Timer tick, asking Buildbucket for the build status
[04:13:12.326] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:12.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:9:0) after 1m0s
[04:14:12.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:9:0)
[04:14:12.323] Timer tick, asking Buildbucket for the build status
[04:14:12.833] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:12.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:10:0) after 1m0s
[04:15:12.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:10:0)
[04:15:12.985] Timer tick, asking Buildbucket for the build status
[04:15:13.251] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:13.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:11:0) after 1m0s
[04:16:13.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:11:0)
[04:16:13.326] Timer tick, asking Buildbucket for the build status
[04:16:13.384] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:13.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:12:0) after 1m0s
[04:17:13.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:12:0)
[04:17:13.482] Timer tick, asking Buildbucket for the build status
[04:17:13.555] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:13.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:13:0) after 1m0s
[04:18:13.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:13:0)
[04:18:13.561] Timer tick, asking Buildbucket for the build status
[04:18:13.664] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:13.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:14:0) after 1m0s
[04:19:13.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:14:0)
[04:19:13.809] Timer tick, asking Buildbucket for the build status
[04:19:13.936] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:13.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:15:0) after 1m0s
[04:20:13.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:15:0)
[04:20:13.992] Timer tick, asking Buildbucket for the build status
[04:20:14.070] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:14.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:16:0) after 1m0s
[04:21:14.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:16:0)
[04:21:14.185] Timer tick, asking Buildbucket for the build status
[04:21:14.247] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:14.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:17:0) after 1m0s
[04:22:14.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:17:0)
[04:22:14.413] Timer tick, asking Buildbucket for the build status
[04:22:14.471] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:14.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:18:0) after 1m0s
[04:23:14.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:18:0)
[04:23:14.508] Timer tick, asking Buildbucket for the build status
[04:23:14.676] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:14.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:19:0) after 1m0s
[04:24:14.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:19:0)
[04:24:14.843] Timer tick, asking Buildbucket for the build status
[04:24:14.903] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:14.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:20:0) after 1m0s
[04:25:14.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:20:0)
[04:25:14.984] Timer tick, asking Buildbucket for the build status
[04:25:15.192] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:15.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:21:0) after 1m0s
[04:26:15.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:21:0)
[04:26:15.321] Timer tick, asking Buildbucket for the build status
[04:26:15.867] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:15.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:22:0) after 1m0s
[04:27:16.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:22:0)
[04:27:16.110] Timer tick, asking Buildbucket for the build status
[04:27:16.235] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:16.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:23:0) after 1m0s
[04:28:16.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:23:0)
[04:28:16.444] Timer tick, asking Buildbucket for the build status
[04:28:16.503] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:16.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:24:0) after 1m0s
[04:29:16.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:24:0)
[04:29:16.625] Timer tick, asking Buildbucket for the build status
[04:29:16.698] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:16.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:25:0) after 1m0s
[04:30:16.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:25:0)
[04:30:16.768] Timer tick, asking Buildbucket for the build status
[04:30:16.935] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:16.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:26:0) after 1m0s
[04:31:16.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:26:0)
[04:31:16.990] Timer tick, asking Buildbucket for the build status
[04:31:17.063] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:17.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:27:0) after 1m0s
[04:32:17.069] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:27:0)
[04:32:17.069] Timer tick, asking Buildbucket for the build status
[04:32:17.197] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:17.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:28:0) after 1m0s
[04:33:17.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:28:0)
[04:33:17.245] Timer tick, asking Buildbucket for the build status
[04:33:17.382] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:17.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:29:0) after 1m0s
[04:34:17.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:29:0)
[04:34:17.433] Timer tick, asking Buildbucket for the build status
[04:34:17.491] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:17.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:30:0) after 1m0s
[04:35:17.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:30:0)
[04:35:17.513] Timer tick, asking Buildbucket for the build status
[04:35:17.576] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:17.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:31:0) after 1m0s
[04:36:17.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:31:0)
[04:36:17.619] Timer tick, asking Buildbucket for the build status
[04:36:17.932] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:17.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:32:0) after 1m0s
[04:37:17.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:32:0)
[04:37:17.950] Timer tick, asking Buildbucket for the build status
[04:37:18.036] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:18.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:33:0) after 1m0s
[04:38:18.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:33:0)
[04:38:18.093] Timer tick, asking Buildbucket for the build status
[04:38:18.231] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:18.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:34:0) after 1m0s
[04:39:18.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:34:0)
[04:39:18.215] Timer tick, asking Buildbucket for the build status
[04:39:18.297] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:18.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:35:0) after 1m0s
[04:40:18.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:35:0)
[04:40:18.358] Timer tick, asking Buildbucket for the build status
[04:40:18.478] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:18.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:36:0) after 1m0s
[04:41:18.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:36:0)
[04:41:18.580] Timer tick, asking Buildbucket for the build status
[04:41:18.652] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:18.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:37:0) after 1m0s
[04:42:18.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:37:0)
[04:42:18.751] Timer tick, asking Buildbucket for the build status
[04:42:18.842] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:18.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:38:0) after 1m0s
[04:43:18.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:38:0)
[04:43:18.863] Timer tick, asking Buildbucket for the build status
[04:43:18.920] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:18.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:39:0) after 1m0s
[04:44:19.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:39:0)
[04:44:19.813] Timer tick, asking Buildbucket for the build status
[04:44:19.871] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:19.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:40:0) after 1m0s
[04:45:19.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:40:0)
[04:45:19.938] Timer tick, asking Buildbucket for the build status
[04:45:19.998] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:19.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:41:0) after 1m0s
[04:46:20.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:41:0)
[04:46:20.042] Timer tick, asking Buildbucket for the build status
[04:46:20.173] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:20.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:42:0) after 1m0s
[04:47:20.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:42:0)
[04:47:20.220] Timer tick, asking Buildbucket for the build status
[04:47:20.693] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:20.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:43:0) after 1m0s
[04:48:20.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:43:0)
[04:48:20.808] Timer tick, asking Buildbucket for the build status
[04:48:20.862] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:20.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:44:0) after 1m0s
[04:49:20.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:44:0)
[04:49:20.888] Timer tick, asking Buildbucket for the build status
[04:49:20.942] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:20.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:45:0) after 1m0s
[04:50:20.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:45:0)
[04:50:20.997] Timer tick, asking Buildbucket for the build status
[04:50:21.096] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:21.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:46:0) after 1m0s
[04:51:21.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:46:0)
[04:51:21.169] Timer tick, asking Buildbucket for the build status
[04:51:21.744] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:21.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:47:0) after 1m0s
[04:52:21.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:47:0)
[04:52:21.855] Timer tick, asking Buildbucket for the build status
[04:52:21.944] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:21.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:48:0) after 1m0s
[04:53:22.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:48:0)
[04:53:22.043] Timer tick, asking Buildbucket for the build status
[04:53:22.124] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:22.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:49:0) after 1m0s
[04:54:22.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:49:0)
[04:54:22.185] Timer tick, asking Buildbucket for the build status
[04:54:22.303] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:22.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:50:0) after 1m0s
[04:55:22.414] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:50:0)
[04:55:22.414] Timer tick, asking Buildbucket for the build status
[04:55:22.521] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:22.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:51:0) after 1m0s
[04:56:22.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:51:0)
[04:56:22.562] Timer tick, asking Buildbucket for the build status
[04:56:22.628] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:22.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:52:0) after 1m0s
[04:57:22.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:52:0)
[04:57:22.683] Timer tick, asking Buildbucket for the build status
[04:57:22.801] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:22.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:53:0) after 1m0s
[04:58:22.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:53:0)
[04:58:22.830] Timer tick, asking Buildbucket for the build status
[04:58:22.905] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:22.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:54:0) after 1m0s
[04:59:23.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:54:0)
[04:59:23.019] Timer tick, asking Buildbucket for the build status
[04:59:23.416] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:23.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:55:0) after 1m0s
[05:00:23.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:55:0)
[05:00:23.457] Timer tick, asking Buildbucket for the build status
[05:00:23.604] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:23.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:56:0) after 1m0s
[05:01:23.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:56:0)
[05:01:23.656] Timer tick, asking Buildbucket for the build status
[05:01:23.929] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:23.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:57:0) after 1m0s
[05:02:23.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:57:0)
[05:02:23.955] Timer tick, asking Buildbucket for the build status
[05:02:24.032] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:24.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:58:0) after 1m0s
[05:03:24.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:58:0)
[05:03:24.087] Timer tick, asking Buildbucket for the build status
[05:03:24.146] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:24.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:59:0) after 1m0s
[05:04:24.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:59:0)
[05:04:24.226] Timer tick, asking Buildbucket for the build status
[05:04:24.297] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:24.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:60:0) after 1m0s
[05:05:24.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:60:0)
[05:05:24.355] Timer tick, asking Buildbucket for the build status
[05:05:24.469] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:24.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:61:0) after 1m0s
[05:06:24.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:61:0)
[05:06:24.585] Timer tick, asking Buildbucket for the build status
[05:06:24.660] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:24.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:62:0) after 1m0s
[05:07:24.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:62:0)
[05:07:24.712] Timer tick, asking Buildbucket for the build status
[05:07:25.029] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:25.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:63:0) after 1m0s
[05:08:25.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:63:0)
[05:08:25.123] Timer tick, asking Buildbucket for the build status
[05:08:25.175] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:25.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:64:0) after 1m0s
[05:09:25.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:64:0)
[05:09:25.280] Timer tick, asking Buildbucket for the build status
[05:09:25.549] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:25.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:65:0) after 1m0s
[05:10:25.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:65:0)
[05:10:25.637] Timer tick, asking Buildbucket for the build status
[05:10:25.711] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:25.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:66:0) after 1m0s
[05:11:25.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:66:0)
[05:11:25.724] Timer tick, asking Buildbucket for the build status
[05:11:25.802] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:25.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:67:0) after 1m0s
[05:12:25.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:67:0)
[05:12:25.820] Timer tick, asking Buildbucket for the build status
[05:12:26.002] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:26.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:68:0) after 1m0s
[05:13:26.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:68:0)
[05:13:26.039] Timer tick, asking Buildbucket for the build status
[05:13:26.219] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:26.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:69:0) after 1m0s
[05:14:26.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:69:0)
[05:14:26.240] Timer tick, asking Buildbucket for the build status
[05:14:26.310] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:26.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:70:0) after 1m0s
[05:15:26.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:70:0)
[05:15:26.341] Timer tick, asking Buildbucket for the build status
[05:15:26.454] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:26.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:71:0) after 1m0s
[05:16:26.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:71:0)
[05:16:26.483] Timer tick, asking Buildbucket for the build status
[05:16:26.640] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:26.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:72:0) after 1m0s
[05:17:26.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:72:0)
[05:17:26.720] Timer tick, asking Buildbucket for the build status
[05:17:26.810] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:26.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:73:0) after 1m0s
[05:18:26.821] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:73:0)
[05:18:26.821] Timer tick, asking Buildbucket for the build status
[05:18:27.077] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:27.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:74:0) after 1m0s
[05:19:27.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:74:0)
[05:19:27.194] Timer tick, asking Buildbucket for the build status
[05:19:27.509] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:27.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:75:0) after 1m0s
[05:20:27.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:75:0)
[05:20:27.513] Timer tick, asking Buildbucket for the build status
[05:20:27.581] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:27.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:76:0) after 1m0s
[05:21:27.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:76:0)
[05:21:27.604] Timer tick, asking Buildbucket for the build status
[05:21:27.700] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:27.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:77:0) after 1m0s
[05:22:27.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:77:0)
[05:22:27.742] Timer tick, asking Buildbucket for the build status
[05:22:27.846] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:27.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:78:0) after 1m0s
[05:23:27.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:78:0)
[05:23:27.867] Timer tick, asking Buildbucket for the build status
[05:23:27.982] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:27.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:79:0) after 1m0s
[05:24:28.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:79:0)
[05:24:28.052] Timer tick, asking Buildbucket for the build status
[05:24:28.179] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:28.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:80:0) after 1m0s
[05:25:28.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:80:0)
[05:25:28.180] Timer tick, asking Buildbucket for the build status
[05:25:28.353] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:28.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:81:0) after 1m0s
[05:26:28.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:81:0)
[05:26:28.425] Timer tick, asking Buildbucket for the build status
[05:26:28.553] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:28.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:82:0) after 1m0s
[05:27:28.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:82:0)
[05:27:28.657] Timer tick, asking Buildbucket for the build status
[05:27:28.853] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:28.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:83:0) after 1m0s
[05:28:28.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:83:0)
[05:28:28.867] Timer tick, asking Buildbucket for the build status
[05:28:29.004] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:29.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:84:0) after 1m0s
[05:29:29.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:84:0)
[05:29:29.043] Timer tick, asking Buildbucket for the build status
[05:29:29.149] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:29.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:85:0) after 1m0s
[05:30:29.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:85:0)
[05:30:29.162] Timer tick, asking Buildbucket for the build status
[05:30:29.273] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:29.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:86:0) after 1m0s
[05:31:29.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:86:0)
[05:31:29.294] Timer tick, asking Buildbucket for the build status
[05:31:29.449] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:29.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:87:0) after 1m0s
[05:32:29.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:87:0)
[05:32:29.492] Timer tick, asking Buildbucket for the build status
[05:32:29.619] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:29.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:88:0) after 1m0s
[05:33:29.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:88:0)
[05:33:29.777] Timer tick, asking Buildbucket for the build status
[05:33:29.859] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:29.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:89:0) after 1m0s
[05:34:29.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:89:0)
[05:34:29.843] Timer tick, asking Buildbucket for the build status
[05:34:29.916] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:29.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:90:0) after 1m0s
[05:35:29.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:90:0)
[05:35:29.937] Timer tick, asking Buildbucket for the build status
[05:35:30.138] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:30.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:91:0) after 1m0s
[05:36:30.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:91:0)
[05:36:30.195] Timer tick, asking Buildbucket for the build status
[05:36:30.271] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:30.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:92:0) after 1m0s
[05:37:30.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:92:0)
[05:37:30.293] Timer tick, asking Buildbucket for the build status
[05:37:30.521] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:30.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:93:0) after 1m0s
[05:38:30.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:93:0)
[05:38:30.543] Timer tick, asking Buildbucket for the build status
[05:38:30.645] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:30.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:94:0) after 1m0s
[05:39:30.673] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:94:0)
[05:39:30.673] Timer tick, asking Buildbucket for the build status
[05:39:30.738] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:30.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:95:0) after 1m0s
[05:40:30.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:95:0)
[05:40:30.779] Timer tick, asking Buildbucket for the build status
[05:40:30.857] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:30.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:96:0) after 1m0s
[05:41:30.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:96:0)
[05:41:30.883] Timer tick, asking Buildbucket for the build status
[05:41:31.000] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:31.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:97:0) after 1m0s
[05:42:31.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:97:0)
[05:42:31.077] Timer tick, asking Buildbucket for the build status
[05:42:31.182] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:31.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:98:0) after 1m0s
[05:43:31.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:98:0)
[05:43:31.192] Timer tick, asking Buildbucket for the build status
[05:43:31.261] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:31.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:99:0) after 1m0s
[05:44:31.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:99:0)
[05:44:31.304] Timer tick, asking Buildbucket for the build status
[05:44:31.529] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:31.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:100:0) after 1m0s
[05:45:31.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:100:0)
[05:45:31.530] Timer tick, asking Buildbucket for the build status
[05:45:31.659] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:31.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:101:0) after 1m0s
[05:46:31.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:101:0)
[05:46:31.674] Timer tick, asking Buildbucket for the build status
[05:46:32.049] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:32.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:102:0) after 1m0s
[05:47:32.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:102:0)
[05:47:32.080] Timer tick, asking Buildbucket for the build status
[05:47:32.197] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:32.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:103:0) after 1m0s
[05:48:32.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:103:0)
[05:48:32.288] Timer tick, asking Buildbucket for the build status
[05:48:32.931] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:32.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:104:0) after 1m0s
[05:49:32.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:104:0)
[05:49:32.951] Timer tick, asking Buildbucket for the build status
[05:49:33.062] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:33.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:105:0) after 1m0s
[05:50:33.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:105:0)
[05:50:33.089] Timer tick, asking Buildbucket for the build status
[05:50:33.169] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:33.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:106:0) after 1m0s
[05:51:33.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:106:0)
[05:51:33.271] Timer tick, asking Buildbucket for the build status
[05:51:33.378] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:33.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:107:0) after 1m0s
[05:52:33.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:107:0)
[05:52:33.454] Timer tick, asking Buildbucket for the build status
[05:52:33.579] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:33.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:108:0) after 1m0s
[05:53:33.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:108:0)
[05:53:33.723] Timer tick, asking Buildbucket for the build status
[05:53:33.869] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:33.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:109:0) after 1m0s
[05:54:33.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:109:0)
[05:54:33.959] Timer tick, asking Buildbucket for the build status
[05:54:34.055] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:34.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:110:0) after 1m0s
[05:55:34.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:110:0)
[05:55:34.035] Timer tick, asking Buildbucket for the build status
[05:55:34.108] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:34.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:111:0) after 1m0s
[05:56:34.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:111:0)
[05:56:34.177] Timer tick, asking Buildbucket for the build status
[05:56:34.254] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:34.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:112:0) after 1m0s
[05:57:34.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:112:0)
[05:57:34.337] Timer tick, asking Buildbucket for the build status
[05:57:34.912] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:34.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:113:0) after 1m0s
[05:58:34.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:113:0)
[05:58:34.988] Timer tick, asking Buildbucket for the build status
[05:58:35.071] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:35.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:114:0) after 1m0s
[05:59:35.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:114:0)
[05:59:35.059] Timer tick, asking Buildbucket for the build status
[05:59:36.174] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:36.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:115:0) after 1m0s
[06:00:36.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:115:0)
[06:00:36.236] Timer tick, asking Buildbucket for the build status
[06:00:36.529] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:36.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:116:0) after 1m0s
[06:01:38.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:116:0)
[06:01:38.695] Timer tick, asking Buildbucket for the build status
[06:01:38.757] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:38.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:117:0) after 1m0s
[06:02:38.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:117:0)
[06:02:38.774] Timer tick, asking Buildbucket for the build status
[06:02:38.885] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:38.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:118:0) after 1m0s
[06:03:38.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:118:0)
[06:03:38.919] Timer tick, asking Buildbucket for the build status
[06:03:39.058] Build 8861512202242422112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:39.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026942264266025920:119:0) after 1m0s
[06:03:41.171] Received PubSub notification, asking Buildbucket for the build status
[06:03:41.251] Build 8861512202242422112: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:03:41.251] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607407420303808",
"created_by": "project:chromium",
"created_ts": "1607400430408451",
"id": "8861512202242422112",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"21f0e105c35140df83999c2514783765229c91ba\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"21f0e105c35140df83999c2514783765229c91ba\", \"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-us-central1-b-32-0-arvd\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16094, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"8b4990bbd6217138d30e19f5309560cc6e2592a0\", \"got_dawn_revision\": \"8a73e1876dbd2d862701fde91bc21630c4bd0e5b\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"21f0e105c35140df83999c2514783765229c91ba\", \"got_revision_cp\": \"refs/heads/master@{#834554}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"84b9bb6621c7eae8da034d31aceab780d8b1abdc\", \"got_v8_revision\": \"8c0eca5f170cfe786bb807f359489e3db7d5b9ce\", \"got_v8_revision_cp\": \"refs/heads/8.9.119@{#1}\", \"got_webrtc_revision\": \"bb1a28de3cf17476142e9de8ee12fe6c567d06c1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32789}\", \"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\": \"21f0e105c35140df83999c2514783765229c91ba\", \"swarm_hashes_refs/heads/master(at){#834554}_without_patch\": {\"absl_hardening_tests\": \"0310ae8c2fd3e3e80398db859ccb1ff2175ecd63\", \"accessibility_unittests\": \"5ac322a37e13dff80f4d218d8952fba9cd83f85d\", \"angle_unittests\": \"ee3dc46f7855c5ab89068707ceb275586fb96e51\", \"app_shell_unittests\": \"558f0ee044b73628ee948403c8112e6ea5c3bd1e\", \"aura_unittests\": \"b1cd60643e488ae1432c47acbcce17f65720b4c4\", \"base_unittests\": \"19529ce657390ab1d2f644c13833015d4102c1c2\", \"base_util_unittests\": \"dc73be40393927482d0ce375b398dc7771ac5a37\", \"blink_common_unittests\": \"efd09699a1d96845a35b93c6068d902cd732b58d\", \"blink_fuzzer_unittests\": \"d201b1bc99c0482fc622e3e80401dae3d9f4d44b\", \"blink_heap_unittests\": \"165445f6dfb4fe0c3549d4bf86b41c92542c1273\", \"blink_platform_unittests\": \"266e747d55f83d220715873e600e565df4263577\", \"blink_unittests\": \"1a58842d5544228d59c98826fbface1d2be6fbdd\", \"boringssl_crypto_tests\": \"cb7bfc2dbeaca9f1e55912db5a87f986a7ebf55c\", \"boringssl_ssl_tests\": \"dfb1f0cf281997544bbcc5376be7fa3553bcb50b\", \"browser_switcher_bho_unittests\": \"2557b925860eaf45bcdc5f44ba18e65508d51808\", \"browser_tests\": \"0dab950c67f03328e366eb750d4f34cd497bd44c\", \"capture_unittests\": \"ef2a17fdb8cb7ab06f00217f277e7e8e0334c8cb\", \"cast_unittests\": \"6ba40f6f1b3122679028b069179909cf04840649\", \"cc_unittests\": \"43bcd3da1d6b76d72e12e34237f767f7f9ce7265\", \"chrome_app_unittests\": \"db55f9684634ad45f8aa74604af88fce816d0528\", \"chrome_cleaner_unittests\": \"a831490ad30e0be1387a1367785f3ea8c21595ba\", \"chrome_elf_unittests\": \"f6a58c18f2d27eb863dc4ac5cb13014933e5b692\", \"chromedriver_unittests\": \"a6c0d1be5e4c878cffda61a0e91451b41699cdd4\", \"components_browsertests\": \"53f7f2b653a9ef50a8f47ddc5326893d202574c4\", \"components_unittests\": \"51d693d07188029b81b3d7333ba9a7ce65886710\", \"compositor_unittests\": \"9e42f58e0be8cfaf33153eba48e92c8272d0d4ef\", \"content_browsertests\": \"11d00c23ea496ea438696d4422cf120da0a68100\", \"content_unittests\": \"81cdf5ad421862a6826584bd024f4aeb3910d868\", \"courgette_unittests\": \"7827927df7c9360d62d6bb4d6c779da8901042f0\", \"crashpad_tests\": \"b884dcd87b26b26528e16acddcc90e3d1235752a\", \"cronet_tests\": \"6216aec2cc945ca8f892664c18f035e25edee5a7\", \"cronet_unittests\": \"f99e6530106416e49e02b8d8afe5b1499f2e4279\", \"crypto_unittests\": \"3fe698216d08f39a7a08387e68ba3880bf8b802d\", \"delayloads_unittests\": \"de9999d44a473e3a08fe57d643a6d42870fe4401\", \"device_unittests\": \"db23edc48b77631129395e5ecfbc4ebb6c328460\", \"display_unittests\": \"c643677737710250feee00cf0206c5675bf6e38f\", \"elevation_service_unittests\": \"030ad57a8107dad2a03fdeaa4da65f78f81292c9\", \"events_unittests\": \"19261d23137873e1f1758bc8adac54be2fd4560c\", \"extensions_browsertests\": \"ec3c90a78cb10e3d3e43861706dc51584d3da774\", \"extensions_unittests\": \"0f75bc5a7464067a97ee37ddef5aa954ebd96e49\", \"filesystem_service_unittests\": \"633d724d4510a45b22efd7d62972dbe563f825b6\", \"gcm_unit_tests\": \"4b83259a37bbf1b2ed0b940451c940ebd723c937\", \"gcp_unittests\": \"7961e58d3b41177b4fe89073c9eb48eb1d2afb0a\", \"gfx_unittests\": \"0792c0d46a0217f3fba49776772d22314c6e9ac3\", \"gin_unittests\": \"73d381f65c741bdfb72278313b29d2deb7cc73e2\", \"google_apis_unittests\": \"16f9ccd3f6e6d7d6be0a4fc1ea21cd35d83a8b91\", \"gpu_unittests\": \"cdf96739d2b98aaabc91ecdc162cc0dccbdeb4d3\", \"gwp_asan_unittests\": \"944dd1100cac47044786aaaa001591e478b5acbe\", \"headless_browsertests\": \"8fda7289c19a4ce6b9561dfa990659674c60069a\", \"headless_unittests\": \"0580c69456ea08f22ce8704e30424006ee0372ee\", \"install_static_unittests\": \"c58812b7c2870433fc598fb819bc9cbc31a2e968\", \"installer_util_unittests\": \"ece6eb0433b25f76faf3a2a2fc73f0e05c2eec06\", \"interactive_ui_tests\": \"1f43b9b651de45755a154234e88f17507978ae60\", \"ipc_tests\": \"42d522049dc595c92c1f190aa3389175b0546cac\", \"jingle_unittests\": \"8152788f695f4d6ba4a51acd69be11b7f8aa4155\", \"latency_unittests\": \"7d517a5035652e62f0b36cc09f2d31ee5891c5f7\", \"libjingle_xmpp_unittests\": \"329131327df3f9bd1a4d393d447eef9e5d2323cf\", \"liburlpattern_unittests\": \"42094932f07d11d11418181e973004c487bc69c3\", \"media_blink_unittests\": \"a4fb2cb3aae5fb5b2752c611ef7c0517d450b982\", \"media_unittests\": \"b73f7f465de29133572848ec54361e20219ac52a\", \"message_center_unittests\": \"e5ee733a981c17dfded36509026be95973a32c42\", \"midi_unittests\": \"27f0654c322cc3583c5a8ea1214e47f20f020da6\", \"mojo_core_unittests\": \"986c6455ff33074ac161b3702bf201d01286631a\", \"mojo_unittests\": \"bb64cb4665913d87b4eb43f2e1bcc4af05fb78ac\", \"nacl_loader_unittests\": \"04d1fc7776c872bf5b53d6622af4872c7da105b5\", \"native_theme_unittests\": \"ab838b09e4eb55a1f4ed236e3289527aa8cff2e3\", \"net_unittests\": \"2458a6d8b1b2f86a11d30796158b8c841bc2c94e\", \"notification_helper_unittests\": \"a3d0316e6beb505741ca4defcb6ff517c4cff396\", \"pdf_unittests\": \"76c79f7a568839e7115c072de1974db22e2c8622\", \"perfetto_unittests\": \"c86eca6028efa4fdc9c079d256fc7d1850eee5b1\", \"ppapi_unittests\": \"eb5e14f505a5b9b361c52f6e69b1b8188d7f1180\", \"printing_unittests\": \"d9a877d365f0b0e346764f58f0abc3cf9c627d6b\", \"remoting_unittests\": \"fe7fd78fb9322ce3f64c39f06084b22c9d53f4ee\", \"sbox_integration_tests\": \"be1f2e39539b550cc8fc41237994bfd794bd78df\", \"sbox_unittests\": \"24d8ae3466879ce746e5a93e87cb5e0a3f70df93\", \"sbox_validation_tests\": \"f0d0a11f4f29732235bc4581ed779a433b633e29\", \"service_manager_unittests\": \"e6bd687aa6673e43c0ac7e1ea3b0c710d0c07972\", \"services_unittests\": \"5bd573b64c9892d3302bfd6f63e3497622545252\", \"setup_unittests\": \"a77a9c3708e48640a80da9ace8cd66c42fd50519\", \"shell_dialogs_unittests\": \"028630c07fe95f422b154a342fe8e82470fdc6d5\", \"skia_unittests\": \"964d0d96b5a17bf98d47ec00fd70dcc8d078fb2b\", \"snapshot_unittests\": \"8cd242caefbde497a9139704b9227166302a7e9a\", \"sql_unittests\": \"fbadf6450222db1e77c74a99748e55a68bf75fb6\", \"storage_unittests\": \"0a35c1a9d5ee551fd7a5f23d346fdff21a7721b3\", \"sync_integration_tests\": \"5bb5748165f30b63c2b6ccbd82377128e3d856ef\", \"traffic_annotation_auditor_unittests\": \"9d4fc1b4d9c41f614232a2c663b1e3fa9f792391\", \"ui_base_unittests\": \"cf4ee050dd5620627a414087367f1ab64e88de30\", \"ui_touch_selection_unittests\": \"4f474a1f8ebd5dd93536dcd92143980914284f91\", \"unit_tests\": \"959e605f6c58c0823fab7a28837f2bd9037988f9\", \"updater_tests\": \"6d88cc5c06b5b8ec378cfd4a0ddf63f78bd5e1fe\", \"url_unittests\": \"e42658813bf77e30abeb09c735406638543fbe40\", \"views_unittests\": \"7d0b9b52bdf280f0e085bed5102bbe3ee776ecd0\", \"viz_unittests\": \"67c39edc0f48d962b09cad64440889392cc4f09e\", \"vr_common_unittests\": \"702f213410b4f8fb9d55eb721eed7ef57972dc85\", \"vr_pixeltests\": \"4598bd6061acbb60e712d9f5da792ee4fec37924\", \"weblayer_browsertests\": \"1b7ab2fbf6bace5dfa8f896803d892d232ca2dd2\", \"weblayer_unittests\": \"33ca2af5f9bd9ecb596ec6a01be87530c7abfcc6\", \"wm_unittests\": \"f06878b6d28bcac5c7b005d91d681ddbf657ee91\", \"wtf_unittests\": \"d1c943f50b1796067975975ff133cb936ba233b7\", \"zlib_unittests\": \"06648efee5e781f41d6cf6c446c18ea9f449b383\", \"zucchini_unittests\": \"4b7973c0a494faab893365f8a6f3849fd3efcbdd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-arvd\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607400774300944",
"status": "COMPLETED",
"status_changed_ts": "1607407420553272",
"tags": [
"build_address:luci.chromium.ci/win-asan/16094",
"builder:win-asan",
"buildset:commit/git/21f0e105c35140df83999c2514783765229c91ba",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/21f0e105c35140df83999c2514783765229c91ba",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026942264266025920",
"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/8861512202242422112/+/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:505959b18ccb8910",
"user_agent:luci-scheduler"
],
"updated_ts": "1607407420563348",
"url": "https://ci.chromium.org/b/8861512202242422112",
"utcnow_ts": "1607407421240522"
}
}
[06:03:41.251] Invocation finished in 1h56m32.155677881s with status SUCCEEDED