[10:13:04.417] New invocation is queued and will start shortly
[10:13:05.505] Starting the invocation (attempt 1)
[10:13:05.599] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:13:05.599] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:13:05.599] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018403127623174128",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1beeedeff28a2bc3e317124a69848d826d029c94\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018403127623174128",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1beeedeff28a2bc3e317124a69848d826d029c94",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1beeedeff28a2bc3e317124a69848d826d029c94",
"gitiles_ref:refs/heads/master"
]
}
[10:13:06.999] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615543985742228",
"id": "8852973065564329424",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1beeedeff28a2bc3e317124a69848d826d029c94\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615543986695161",
"tags": [
"build_address:luci.chromium.ci/win-asan/17769",
"builder:win-asan",
"buildset:commit/git/1beeedeff28a2bc3e317124a69848d826d029c94",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1beeedeff28a2bc3e317124a69848d826d029c94",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018403127623174128",
"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/8852973065564329424/+/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": "1615543986695319",
"url": "https://ci.chromium.org/b/8852973065564329424",
"utcnow_ts": "1615543986947497"
}
}
[10:13:06.999] Task URL: https://ci.chromium.org/b/8852973065564329424
[10:13:06.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:2:0) after 1m0s
[10:13:29.119] Received PubSub notification, asking Buildbucket for the build status
[10:13:29.185] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:07.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:2:0)
[10:14:07.376] Timer tick, asking Buildbucket for the build status
[10:14:07.441] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:07.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:4:0) after 1m0s
[10:15:07.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:4:0)
[10:15:07.563] Timer tick, asking Buildbucket for the build status
[10:15:07.626] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:07.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:5:0) after 1m0s
[10:16:07.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:5:0)
[10:16:07.678] Timer tick, asking Buildbucket for the build status
[10:16:07.781] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:07.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:6:0) after 1m0s
[10:17:07.812] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:6:0)
[10:17:07.812] Timer tick, asking Buildbucket for the build status
[10:17:07.956] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:07.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:7:0) after 1m0s
[10:18:08.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:7:0)
[10:18:08.095] Timer tick, asking Buildbucket for the build status
[10:18:08.188] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:08.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:8:0) after 1m0s
[10:19:08.458] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:8:0)
[10:19:08.458] Timer tick, asking Buildbucket for the build status
[10:19:08.608] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:08.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:9:0) after 1m0s
[10:20:08.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:9:0)
[10:20:08.714] Timer tick, asking Buildbucket for the build status
[10:20:08.805] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:08.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:10:0) after 1m0s
[10:21:08.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:10:0)
[10:21:08.906] Timer tick, asking Buildbucket for the build status
[10:21:08.971] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:08.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:11:0) after 1m0s
[10:22:09.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:11:0)
[10:22:09.031] Timer tick, asking Buildbucket for the build status
[10:22:09.307] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:09.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:12:0) after 1m0s
[10:23:09.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:12:0)
[10:23:09.327] Timer tick, asking Buildbucket for the build status
[10:23:09.399] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:09.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:13:0) after 1m0s
[10:24:09.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:13:0)
[10:24:09.419] Timer tick, asking Buildbucket for the build status
[10:24:09.496] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:09.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:14:0) after 1m0s
[10:25:09.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:14:0)
[10:25:09.537] Timer tick, asking Buildbucket for the build status
[10:25:09.655] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:09.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:15:0) after 1m0s
[10:26:09.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:15:0)
[10:26:09.748] Timer tick, asking Buildbucket for the build status
[10:26:09.842] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:09.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:16:0) after 1m0s
[10:27:09.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:16:0)
[10:27:09.928] Timer tick, asking Buildbucket for the build status
[10:27:09.997] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:09.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:17:0) after 1m0s
[10:28:10.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:17:0)
[10:28:10.060] Timer tick, asking Buildbucket for the build status
[10:28:10.440] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:10.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:18:0) after 1m0s
[10:29:10.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:18:0)
[10:29:10.534] Timer tick, asking Buildbucket for the build status
[10:29:10.600] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:10.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:19:0) after 1m0s
[10:30:10.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:19:0)
[10:30:10.738] Timer tick, asking Buildbucket for the build status
[10:30:10.803] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:10.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:20:0) after 1m0s
[10:31:10.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:20:0)
[10:31:10.907] Timer tick, asking Buildbucket for the build status
[10:31:10.997] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:10.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:21:0) after 1m0s
[10:32:11.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:21:0)
[10:32:11.064] Timer tick, asking Buildbucket for the build status
[10:32:11.192] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:11.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:22:0) after 1m0s
[10:33:11.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:22:0)
[10:33:11.374] Timer tick, asking Buildbucket for the build status
[10:33:11.624] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:11.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:23:0) after 1m0s
[10:34:11.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:23:0)
[10:34:11.747] Timer tick, asking Buildbucket for the build status
[10:34:11.813] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:11.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:24:0) after 1m0s
[10:35:11.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:24:0)
[10:35:11.841] Timer tick, asking Buildbucket for the build status
[10:35:11.905] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:11.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:25:0) after 1m0s
[10:36:11.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:25:0)
[10:36:11.957] Timer tick, asking Buildbucket for the build status
[10:36:12.042] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:12.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:26:0) after 1m0s
[10:37:12.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:26:0)
[10:37:12.060] Timer tick, asking Buildbucket for the build status
[10:37:12.155] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:12.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:27:0) after 1m0s
[10:38:12.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:27:0)
[10:38:12.206] Timer tick, asking Buildbucket for the build status
[10:38:12.292] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:12.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:28:0) after 1m0s
[10:39:12.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:28:0)
[10:39:12.308] Timer tick, asking Buildbucket for the build status
[10:39:12.444] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:12.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:29:0) after 1m0s
[10:40:12.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:29:0)
[10:40:12.503] Timer tick, asking Buildbucket for the build status
[10:40:12.696] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:12.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:30:0) after 1m0s
[10:41:12.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:30:0)
[10:41:12.699] Timer tick, asking Buildbucket for the build status
[10:41:12.828] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:12.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:31:0) after 1m0s
[10:42:12.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:31:0)
[10:42:12.878] Timer tick, asking Buildbucket for the build status
[10:42:12.991] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:12.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:32:0) after 1m0s
[10:43:13.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:32:0)
[10:43:13.041] Timer tick, asking Buildbucket for the build status
[10:43:13.112] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:13.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:33:0) after 1m0s
[10:44:13.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:33:0)
[10:44:13.131] Timer tick, asking Buildbucket for the build status
[10:44:13.256] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:13.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:34:0) after 1m0s
[10:45:13.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:34:0)
[10:45:13.273] Timer tick, asking Buildbucket for the build status
[10:45:13.342] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:13.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:35:0) after 1m0s
[10:46:13.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:35:0)
[10:46:13.351] Timer tick, asking Buildbucket for the build status
[10:46:13.560] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:13.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:36:0) after 1m0s
[10:47:13.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:36:0)
[10:47:13.581] Timer tick, asking Buildbucket for the build status
[10:47:13.871] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:13.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:37:0) after 1m0s
[10:48:13.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:37:0)
[10:48:13.859] Timer tick, asking Buildbucket for the build status
[10:48:14.003] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:14.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:38:0) after 1m0s
[10:49:14.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:38:0)
[10:49:14.095] Timer tick, asking Buildbucket for the build status
[10:49:14.266] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:14.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:39:0) after 1m0s
[10:50:14.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:39:0)
[10:50:14.287] Timer tick, asking Buildbucket for the build status
[10:50:14.349] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:14.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:40:0) after 1m0s
[10:51:14.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:40:0)
[10:51:14.357] Timer tick, asking Buildbucket for the build status
[10:51:14.418] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:14.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:41:0) after 1m0s
[10:52:14.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:41:0)
[10:52:14.477] Timer tick, asking Buildbucket for the build status
[10:52:14.618] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:14.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:42:0) after 1m0s
[10:53:14.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:42:0)
[10:53:14.699] Timer tick, asking Buildbucket for the build status
[10:53:14.788] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:14.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:43:0) after 1m0s
[10:54:14.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:43:0)
[10:54:14.844] Timer tick, asking Buildbucket for the build status
[10:54:14.916] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:14.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:44:0) after 1m0s
[10:55:14.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:44:0)
[10:55:14.932] Timer tick, asking Buildbucket for the build status
[10:55:15.268] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:15.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:45:0) after 1m0s
[10:56:15.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:45:0)
[10:56:15.278] Timer tick, asking Buildbucket for the build status
[10:56:15.343] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:15.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:46:0) after 1m0s
[10:57:15.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:46:0)
[10:57:15.569] Timer tick, asking Buildbucket for the build status
[10:57:15.713] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:15.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:47:0) after 1m0s
[10:58:15.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:47:0)
[10:58:15.931] Timer tick, asking Buildbucket for the build status
[10:58:16.004] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:16.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:48:0) after 1m0s
[10:59:16.277] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:48:0)
[10:59:16.277] Timer tick, asking Buildbucket for the build status
[10:59:16.356] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:16.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:49:0) after 1m0s
[11:00:16.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:49:0)
[11:00:16.650] Timer tick, asking Buildbucket for the build status
[11:00:16.845] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:16.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:50:0) after 1m0s
[11:01:18.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:50:0)
[11:01:18.832] Timer tick, asking Buildbucket for the build status
[11:01:18.911] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:18.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:51:0) after 1m0s
[11:02:18.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:51:0)
[11:02:18.937] Timer tick, asking Buildbucket for the build status
[11:02:19.229] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:19.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:52:0) after 1m0s
[11:03:19.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:52:0)
[11:03:19.284] Timer tick, asking Buildbucket for the build status
[11:03:19.356] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:19.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:53:0) after 1m0s
[11:04:19.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:53:0)
[11:04:19.339] Timer tick, asking Buildbucket for the build status
[11:04:19.511] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:19.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:54:0) after 1m0s
[11:05:19.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:54:0)
[11:05:19.577] Timer tick, asking Buildbucket for the build status
[11:05:19.676] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:19.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:55:0) after 1m0s
[11:06:19.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:55:0)
[11:06:19.684] Timer tick, asking Buildbucket for the build status
[11:06:19.832] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:19.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:56:0) after 1m0s
[11:07:20.338] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:56:0)
[11:07:20.338] Timer tick, asking Buildbucket for the build status
[11:07:20.428] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:20.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:57:0) after 1m0s
[11:08:20.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:57:0)
[11:08:20.478] Timer tick, asking Buildbucket for the build status
[11:08:20.555] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:20.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:58:0) after 1m0s
[11:09:20.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:58:0)
[11:09:20.882] Timer tick, asking Buildbucket for the build status
[11:09:21.031] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:21.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:59:0) after 1m0s
[11:10:21.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:59:0)
[11:10:21.205] Timer tick, asking Buildbucket for the build status
[11:10:21.282] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:21.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:60:0) after 1m0s
[11:11:21.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:60:0)
[11:11:21.384] Timer tick, asking Buildbucket for the build status
[11:11:21.468] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:21.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:61:0) after 1m0s
[11:12:21.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:61:0)
[11:12:21.512] Timer tick, asking Buildbucket for the build status
[11:12:21.710] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:21.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:62:0) after 1m0s
[11:13:21.756] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:62:0)
[11:13:21.756] Timer tick, asking Buildbucket for the build status
[11:13:21.851] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:21.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:63:0) after 1m0s
[11:14:21.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:63:0)
[11:14:21.916] Timer tick, asking Buildbucket for the build status
[11:14:22.125] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:22.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:64:0) after 1m0s
[11:15:22.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:64:0)
[11:15:22.204] Timer tick, asking Buildbucket for the build status
[11:15:22.463] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:22.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:65:0) after 1m0s
[11:16:22.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:65:0)
[11:16:22.489] Timer tick, asking Buildbucket for the build status
[11:16:22.563] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:22.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:66:0) after 1m0s
[11:17:22.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:66:0)
[11:17:22.610] Timer tick, asking Buildbucket for the build status
[11:17:22.763] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:22.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:67:0) after 1m0s
[11:18:22.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:67:0)
[11:18:22.895] Timer tick, asking Buildbucket for the build status
[11:18:22.977] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:22.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:68:0) after 1m0s
[11:19:23.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:68:0)
[11:19:23.223] Timer tick, asking Buildbucket for the build status
[11:19:23.307] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:23.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:69:0) after 1m0s
[11:20:23.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:69:0)
[11:20:23.516] Timer tick, asking Buildbucket for the build status
[11:20:23.603] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:23.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:70:0) after 1m0s
[11:21:23.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:70:0)
[11:21:23.813] Timer tick, asking Buildbucket for the build status
[11:21:23.888] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:23.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:71:0) after 1m0s
[11:22:24.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:71:0)
[11:22:24.034] Timer tick, asking Buildbucket for the build status
[11:22:24.116] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:24.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:72:0) after 1m0s
[11:23:24.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:72:0)
[11:23:24.396] Timer tick, asking Buildbucket for the build status
[11:23:24.500] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:24.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:73:0) after 1m0s
[11:24:24.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:73:0)
[11:24:24.655] Timer tick, asking Buildbucket for the build status
[11:24:24.731] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:24.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:74:0) after 1m0s
[11:25:24.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:74:0)
[11:25:24.778] Timer tick, asking Buildbucket for the build status
[11:25:24.859] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:24.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:75:0) after 1m0s
[11:26:25.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:75:0)
[11:26:25.068] Timer tick, asking Buildbucket for the build status
[11:26:25.153] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:25.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:76:0) after 1m0s
[11:27:25.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:76:0)
[11:27:25.303] Timer tick, asking Buildbucket for the build status
[11:27:25.376] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:25.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:77:0) after 1m0s
[11:28:25.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:77:0)
[11:28:25.483] Timer tick, asking Buildbucket for the build status
[11:28:25.597] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:25.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:78:0) after 1m0s
[11:29:25.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:78:0)
[11:29:25.668] Timer tick, asking Buildbucket for the build status
[11:29:25.995] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:25.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:79:0) after 1m0s
[11:30:26.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:79:0)
[11:30:26.042] Timer tick, asking Buildbucket for the build status
[11:30:26.148] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:26.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:80:0) after 1m0s
[11:31:26.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:80:0)
[11:31:26.205] Timer tick, asking Buildbucket for the build status
[11:31:26.285] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:26.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:81:0) after 1m0s
[11:32:26.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:81:0)
[11:32:26.303] Timer tick, asking Buildbucket for the build status
[11:32:26.385] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:26.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:82:0) after 1m0s
[11:33:26.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:82:0)
[11:33:26.460] Timer tick, asking Buildbucket for the build status
[11:33:26.558] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:26.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:83:0) after 1m0s
[11:34:26.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:83:0)
[11:34:26.581] Timer tick, asking Buildbucket for the build status
[11:34:26.673] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:26.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:84:0) after 1m0s
[11:35:26.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:84:0)
[11:35:26.740] Timer tick, asking Buildbucket for the build status
[11:35:26.952] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:26.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:85:0) after 1m0s
[11:36:26.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:85:0)
[11:36:26.971] Timer tick, asking Buildbucket for the build status
[11:36:27.044] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:27.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:86:0) after 1m0s
[11:37:27.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:86:0)
[11:37:27.115] Timer tick, asking Buildbucket for the build status
[11:37:27.190] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:27.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:87:0) after 1m0s
[11:38:27.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:87:0)
[11:38:27.247] Timer tick, asking Buildbucket for the build status
[11:38:27.361] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:27.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:88:0) after 1m0s
[11:39:27.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:88:0)
[11:39:27.419] Timer tick, asking Buildbucket for the build status
[11:39:27.496] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:27.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:89:0) after 1m0s
[11:40:27.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:89:0)
[11:40:27.625] Timer tick, asking Buildbucket for the build status
[11:40:28.335] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:28.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:90:0) after 1m0s
[11:41:28.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:90:0)
[11:41:28.361] Timer tick, asking Buildbucket for the build status
[11:41:28.426] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:28.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:91:0) after 1m0s
[11:42:28.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:91:0)
[11:42:28.506] Timer tick, asking Buildbucket for the build status
[11:42:28.583] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:28.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:92:0) after 1m0s
[11:43:28.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:92:0)
[11:43:28.601] Timer tick, asking Buildbucket for the build status
[11:43:28.689] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:28.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:93:0) after 1m0s
[11:44:28.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:93:0)
[11:44:28.720] Timer tick, asking Buildbucket for the build status
[11:44:29.053] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:29.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:94:0) after 1m0s
[11:45:29.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:94:0)
[11:45:29.073] Timer tick, asking Buildbucket for the build status
[11:45:29.171] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:29.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:95:0) after 1m0s
[11:46:29.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:95:0)
[11:46:29.192] Timer tick, asking Buildbucket for the build status
[11:46:29.448] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:29.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:96:0) after 1m0s
[11:47:29.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:96:0)
[11:47:29.519] Timer tick, asking Buildbucket for the build status
[11:47:29.603] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:29.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:97:0) after 1m0s
[11:48:29.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:97:0)
[11:48:29.727] Timer tick, asking Buildbucket for the build status
[11:48:30.135] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:30.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:98:0) after 1m0s
[11:49:30.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:98:0)
[11:49:30.239] Timer tick, asking Buildbucket for the build status
[11:49:30.672] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:30.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:99:0) after 1m0s
[11:50:30.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:99:0)
[11:50:30.818] Timer tick, asking Buildbucket for the build status
[11:50:30.949] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:30.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:100:0) after 1m0s
[11:51:30.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:100:0)
[11:51:30.978] Timer tick, asking Buildbucket for the build status
[11:51:31.090] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:31.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:101:0) after 1m0s
[11:52:31.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:101:0)
[11:52:31.243] Timer tick, asking Buildbucket for the build status
[11:52:31.620] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:31.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:102:0) after 1m0s
[11:53:31.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:102:0)
[11:53:31.746] Timer tick, asking Buildbucket for the build status
[11:53:32.217] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:32.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:103:0) after 1m0s
[11:54:32.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:103:0)
[11:54:32.289] Timer tick, asking Buildbucket for the build status
[11:54:32.624] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:32.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:104:0) after 1m0s
[11:55:32.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:104:0)
[11:55:32.745] Timer tick, asking Buildbucket for the build status
[11:55:32.849] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:32.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:105:0) after 1m0s
[11:56:32.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:105:0)
[11:56:32.867] Timer tick, asking Buildbucket for the build status
[11:56:33.113] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:33.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:106:0) after 1m0s
[11:57:33.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:106:0)
[11:57:33.552] Timer tick, asking Buildbucket for the build status
[11:57:36.462] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:36.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:107:0) after 1m0s
[11:58:36.690] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:107:0)
[11:58:36.690] Timer tick, asking Buildbucket for the build status
[11:58:40.310] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:40.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:108:0) after 1m0s
[11:59:40.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:108:0)
[11:59:40.358] Timer tick, asking Buildbucket for the build status
[11:59:40.710] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:40.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:109:0) after 1m0s
[12:00:40.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:109:0)
[12:00:40.759] Timer tick, asking Buildbucket for the build status
[12:00:40.836] Build 8852973065564329424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:40.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018403127623174128:110:0) after 1m0s
[12:01:12.203] Received PubSub notification, asking Buildbucket for the build status
[12:01:12.358] Build 8852973065564329424: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[12:01:12.358] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615550470898307",
"created_by": "project:chromium",
"created_ts": "1615543985742228",
"failure_reason": "BUILD_FAILURE",
"id": "8852973065564329424",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1beeedeff28a2bc3e317124a69848d826d029c94\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithInvalidModelInfoShouldDeleteTempFile\"}, \"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\": \"1beeedeff28a2bc3e317124a69848d826d029c94\", \"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-10-bsc3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17769, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"225e1952d49477a114942c86328cb294e52781c6\", \"got_dawn_revision\": \"2f72aeda4fa52c3e7eb6276b0a59b1d568983517\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"1beeedeff28a2bc3e317124a69848d826d029c94\", \"got_revision_cp\": \"refs/heads/master@{#862362}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3c4fd15fb075ab3a8eb5809f9a42b5faa9ac63ae\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"14a626af56675c9d95f9fb207d53366a53a95fbd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33409}\", \"is_cached\": true, \"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\": \"1beeedeff28a2bc3e317124a69848d826d029c94\", \"swarm_hashes_refs/heads/master(at){#862362}_without_patch\": {\"absl_hardening_tests\": \"edf477556a2e14b23d410133b1f10bacc70350d7\", \"accessibility_unittests\": \"fbca52c18bb02ecf66cee6dc5e1df2562c7d51d6\", \"angle_unittests\": \"7b37cd225952f578da35b7b2c808d42e0e479fde\", \"app_shell_unittests\": \"cc5c06658870348c767b102273d4769b7d3a29ce\", \"aura_unittests\": \"fa328897b846e12a8ab07eee5be5cfb38507bf81\", \"base_unittests\": \"e2f967f13d9092f32d16ecf2675bdf543be3176b\", \"base_util_unittests\": \"909e4d06e9e52b530e76a2bd764e727976e3fb46\", \"blink_common_unittests\": \"36ced908c8ecb4c29d62cbbaaadf6a3decd4cbac\", \"blink_fuzzer_unittests\": \"5908b4757b27b25b118a1ce47da67f6b4b96146d\", \"blink_heap_unittests\": \"2767f2e7bd6eef39a09f4a3d796f2d9f6e6f79f8\", \"blink_platform_unittests\": \"603d5eb95079112d2f929ddfeedcde9383e5fb06\", \"blink_unittests\": \"226f4a2cbedaedfda257f0b10f201bcde5261ae2\", \"boringssl_crypto_tests\": \"908c0ea56a8013697e6eb1ee185d431e58ab66ca\", \"boringssl_ssl_tests\": \"c5f86ed5f97ed2a63fd46ad26b738683005adc85\", \"browser_switcher_bho_unittests\": \"67cd5403b9c8dc9b2b1546fadccd7e55d21b0155\", \"browser_tests\": \"0845854a19f177e6c33871fea4e5199d8a0dcc36\", \"capture_unittests\": \"73b5eb31bb02e507d867475e8c7d318c1fc1f524\", \"cast_unittests\": \"bff6aa87ee7f8e90384f9e4a6642fc89a13ee894\", \"cc_unittests\": \"96a580a0b1551d8da56b11b5ec99f616aebbd793\", \"chrome_app_unittests\": \"eff95db0239116b1742df6f01552c827b6b49c08\", \"chrome_cleaner_unittests\": \"00abcc8a438954198876a0be7afc332ca4b8edc7\", \"chrome_elf_unittests\": \"97b16010e6fb7046983b9bc004759a4bf3fbad93\", \"chromedriver_unittests\": \"8eab1343e43538b8d183a4c6cece0e9224e4f154\", \"color_unittests\": \"9fa57f573cc5ce353f6600fd36eeaedf7ec9fd5f\", \"components_browsertests\": \"ad155c58f1f7e9abdfb1b77ff4d1a8012ef89ee7\", \"components_unittests\": \"9eeae6069b48eda53912cd986877b4ae74fe7bd5\", \"compositor_unittests\": \"788b26e81a6acc6893748916e2280c19c222e890\", \"content_browsertests\": \"6a2734cb3d56cdc86a140ec27846d105f9da14ee\", \"content_unittests\": \"32947db4357e2b89f6a95d9355affcc96ca051df\", \"courgette_unittests\": \"3cd2e0e7343f3509d023f0e6d05a23b13651b21e\", \"crashpad_tests\": \"fc146a6654ca64a1f66ec364134a1b7c4781c76e\", \"cronet_tests\": \"6c8b2679f9b94875e276173db7df19aa221e3713\", \"cronet_unittests\": \"3a63d57518c6ce05f9f04dd4806b205271a67380\", \"crypto_unittests\": \"4087369f2ed0c9100600b8d83485a397445cf991\", \"delayloads_unittests\": \"f9967faf45d12c39e44438b1bbec5067e3db0f64\", \"device_unittests\": \"85a36fe4c7599c2315fbc4c0a523ee3570cfceb4\", \"display_unittests\": \"ae218771799c0fec3c0b401ac1a61fafae70e5c4\", \"elevation_service_unittests\": \"adaf193ceb52134bfda32744657ae53abd3a81c9\", \"events_unittests\": \"9703d9b7d2807cae99c3c95ff380c38690c42122\", \"extensions_browsertests\": \"45b76eb5e6705b5a9be2fc9364d44233944055f3\", \"extensions_unittests\": \"ec7c27c45dc91c681e471e7806251b970286c35a\", \"filesystem_service_unittests\": \"db6ea696976511c840668aa078d6a6f4a25e5cd5\", \"gcm_unit_tests\": \"5fd2f31fe0fd6dd9244d8015923515b463b7afa2\", \"gcp_unittests\": \"4b1729d3ffa099ff10a8b020c5c0d5e58bea2e66\", \"gfx_unittests\": \"43535cb2fc9343fcbbf968572e69bb0d31a3287e\", \"gin_unittests\": \"7fb128f7e5f2ddc3bc54ef365d55cef399f3827c\", \"google_apis_unittests\": \"c92abb9d2c38b950bbe4836a0774d87f7f998e94\", \"gpu_unittests\": \"e296362918319569a8b74dc70989b716b47a7405\", \"gwp_asan_unittests\": \"626221dc65eab352eea542cf495121aa772364e1\", \"headless_browsertests\": \"e68d4d1be5a0d33c5353b6d6f021263553971434\", \"headless_unittests\": \"3e3bcb6936c6dc8d5ea1a89b9e373f7691bebafc\", \"install_static_unittests\": \"41f92029b55e2a90c429a96bcb869eeab568edeb\", \"installer_util_unittests\": \"23be7634ad6d04fc184ce6ea41996497a9aafa72\", \"interactive_ui_tests\": \"51ebc78495e120b5525d8859b6af1915b47e2fab\", \"ipc_tests\": \"10ae0cbcd651524f1ea83c63974a37836faddb85\", \"jingle_unittests\": \"5cbcadf67b9515b7e93dbffddc28693db58b9c21\", \"latency_unittests\": \"1f4dfbb300e980f1812af61fefd81748a12bf884\", \"libjingle_xmpp_unittests\": \"962cfa40d2aa323ca272bb5bd3c497b895abce52\", \"liburlpattern_unittests\": \"98960badd88a66b1c221ff95a84320b34c6d7639\", \"media_blink_unittests\": \"341144afb7d205032441f710b0096265e5d555dd\", \"media_unittests\": \"94b77260fabae57d7d9eda64aceff8250d11611c\", \"message_center_unittests\": \"a5aa290a7576daebe9c8827c464657c80821679c\", \"midi_unittests\": \"59a9a18244ee8a3431f7962327184d9315b37d8c\", \"mojo_core_unittests\": \"4af0f69b58fafa6fc111710c082f18a43310c85c\", \"mojo_unittests\": \"76c384bd60ce1c9e98c168bd92aaf38c4f8085ad\", \"nacl_loader_unittests\": \"e37cccb5a90492a1e91ed7362c374c0b1d56482b\", \"native_theme_unittests\": \"29585ac31d089c2896dcaf7d4f6f47a41e003f4c\", \"net_unittests\": \"999c1924297d8922a19d96f33c12b4e9f6a73153\", \"notification_helper_unittests\": \"32121f68df90cf937cbf5b9daaab568dfc34b802\", \"pdf_unittests\": \"8193061abd66a89672c013286f9d8df499ea0462\", \"perfetto_unittests\": \"637c6747e55401ba3dc2c0c14a56156c5e4728ad\", \"ppapi_unittests\": \"7edcbe9dfeb4b70a84542ebc5bd5608f34014939\", \"printing_unittests\": \"ba7e0958f7628a7ad664f6ce0b78d0717f0b64b4\", \"remoting_unittests\": \"8edf9f2788ef5feb82cdf3bd5d22628800e73102\", \"sbox_integration_tests\": \"4131d5e5ff31729aef31e300ffa3ddf14cc79512\", \"sbox_unittests\": \"5f5aa3c604de65fff72d74924cbdba81d1ac6626\", \"sbox_validation_tests\": \"6cc7da75acaf26b31c0dfb88d0ef38dbea497924\", \"service_manager_unittests\": \"140811446edfd7ce3760580890c1f769dcc492dc\", \"services_unittests\": \"10d0acd720f75f2c9d9a039b7acae372d43a1de3\", \"setup_unittests\": \"6e6bbb6af47bd6ad035cd02b800f2608c091167e\", \"shell_dialogs_unittests\": \"4ce63562317c65ffd1479af8e8c8d70d39b6b09f\", \"skia_unittests\": \"2bc110d0f19062e4bc8d1e2052e73aa95c621cea\", \"snapshot_unittests\": \"f4c0a56227852ef9c6023267b7b002c91c1f1622\", \"sql_unittests\": \"5fb81ca1912a31ef918cbb291fb48bccda87c987\", \"storage_unittests\": \"21664a0a64d0953ae7a571ff52d09bf2bf111182\", \"sync_integration_tests\": \"507c93f0952c96f6b6013d71d6a47ae38d0ef56b\", \"traffic_annotation_auditor_unittests\": \"21c0516e1370563aecf3d1e9d0baec723d022703\", \"ui_base_unittests\": \"6e1986594178f620de9ddb774e01fedea8663601\", \"ui_touch_selection_unittests\": \"5d2abb9366ea25af90c66e2b48185158849751a6\", \"unit_tests\": \"1418c18bd058fe3d483b2b3c0c7396af9c0f8ae4\", \"updater_tests\": \"a2d7b5eb32330e4e0ea5742e11bd4e9e19cff7e8\", \"url_unittests\": \"3c4b355582bcbc2ddc49972e7ececccf67d90401\", \"views_unittests\": \"c2afdabc22f4542be5d01b7e6b5f6836f8dee6ff\", \"viz_unittests\": \"8b075c2ec9d0421d61c1c2b4ce9338e57e586cc5\", \"vr_common_unittests\": \"97da28cd228bd8e8a34f2e3139df691a49b85fb3\", \"vr_pixeltests\": \"ae305b84cb0ce2360333cb2cef8b653ede9f9ca1\", \"weblayer_browsertests\": \"b1b5281716f27bd18d9d2e4fd9a1fd486d626854\", \"weblayer_unittests\": \"3271116ea8f50ffc8aeab202746599afc02eac8e\", \"wm_unittests\": \"883fd1cf502a337198194e5ed54f3d8addcb8f9b\", \"wtf_unittests\": \"5b1f0d24f9e0929c575cef4f226b6fd7858135e5\", \"zlib_unittests\": \"6b19a44b4e81cb9f9d08d9eab29358ef3ee224ca\", \"zucchini_unittests\": \"05ddf2d58a31381fd224e7ed60ae0ff1f7c69edc\"}}, \"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-10-bsc3\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**unit_tests** failed because of:\\n\\n- PredictionModelDownloadManagerTest.VerifiedCrxWithInvalidModelInfoShouldDeleteTempFile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615544008347655",
"status": "COMPLETED",
"status_changed_ts": "1615544008914935",
"tags": [
"build_address:luci.chromium.ci/win-asan/17769",
"builder:win-asan",
"buildset:commit/git/1beeedeff28a2bc3e317124a69848d826d029c94",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1beeedeff28a2bc3e317124a69848d826d029c94",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018403127623174128",
"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/8852973065564329424/+/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:523ebe7418816c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615550470898817",
"url": "https://ci.chromium.org/b/8852973065564329424",
"utcnow_ts": "1615550472350256"
}
}
[12:01:12.358] Invocation finished in 1h48m7.962722674s with status FAILED